builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-073 starttime: 1506164927.67 results: success (0) revision: 8db0c4ecd94cb62a35a25a5fe411d9f0aed7087e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-09-23 04:08:47.670111) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-09-23 04:08:47.670584) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-23 04:08:47.670864) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-09-23 04:08:47.698562) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 04:08:47.699134) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-09-23 04:08:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 35.8M=0s 2017-09-23 04:08:47 (35.8 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.176828 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 04:08:47.899473) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 04:08:47.899819) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016866 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 04:08:47.949239) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-23 04:08:47.949606) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8db0c4ecd94cb62a35a25a5fe411d9f0aed7087e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 8db0c4ecd94cb62a35a25a5fe411d9f0aed7087e --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-09-23 04:08:47,999 truncating revision to first 12 chars 2017-09-23 04:08:47,999 Setting DEBUG logging. 2017-09-23 04:08:47,999 attempt 1/10 2017-09-23 04:08:47,999 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8db0c4ecd94c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-09-23 04:08:49,282 unpacking tar archive at: mozilla-central-8db0c4ecd94c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.551720 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-09-23 04:08:49.523591) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-23 04:08:49.523937) ========= script_repo_revision: 8db0c4ecd94cb62a35a25a5fe411d9f0aed7087e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-09-23 04:08:49.524324) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-23 04:08:49.524599) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-09-23 04:08:49.538036) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-23 04:08:49.538402) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:08:49 INFO - MultiFileLogger online at 20170923 04:08:49 in /builds/slave/test 04:08:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:08:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:08:49 INFO - {'append_to_log': False, 04:08:49 INFO - 'base_work_dir': '/builds/slave/test', 04:08:49 INFO - 'blob_upload_branch': 'Firefox', 04:08:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:08:49 INFO - 'branch': 'Firefox', 04:08:49 INFO - 'buildbot_json_path': 'buildprops.json', 04:08:49 INFO - 'code_coverage': False, 04:08:49 INFO - 'config_files': ('talos/linux_config.py',), 04:08:49 INFO - 'default_actions': ('clobber', 04:08:49 INFO - 'read-buildbot-config', 04:08:49 INFO - 'download-and-extract', 04:08:49 INFO - 'populate-webroot', 04:08:49 INFO - 'create-virtualenv', 04:08:49 INFO - 'install', 04:08:49 INFO - 'setup-mitmproxy', 04:08:49 INFO - 'run-tests'), 04:08:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:08:49 INFO - 'disable_ccov_upload': False, 04:08:49 INFO - 'disable_stylo': False, 04:08:49 INFO - 'download_minidump_stackwalk': True, 04:08:49 INFO - 'download_symbols': 'ondemand', 04:08:49 INFO - 'enable_stylo': False, 04:08:49 INFO - 'enable_webrender': False, 04:08:49 INFO - 'exes': {'tooltool.py': '/tools/tooltool.py'}, 04:08:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:08:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:08:49 INFO - 'gecko_profile': False, 04:08:49 INFO - 'gecko_profile_interval': 0, 04:08:49 INFO - 'installer_path': 'installer.exe', 04:08:49 INFO - 'log_level': 'info', 04:08:49 INFO - 'log_name': 'talos', 04:08:49 INFO - 'log_to_console': True, 04:08:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:08:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:08:49 INFO - 'opt_config_files': (), 04:08:49 INFO - 'pip_index': False, 04:08:49 INFO - 'suite': 'dromaeojs-e10s', 04:08:49 INFO - 'system_bits': '32', 04:08:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:08:49 INFO - 'title': 'talos-linux64-ix-073', 04:08:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:08:49 INFO - 'use_talos_json': True, 04:08:49 INFO - 'verify': 'False', 04:08:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:08:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:08:49 INFO - 'work_dir': 'build'} 04:08:49 INFO - [mozharness: 2017-09-23 11:08:49.825892Z] Running clobber step. 04:08:49 INFO - Running pre-action listener: _resource_record_pre_action 04:08:49 INFO - Running main action method: clobber 04:08:49 INFO - rmtree: /builds/slave/test/build 04:08:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:08:50 INFO - Running post-action listener: _resource_record_post_action 04:08:50 INFO - [mozharness: 2017-09-23 11:08:50.769259Z] Finished clobber step (success) 04:08:50 INFO - [mozharness: 2017-09-23 11:08:50.769356Z] Running read-buildbot-config step. 04:08:50 INFO - Running pre-action listener: _resource_record_pre_action 04:08:50 INFO - Running main action method: read_buildbot_config 04:08:50 INFO - Using buildbot properties: 04:08:50 INFO - { 04:08:50 INFO - "project": "", 04:08:50 INFO - "product": "firefox", 04:08:50 INFO - "who": "archaeopteryx@coole-files.de", 04:08:50 INFO - "installer_path": "public/build/target.tar.bz2", 04:08:50 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:08:50 INFO - "repository": "https://hg.mozilla.org/mozilla-central", 04:08:50 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs-e10s", 04:08:50 INFO - "stage_platform": "linux64-pgo", 04:08:50 INFO - "basedir": "/builds/slave/test", 04:08:50 INFO - "buildnumber": 200, 04:08:50 INFO - "platform": "ubuntu64_hw", 04:08:50 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 04:08:50 INFO - "slavebuilddir": "test", 04:08:50 INFO - "taskId": "Uuty4qW2Q4eD1KvYTNFdvA", 04:08:50 INFO - "branch": "mozilla-central", 04:08:50 INFO - "script_repo_revision": "production", 04:08:50 INFO - "revision": "8db0c4ecd94cb62a35a25a5fe411d9f0aed7087e", 04:08:50 INFO - "slavename": "talos-linux64-ix-073", 04:08:50 INFO - "repo_path": "mozilla-central" 04:08:50 INFO - } 04:08:50 INFO - Finding installer, test and symbols from parent task. 04:08:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Uuty4qW2Q4eD1KvYTNFdvA'}, attempt #1 04:08:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Uuty4qW2Q4eD1KvYTNFdvA'}, attempt #1 04:08:51 INFO - Task dependencies: a0jBBM_RSdqJvUB-BgrX8w 04:08:51 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a0jBBM_RSdqJvUB-BgrX8w'}, attempt #1 04:08:52 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/a0jBBM_RSdqJvUB-BgrX8w/artifacts/public/build/target.tar.bz2 04:08:52 INFO - Set test_packages_url: https://queue.taskcluster.net/v1/task/a0jBBM_RSdqJvUB-BgrX8w/artifacts/public/build/target.test_packages.json 04:08:52 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/a0jBBM_RSdqJvUB-BgrX8w/artifacts/public/build/target.crashreporter-symbols.zip 04:08:52 INFO - Running post-action listener: _resource_record_post_action 04:08:52 INFO - [mozharness: 2017-09-23 11:08:52.190988Z] Finished read-buildbot-config step (success) 04:08:52 INFO - [mozharness: 2017-09-23 11:08:52.191205Z] Running download-and-extract step. 04:08:52 INFO - Running pre-action listener: _resource_record_pre_action 04:08:52 INFO - Running main action method: download_and_extract 04:08:52 INFO - mkdir: /builds/slave/test/build/tests 04:08:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:08:52 INFO - trying https://queue.taskcluster.net/v1/task/a0jBBM_RSdqJvUB-BgrX8w/artifacts/public/build/target.test_packages.json 04:08:52 INFO - Downloading https://queue.taskcluster.net/v1/task/a0jBBM_RSdqJvUB-BgrX8w/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:08:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a0jBBM_RSdqJvUB-BgrX8w/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:08:52 INFO - Downloaded 989 bytes. 04:08:52 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:08:52 INFO - Using the following test package requirements: 04:08:52 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 04:08:52 INFO - u'common': [u'target.common.tests.zip'], 04:08:52 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:08:52 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:08:52 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:08:52 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:08:52 INFO - u'mozbase': [u'target.common.tests.zip'], 04:08:52 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:08:52 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:08:52 INFO - u'web-platform': [u'target.common.tests.zip', 04:08:52 INFO - u'target.web-platform.tests.tar.gz'], 04:08:52 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:08:52 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite categories: ['common', 'talos'] 04:08:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a0jBBM_RSdqJvUB-BgrX8w/artifacts/public/build/target.common.tests.zip 04:08:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a0jBBM_RSdqJvUB-BgrX8w/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:08:52 INFO - Fetch https://queue.taskcluster.net/v1/task/a0jBBM_RSdqJvUB-BgrX8w/artifacts/public/build/target.common.tests.zip into memory 04:08:53 INFO - Content-Length response header: 55342333 04:08:53 INFO - Bytes received: 55342333 04:09:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/a0jBBM_RSdqJvUB-BgrX8w/artifacts/public/build/target.talos.tests.zip 04:09:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/a0jBBM_RSdqJvUB-BgrX8w/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:09:04 INFO - Fetch https://queue.taskcluster.net/v1/task/a0jBBM_RSdqJvUB-BgrX8w/artifacts/public/build/target.talos.tests.zip into memory 04:09:05 INFO - Content-Length response header: 14513823 04:09:05 INFO - Bytes received: 14513823 04:09:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:05 INFO - trying https://queue.taskcluster.net/v1/task/a0jBBM_RSdqJvUB-BgrX8w/artifacts/public/build/target.tar.bz2 04:09:05 INFO - Downloading https://queue.taskcluster.net/v1/task/a0jBBM_RSdqJvUB-BgrX8w/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:09:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/a0jBBM_RSdqJvUB-BgrX8w/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:09:06 INFO - Downloaded 62439911 bytes. 04:09:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/a0jBBM_RSdqJvUB-BgrX8w/artifacts/public/build/target.tar.bz2 04:09:06 INFO - mkdir: /builds/slave/test/properties 04:09:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:09:06 INFO - Writing to file /builds/slave/test/properties/build_url 04:09:06 INFO - Contents: 04:09:06 INFO - build_url:https://queue.taskcluster.net/v1/task/a0jBBM_RSdqJvUB-BgrX8w/artifacts/public/build/target.tar.bz2 04:09:06 INFO - Running post-action listener: _resource_record_post_action 04:09:06 INFO - Running post-action listener: find_tests_for_verification 04:09:06 INFO - Running post-action listener: set_extra_try_arguments 04:09:06 INFO - [mozharness: 2017-09-23 11:09:06.827244Z] Finished download-and-extract step (success) 04:09:06 INFO - [mozharness: 2017-09-23 11:09:06.827340Z] Running populate-webroot step. 04:09:06 INFO - Running pre-action listener: _resource_record_pre_action 04:09:06 INFO - Running main action method: populate_webroot 04:09:06 INFO - {'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:09:06 INFO - 'chromez-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:06 INFO - 'tests': ['tresize', 04:09:06 INFO - 'tcanvasmark']}, 04:09:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:09:06 INFO - 'dromaeojs-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:06 INFO - 'tests': ['dromaeo_css', 04:09:06 INFO - 'kraken']}, 04:09:06 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:09:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:09:06 INFO - 'g1-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:09:06 INFO - 'talos_options': ['--disable-stylo'], 04:09:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:09:06 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:09:06 INFO - 'tests': ['damp', 'tps']}, 04:09:06 INFO - 'g2-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:09:06 INFO - 'talos_options': ['--disable-stylo'], 04:09:06 INFO - 'tests': ['damp', 'tps']}, 04:09:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:09:06 INFO - 'g3-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:06 INFO - 'tests': ['dromaeo_dom']}, 04:09:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 04:09:06 INFO - 'g4-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:06 INFO - 'tests': ['basic_compositor_video', 04:09:06 INFO - 'glvideo']}, 04:09:06 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 04:09:06 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 04:09:06 INFO - 'g5-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:09:06 INFO - 'talos_options': ['--disable-stylo'], 04:09:06 INFO - 'tests': ['ts_paint_webext', 04:09:06 INFO - 'tp5o_webext']}, 04:09:06 INFO - 'other-e10s': {'tests': ['a11yr', 04:09:06 INFO - 'ts_paint', 04:09:06 INFO - 'tpaint', 04:09:06 INFO - 'sessionrestore', 04:09:06 INFO - 'sessionrestore_many_windows', 04:09:06 INFO - 'sessionrestore_no_auto_restore', 04:09:06 INFO - 'tabpaint']}, 04:09:06 INFO - 'other-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:06 INFO - 'tests': ['a11yr', 04:09:06 INFO - 'ts_paint', 04:09:06 INFO - 'tpaint', 04:09:06 INFO - 'sessionrestore', 04:09:06 INFO - 'sessionrestore_many_windows', 04:09:06 INFO - 'sessionrestore_no_auto_restore', 04:09:06 INFO - 'tabpaint']}, 04:09:06 INFO - 'perf-reftest-e10s': {'tests': ['perf_reftest']}, 04:09:06 INFO - 'perf-reftest-singletons-e10s': {'tests': ['perf_reftest_singletons']}, 04:09:06 INFO - 'perf-reftest-singletons-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:06 INFO - 'tests': ['perf_reftest_singletons']}, 04:09:06 INFO - 'perf-reftest-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:06 INFO - 'tests': ['perf_reftest']}, 04:09:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:09:06 INFO - 'tsvgr_opacity', 04:09:06 INFO - 'tart', 04:09:06 INFO - 'tscrollx', 04:09:06 INFO - 'tsvg_static']}, 04:09:06 INFO - 'svgr-stylo-disabled-e10s': {'talos_options': ['--disable-stylo'], 04:09:06 INFO - 'tests': ['tsvgx', 04:09:06 INFO - 'tsvgr_opacity', 04:09:06 INFO - 'tart', 04:09:06 INFO - 'tscrollx', 04:09:06 INFO - 'tsvg_static']}, 04:09:06 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:09:06 INFO - 'tp5o-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:09:06 INFO - 'talos_options': ['--disable-stylo'], 04:09:06 INFO - 'tests': ['tp5o']}, 04:09:06 INFO - 'tp6-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:09:06 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:09:06 INFO - 'talos_options': ['--mitmproxy', 04:09:06 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:09:06 INFO - '--firstNonBlankPaint'], 04:09:06 INFO - 'tests': ['tp6_google', 04:09:06 INFO - 'tp6_youtube', 04:09:06 INFO - 'tp6_amazon', 04:09:06 INFO - 'tp6_facebook']}, 04:09:06 INFO - 'tp6-stylo-disabled-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:09:06 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:09:06 INFO - 'talos_options': ['--disable-stylo', 04:09:06 INFO - '--mitmproxy', 04:09:06 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:09:06 INFO - '--firstNonBlankPaint'], 04:09:06 INFO - 'tests': ['tp6_google', 04:09:06 INFO - 'tp6_youtube', 04:09:06 INFO - 'tp6_amazon', 04:09:06 INFO - 'tp6_facebook']}, 04:09:06 INFO - 'tp6-stylo-threads-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 04:09:06 INFO - 'mitmproxy_release_bin_osx': 'mitmproxy-2.0.2-osx.tar.gz', 04:09:06 INFO - 'talos_options': ['--stylo-threads=1', 04:09:06 INFO - '--mitmproxy', 04:09:06 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 04:09:06 INFO - '--firstNonBlankPaint'], 04:09:06 INFO - 'tests': ['tp6_google', 04:09:06 INFO - 'tp6_youtube', 04:09:06 INFO - 'tp6_amazon', 04:09:06 INFO - 'tp6_facebook']}, 04:09:06 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:09:06 INFO - 'talos_options': ['--xperf_path', 04:09:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:09:06 INFO - 'tests': ['tp5n']}, 04:09:06 INFO - 'xperf-stylo-disabled-e10s': {'pagesets_name': 'tp5n.zip', 04:09:06 INFO - 'talos_options': ['--disable-stylo', 04:09:06 INFO - '--xperf_path', 04:09:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:09:06 INFO - 'tests': ['tp5n']}}} 04:09:06 INFO - Running post-action listener: _resource_record_post_action 04:09:06 INFO - [mozharness: 2017-09-23 11:09:06.842767Z] Finished populate-webroot step (success) 04:09:06 INFO - [mozharness: 2017-09-23 11:09:06.842856Z] Running create-virtualenv step. 04:09:06 INFO - Running pre-action listener: _resource_record_pre_action 04:09:06 INFO - Running main action method: create_virtualenv 04:09:06 INFO - Creating virtualenv /builds/slave/test/build/venv 04:09:06 INFO - Running command: ['virtualenv', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:09:06 INFO - Copy/paste: virtualenv --no-site-packages --distribute /builds/slave/test/build/venv 04:09:06 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 04:09:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:09:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:09:07 INFO - Installing distribute.............................................................................................................................................................................................done. 04:09:08 INFO - Installing pip...............done. 04:09:08 INFO - Return code: 0 04:09:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:09:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:09:08 INFO - Reading from file tmpfile_stdout 04:09:08 INFO - Output received: 04:09:08 INFO - pip 1.1 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.1-py2.7.egg (python 2.7) 04:09:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:09:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d16b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb8d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eb9ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:09:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:09:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:09:08 INFO - Using env: {'DISPLAY': ':0', 04:09:08 INFO - 'HOME': '/home/cltbld', 04:09:08 INFO - 'LANG': 'en_US.UTF-8', 04:09:08 INFO - 'LANGUAGE': 'en_US:en', 04:09:08 INFO - 'LOGNAME': 'cltbld', 04:09:08 INFO - 'MAIL': '/var/mail/cltbld', 04:09:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:08 INFO - 'MOZ_NO_REMOTE': '1', 04:09:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:08 INFO - 'NO_EM_RESTART': '1', 04:09:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:08 INFO - 'PWD': '/builds/slave/test', 04:09:08 INFO - 'SHELL': '/bin/bash', 04:09:08 INFO - 'SHLVL': '1', 04:09:08 INFO - 'TERM': 'linux', 04:09:08 INFO - 'TMOUT': '86400', 04:09:08 INFO - 'USER': 'cltbld', 04:09:08 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642', 04:09:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:08 INFO - '_': '/tools/buildbot/bin/python'} 04:09:09 INFO - Ignoring indexes: http://pypi.python.org/simple/ 04:09:09 INFO - Downloading/unpacking pip>=1.5 04:09:09 INFO - Running setup.py egg_info for package pip 04:09:09 INFO - warning: no files found matching 'pip/cacert.pem' 04:09:09 INFO - warning: no files found matching '*.html' under directory 'docs' 04:09:09 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:09:09 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:09:09 INFO - Installing collected packages: pip 04:09:09 INFO - Found existing installation: pip 1.1 04:09:09 INFO - Uninstalling pip: 04:09:09 INFO - Successfully uninstalled pip 04:09:09 INFO - Running setup.py install for pip 04:09:09 INFO - warning: no files found matching 'pip/cacert.pem' 04:09:09 INFO - warning: no files found matching '*.html' under directory 'docs' 04:09:09 INFO - warning: no previously-included files matching '*.rst' found under directory 'docs/_build' 04:09:09 INFO - no previously-included directories found matching 'docs/_build/_sources' 04:09:09 INFO - Installing pip script to /builds/slave/test/build/venv/bin 04:09:09 INFO - Installing pip2.7 script to /builds/slave/test/build/venv/bin 04:09:09 INFO - Installing pip2 script to /builds/slave/test/build/venv/bin 04:09:09 INFO - Successfully installed pip 04:09:09 INFO - Cleaning up... 04:09:09 INFO - Return code: 0 04:09:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:09:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d16b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb8d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eb9ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:09:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:09:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:09:09 INFO - Using env: {'DISPLAY': ':0', 04:09:09 INFO - 'HOME': '/home/cltbld', 04:09:09 INFO - 'LANG': 'en_US.UTF-8', 04:09:09 INFO - 'LANGUAGE': 'en_US:en', 04:09:09 INFO - 'LOGNAME': 'cltbld', 04:09:09 INFO - 'MAIL': '/var/mail/cltbld', 04:09:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:09 INFO - 'MOZ_NO_REMOTE': '1', 04:09:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:09 INFO - 'NO_EM_RESTART': '1', 04:09:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:09 INFO - 'PWD': '/builds/slave/test', 04:09:09 INFO - 'SHELL': '/bin/bash', 04:09:09 INFO - 'SHLVL': '1', 04:09:09 INFO - 'TERM': 'linux', 04:09:09 INFO - 'TMOUT': '86400', 04:09:09 INFO - 'USER': 'cltbld', 04:09:09 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642', 04:09:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:09 INFO - '_': '/tools/buildbot/bin/python'} 04:09:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:09 INFO - Downloading/unpacking psutil>=3.1.1 04:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:09:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:09:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:09:12 INFO - Installing collected packages: psutil 04:09:12 INFO - Running setup.py install for psutil 04:09:12 INFO - building 'psutil._psutil_linux' extension 04:09:12 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 04:09:13 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 04:09:13 INFO - building 'psutil._psutil_posix' extension 04:09:13 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 04:09:13 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:09:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:09:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:09:13 INFO - Successfully installed psutil 04:09:13 INFO - Cleaning up... 04:09:13 INFO - Return code: 0 04:09:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:09:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d16b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb8d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eb9ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:09:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:09:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:09:13 INFO - Using env: {'DISPLAY': ':0', 04:09:13 INFO - 'HOME': '/home/cltbld', 04:09:13 INFO - 'LANG': 'en_US.UTF-8', 04:09:13 INFO - 'LANGUAGE': 'en_US:en', 04:09:13 INFO - 'LOGNAME': 'cltbld', 04:09:13 INFO - 'MAIL': '/var/mail/cltbld', 04:09:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:13 INFO - 'MOZ_NO_REMOTE': '1', 04:09:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:13 INFO - 'NO_EM_RESTART': '1', 04:09:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:13 INFO - 'PWD': '/builds/slave/test', 04:09:13 INFO - 'SHELL': '/bin/bash', 04:09:13 INFO - 'SHLVL': '1', 04:09:13 INFO - 'TERM': 'linux', 04:09:13 INFO - 'TMOUT': '86400', 04:09:13 INFO - 'USER': 'cltbld', 04:09:13 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642', 04:09:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:13 INFO - '_': '/tools/buildbot/bin/python'} 04:09:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:09:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:09:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:09:15 INFO - Installing collected packages: mozsystemmonitor 04:09:15 INFO - Running setup.py install for mozsystemmonitor 04:09:15 INFO - Successfully installed mozsystemmonitor 04:09:15 INFO - Cleaning up... 04:09:15 INFO - Return code: 0 04:09:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:09:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d16b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb8d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eb9ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:09:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:09:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:09:15 INFO - Using env: {'DISPLAY': ':0', 04:09:15 INFO - 'HOME': '/home/cltbld', 04:09:15 INFO - 'LANG': 'en_US.UTF-8', 04:09:15 INFO - 'LANGUAGE': 'en_US:en', 04:09:15 INFO - 'LOGNAME': 'cltbld', 04:09:15 INFO - 'MAIL': '/var/mail/cltbld', 04:09:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:15 INFO - 'MOZ_NO_REMOTE': '1', 04:09:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:15 INFO - 'NO_EM_RESTART': '1', 04:09:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:15 INFO - 'PWD': '/builds/slave/test', 04:09:15 INFO - 'SHELL': '/bin/bash', 04:09:15 INFO - 'SHLVL': '1', 04:09:15 INFO - 'TERM': 'linux', 04:09:15 INFO - 'TMOUT': '86400', 04:09:15 INFO - 'USER': 'cltbld', 04:09:15 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642', 04:09:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:15 INFO - '_': '/tools/buildbot/bin/python'} 04:09:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:15 INFO - Downloading/unpacking jsonschema==2.5.1 04:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:09:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:16 INFO - Downloading functools32-3.2.3-2.tar.gz 04:09:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:09:16 INFO - warning: no files found matching '*.txt' 04:09:16 INFO - no previously-included directories found matching 'build' 04:09:16 INFO - no previously-included directories found matching 'dist' 04:09:16 INFO - no previously-included directories found matching '.git*' 04:09:16 INFO - Installing collected packages: jsonschema, functools32 04:09:17 INFO - Running setup.py install for functools32 04:09:17 INFO - warning: no files found matching '*.txt' 04:09:17 INFO - no previously-included directories found matching 'build' 04:09:17 INFO - no previously-included directories found matching 'dist' 04:09:17 INFO - no previously-included directories found matching '.git*' 04:09:17 INFO - Successfully installed jsonschema functools32 04:09:17 INFO - Cleaning up... 04:09:17 INFO - Return code: 0 04:09:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d16b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb8d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eb9ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:09:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:09:17 INFO - Using env: {'DISPLAY': ':0', 04:09:17 INFO - 'HOME': '/home/cltbld', 04:09:17 INFO - 'LANG': 'en_US.UTF-8', 04:09:17 INFO - 'LANGUAGE': 'en_US:en', 04:09:17 INFO - 'LOGNAME': 'cltbld', 04:09:17 INFO - 'MAIL': '/var/mail/cltbld', 04:09:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:17 INFO - 'MOZ_NO_REMOTE': '1', 04:09:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:17 INFO - 'NO_EM_RESTART': '1', 04:09:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:17 INFO - 'PWD': '/builds/slave/test', 04:09:17 INFO - 'SHELL': '/bin/bash', 04:09:17 INFO - 'SHLVL': '1', 04:09:17 INFO - 'TERM': 'linux', 04:09:17 INFO - 'TMOUT': '86400', 04:09:17 INFO - 'USER': 'cltbld', 04:09:17 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642', 04:09:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:17 INFO - '_': '/tools/buildbot/bin/python'} 04:09:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:09:17 INFO - Cleaning up... 04:09:17 INFO - Return code: 0 04:09:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d16b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb8d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eb9ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:09:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:09:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:09:17 INFO - Using env: {'DISPLAY': ':0', 04:09:17 INFO - 'HOME': '/home/cltbld', 04:09:17 INFO - 'LANG': 'en_US.UTF-8', 04:09:17 INFO - 'LANGUAGE': 'en_US:en', 04:09:17 INFO - 'LOGNAME': 'cltbld', 04:09:17 INFO - 'MAIL': '/var/mail/cltbld', 04:09:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:17 INFO - 'MOZ_NO_REMOTE': '1', 04:09:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:17 INFO - 'NO_EM_RESTART': '1', 04:09:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:17 INFO - 'PWD': '/builds/slave/test', 04:09:17 INFO - 'SHELL': '/bin/bash', 04:09:17 INFO - 'SHLVL': '1', 04:09:17 INFO - 'TERM': 'linux', 04:09:17 INFO - 'TMOUT': '86400', 04:09:17 INFO - 'USER': 'cltbld', 04:09:17 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642', 04:09:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:17 INFO - '_': '/tools/buildbot/bin/python'} 04:09:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:17 INFO - Downloading/unpacking blobuploader==1.2.4 04:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:18 INFO - Downloading blobuploader-1.2.4.tar.gz 04:09:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:09:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:09:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:19 INFO - Downloading docopt-0.6.1.tar.gz 04:09:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:09:19 INFO - Installing collected packages: blobuploader, requests, docopt 04:09:19 INFO - Running setup.py install for blobuploader 04:09:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:09:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:09:19 INFO - Running setup.py install for requests 04:09:19 INFO - Running setup.py install for docopt 04:09:19 INFO - Successfully installed blobuploader requests docopt 04:09:19 INFO - Cleaning up... 04:09:19 INFO - Return code: 0 04:09:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d16b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb8d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eb9ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:09:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:09:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:09:19 INFO - Using env: {'DISPLAY': ':0', 04:09:19 INFO - 'HOME': '/home/cltbld', 04:09:19 INFO - 'LANG': 'en_US.UTF-8', 04:09:19 INFO - 'LANGUAGE': 'en_US:en', 04:09:19 INFO - 'LOGNAME': 'cltbld', 04:09:19 INFO - 'MAIL': '/var/mail/cltbld', 04:09:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:19 INFO - 'MOZ_NO_REMOTE': '1', 04:09:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:19 INFO - 'NO_EM_RESTART': '1', 04:09:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:19 INFO - 'PWD': '/builds/slave/test', 04:09:19 INFO - 'SHELL': '/bin/bash', 04:09:19 INFO - 'SHLVL': '1', 04:09:19 INFO - 'TERM': 'linux', 04:09:19 INFO - 'TMOUT': '86400', 04:09:19 INFO - 'USER': 'cltbld', 04:09:19 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642', 04:09:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:19 INFO - '_': '/tools/buildbot/bin/python'} 04:09:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:09:20 INFO - Running setup.py (path:/tmp/pip-CuMvmT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:09:20 INFO - Running setup.py (path:/tmp/pip-H7K5xN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:09:20 INFO - Running setup.py (path:/tmp/pip-iebklA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:09:20 INFO - Running setup.py (path:/tmp/pip-rrTi3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:09:20 INFO - Running setup.py (path:/tmp/pip-dcUgVY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:09:20 INFO - Running setup.py (path:/tmp/pip-q2cFmU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:09:20 INFO - Running setup.py (path:/tmp/pip-Dv0Cri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:09:20 INFO - Running setup.py (path:/tmp/pip-TPvPL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:09:20 INFO - Running setup.py (path:/tmp/pip-YwOKky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:09:20 INFO - Running setup.py (path:/tmp/pip-ApF9TO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:09:20 INFO - Running setup.py (path:/tmp/pip-iV_3mj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:09:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:09:20 INFO - Running setup.py (path:/tmp/pip-jqt9DG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:09:21 INFO - Running setup.py (path:/tmp/pip-Bex1da-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:09:21 INFO - Running setup.py (path:/tmp/pip-aMA59S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:09:21 INFO - Running setup.py (path:/tmp/pip-6TI0Qt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:09:21 INFO - Running setup.py (path:/tmp/pip-WaStM8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:09:21 INFO - Running setup.py (path:/tmp/pip-hD6QF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:09:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:09:21 INFO - Running setup.py install for manifestparser 04:09:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:09:21 INFO - Running setup.py install for mozcrash 04:09:21 INFO - Running setup.py install for mozdebug 04:09:21 INFO - Running setup.py install for mozdevice 04:09:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:09:21 INFO - Running setup.py install for mozfile 04:09:21 INFO - Running setup.py install for mozhttpd 04:09:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:09:21 INFO - Running setup.py install for mozinfo 04:09:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:09:22 INFO - Running setup.py install for mozInstall 04:09:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:09:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:09:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:09:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:09:22 INFO - Running setup.py install for mozleak 04:09:22 INFO - Running setup.py install for mozlog 04:09:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:09:22 INFO - Running setup.py install for moznetwork 04:09:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:09:22 INFO - Running setup.py install for mozprocess 04:09:22 INFO - Running setup.py install for mozprofile 04:09:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:09:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:09:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:09:22 INFO - Running setup.py install for mozrunner 04:09:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:09:22 INFO - Running setup.py install for mozscreenshot 04:09:22 INFO - Running setup.py install for moztest 04:09:23 INFO - Running setup.py install for mozversion 04:09:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:09:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:09:23 INFO - Cleaning up... 04:09:23 INFO - Return code: 0 04:09:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d16b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb8d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eb9ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:09:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:09:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:09:23 INFO - Using env: {'DISPLAY': ':0', 04:09:23 INFO - 'HOME': '/home/cltbld', 04:09:23 INFO - 'LANG': 'en_US.UTF-8', 04:09:23 INFO - 'LANGUAGE': 'en_US:en', 04:09:23 INFO - 'LOGNAME': 'cltbld', 04:09:23 INFO - 'MAIL': '/var/mail/cltbld', 04:09:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:23 INFO - 'MOZ_NO_REMOTE': '1', 04:09:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:23 INFO - 'NO_EM_RESTART': '1', 04:09:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:23 INFO - 'PWD': '/builds/slave/test', 04:09:23 INFO - 'SHELL': '/bin/bash', 04:09:23 INFO - 'SHLVL': '1', 04:09:23 INFO - 'TERM': 'linux', 04:09:23 INFO - 'TMOUT': '86400', 04:09:23 INFO - 'USER': 'cltbld', 04:09:23 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642', 04:09:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:23 INFO - '_': '/tools/buildbot/bin/python'} 04:09:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:09:23 INFO - Running setup.py (path:/tmp/pip-RY6Tk2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:09:23 INFO - Running setup.py (path:/tmp/pip-Djoaqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:09:23 INFO - Running setup.py (path:/tmp/pip-1TmAHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:09:23 INFO - Running setup.py (path:/tmp/pip-A8BUV_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:09:23 INFO - Running setup.py (path:/tmp/pip-qU12lj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:09:23 INFO - Running setup.py (path:/tmp/pip-Y0ElSo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:09:23 INFO - Running setup.py (path:/tmp/pip-niayYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:09:23 INFO - Running setup.py (path:/tmp/pip-Lf9ptB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:09:23 INFO - Running setup.py (path:/tmp/pip-2kQ_bW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:09:23 INFO - Running setup.py (path:/tmp/pip-qZGgJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.5 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:09:23 INFO - Running setup.py (path:/tmp/pip-gbgKZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:09:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:09:23 INFO - Running setup.py (path:/tmp/pip-EUaycd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:09:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:09:24 INFO - Running setup.py (path:/tmp/pip-8DipMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:09:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:09:24 INFO - Running setup.py (path:/tmp/pip-h_L9gB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:09:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:09:24 INFO - Running setup.py (path:/tmp/pip-uABX4P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:09:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:09:24 INFO - Running setup.py (path:/tmp/pip-C_Psuj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:09:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:09:24 INFO - Running setup.py (path:/tmp/pip-78jEhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:09:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:09:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:09:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:09:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:09:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:09:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:09:24 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:09:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.5->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:25 INFO - Downloading blessings-1.6.tar.gz 04:09:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:09:25 INFO - Installing collected packages: blessings 04:09:25 INFO - Running setup.py install for blessings 04:09:25 INFO - Successfully installed blessings 04:09:25 INFO - Cleaning up... 04:09:25 INFO - Return code: 0 04:09:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:09:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:09:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:09:26 INFO - Reading from file tmpfile_stdout 04:09:26 INFO - Current package versions: 04:09:26 INFO - argparse == 1.2.1 04:09:26 INFO - blessings == 1.6 04:09:26 INFO - blobuploader == 1.2.4 04:09:26 INFO - docopt == 0.6.1 04:09:26 INFO - functools32 == 3.2.3-2 04:09:26 INFO - jsonschema == 2.5.1 04:09:26 INFO - manifestparser == 1.1 04:09:26 INFO - mozInstall == 1.13 04:09:26 INFO - mozcrash == 1.0 04:09:26 INFO - mozdebug == 0.1 04:09:26 INFO - mozdevice == 0.51 04:09:26 INFO - mozfile == 1.2 04:09:26 INFO - mozhttpd == 0.7 04:09:26 INFO - mozinfo == 0.10 04:09:26 INFO - mozleak == 0.1 04:09:26 INFO - mozlog == 3.5 04:09:26 INFO - moznetwork == 0.27 04:09:26 INFO - mozprocess == 0.25 04:09:26 INFO - mozprofile == 0.28 04:09:26 INFO - mozrunner == 6.13 04:09:26 INFO - mozscreenshot == 0.1 04:09:26 INFO - mozsystemmonitor == 0.3 04:09:26 INFO - moztest == 0.8 04:09:26 INFO - mozversion == 1.4 04:09:26 INFO - psutil == 3.1.1 04:09:26 INFO - requests == 1.2.3 04:09:26 INFO - wsgiref == 0.1.2 04:09:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:09:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d16b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb8d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eb9ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:09:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:09:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:09:26 INFO - Using env: {'DISPLAY': ':0', 04:09:26 INFO - 'HOME': '/home/cltbld', 04:09:26 INFO - 'LANG': 'en_US.UTF-8', 04:09:26 INFO - 'LANGUAGE': 'en_US:en', 04:09:26 INFO - 'LOGNAME': 'cltbld', 04:09:26 INFO - 'MAIL': '/var/mail/cltbld', 04:09:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:26 INFO - 'MOZ_NO_REMOTE': '1', 04:09:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:26 INFO - 'NO_EM_RESTART': '1', 04:09:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:26 INFO - 'PWD': '/builds/slave/test', 04:09:26 INFO - 'SHELL': '/bin/bash', 04:09:26 INFO - 'SHLVL': '1', 04:09:26 INFO - 'TERM': 'linux', 04:09:26 INFO - 'TMOUT': '86400', 04:09:26 INFO - 'USER': 'cltbld', 04:09:26 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642', 04:09:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:26 INFO - '_': '/tools/buildbot/bin/python'} 04:09:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:09:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:09:26 INFO - Downloading/unpacking simplejson>=2.1.1 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 04:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:09:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:09:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/simplejson/setup.py) egg_info for package simplejson 04:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:09:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:09:27 INFO - Installing collected packages: simplejson 04:09:27 INFO - Running setup.py install for simplejson 04:09:27 INFO - building 'simplejson._speedups' extension 04:09:27 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c simplejson/_speedups.c -o build/temp.linux-x86_64-2.7/simplejson/_speedups.o 04:09:28 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/simplejson/_speedups.o -o build/lib.linux-x86_64-2.7/simplejson/_speedups.so 04:09:28 INFO - Successfully installed simplejson 04:09:28 INFO - Cleaning up... 04:09:28 INFO - Return code: 0 04:09:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:09:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:09:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:09:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b4e1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1d16b70>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1ebb9c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb8d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eb9ef0>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:09:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:09:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:09:28 INFO - Using env: {'DISPLAY': ':0', 04:09:28 INFO - 'HOME': '/home/cltbld', 04:09:28 INFO - 'LANG': 'en_US.UTF-8', 04:09:28 INFO - 'LANGUAGE': 'en_US:en', 04:09:28 INFO - 'LOGNAME': 'cltbld', 04:09:28 INFO - 'MAIL': '/var/mail/cltbld', 04:09:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:28 INFO - 'MOZ_NO_REMOTE': '1', 04:09:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:28 INFO - 'NO_EM_RESTART': '1', 04:09:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:28 INFO - 'PWD': '/builds/slave/test', 04:09:28 INFO - 'SHELL': '/bin/bash', 04:09:28 INFO - 'SHLVL': '1', 04:09:28 INFO - 'TERM': 'linux', 04:09:28 INFO - 'TMOUT': '86400', 04:09:28 INFO - 'USER': 'cltbld', 04:09:28 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642', 04:09:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:28 INFO - '_': '/tools/buildbot/bin/python'} 04:09:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:09:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:09:28 INFO - Cleaning up... 04:09:28 INFO - Return code: 0 04:09:28 INFO - Running post-action listener: _resource_record_post_action 04:09:28 INFO - Running post-action listener: _start_resource_monitoring 04:09:28 INFO - Starting resource monitoring. 04:09:28 INFO - [mozharness: 2017-09-23 11:09:28.780917Z] Finished create-virtualenv step (success) 04:09:28 INFO - [mozharness: 2017-09-23 11:09:28.781949Z] Running install step. 04:09:28 INFO - Running pre-action listener: _resource_record_pre_action 04:09:28 INFO - Running main action method: install 04:09:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:09:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:09:28 INFO - Reading from file tmpfile_stdout 04:09:28 INFO - Detecting whether we're running mozinstall >=1.0... 04:09:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:09:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:09:29 INFO - Reading from file tmpfile_stdout 04:09:29 INFO - Output received: 04:09:29 INFO - Usage: mozinstall [options] installer 04:09:29 INFO - Options: 04:09:29 INFO - -h, --help show this help message and exit 04:09:29 INFO - -d DEST, --destination=DEST 04:09:29 INFO - Directory to install application into. [default: 04:09:29 INFO - "/builds/slave/test"] 04:09:29 INFO - --app=APP Application being installed. [default: firefox] 04:09:29 INFO - mkdir: /builds/slave/test/build/application 04:09:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:09:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:09:46 INFO - Reading from file tmpfile_stdout 04:09:46 INFO - Output received: 04:09:46 INFO - /builds/slave/test/build/application/firefox/firefox 04:09:46 INFO - Running post-action listener: _resource_record_post_action 04:09:46 INFO - [mozharness: 2017-09-23 11:09:46.763637Z] Finished install step (success) 04:09:46 INFO - [mozharness: 2017-09-23 11:09:46.763782Z] Running setup-mitmproxy step. 04:09:46 INFO - Running pre-action listener: _resource_record_pre_action 04:09:46 INFO - Running main action method: setup_mitmproxy 04:09:46 INFO - Skipping: mitmproxy is not required 04:09:46 INFO - Running post-action listener: _resource_record_post_action 04:09:46 INFO - [mozharness: 2017-09-23 11:09:46.764312Z] Finished setup-mitmproxy step (success) 04:09:46 INFO - [mozharness: 2017-09-23 11:09:46.764414Z] Running run-tests step. 04:09:46 INFO - Running pre-action listener: _resource_record_pre_action 04:09:46 INFO - Running pre-action listener: _set_gcov_prefix 04:09:46 INFO - Running main action method: run_tests 04:09:46 WARNING - Try message not found. 04:09:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:09:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:09:46 INFO - Python 2.7.3 04:09:46 INFO - Return code: 0 04:09:46 INFO - grabbing minidump binary from tooltool 04:09:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:09:46 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb8d60>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1eb9a60>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1eb9ef0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:09:46 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:09:46 INFO - Copy/paste: /tools/tooltool.py --url https://tooltool.mozilla-releng.net/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 04:09:46 INFO - Calling ['/tools/tooltool.py', '--url', 'https://tooltool.mozilla-releng.net/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:09:46 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:09:46 INFO - Return code: 0 04:09:46 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:09:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:09:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:09:46 INFO - ENV: RUST_BACKTRACE is now full 04:09:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:09:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:09:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a0jBBM_RSdqJvUB-BgrX8w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-073', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:09:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/a0jBBM_RSdqJvUB-BgrX8w/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-073 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:09:46 INFO - Using env: {'DISPLAY': ':0', 04:09:46 INFO - 'HOME': '/home/cltbld', 04:09:46 INFO - 'LANG': 'en_US.UTF-8', 04:09:46 INFO - 'LANGUAGE': 'en_US:en', 04:09:46 INFO - 'LOGNAME': 'cltbld', 04:09:46 INFO - 'MAIL': '/var/mail/cltbld', 04:09:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:09:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:09:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:46 INFO - 'MOZ_NO_REMOTE': '1', 04:09:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:09:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:46 INFO - 'NO_EM_RESTART': '1', 04:09:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:46 INFO - 'PWD': '/builds/slave/test', 04:09:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:09:46 INFO - 'RUST_BACKTRACE': 'full', 04:09:46 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:09:46 INFO - 'SHELL': '/bin/bash', 04:09:46 INFO - 'SHLVL': '1', 04:09:46 INFO - 'TERM': 'linux', 04:09:46 INFO - 'TMOUT': '86400', 04:09:46 INFO - 'USER': 'cltbld', 04:09:46 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642', 04:09:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:46 INFO - '_': '/tools/buildbot/bin/python'} 04:09:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/a0jBBM_RSdqJvUB-BgrX8w/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-073', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:09:47 INFO - mozversion application_buildid: 20170923093829 04:09:47 INFO - mozversion application_changeset: 8db0c4ecd94cb62a35a25a5fe411d9f0aed7087e 04:09:47 INFO - mozversion application_display_name: Nightly 04:09:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:09:47 INFO - mozversion application_name: Firefox 04:09:47 INFO - mozversion application_remotingname: firefox 04:09:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:09:47 INFO - mozversion application_vendor: Mozilla 04:09:47 INFO - mozversion application_version: 58.0a1 04:09:47 INFO - mozversion platform_buildid: 20170923093829 04:09:47 INFO - mozversion platform_changeset: 8db0c4ecd94cb62a35a25a5fe411d9f0aed7087e 04:09:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:09:47 INFO - mozversion platform_version: 58.0a1 04:09:47 INFO - using testdate: 1506164987 04:09:47 INFO - actual date: 1506164987 04:09:47 INFO - starting webserver on 'localhost:59207' 04:09:47 INFO - SUITE-START | Running 2 tests 04:09:47 INFO - TEST-START | dromaeo_css 04:09:47 INFO - Initialising browser for dromaeo_css test... 04:09:47 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59207/getInfo.html -profile /tmp/tmpFwJsbl/profile 04:09:47 INFO - TEST-INFO | started process 3551 (/builds/slave/test/build/application/firefox/firefox http://localhost:59207/getInfo.html) 04:09:55 INFO - TEST-INFO | 3551: exit 0 04:09:55 INFO - Browser initialized. 04:09:55 INFO - Running cycle 1/1 for dromaeo_css test... 04:09:55 INFO - Using env: {'DISPLAY': ':0', 04:09:55 INFO - 'HOME': '/home/cltbld', 04:09:55 INFO - 'JSGC_DISABLE_POISONING': '1', 04:09:55 INFO - 'LANG': 'en_US.UTF-8', 04:09:55 INFO - 'LANGUAGE': 'en_US:en', 04:09:55 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:09:55 INFO - 'LOGNAME': 'cltbld', 04:09:55 INFO - 'MAIL': '/var/mail/cltbld', 04:09:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:09:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:09:55 INFO - 'MOZ_CRASHREPORTER': '1', 04:09:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:09:55 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:09:55 INFO - 'MOZ_NO_REMOTE': '1', 04:09:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:09:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:09:55 INFO - 'NO_EM_RESTART': '1', 04:09:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:09:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:09:55 INFO - 'PWD': '/builds/slave/test', 04:09:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:09:55 INFO - 'RUST_BACKTRACE': 'full', 04:09:55 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:09:55 INFO - 'SHELL': '/bin/bash', 04:09:55 INFO - 'SHLVL': '1', 04:09:55 INFO - 'TERM': 'linux', 04:09:55 INFO - 'TMOUT': '86400', 04:09:55 INFO - 'USER': 'cltbld', 04:09:55 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642', 04:09:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:09:55 INFO - '_': '/tools/buildbot/bin/python'} 04:09:55 INFO - TEST-INFO | started process 3792 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFwJsbl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:09:56 INFO - PID 3792 | 04:09:56 INFO - PID 3792 | (/builds/slave/test/build/application/firefox/firefox:3841): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:09:56 INFO - PID 3792 | 04:10:06 INFO - PID 3792 | RSS: Main: 145129472 04:10:06 INFO - PID 3792 | 04:13:04 INFO - PID 3792 | Cycle 1(1): loaded http://localhost:59207/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59207/tests/dromaeo/cssquery-ext.html) 04:13:05 INFO - PID 3792 | RSS: Main: 146866176 04:13:05 INFO - PID 3792 | 04:16:06 INFO - PID 3792 | Cycle 1(1): loaded http://localhost:59207/tests/dromaeo/cssquery-ext.html (next: http://localhost:59207/tests/dromaeo/cssquery-jquery.html) 04:16:07 INFO - PID 3792 | RSS: Main: 147005440 04:16:07 INFO - PID 3792 | 04:19:09 INFO - PID 3792 | Cycle 1(1): loaded http://localhost:59207/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59207/tests/dromaeo/cssquery-mootools.html) 04:19:09 INFO - PID 3792 | RSS: Main: 148975616 04:19:09 INFO - PID 3792 | 04:22:11 INFO - PID 3792 | Cycle 1(1): loaded http://localhost:59207/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59207/tests/dromaeo/cssquery-prototype.html) 04:22:11 INFO - PID 3792 | RSS: Main: 147648512 04:22:11 INFO - PID 3792 | 04:25:14 INFO - PID 3792 | Cycle 1(1): loaded http://localhost:59207/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59207/tests/dromaeo/cssquery-yui.html) 04:25:15 INFO - PID 3792 | RSS: Main: 149409792 04:25:15 INFO - PID 3792 | 04:28:17 INFO - PID 3792 | Cycle 1(1): loaded http://localhost:59207/tests/dromaeo/cssquery-yui.html (next: http://localhost:59207/tests/dromaeo/cssquery-dojo.html) 04:28:17 INFO - PID 3792 | RSS: Main: 151003136 04:28:17 INFO - PID 3792 | 04:28:17 INFO - PID 3792 | __start_tp_report 04:28:17 INFO - PID 3792 | _x_x_mozilla_page_load 04:28:17 INFO - PID 3792 | _x_x_mozilla_page_load_details 04:28:17 INFO - PID 3792 | |i|pagename|runs| 04:28:17 INFO - PID 3792 | |0;dojo.html;3793.696504279658;3838.1747924196293;3838.1939792643543;3839.7504162229443;3852.5954774748634;6214.7203375848085;6248;6301.369863013696;6319.39965703258;6323.873522529547;6310.021946598273;6310.085037669535;6319.17850679411;6330.050492426139;6344.841378965517;5584.972075139619;5637.661740295585;5647;5702.857428564277;5760.308762948452;5650.841577476628;5681.176229446728;5744.281964754406;5744.54043676507;5774.566907481934;5516.3380394352935;5599.636023658449;5607.186957891083;5668.43315668434;5775.509081728037;5542.224088627595;5593.860153496195;5653.660780353192;5710.143478478261;5710.914336284959;5619.072852979253;5659.5755318351285;5703.686297253649;5723.656580605136;5727.255456790632;6241.407066328691;6256.499480041632;6277.403646653561;6280;6356.682165891687;6768.661566921634;6788.6945087470685;6877.552959057645;6883.518153729241;6889.0355350251;6945.826354341182;6967.407770339477;6982.2319544850025;6984.755533556301;6990.510664253505;6373.298937116913;6394.424501794861;6418.1656384669695;6465.224173099211;6477.5465717400275;6970.163580370388;7021.684024218923;7074.540154889915;7120.038794762744;7123.2520585338825;6667.86664266712;6804.931950680529;6808.012838138442;6814.829629259309;6852;6824.385805277549;6945.791626251221;6957;6964.4428445724225;6966.79099627012;6762.492813039042;6817.5909445433435;6874.621895795779;6899.551529150589;6900.482463815234;6712.496562757813;6785.830354241183;6787.830304242433;6799.150106236721;6872.965635171792;5003.824866129668;5025.7989680412375;5040.067587496325;5044.268581055726;5055.797768089309;4513.052259025576;4523.660725445605;4565.406497155415;4582.679212455163;4595.448546174481;6050.909236361458;6123.08153776937;6135.263768347827;6194.5973511721595;6203.2245969253845;6771.356721111487;6794.0827988221945;6796.320367963263;6800.217974932946;6801.4898882584;6092.817215483543;6122.112293717475;6156.138140660311;6218.782342617987;6224.62652240867;5502.642328248651;5512.6968016758665;5535.833924982257;5591.916121258185;5593.608447408724;6840.897386539206;6861.965690171517;6865.897011544831;6980.895286570705;6982.2319544850025;6775.796726098323;6812.21659509143;6816;6816.863662726817;6836.589804611739;6933.826654333682;6935.4104901081955;6952.270011648704;7036.663034023724;7045.26024767392;6800.761973330909;6816.931830681629;6876.140482439695;6878.105846239957;6887.724491020504;6831.214410342675;6835.316468353125;6839.350261725128;6932.064171337007;6956.130483689539;6667.899981500184;6677.131972843499;6694.631795251308;6738.35985581369;6739.326067393222;6539.934600653933;6546.607203567801;6602.306757790363;6654.700538475683;6716.932830671826;6518;6541.771038013647;6542.44389226902;6549.312322206099;6571.901421478778;6589.703463344065;6613.966930165318;6635.701393437211;6712.127423434922;6728.158980127484;6423.197100362455;6426.485881129592;6520.510961677798;6529;6537.738490460519 04:28:17 INFO - PID 3792 | |1;ext.html;950.4487397309562;972.7762614598638;984.91628211602;996.2826764729394;1011.1758916483066;44700.55299447005;45047;45400.772996135056;45460.772696136555;45564.31653525196;41494;41856.37215441771;42010.159796804124;42019.57980420195;42111.57888421115;7830.765077047698;7984.680612775482;8005;8007.439479236456;8013.318867896207;7845.176256493071;7952.085510166318;7954.761357159295;7956.880646790303;7960.960195199016;8524.701635442761;8621.310295176403;8662.826743465126;8676.956615216915;8680.34897382699;8551.230389264965;8613;8655.221030107321;8658.956705216433;8660.653573857038;8298.128696486903;8389.95805020977;8405.663773449054;8433.873491897626;8504.277136443348;41289.1742165155;41943.7902810484;41963.1607367854;41989.37015944763;42062.36906446406;3753.305638456894;3788.22341420011;3805.904852378685;3807.5240594925635;3808.5810560838286;33658.326833463194;33689.32621347584;33751.83124084389;33807.830960845284;33820.32359352799;5377.354717433894;5441.727913604304;5490.478404551562;5552.77788888444;5555.722213889329;217743;218203;218536.9999999984;218615;220123;54090.72954635201;54110;54127.752334859244;54195.18707219395;54261;55981.720091399286;56153.7192314044;56261.718691406284;56265;56266.71866640641;17315.134243288037;17652.735208971877;17672.911635441997;17678.911605441892;17727.55681107957;14338.641533961734;14357.282135893163;14456.927715361357;14462.638434039023;14472.421103155755;15468.767968480483;15544.455944041903;15565.14391708467;15593.766093508606;15649.139297338528;6022.277326720821;6179.134921111048;6201.10084037812;6227.719752611053;6291.622502649764;1120.9887901121047;1126.9436528173558;1131.660501849442;1133.7279053027214;1137.7781332640056;26288.605670914952;26494;26531.86734066317;26613.467730645665;26754.866225669135;1235.3637876493613;1254.861965183829;1256.7863463211277;1262.4760724299497;1275.9808602870964;13402.190704254801;13977.58067257984;13994.860051399559;14030;14208.360623771956;267892;269375;269450;269928;270682;59056.704716476146;59989.40010599925;62367;62477.687611562564;62516;1523.1089812459836;1542.5141080559813;1544.2587557972236;1552.5808031831343;1558.1975282729404;31664.841675791475;31831.681683182873;31984.68015319817;32249.51625725569;32356.83821580877;43610.563894360654;43641.78179109084;43710.781446092566;43844.78077609592;43896;239113;240001;242249;243083;244308;36160.63839361573;36729.26541469102;37108;37372.62627373692;37439.62560374361;48277;48300;48494;48782;48820;36188;36632.45051324285;36677;36755.264894701424;36857.63142368542;35658;35874.4618830723;36031.63968360388;36220;36234.27531449409;42911.78544107259;42954.49659261911;43011.78494107509;43042.35436468518;43267.78366108149;34175.31649366949;34415.48376774401;34639.480407794406;34695.479567805996;35050;25910.74089259083;25937.48125037451;26198.214053578424;26516.734832651426;26966.595501067106 04:28:17 INFO - PID 3792 | |2;jquery.html;635.1806170040642;635.3702752439991;639.0861068671799;648.9740410383582;664.3223911610157;23519.5296094078;23633.291001269947;23879.64180537289;24376.268711938646;24459.877700611494;25211.369715757115;26300.0794972176;26322.736772632266;26349.73650263497;26365.3408664783;6872.518923675345;6904;7025.402840758542;7034.3317384848615;7034.613096279702;5846.707664616773;6679.432248258904;6686.498512611549;6691.665416729168;6812.080369150151;6641.867162656744;6651.434628056621;6652.600843949354;6654.26803051664;6658.800235992928;6491.545591808575;6613.834654133661;6654.168228971354;6660.800175994704;6748.291429399891;6268.028455589345;6273.56085074045;6281.654509002003;6291.43377096059;6309.4636955858805;29027.41945161085;29357.119286421443;29373.559396609067;29487;29633.555496667566;2336.941576460585;2365.9053637854463;2383.582872997236;2385.33210701004;2386.892589833444;19973.400797976083;20586.382408527617;20717.171313147457;20731.27440539574;20773.16907323705;2499.487605040962;2519.7354277800932;2555.782758465533;2569.11365578875;2592.8185027048116;139356;144605;156979.2151039249;157918;161179;15833.604159896095;15901.840981590036;16389;16503.17484125789;17145.571360716083;25433.87283063573;25501.107461238767;25547.105851295117;25633.74366256351;25650;10228.59085636581;10288.331258468324;10433.198259640185;10494.790104197873;10658.253922225525;9524.285678574135;9532.428054316762;9551.952240238754;9577.856332155023;9667.9033209667;10126;10137.442440665687;10168.847467287997;10188.337758045704;10311.587536498606;2974.940501189964;3017.1250337401907;3037.2862377341307;3054;3128.6089238845143;1049.1868801678606;1053.2153545608417;1066.8186408310605;1068.7488440216543;1069.5187165775433;21388.78611213899;21756.56486870254;21777.128914843546;21779.12883484643;21890.23384181546;1111.372074777748;1118.4184224203368;1119.9384033878052;1121.5906194239044;1129.8474705914762;11882.16824822254;12024.398780060961;12161.209521381083;12171.269723816422;12175.14773965814;557746;574664;574837;591297;619949;132949;133476;135855;138915;139510;1120.467777805536;1182.5447202826974;1212.3029258176584;1223.889849913512;1272.917260378054;22480.379905023743;22879.542409152058;23036.539269214856;23056.654150188097;23291.53416931686;64620.676896615216;64803;65729;66798.66600666965;67581;81315.59342203251;81589;82365;82416.58791706002;83447.5827620858;15542.5337239883;15715.292811823267;15787.921060394625;15940.123293218985;15980.840191597936;24400.755992440558;24486.387840304134;24599.38501537476;24825.751742483066;24967;19853.60292794165;20048.799512004694;20149.899250503655;20281.695774563097;20532;17784.199711012778;17808.732869007235;17980.91009544944;18040.278388864823;18435.446936591386;19755.308564200757;20169.092390842154;20304.695429568863;20467.385978420672;20521.38435846927;15299.694006120038;15534.84465155334;15624.14067226268;15677.843221567638;15724.68550629004;19616.411507654793;19799.802001980373;19965.900170499055;19972;19976.60046799085 04:28:17 INFO - PID 3792 | |3;mootools.html;1415.3984556563462;1488.2558720639681;1490.3516970117992;1494.9626259343515;1512.9938590837087;16559.172041397927;16981.91509042453;17151.48545543634;17152;17156.82843171568;16641.084740339276;16825.663486730256;16875.746863797052;16879.746803797952;17003.23485443155;2831.83009019459;2870.698576649453;2876.6116574262414;2884.927876803076;2890.1474065150833;2760.4203117345282;2829.2643912582776;2833.150054983507;2833.320003199238;2837.744602985731;2659.4415172813733;2724.523208438515;2755.8484283364405;2756.6691996960294;2758.8344699318004;2612.020492315382;2663.2809141531775;2665.027265048263;2666.4933662604044;2666.879990400437;2603.7526434988836;2649.774769144625;2652.2175958092407;2653.230563136688;2658.9202323930313;16200.75698864518;16434.424795132112;16516.669666606722;16547.586310342216;16548.917255413646;7997.840043199161;8284.668613255462;8287.171282871725;8308.335333173332;8309.750707478785;5152.793888244466;5300.363956325227;5373.59698022646;5381.112116500773;5637.520810731093;2134.423705599495;2139.1657253671083;2149.580831737812;2209.436593668604;2215.490437199437;27605.585916211272;28400.28999275014;28542;29231.2692182695;29265.85367073172;7441.776746697499;7496.100467943882;7634.961825190839;7776.805579860549;7779.883301750365;23475.178368757013;23499;23514.882425588105;23518.529629407316;23521.7647823523;8688.695895643623;8715.956420217859;8769.736907892773;8875.378723489293;8879.24526415249;7423.329750806025;7731.381489480828;7829.3345065670055;7942.166072562413;8021.117677055519;6029.186059881948;6303.842403939938;6312.368763123651;6346.524010699216;6406.2953075161695;1847.214933653192;1849.722541618768;1876.4839669090836;1879.2858713688984;1915.1669023974616;953.7615596100975;961.485605201214;977.4772535176169;984.0258144436956;1000.46475135802;8523.445976011539;8664.696735614349;8747.256483198998;8837.160469755363;8881.511516866634;773.8413625206819;774.7327172125607;781.9530828150329;794.1066300412036;812.7439856445201;5058.161875152792;5088.160453525127;5120.846374608767;5211.609129315317;5227.555657769132;24279.63580546293;24527.754722452904;24792.628110578356;24808;25277.368065798135;25451.872740636547;25532.23403297904;25624.615630765555;25659.87170064138;25696.100636477633;12422.875771242172;12938.159019663692;13010.21938683682;13014.023948203923;13115.934420327838;9593.090019471932;9615.375000624937;9638.518074096128;9677.322587418814;9724.75688107803;20328.898355508718;20734.585308294052;20883.582328353652;21540.676889846978;21943.231986880382;21741;21824;22132.33602991913;22276.665850011937;22280.77719222787;13359;13391.732165356443;13394.732105358033;13469.12450690702;13574.59276221715;15344.769828452805;15407.922960385127;15549.300281487134;15599.610009749393;15615.921920390325;12165.69585760363;12276.263424194576;12343.629691109281;12410.379481025731;12467.438965246767;12086.274823510266;12187.207831490925;12342.81485777732;12408.503659853504;12455.190412623513;9938.204943604349;10000.849987250343;10017.39895606266;10096.19230461576;10106.545205465625;8056.516609003478;8093.231143041402;8252.958735206286;8256.215659512336;8275.83448331042;7959;8025.678972841021;8028.799280018046;8062.919370806217;8143.51138931666 04:28:17 INFO - PID 3792 | |4;prototype.html;553.300075404613;575.1258087706686;581.4708615160205;586.870888404551;590.6515156057925;6115.055789016082;6164.506839452844;6187.597806142603;6195.597286176396;6215.04785227017;6095.329513753494;6096.786612468564;6103.7253323600435;6120.755169793203;6136.202293701824;4857.498388330013;5064.873378165538;5066.36670416198;5075.340205773254;5083.339165908426;4912.901741965158;4929.605631549467;4946.629002824785;4953.23224900141;4996.1756310208775;4867.610591152699;4899.216125419934;4917.926231106536;4963.578095861856;4965.826196083138;4890;4896.24108263218;4935.840077581765;4944.678595891256;4944.678595891256;4518.1415531049;4522.728636281834;4533.116042371739;4534.410526631516;4546.431696037996;5963.940360596382;5990.341062483123;6056.96971515144;6071.241094863142;6181;5737.483626473638;5789.218455508494;5793.826185214451;5796.507501774236;5829.72640670139;6579.24338701046;6593.175853018372;6605.504587155982;6609.306022867626;6634.573566683138;4832.178529649933;4974.87562810929;4983.6013118950395;4995.975020124876;5005.299258103868;6651.135352404156;6709.161354830646;6712.261651218362;6747.561408508432;6761.121054262963;5128.615353848476;5155.664881782672;5162.664426812245;5172.741362931838;5182.32629758129;5211.557017653466;5221.55616772571;5244.108501554745;5244.423113457517;5252.606054545924;5733.709915269031;5776.653400795965;5820.3888591698105;5860.326062502867;5861.6189947653265;6555.967220163869;6585.506087043491;6607.240167380813;6647.734090636417;6650.168728908887;1802.2430579156785;1807.620399716048;1822.4259358302093;1822.8632852535848;1842.7880793708628;1227.969300767488;1281.436168086039;1283.1210620724833;1295.6372215779409;1296.4101333893057;5926.229590153339;5989.790357337472;5999;6017.729202185825;6038.456538911519;6445.484361251089;6654.467642588678;6661.467082633378;6685.6322902240845;6691.76578819739;5832.795852145155;5891.528677705774;5925.34821169671;5927.628948420666;5998.340182579913;3460.584729832436;3483.6864682178725;3489.180042689966;3509.771864828778;3513.2797776455764;6707.932920670731;6763.932360676428;6771.796846094625;6782.796516104525;6822.727090916407;6643.202815662152;6744.696488657924;6746.4265537429865;6855.760048398282;6890.379865812101;6637.402633762985;6681.759346716742;6725.966370168216;6731.596104233761;6753.324667533306;6544.476441884733;6556.770513032021;6559.523452570145;6574.474042076718;6650.933490665031;6528.21661400616;6541.215054193527;6648.468122550281;6657.101291325609;6727.562708423937;6800.93199068003;6838.350356716104;6839.179298484214;6859.485538584527;6879;5165.380154381498;5178.97410512945;5189.844304670866;5199.63602547818;5211.348581427322;6388.297287298301;6648.301928297459;6648.700808463727;6650.334966503311;6723.562968407038;6276.152719383008;6405.8398540035005;6460.741570337322;6499.512536559682;6533.379328963741;6364.936350636434;6388.744450221939;6428.55000149985;6431.646259455775;6508.349165083643;6313.3686631336495;6324.027706977713;6326.936730632635;6363.172787537591;6434.034894765636;6491.350864913471;6512.6092434454085;6530.67346632657;6574.4740420765265;6592.934070659232;6259.467945224706;6265.404786545271;6339.985602303609;6378.266499352634;6395.584287021329 04:28:17 INFO - PID 3792 | |5;yui.html;967.7000129959713;985.8373368394215;1000.0549480740698;1012.6810054832722;1018.2414101494388;17900.283988640454;18128.72806907896;18139.637207255848;18143;18149.727754083666;17127.82872171278;17135.228914698837;18759.718604220947;19256.711149332772;19303.61392772147;1038.2576457832647;1058.2116323339123;1060.7295139739356;1061.8566493523392;1067.4022547373456;1051.5478344311944;1055.0978913029326;1059.8251288537383;1060.3266925502285;1061.9681409557725;1071.1056268016216;1104.4091411095067;1104.5416152296827;1109.4619109731773;1117.6032508459502;1095.780843831233;1096.413418820931;1096.4572536594358;1096.7916095941785;1098.1708809848592;791.4380789639363;824.2828738997051;826.3595713322223;826.5495305058757;826.6569373709904;19406.70889936652;19685.01574921248;19726.802731972784;19740.210391584318;19752.111154997914;22032.669509957363;22649.54700905989;23689;23713.644295335584;23863.64204536933;17210.655786884316;17257.050862202574;17356.47930562085;17548.122593870256;17698.823011769848;3923.2545816294814;3967.2858885400615;3974.1455587048636;3976.642102210786;3981.3430783920617;132040;160225;162125.18937405356;162601.18699406544;165153.17423412926;18010.63978720418;18070.5482362942;18336.266549338143;18773.24907003732;18850.622987540173;34298.65701342955;34772;35022.47466288008;35270.8236458816;35289.47065793964;9611.375260608038;9697.563609637584;9711.174550163316;9751.609935602639;9751.658691945748;4542.727436353829;4555.40779698637;4629.027904140168;4691.859244222679;4724.338592597039;5741.655500669973;5766.654000759884;5815.04051831443;5834.708264586754;5850.12248162779;1223.889849913512;1232.4885172653248;1247.7067889046066;1248.8751124887438;1259.7606454773747;758.0940775773008;770.1451388958252;770.1951460723558;783.9843203135905;788.9723859664884;13480.39338229782;13724.519641812489;13726.636973941448;14715.632109197142;14760;733.8312188196745;741.2846603028104;747.7980945144781;760.7983884270673;767.7274567528559;6265.530085243625;6286.49708023357;6344.746210151542;6399.392057754506;6518.57629254097;152697;153263;153954;158334;161865;125198;125815;128389;130887;136199;109361;114791;115214.42392788151;115809;117978;14118.43526258938;14220.288985550887;14424.783628245375;14619.415223390944;14749.115053096848;53627;55165;55280;55399.72300138474;56013.15980260218;69790;69838;69897.65051174711;70374.29625703677;70704.29295706977;19737;20128.59742805165;20171.294004709765;20372.89813550923;20395.08222129978;20976.47558811042;21094.156233750477;21318.573628527654;21322.36032919015;21488.462788430414;20681.27615533449;20744.48138796542;20834.1666333345;20868.47828804292;20902.686459703422;20035.49911252172;20296.289629862884;20308.390748277576;20331.390058298275;20525.89737051365;19740.703889441957;20000;20011.399658010283;20135.899320503304;20340.69488957637;13559.389827457591;13659.112157709716;13753.243571603658;14039.859601403856;14047.918789840933;15220;15248.847511524742;15451.459198927983;15477.84522154764;15519.922400387926 04:28:17 INFO - PID 3792 | __end_tp_report 04:28:17 INFO - PID 3792 | __start_cc_report 04:28:17 INFO - PID 3792 | _x_x_mozilla_cycle_collect,277 04:28:17 INFO - PID 3792 | __end_cc_report 04:28:17 INFO - PID 3792 | __startTimestamp1506166097309__endTimestamp 04:28:17 INFO - PID 3792 | 04:28:17 INFO - PID 3792 | ------- Summary: start ------- 04:28:17 INFO - PID 3792 | Number of tests: 6 04:28:17 INFO - PID 3792 | 04:28:17 INFO - PID 3792 | [#0] dojo.html Cycles:165 Average:6284.89 Median:6541.77 stddev:748.75 (11.4%) stddev-sans-first:725.09 04:28:17 INFO - PID 3792 | Values: 3793.7 3838.2 3838.2 3839.8 3852.6 6214.7 6248.0 6301.4 6319.4 6323.9 6310.0 6310.1 6319.2 6330.1 6344.8 5585.0 5637.7 5647.0 5702.9 5760.3 5650.8 5681.2 5744.3 5744.5 5774.6 5516.3 5599.6 5607.2 5668.4 5775.5 5542.2 5593.9 5653.7 5710.1 5710.9 5619.1 5659.6 5703.7 5723.7 5727.3 6241.4 6256.5 6277.4 6280.0 6356.7 6768.7 6788.7 6877.6 6883.5 6889.0 6945.8 6967.4 6982.2 6984.8 6990.5 6373.3 6394.4 6418.2 6465.2 6477.5 6970.2 7021.7 7074.5 7120.0 7123.3 6667.9 6804.9 6808.0 6814.8 6852.0 6824.4 6945.8 6957.0 6964.4 6966.8 6762.5 6817.6 6874.6 6899.6 6900.5 6712.5 6785.8 6787.8 6799.2 6873.0 5003.8 5025.8 5040.1 5044.3 5055.8 4513.1 4523.7 4565.4 4582.7 4595.4 6050.9 6123.1 6135.3 6194.6 6203.2 6771.4 6794.1 6796.3 6800.2 6801.5 6092.8 6122.1 6156.1 6218.8 6224.6 5502.6 5512.7 5535.8 5591.9 5593.6 6840.9 6862.0 6865.9 6980.9 6982.2 6775.8 6812.2 6816.0 6816.9 6836.6 6933.8 6935.4 6952.3 7036.7 7045.3 6800.8 6816.9 6876.1 6878.1 6887.7 6831.2 6835.3 6839.4 6932.1 6956.1 6667.9 6677.1 6694.6 6738.4 6739.3 6539.9 6546.6 6602.3 6654.7 6716.9 6518.0 6541.8 6542.4 6549.3 6571.9 6589.7 6614.0 6635.7 6712.1 6728.2 6423.2 6426.5 6520.5 6529.0 6537.7 04:28:17 INFO - PID 3792 | 04:28:17 INFO - PID 3792 | [#1] ext.html Cycles:180 Average:43111.87 Median:20596.47 stddev:63339.76 (307.5%) stddev-sans-first:63438.33 04:28:17 INFO - PID 3792 | Values: 950.4 972.8 984.9 996.3 1011.2 44700.6 45047.0 45400.8 45460.8 45564.3 41494.0 41856.4 42010.2 42019.6 42111.6 7830.8 7984.7 8005.0 8007.4 8013.3 7845.2 7952.1 7954.8 7956.9 7961.0 8524.7 8621.3 8662.8 8677.0 8680.3 8551.2 8613.0 8655.2 8659.0 8660.7 8298.1 8390.0 8405.7 8433.9 8504.3 41289.2 41943.8 41963.2 41989.4 42062.4 3753.3 3788.2 3805.9 3807.5 3808.6 33658.3 33689.3 33751.8 33807.8 33820.3 5377.4 5441.7 5490.5 5552.8 5555.7 217743.0 218203.0 218537.0 218615.0 220123.0 54090.7 54110.0 54127.8 54195.2 54261.0 55981.7 56153.7 56261.7 56265.0 56266.7 17315.1 17652.7 17672.9 17678.9 17727.6 14338.6 14357.3 14456.9 14462.6 14472.4 15468.8 15544.5 15565.1 15593.8 15649.1 6022.3 6179.1 6201.1 6227.7 6291.6 1121.0 1126.9 1131.7 1133.7 1137.8 26288.6 26494.0 26531.9 26613.5 26754.9 1235.4 1254.9 1256.8 1262.5 1276.0 13402.2 13977.6 13994.9 14030.0 14208.4 267892.0 269375.0 269450.0 269928.0 270682.0 59056.7 59989.4 62367.0 62477.7 62516.0 1523.1 1542.5 1544.3 1552.6 1558.2 31664.8 31831.7 31984.7 32249.5 32356.8 43610.6 43641.8 43710.8 43844.8 43896.0 239113.0 240001.0 242249.0 243083.0 244308.0 36160.6 36729.3 37108.0 37372.6 37439.6 48277.0 48300.0 48494.0 48782.0 48820.0 36188.0 36632.5 36677.0 36755.3 36857.6 35658.0 35874.5 36031.6 36220.0 36234.3 42911.8 42954.5 43011.8 43042.4 43267.8 34175.3 34415.5 34639.5 34695.5 35050.0 25910.7 25937.5 26198.2 26516.7 26966.6 04:28:17 INFO - PID 3792 | 04:28:17 INFO - PID 3792 | [#2] jquery.html Cycles:180 Average:39672.89 Median:12928.24 stddev:98182.11 (759.4%) stddev-sans-first:98413.78 04:28:17 INFO - PID 3792 | Values: 635.2 635.4 639.1 649.0 664.3 23519.5 23633.3 23879.6 24376.3 24459.9 25211.4 26300.1 26322.7 26349.7 26365.3 6872.5 6904.0 7025.4 7034.3 7034.6 5846.7 6679.4 6686.5 6691.7 6812.1 6641.9 6651.4 6652.6 6654.3 6658.8 6491.5 6613.8 6654.2 6660.8 6748.3 6268.0 6273.6 6281.7 6291.4 6309.5 29027.4 29357.1 29373.6 29487.0 29633.6 2336.9 2365.9 2383.6 2385.3 2386.9 19973.4 20586.4 20717.2 20731.3 20773.2 2499.5 2519.7 2555.8 2569.1 2592.8 139356.0 144605.0 156979.2 157918.0 161179.0 15833.6 15901.8 16389.0 16503.2 17145.6 25433.9 25501.1 25547.1 25633.7 25650.0 10228.6 10288.3 10433.2 10494.8 10658.3 9524.3 9532.4 9552.0 9577.9 9667.9 10126.0 10137.4 10168.8 10188.3 10311.6 2974.9 3017.1 3037.3 3054.0 3128.6 1049.2 1053.2 1066.8 1068.7 1069.5 21388.8 21756.6 21777.1 21779.1 21890.2 1111.4 1118.4 1119.9 1121.6 1129.8 11882.2 12024.4 12161.2 12171.3 12175.1 557746.0 574664.0 574837.0 591297.0 619949.0 132949.0 133476.0 135855.0 138915.0 139510.0 1120.5 1182.5 1212.3 1223.9 1272.9 22480.4 22879.5 23036.5 23056.7 23291.5 64620.7 64803.0 65729.0 66798.7 67581.0 81315.6 81589.0 82365.0 82416.6 83447.6 15542.5 15715.3 15787.9 15940.1 15980.8 24400.8 24486.4 24599.4 24825.8 24967.0 19853.6 20048.8 20149.9 20281.7 20532.0 17784.2 17808.7 17980.9 18040.3 18435.4 19755.3 20169.1 20304.7 20467.4 20521.4 15299.7 15534.8 15624.1 15677.8 15724.7 19616.4 19799.8 19965.9 19972.0 19976.6 04:28:17 INFO - PID 3792 | 04:28:17 INFO - PID 3792 | [#3] mootools.html Cycles:180 Average:10478.47 Median:2659.18 stddev:7769.81 (292.2%) stddev-sans-first:7761.77 04:28:17 INFO - PID 3792 | Values: 1415.4 1488.3 1490.4 1495.0 1513.0 16559.2 16981.9 17151.5 17152.0 17156.8 16641.1 16825.7 16875.7 16879.7 17003.2 2831.8 2870.7 2876.6 2884.9 2890.1 2760.4 2829.3 2833.2 2833.3 2837.7 2659.4 2724.5 2755.8 2756.7 2758.8 2612.0 2663.3 2665.0 2666.5 2666.9 2603.8 2649.8 2652.2 2653.2 2658.9 16200.8 16434.4 16516.7 16547.6 16548.9 7997.8 8284.7 8287.2 8308.3 8309.8 5152.8 5300.4 5373.6 5381.1 5637.5 2134.4 2139.2 2149.6 2209.4 2215.5 27605.6 28400.3 28542.0 29231.3 29265.9 7441.8 7496.1 7635.0 7776.8 7779.9 23475.2 23499.0 23514.9 23518.5 23521.8 8688.7 8716.0 8769.7 8875.4 8879.2 7423.3 7731.4 7829.3 7942.2 8021.1 6029.2 6303.8 6312.4 6346.5 6406.3 1847.2 1849.7 1876.5 1879.3 1915.2 953.8 961.5 977.5 984.0 1000.5 8523.4 8664.7 8747.3 8837.2 8881.5 773.8 774.7 782.0 794.1 812.7 5058.2 5088.2 5120.8 5211.6 5227.6 24279.6 24527.8 24792.6 24808.0 25277.4 25451.9 25532.2 25624.6 25659.9 25696.1 12422.9 12938.2 13010.2 13014.0 13115.9 9593.1 9615.4 9638.5 9677.3 9724.8 20328.9 20734.6 20883.6 21540.7 21943.2 21741.0 21824.0 22132.3 22276.7 22280.8 13359.0 13391.7 13394.7 13469.1 13574.6 15344.8 15407.9 15549.3 15599.6 15615.9 12165.7 12276.3 12343.6 12410.4 12467.4 12086.3 12187.2 12342.8 12408.5 12455.2 9938.2 10000.8 10017.4 10096.2 10106.5 8056.5 8093.2 8253.0 8256.2 8275.8 7959.0 8025.7 8028.8 8062.9 8143.5 04:28:17 INFO - PID 3792 | 04:28:17 INFO - PID 3792 | [#4] prototype.html Cycles:180 Average:5540.33 Median:6100.26 stddev:1529.01 (25.1%) stddev-sans-first:1486.77 04:28:17 INFO - PID 3792 | Values: 553.3 575.1 581.5 586.9 590.7 6115.1 6164.5 6187.6 6195.6 6215.0 6095.3 6096.8 6103.7 6120.8 6136.2 4857.5 5064.9 5066.4 5075.3 5083.3 4912.9 4929.6 4946.6 4953.2 4996.2 4867.6 4899.2 4917.9 4963.6 4965.8 4890.0 4896.2 4935.8 4944.7 4944.7 4518.1 4522.7 4533.1 4534.4 4546.4 5963.9 5990.3 6057.0 6071.2 6181.0 5737.5 5789.2 5793.8 5796.5 5829.7 6579.2 6593.2 6605.5 6609.3 6634.6 4832.2 4974.9 4983.6 4996.0 5005.3 6651.1 6709.2 6712.3 6747.6 6761.1 5128.6 5155.7 5162.7 5172.7 5182.3 5211.6 5221.6 5244.1 5244.4 5252.6 5733.7 5776.7 5820.4 5860.3 5861.6 6556.0 6585.5 6607.2 6647.7 6650.2 1802.2 1807.6 1822.4 1822.9 1842.8 1228.0 1281.4 1283.1 1295.6 1296.4 5926.2 5989.8 5999.0 6017.7 6038.5 6445.5 6654.5 6661.5 6685.6 6691.8 5832.8 5891.5 5925.3 5927.6 5998.3 3460.6 3483.7 3489.2 3509.8 3513.3 6707.9 6763.9 6771.8 6782.8 6822.7 6643.2 6744.7 6746.4 6855.8 6890.4 6637.4 6681.8 6726.0 6731.6 6753.3 6544.5 6556.8 6559.5 6574.5 6650.9 6528.2 6541.2 6648.5 6657.1 6727.6 6800.9 6838.4 6839.2 6859.5 6879.0 5165.4 5179.0 5189.8 5199.6 5211.3 6388.3 6648.3 6648.7 6650.3 6723.6 6276.2 6405.8 6460.7 6499.5 6533.4 6364.9 6388.7 6428.6 6431.6 6508.3 6313.4 6324.0 6326.9 6363.2 6434.0 6491.4 6512.6 6530.7 6574.5 6592.9 6259.5 6265.4 6340.0 6378.3 6395.6 04:28:17 INFO - PID 3792 | 04:28:17 INFO - PID 3792 | [#5] yui.html Cycles:180 Average:28645.17 Median:19731.90 stddev:42240.74 (214.1%) stddev-sans-first:42308.11 04:28:17 INFO - PID 3792 | Values: 967.7 985.8 1000.1 1012.7 1018.2 17900.3 18128.7 18139.6 18143.0 18149.7 17127.8 17135.2 18759.7 19256.7 19303.6 1038.3 1058.2 1060.7 1061.9 1067.4 1051.5 1055.1 1059.8 1060.3 1062.0 1071.1 1104.4 1104.5 1109.5 1117.6 1095.8 1096.4 1096.5 1096.8 1098.2 791.4 824.3 826.4 826.5 826.7 19406.7 19685.0 19726.8 19740.2 19752.1 22032.7 22649.5 23689.0 23713.6 23863.6 17210.7 17257.1 17356.5 17548.1 17698.8 3923.3 3967.3 3974.1 3976.6 3981.3 132040.0 160225.0 162125.2 162601.2 165153.2 18010.6 18070.5 18336.3 18773.2 18850.6 34298.7 34772.0 35022.5 35270.8 35289.5 9611.4 9697.6 9711.2 9751.6 9751.7 4542.7 4555.4 4629.0 4691.9 4724.3 5741.7 5766.7 5815.0 5834.7 5850.1 1223.9 1232.5 1247.7 1248.9 1259.8 758.1 770.1 770.2 784.0 789.0 13480.4 13724.5 13726.6 14715.6 14760.0 733.8 741.3 747.8 760.8 767.7 6265.5 6286.5 6344.7 6399.4 6518.6 152697.0 153263.0 153954.0 158334.0 161865.0 125198.0 125815.0 128389.0 130887.0 136199.0 109361.0 114791.0 115214.4 115809.0 117978.0 14118.4 14220.3 14424.8 14619.4 14749.1 53627.0 55165.0 55280.0 55399.7 56013.2 69790.0 69838.0 69897.7 70374.3 70704.3 19737.0 20128.6 20171.3 20372.9 20395.1 20976.5 21094.2 21318.6 21322.4 21488.5 20681.3 20744.5 20834.2 20868.5 20902.7 20035.5 20296.3 20308.4 20331.4 20525.9 19740.7 20000.0 20011.4 20135.9 20340.7 13559.4 13659.1 13753.2 14039.9 14047.9 15220.0 15248.8 15451.5 15477.8 15519.9 04:28:17 INFO - PID 3792 | -------- Summary: end -------- 04:28:17 INFO - PID 3792 | 04:28:17 INFO - TEST-INFO | 3792: exit 0 04:28:17 INFO - TEST-OK | dromaeo_css | took 1110541ms 04:28:17 INFO - TEST-START | kraken 04:28:17 INFO - Initialising browser for kraken test... 04:28:17 INFO - Application command: /builds/slave/test/build/application/firefox/firefox http://localhost:59207/getInfo.html -profile /tmp/tmpg9E8xN/profile 04:28:17 INFO - TEST-INFO | started process 4168 (/builds/slave/test/build/application/firefox/firefox http://localhost:59207/getInfo.html) 04:28:23 INFO - TEST-INFO | 4168: exit 0 04:28:23 INFO - Browser initialized. 04:28:23 INFO - Running cycle 1/1 for kraken test... 04:28:23 INFO - Using env: {'DISPLAY': ':0', 04:28:23 INFO - 'HOME': '/home/cltbld', 04:28:23 INFO - 'JSGC_DISABLE_POISONING': '1', 04:28:23 INFO - 'LANG': 'en_US.UTF-8', 04:28:23 INFO - 'LANGUAGE': 'en_US:en', 04:28:23 INFO - 'LD_LIBRARY_PATH': '/builds/slave/test/build/application/firefox', 04:28:23 INFO - 'LOGNAME': 'cltbld', 04:28:23 INFO - 'MAIL': '/var/mail/cltbld', 04:28:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:28:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:28:23 INFO - 'MOZ_CRASHREPORTER': '1', 04:28:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:23 INFO - 'MOZ_DISABLE_NONLOCAL_CONNECTIONS': '1', 04:28:23 INFO - 'MOZ_NO_REMOTE': '1', 04:28:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:28:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:28:23 INFO - 'NO_EM_RESTART': '1', 04:28:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:28:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:23 INFO - 'PWD': '/builds/slave/test', 04:28:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:28:23 INFO - 'RUST_BACKTRACE': 'full', 04:28:23 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 04:28:23 INFO - 'SHELL': '/bin/bash', 04:28:23 INFO - 'SHLVL': '1', 04:28:23 INFO - 'TERM': 'linux', 04:28:23 INFO - 'TMOUT': '86400', 04:28:23 INFO - 'USER': 'cltbld', 04:28:23 INFO - 'XDG_SESSION_COOKIE': '9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642', 04:28:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:23 INFO - '_': '/tools/buildbot/bin/python'} 04:28:23 INFO - TEST-INFO | started process 4410 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpg9E8xN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpnoisy -tpcycles 1 -tppagecycles 1) 04:28:24 INFO - PID 4410 | 04:28:24 INFO - PID 4410 | (/builds/slave/test/build/application/firefox/firefox:4460): Pango-WARNING **: error opening config file '/home/cltbld/.pangorc': Permission denied 04:28:24 INFO - PID 4410 | 04:28:34 INFO - PID 4410 | RSS: Main: 144592896 04:28:34 INFO - PID 4410 | 04:28:36 INFO - PID 4410 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59207/tests/kraken/driver.html?testName=audio-beat-detection) 04:28:37 INFO - PID 4410 | RSS: Main: 150794240 04:28:37 INFO - PID 4410 | 04:28:40 INFO - PID 4410 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59207/tests/kraken/driver.html?testName=audio-dft) 04:28:40 INFO - PID 4410 | RSS: Main: 151408640 04:28:40 INFO - PID 4410 | 04:28:43 INFO - PID 4410 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59207/tests/kraken/driver.html?testName=audio-fft) 04:28:44 INFO - PID 4410 | RSS: Main: 147763200 04:28:44 INFO - PID 4410 | 04:28:46 INFO - PID 4410 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59207/tests/kraken/driver.html?testName=audio-oscillator) 04:28:47 INFO - PID 4410 | RSS: Main: 148897792 04:28:47 INFO - PID 4410 | 04:28:49 INFO - PID 4410 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59207/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:28:49 INFO - PID 4410 | RSS: Main: 148443136 04:28:49 INFO - PID 4410 | 04:28:52 INFO - PID 4410 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59207/tests/kraken/driver.html?testName=imaging-darkroom) 04:28:53 INFO - PID 4410 | RSS: Main: 148803584 04:28:53 INFO - PID 4410 | 04:28:57 INFO - PID 4410 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59207/tests/kraken/driver.html?testName=imaging-desaturate) 04:28:57 INFO - PID 4410 | RSS: Main: 150085632 04:28:57 INFO - PID 4410 | 04:29:00 INFO - PID 4410 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59207/tests/kraken/driver.html?testName=json-parse-financial) 04:29:01 INFO - PID 4410 | RSS: Main: 151449600 04:29:01 INFO - PID 4410 | 04:29:02 INFO - PID 4410 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59207/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:29:03 INFO - PID 4410 | RSS: Main: 151232512 04:29:03 INFO - PID 4410 | 04:29:04 INFO - PID 4410 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59207/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:29:05 INFO - PID 4410 | RSS: Main: 151339008 04:29:05 INFO - PID 4410 | 04:29:07 INFO - PID 4410 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59207/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:29:08 INFO - PID 4410 | RSS: Main: 148086784 04:29:08 INFO - PID 4410 | 04:29:10 INFO - PID 4410 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59207/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:29:11 INFO - PID 4410 | RSS: Main: 148344832 04:29:11 INFO - PID 4410 | 04:29:13 INFO - PID 4410 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59207/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:29:14 INFO - PID 4410 | RSS: Main: 149032960 04:29:14 INFO - PID 4410 | 04:29:16 INFO - PID 4410 | Cycle 1(1): loaded http://localhost:59207/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59207/tests/kraken/driver.html?testName=ai-astar) 04:29:16 INFO - PID 4410 | RSS: Main: 150937600 04:29:16 INFO - PID 4410 | 04:29:16 INFO - PID 4410 | __start_tp_report 04:29:16 INFO - PID 4410 | _x_x_mozilla_page_load 04:29:16 INFO - PID 4410 | _x_x_mozilla_page_load_details 04:29:16 INFO - PID 4410 | |i|pagename|runs| 04:29:16 INFO - PID 4410 | |0;ai-astar;106;104;105;105;107;110;105;105;107;106 04:29:16 INFO - PID 4410 | |1;audio-beat-detection;116;112;112;112;118;111;114;115;110;113 04:29:16 INFO - PID 4410 | |2;audio-dft;163;163;163;161;162;161;162;161;165;169 04:29:16 INFO - PID 4410 | |3;audio-fft;75;72;76;73;73;75;76;74;73;71 04:29:16 INFO - PID 4410 | |4;audio-oscillator;78;80;78;78;76;77;77;78;78;78 04:29:16 INFO - PID 4410 | |5;imaging-gaussian-blur;110;114;113;113;115;110;114;114;115;115 04:29:16 INFO - PID 4410 | |6;imaging-darkroom;149;150;160;150;150;150;150;151;150;151 04:29:16 INFO - PID 4410 | |7;imaging-desaturate;99;94;94;94;94;96;94;94;94;94 04:29:16 INFO - PID 4410 | |8;json-parse-financial;45;45;46;47;46;47;45;46;46;45 04:29:16 INFO - PID 4410 | |9;json-stringify-tinderbox;37;37;37;39;37;37;37;37;37;37 04:29:16 INFO - PID 4410 | |10;stanford-crypto-aes;74;70;70;72;72;73;69;72;72;70 04:29:16 INFO - PID 4410 | |11;stanford-crypto-ccm;102;104;110;113;103;105;107;107;108;103 04:29:16 INFO - PID 4410 | |12;stanford-crypto-pbkdf2;153;159;149;151;158;157;150;152;149;154 04:29:16 INFO - PID 4410 | |13;stanford-crypto-sha256-iterative;65;62;58;60;60;62;69;61;61;60 04:29:16 INFO - PID 4410 | __end_tp_report 04:29:16 INFO - PID 4410 | __start_cc_report 04:29:16 INFO - PID 4410 | _x_x_mozilla_cycle_collect,720 04:29:16 INFO - PID 4410 | __end_cc_report 04:29:16 INFO - PID 4410 | __startTimestamp1506166156416__endTimestamp 04:29:16 INFO - PID 4410 | 04:29:16 INFO - PID 4410 | ------- Summary: start ------- 04:29:16 INFO - PID 4410 | Number of tests: 14 04:29:16 INFO - PID 4410 | 04:29:16 INFO - PID 4410 | [#0] ai-astar Cycles:10 Average:106.00 Median:106.00 stddev:1.70 (1.6%) stddev-sans-first:1.80 04:29:16 INFO - PID 4410 | Values: 106.0 104.0 105.0 105.0 107.0 110.0 105.0 105.0 107.0 106.0 04:29:16 INFO - PID 4410 | 04:29:16 INFO - PID 4410 | [#1] audio-beat-detection Cycles:10 Average:113.30 Median:113.50 stddev:2.45 (2.2%) stddev-sans-first:2.40 04:29:16 INFO - PID 4410 | Values: 116.0 112.0 112.0 112.0 118.0 111.0 114.0 115.0 110.0 113.0 04:29:16 INFO - PID 4410 | 04:29:16 INFO - PID 4410 | [#2] audio-dft Cycles:10 Average:163.00 Median:163.00 stddev:2.45 (1.5%) stddev-sans-first:2.60 04:29:16 INFO - PID 4410 | Values: 163.0 163.0 163.0 161.0 162.0 161.0 162.0 161.0 165.0 169.0 04:29:16 INFO - PID 4410 | 04:29:16 INFO - PID 4410 | [#3] audio-fft Cycles:10 Average:73.80 Median:74.50 stddev:1.69 (2.3%) stddev-sans-first:1.73 04:29:16 INFO - PID 4410 | Values: 75.0 72.0 76.0 73.0 73.0 75.0 76.0 74.0 73.0 71.0 04:29:16 INFO - PID 4410 | 04:29:16 INFO - PID 4410 | [#4] audio-oscillator Cycles:10 Average:77.80 Median:78.00 stddev:1.03 (1.3%) stddev-sans-first:1.09 04:29:16 INFO - PID 4410 | Values: 78.0 80.0 78.0 78.0 76.0 77.0 77.0 78.0 78.0 78.0 04:29:16 INFO - PID 4410 | 04:29:16 INFO - PID 4410 | [#5] imaging-gaussian-blur Cycles:10 Average:113.30 Median:114.00 stddev:1.89 (1.7%) stddev-sans-first:1.58 04:29:16 INFO - PID 4410 | Values: 110.0 114.0 113.0 113.0 115.0 110.0 114.0 114.0 115.0 115.0 04:29:16 INFO - PID 4410 | 04:29:16 INFO - PID 4410 | [#6] imaging-darkroom Cycles:10 Average:151.10 Median:150.00 stddev:3.18 (2.1%) stddev-sans-first:3.28 04:29:16 INFO - PID 4410 | Values: 149.0 150.0 160.0 150.0 150.0 150.0 150.0 151.0 150.0 151.0 04:29:16 INFO - PID 4410 | 04:29:16 INFO - PID 4410 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:94.00 stddev:1.64 (1.7%) stddev-sans-first:0.67 04:29:16 INFO - PID 4410 | Values: 99.0 94.0 94.0 94.0 94.0 96.0 94.0 94.0 94.0 94.0 04:29:16 INFO - PID 4410 | 04:29:16 INFO - PID 4410 | [#8] json-parse-financial Cycles:10 Average:45.80 Median:46.00 stddev:0.79 (1.7%) stddev-sans-first:0.78 04:29:16 INFO - PID 4410 | Values: 45.0 45.0 46.0 47.0 46.0 47.0 45.0 46.0 46.0 45.0 04:29:16 INFO - PID 4410 | 04:29:16 INFO - PID 4410 | [#9] json-stringify-tinderbox Cycles:10 Average:37.20 Median:37.00 stddev:0.63 (1.7%) stddev-sans-first:0.67 04:29:16 INFO - PID 4410 | Values: 37.0 37.0 37.0 39.0 37.0 37.0 37.0 37.0 37.0 37.0 04:29:16 INFO - PID 4410 | 04:29:16 INFO - PID 4410 | [#10] stanford-crypto-aes Cycles:10 Average:71.40 Median:72.00 stddev:1.58 (2.2%) stddev-sans-first:1.36 04:29:16 INFO - PID 4410 | Values: 74.0 70.0 70.0 72.0 72.0 73.0 69.0 72.0 72.0 70.0 04:29:16 INFO - PID 4410 | 04:29:16 INFO - PID 4410 | [#11] stanford-crypto-ccm Cycles:10 Average:106.20 Median:107.00 stddev:3.49 (3.3%) stddev-sans-first:3.35 04:29:16 INFO - PID 4410 | Values: 102.0 104.0 110.0 113.0 103.0 105.0 107.0 107.0 108.0 103.0 04:29:16 INFO - PID 4410 | 04:29:16 INFO - PID 4410 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.20 Median:153.50 stddev:3.71 (2.4%) stddev-sans-first:3.93 04:29:16 INFO - PID 4410 | Values: 153.0 159.0 149.0 151.0 158.0 157.0 150.0 152.0 149.0 154.0 04:29:16 INFO - PID 4410 | 04:29:16 INFO - PID 4410 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:61.50 stddev:3.12 (5.1%) stddev-sans-first:3.09 04:29:16 INFO - PID 4410 | Values: 65.0 62.0 58.0 60.0 60.0 62.0 69.0 61.0 61.0 60.0 04:29:16 INFO - PID 4410 | -------- Summary: end -------- 04:29:16 INFO - PID 4410 | 04:29:16 INFO - PID 4410 | *** UTM:SVC TimerManager:registerTimer called after profile-before-change notification. Ignoring timer registration for id: telemetry_modules_ping 04:29:16 INFO - TEST-INFO | 4410: exit 0 04:29:16 INFO - TEST-OK | kraken | took 59022ms 04:29:16 INFO - SUITE-END | took 1169s 04:29:17 INFO - Completed test suite (00:19:29) 04:29:17 INFO - javascript benchmark 04:29:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 9153.435618251358, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3793.696504279658, 3838.1747924196293, 3838.1939792643543, 3839.7504162229443, 3852.5954774748634, 6214.7203375848085, 6248.0, 6301.369863013696, 6319.39965703258, 6323.873522529547, 6310.021946598273, 6310.085037669535, 6319.17850679411, 6330.050492426139, 6344.841378965517, 5584.972075139619, 5637.661740295585, 5647.0, 5702.857428564277, 5760.308762948452, 5650.841577476628, 5681.176229446728, 5744.281964754406, 5744.54043676507, 5774.566907481934, 5516.3380394352935, 5599.636023658449, 5607.186957891083, 5668.43315668434, 5775.509081728037, 5542.224088627595, 5593.860153496195, 5653.660780353192, 5710.143478478261, 5710.914336284959, 5619.072852979253, 5659.5755318351285, 5703.686297253649, 5723.656580605136, 5727.255456790632, 6241.407066328691, 6256.499480041632, 6277.403646653561, 6280.0, 6356.682165891687, 6768.661566921634, 6788.6945087470685, 6877.552959057645, 6883.518153729241, 6889.0355350251, 6945.826354341182, 6967.407770339477, 6982.2319544850025, 6984.755533556301, 6990.510664253505, 6373.298937116913, 6394.424501794861, 6418.1656384669695, 6465.224173099211, 6477.5465717400275, 6970.163580370388, 7021.684024218923, 7074.540154889915, 7120.038794762744, 7123.2520585338825, 6667.86664266712, 6804.931950680529, 6808.012838138442, 6814.829629259309, 6852.0, 6824.385805277549, 6945.791626251221, 6957.0, 6964.4428445724225, 6966.79099627012, 6762.492813039042, 6817.5909445433435, 6874.621895795779, 6899.551529150589, 6900.482463815234, 6712.496562757813, 6785.830354241183, 6787.830304242433, 6799.150106236721, 6872.965635171792, 5003.824866129668, 5025.7989680412375, 5040.067587496325, 5044.268581055726, 5055.797768089309, 4513.052259025576, 4523.660725445605, 4565.406497155415, 4582.679212455163, 4595.448546174481, 6050.909236361458, 6123.08153776937, 6135.263768347827, 6194.5973511721595, 6203.2245969253845, 6771.356721111487, 6794.0827988221945, 6796.320367963263, 6800.217974932946, 6801.4898882584, 6092.817215483543, 6122.112293717475, 6156.138140660311, 6218.782342617987, 6224.62652240867, 5502.642328248651, 5512.6968016758665, 5535.833924982257, 5591.916121258185, 5593.608447408724, 6840.897386539206, 6861.965690171517, 6865.897011544831, 6980.895286570705, 6982.2319544850025, 6775.796726098323, 6812.21659509143, 6816.0, 6816.863662726817, 6836.589804611739, 6933.826654333682, 6935.4104901081955, 6952.270011648704, 7036.663034023724, 7045.26024767392, 6800.761973330909, 6816.931830681629, 6876.140482439695, 6878.105846239957, 6887.724491020504, 6831.214410342675, 6835.316468353125, 6839.350261725128, 6932.064171337007, 6956.130483689539, 6667.899981500184, 6677.131972843499, 6694.631795251308, 6738.35985581369, 6739.326067393222, 6539.934600653933, 6546.607203567801, 6602.306757790363, 6654.700538475683, 6716.932830671826, 6518.0, 6541.771038013647, 6542.44389226902, 6549.312322206099, 6571.901421478778, 6589.703463344065, 6613.966930165318, 6635.701393437211, 6712.127423434922, 6728.158980127484, 6423.197100362455, 6426.485881129592, 6520.510961677798, 6529.0, 6537.738490460519], "value": 6233.613274270147, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [950.4487397309562, 972.7762614598638, 984.91628211602, 996.2826764729394, 1011.1758916483066, 44700.55299447005, 45047.0, 45400.772996135056, 45460.772696136555, 45564.31653525196, 41494.0, 41856.37215441771, 42010.159796804124, 42019.57980420195, 42111.57888421115, 7830.765077047698, 7984.680612775482, 8005.0, 8007.439479236456, 8013.318867896207, 7845.176256493071, 7952.085510166318, 7954.761357159295, 7956.880646790303, 7960.960195199016, 8524.701635442761, 8621.310295176403, 8662.826743465126, 8676.956615216915, 8680.34897382699, 8551.230389264965, 8613.0, 8655.221030107321, 8658.956705216433, 8660.653573857038, 8298.128696486903, 8389.95805020977, 8405.663773449054, 8433.873491897626, 8504.277136443348, 41289.1742165155, 41943.7902810484, 41963.1607367854, 41989.37015944763, 42062.36906446406, 3753.305638456894, 3788.22341420011, 3805.904852378685, 3807.5240594925635, 3808.5810560838286, 33658.326833463194, 33689.32621347584, 33751.83124084389, 33807.830960845284, 33820.32359352799, 5377.354717433894, 5441.727913604304, 5490.478404551562, 5552.77788888444, 5555.722213889329, 217743.0, 218203.0, 218536.9999999984, 218615.0, 220123.0, 54090.72954635201, 54110.0, 54127.752334859244, 54195.18707219395, 54261.0, 55981.720091399286, 56153.7192314044, 56261.718691406284, 56265.0, 56266.71866640641, 17315.134243288037, 17652.735208971877, 17672.911635441997, 17678.911605441892, 17727.55681107957, 14338.641533961734, 14357.282135893163, 14456.927715361357, 14462.638434039023, 14472.421103155755, 15468.767968480483, 15544.455944041903, 15565.14391708467, 15593.766093508606, 15649.139297338528, 6022.277326720821, 6179.134921111048, 6201.10084037812, 6227.719752611053, 6291.622502649764, 1120.9887901121047, 1126.9436528173558, 1131.660501849442, 1133.7279053027214, 1137.7781332640056, 26288.605670914952, 26494.0, 26531.86734066317, 26613.467730645665, 26754.866225669135, 1235.3637876493613, 1254.861965183829, 1256.7863463211277, 1262.4760724299497, 1275.9808602870964, 13402.190704254801, 13977.58067257984, 13994.860051399559, 14030.0, 14208.360623771956, 267892.0, 269375.0, 269450.0, 269928.0, 270682.0, 59056.704716476146, 59989.40010599925, 62367.0, 62477.687611562564, 62516.0, 1523.1089812459836, 1542.5141080559813, 1544.2587557972236, 1552.5808031831343, 1558.1975282729404, 31664.841675791475, 31831.681683182873, 31984.68015319817, 32249.51625725569, 32356.83821580877, 43610.563894360654, 43641.78179109084, 43710.781446092566, 43844.78077609592, 43896.0, 239113.0, 240001.0, 242249.0, 243083.0, 244308.0, 36160.63839361573, 36729.26541469102, 37108.0, 37372.62627373692, 37439.62560374361, 48277.0, 48300.0, 48494.0, 48782.0, 48820.0, 36188.0, 36632.45051324285, 36677.0, 36755.264894701424, 36857.63142368542, 35658.0, 35874.4618830723, 36031.63968360388, 36220.0, 36234.27531449409, 42911.78544107259, 42954.49659261911, 43011.78494107509, 43042.35436468518, 43267.78366108149, 34175.31649366949, 34415.48376774401, 34639.480407794406, 34695.479567805996, 35050.0, 25910.74089259083, 25937.48125037451, 26198.214053578424, 26516.734832651426, 26966.595501067106], "value": 19166.270172027063, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [635.1806170040642, 635.3702752439991, 639.0861068671799, 648.9740410383582, 664.3223911610157, 23519.5296094078, 23633.291001269947, 23879.64180537289, 24376.268711938646, 24459.877700611494, 25211.369715757115, 26300.0794972176, 26322.736772632266, 26349.73650263497, 26365.3408664783, 6872.518923675345, 6904.0, 7025.402840758542, 7034.3317384848615, 7034.613096279702, 5846.707664616773, 6679.432248258904, 6686.498512611549, 6691.665416729168, 6812.080369150151, 6641.867162656744, 6651.434628056621, 6652.600843949354, 6654.26803051664, 6658.800235992928, 6491.545591808575, 6613.834654133661, 6654.168228971354, 6660.800175994704, 6748.291429399891, 6268.028455589345, 6273.56085074045, 6281.654509002003, 6291.43377096059, 6309.4636955858805, 29027.41945161085, 29357.119286421443, 29373.559396609067, 29487.0, 29633.555496667566, 2336.941576460585, 2365.9053637854463, 2383.582872997236, 2385.33210701004, 2386.892589833444, 19973.400797976083, 20586.382408527617, 20717.171313147457, 20731.27440539574, 20773.16907323705, 2499.487605040962, 2519.7354277800932, 2555.782758465533, 2569.11365578875, 2592.8185027048116, 139356.0, 144605.0, 156979.2151039249, 157918.0, 161179.0, 15833.604159896095, 15901.840981590036, 16389.0, 16503.17484125789, 17145.571360716083, 25433.87283063573, 25501.107461238767, 25547.105851295117, 25633.74366256351, 25650.0, 10228.59085636581, 10288.331258468324, 10433.198259640185, 10494.790104197873, 10658.253922225525, 9524.285678574135, 9532.428054316762, 9551.952240238754, 9577.856332155023, 9667.9033209667, 10126.0, 10137.442440665687, 10168.847467287997, 10188.337758045704, 10311.587536498606, 2974.940501189964, 3017.1250337401907, 3037.2862377341307, 3054.0, 3128.6089238845143, 1049.1868801678606, 1053.2153545608417, 1066.8186408310605, 1068.7488440216543, 1069.5187165775433, 21388.78611213899, 21756.56486870254, 21777.128914843546, 21779.12883484643, 21890.23384181546, 1111.372074777748, 1118.4184224203368, 1119.9384033878052, 1121.5906194239044, 1129.8474705914762, 11882.16824822254, 12024.398780060961, 12161.209521381083, 12171.269723816422, 12175.14773965814, 557746.0, 574664.0, 574837.0, 591297.0, 619949.0, 132949.0, 133476.0, 135855.0, 138915.0, 139510.0, 1120.467777805536, 1182.5447202826974, 1212.3029258176584, 1223.889849913512, 1272.917260378054, 22480.379905023743, 22879.542409152058, 23036.539269214856, 23056.654150188097, 23291.53416931686, 64620.676896615216, 64803.0, 65729.0, 66798.66600666965, 67581.0, 81315.59342203251, 81589.0, 82365.0, 82416.58791706002, 83447.5827620858, 15542.5337239883, 15715.292811823267, 15787.921060394625, 15940.123293218985, 15980.840191597936, 24400.755992440558, 24486.387840304134, 24599.38501537476, 24825.751742483066, 24967.0, 19853.60292794165, 20048.799512004694, 20149.899250503655, 20281.695774563097, 20532.0, 17784.199711012778, 17808.732869007235, 17980.91009544944, 18040.278388864823, 18435.446936591386, 19755.308564200757, 20169.092390842154, 20304.695429568863, 20467.385978420672, 20521.38435846927, 15299.694006120038, 15534.84465155334, 15624.14067226268, 15677.843221567638, 15724.68550629004, 19616.411507654793, 19799.802001980373, 19965.900170499055, 19972.0, 19976.60046799085], "value": 12983.225039230503, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1415.3984556563462, 1488.2558720639681, 1490.3516970117992, 1494.9626259343515, 1512.9938590837087, 16559.172041397927, 16981.91509042453, 17151.48545543634, 17152.0, 17156.82843171568, 16641.084740339276, 16825.663486730256, 16875.746863797052, 16879.746803797952, 17003.23485443155, 2831.83009019459, 2870.698576649453, 2876.6116574262414, 2884.927876803076, 2890.1474065150833, 2760.4203117345282, 2829.2643912582776, 2833.150054983507, 2833.320003199238, 2837.744602985731, 2659.4415172813733, 2724.523208438515, 2755.8484283364405, 2756.6691996960294, 2758.8344699318004, 2612.020492315382, 2663.2809141531775, 2665.027265048263, 2666.4933662604044, 2666.879990400437, 2603.7526434988836, 2649.774769144625, 2652.2175958092407, 2653.230563136688, 2658.9202323930313, 16200.75698864518, 16434.424795132112, 16516.669666606722, 16547.586310342216, 16548.917255413646, 7997.840043199161, 8284.668613255462, 8287.171282871725, 8308.335333173332, 8309.750707478785, 5152.793888244466, 5300.363956325227, 5373.59698022646, 5381.112116500773, 5637.520810731093, 2134.423705599495, 2139.1657253671083, 2149.580831737812, 2209.436593668604, 2215.490437199437, 27605.585916211272, 28400.28999275014, 28542.0, 29231.2692182695, 29265.85367073172, 7441.776746697499, 7496.100467943882, 7634.961825190839, 7776.805579860549, 7779.883301750365, 23475.178368757013, 23499.0, 23514.882425588105, 23518.529629407316, 23521.7647823523, 8688.695895643623, 8715.956420217859, 8769.736907892773, 8875.378723489293, 8879.24526415249, 7423.329750806025, 7731.381489480828, 7829.3345065670055, 7942.166072562413, 8021.117677055519, 6029.186059881948, 6303.842403939938, 6312.368763123651, 6346.524010699216, 6406.2953075161695, 1847.214933653192, 1849.722541618768, 1876.4839669090836, 1879.2858713688984, 1915.1669023974616, 953.7615596100975, 961.485605201214, 977.4772535176169, 984.0258144436956, 1000.46475135802, 8523.445976011539, 8664.696735614349, 8747.256483198998, 8837.160469755363, 8881.511516866634, 773.8413625206819, 774.7327172125607, 781.9530828150329, 794.1066300412036, 812.7439856445201, 5058.161875152792, 5088.160453525127, 5120.846374608767, 5211.609129315317, 5227.555657769132, 24279.63580546293, 24527.754722452904, 24792.628110578356, 24808.0, 25277.368065798135, 25451.872740636547, 25532.23403297904, 25624.615630765555, 25659.87170064138, 25696.100636477633, 12422.875771242172, 12938.159019663692, 13010.21938683682, 13014.023948203923, 13115.934420327838, 9593.090019471932, 9615.375000624937, 9638.518074096128, 9677.322587418814, 9724.75688107803, 20328.898355508718, 20734.585308294052, 20883.582328353652, 21540.676889846978, 21943.231986880382, 21741.0, 21824.0, 22132.33602991913, 22276.665850011937, 22280.77719222787, 13359.0, 13391.732165356443, 13394.732105358033, 13469.12450690702, 13574.59276221715, 15344.769828452805, 15407.922960385127, 15549.300281487134, 15599.610009749393, 15615.921920390325, 12165.69585760363, 12276.263424194576, 12343.629691109281, 12410.379481025731, 12467.438965246767, 12086.274823510266, 12187.207831490925, 12342.81485777732, 12408.503659853504, 12455.190412623513, 9938.204943604349, 10000.849987250343, 10017.39895606266, 10096.19230461576, 10106.545205465625, 8056.516609003478, 8093.231143041402, 8252.958735206286, 8256.215659512336, 8275.83448331042, 7959.0, 8025.678972841021, 8028.799280018046, 8062.919370806217, 8143.51138931666], "value": 7288.311876655165, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [553.300075404613, 575.1258087706686, 581.4708615160205, 586.870888404551, 590.6515156057925, 6115.055789016082, 6164.506839452844, 6187.597806142603, 6195.597286176396, 6215.04785227017, 6095.329513753494, 6096.786612468564, 6103.7253323600435, 6120.755169793203, 6136.202293701824, 4857.498388330013, 5064.873378165538, 5066.36670416198, 5075.340205773254, 5083.339165908426, 4912.901741965158, 4929.605631549467, 4946.629002824785, 4953.23224900141, 4996.1756310208775, 4867.610591152699, 4899.216125419934, 4917.926231106536, 4963.578095861856, 4965.826196083138, 4890.0, 4896.24108263218, 4935.840077581765, 4944.678595891256, 4944.678595891256, 4518.1415531049, 4522.728636281834, 4533.116042371739, 4534.410526631516, 4546.431696037996, 5963.940360596382, 5990.341062483123, 6056.96971515144, 6071.241094863142, 6181.0, 5737.483626473638, 5789.218455508494, 5793.826185214451, 5796.507501774236, 5829.72640670139, 6579.24338701046, 6593.175853018372, 6605.504587155982, 6609.306022867626, 6634.573566683138, 4832.178529649933, 4974.87562810929, 4983.6013118950395, 4995.975020124876, 5005.299258103868, 6651.135352404156, 6709.161354830646, 6712.261651218362, 6747.561408508432, 6761.121054262963, 5128.615353848476, 5155.664881782672, 5162.664426812245, 5172.741362931838, 5182.32629758129, 5211.557017653466, 5221.55616772571, 5244.108501554745, 5244.423113457517, 5252.606054545924, 5733.709915269031, 5776.653400795965, 5820.3888591698105, 5860.326062502867, 5861.6189947653265, 6555.967220163869, 6585.506087043491, 6607.240167380813, 6647.734090636417, 6650.168728908887, 1802.2430579156785, 1807.620399716048, 1822.4259358302093, 1822.8632852535848, 1842.7880793708628, 1227.969300767488, 1281.436168086039, 1283.1210620724833, 1295.6372215779409, 1296.4101333893057, 5926.229590153339, 5989.790357337472, 5999.0, 6017.729202185825, 6038.456538911519, 6445.484361251089, 6654.467642588678, 6661.467082633378, 6685.6322902240845, 6691.76578819739, 5832.795852145155, 5891.528677705774, 5925.34821169671, 5927.628948420666, 5998.340182579913, 3460.584729832436, 3483.6864682178725, 3489.180042689966, 3509.771864828778, 3513.2797776455764, 6707.932920670731, 6763.932360676428, 6771.796846094625, 6782.796516104525, 6822.727090916407, 6643.202815662152, 6744.696488657924, 6746.4265537429865, 6855.760048398282, 6890.379865812101, 6637.402633762985, 6681.759346716742, 6725.966370168216, 6731.596104233761, 6753.324667533306, 6544.476441884733, 6556.770513032021, 6559.523452570145, 6574.474042076718, 6650.933490665031, 6528.21661400616, 6541.215054193527, 6648.468122550281, 6657.101291325609, 6727.562708423937, 6800.93199068003, 6838.350356716104, 6839.179298484214, 6859.485538584527, 6879.0, 5165.380154381498, 5178.97410512945, 5189.844304670866, 5199.63602547818, 5211.348581427322, 6388.297287298301, 6648.301928297459, 6648.700808463727, 6650.334966503311, 6723.562968407038, 6276.152719383008, 6405.8398540035005, 6460.741570337322, 6499.512536559682, 6533.379328963741, 6364.936350636434, 6388.744450221939, 6428.55000149985, 6431.646259455775, 6508.349165083643, 6313.3686631336495, 6324.027706977713, 6326.936730632635, 6363.172787537591, 6434.034894765636, 6491.350864913471, 6512.6092434454085, 6530.67346632657, 6574.4740420765265, 6592.934070659232, 6259.467945224706, 6265.404786545271, 6339.985602303609, 6378.266499352634, 6395.584287021329], "value": 5106.479874627244, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [967.7000129959713, 985.8373368394215, 1000.0549480740698, 1012.6810054832722, 1018.2414101494388, 17900.283988640454, 18128.72806907896, 18139.637207255848, 18143.0, 18149.727754083666, 17127.82872171278, 17135.228914698837, 18759.718604220947, 19256.711149332772, 19303.61392772147, 1038.2576457832647, 1058.2116323339123, 1060.7295139739356, 1061.8566493523392, 1067.4022547373456, 1051.5478344311944, 1055.0978913029326, 1059.8251288537383, 1060.3266925502285, 1061.9681409557725, 1071.1056268016216, 1104.4091411095067, 1104.5416152296827, 1109.4619109731773, 1117.6032508459502, 1095.780843831233, 1096.413418820931, 1096.4572536594358, 1096.7916095941785, 1098.1708809848592, 791.4380789639363, 824.2828738997051, 826.3595713322223, 826.5495305058757, 826.6569373709904, 19406.70889936652, 19685.01574921248, 19726.802731972784, 19740.210391584318, 19752.111154997914, 22032.669509957363, 22649.54700905989, 23689.0, 23713.644295335584, 23863.64204536933, 17210.655786884316, 17257.050862202574, 17356.47930562085, 17548.122593870256, 17698.823011769848, 3923.2545816294814, 3967.2858885400615, 3974.1455587048636, 3976.642102210786, 3981.3430783920617, 132040.0, 160225.0, 162125.18937405356, 162601.18699406544, 165153.17423412926, 18010.63978720418, 18070.5482362942, 18336.266549338143, 18773.24907003732, 18850.622987540173, 34298.65701342955, 34772.0, 35022.47466288008, 35270.8236458816, 35289.47065793964, 9611.375260608038, 9697.563609637584, 9711.174550163316, 9751.609935602639, 9751.658691945748, 4542.727436353829, 4555.40779698637, 4629.027904140168, 4691.859244222679, 4724.338592597039, 5741.655500669973, 5766.654000759884, 5815.04051831443, 5834.708264586754, 5850.12248162779, 1223.889849913512, 1232.4885172653248, 1247.7067889046066, 1248.8751124887438, 1259.7606454773747, 758.0940775773008, 770.1451388958252, 770.1951460723558, 783.9843203135905, 788.9723859664884, 13480.39338229782, 13724.519641812489, 13726.636973941448, 14715.632109197142, 14760.0, 733.8312188196745, 741.2846603028104, 747.7980945144781, 760.7983884270673, 767.7274567528559, 6265.530085243625, 6286.49708023357, 6344.746210151542, 6399.392057754506, 6518.57629254097, 152697.0, 153263.0, 153954.0, 158334.0, 161865.0, 125198.0, 125815.0, 128389.0, 130887.0, 136199.0, 109361.0, 114791.0, 115214.42392788151, 115809.0, 117978.0, 14118.43526258938, 14220.288985550887, 14424.783628245375, 14619.415223390944, 14749.115053096848, 53627.0, 55165.0, 55280.0, 55399.72300138474, 56013.15980260218, 69790.0, 69838.0, 69897.65051174711, 70374.29625703677, 70704.29295706977, 19737.0, 20128.59742805165, 20171.294004709765, 20372.89813550923, 20395.08222129978, 20976.47558811042, 21094.156233750477, 21318.573628527654, 21322.36032919015, 21488.462788430414, 20681.27615533449, 20744.48138796542, 20834.1666333345, 20868.47828804292, 20902.686459703422, 20035.49911252172, 20296.289629862884, 20308.390748277576, 20331.390058298275, 20525.89737051365, 19740.703889441957, 20000.0, 20011.399658010283, 20135.899320503304, 20340.69488957637, 13559.389827457591, 13659.112157709716, 13753.243571603658, 14039.859601403856, 14047.918789840933, 15220.0, 15248.847511524742, 15451.459198927983, 15477.84522154764, 15519.922400387926], "value": 10187.504314666563, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1368.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 104.0, 105.0, 105.0, 107.0, 110.0, 105.0, 105.0, 107.0, 106.0], "value": 106.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 112.0, 112.0, 112.0, 118.0, 111.0, 114.0, 115.0, 110.0, 113.0], "value": 113.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 163.0, 161.0, 162.0, 161.0, 162.0, 161.0, 165.0, 169.0], "value": 163.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 72.0, 76.0, 73.0, 73.0, 75.0, 76.0, 74.0, 73.0, 71.0], "value": 73.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 80.0, 78.0, 78.0, 76.0, 77.0, 77.0, 78.0, 78.0, 78.0], "value": 77.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 114.0, 113.0, 113.0, 115.0, 110.0, 114.0, 114.0, 115.0, 115.0], "value": 113.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 150.0, 160.0, 150.0, 150.0, 150.0, 150.0, 151.0, 150.0, 151.0], "value": 151.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 94.0, 94.0, 94.0, 94.0, 96.0, 94.0, 94.0, 94.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [45.0, 45.0, 46.0, 47.0, 46.0, 47.0, 45.0, 46.0, 46.0, 45.0], "value": 45.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [37.0, 37.0, 37.0, 39.0, 37.0, 37.0, 37.0, 37.0, 37.0, 37.0], "value": 37.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 70.0, 70.0, 72.0, 72.0, 73.0, 69.0, 72.0, 72.0, 70.0], "value": 71.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 104.0, 110.0, 113.0, 103.0, 105.0, 107.0, 107.0, 108.0, 103.0], "value": 106.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 159.0, 149.0, 151.0, 158.0, 157.0, 150.0, 152.0, 149.0, 154.0], "value": 153.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 62.0, 58.0, 60.0, 60.0, 62.0, 69.0, 61.0, 61.0, 60.0], "value": 61.8, "unit": "score"}]}]} 04:29:17 INFO - Return code: 0 04:29:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:29:17 INFO - # TBPL SUCCESS # 04:29:17 INFO - Running post-action listener: _package_coverage_data 04:29:17 INFO - Running post-action listener: _resource_record_post_action 04:29:17 INFO - [mozharness: 2017-09-23 11:29:17.202247Z] Finished run-tests step (success) 04:29:17 INFO - Running post-run listener: _resource_record_post_run 04:29:17 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 8847360; Write bytes: 312528896; Read time: 2728; Write time: 263560 04:29:17 INFO - TinderboxPrint: CPU usage
13.1% 04:29:17 INFO - TinderboxPrint: I/O read bytes / time
8,847,360 / 2,728 04:29:17 INFO - TinderboxPrint: I/O write bytes / time
312,528,896 / 263,560 04:29:17 INFO - TinderboxPrint: CPU idle
8,232.5 (86.9%) 04:29:17 INFO - TinderboxPrint: CPU user
1,187.6 (12.5%) 04:29:17 INFO - TinderboxPrint: Swap in / out
0 / 0 04:29:17 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 503808; Read time: 0; Write time: 40 04:29:17 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 04:29:17 INFO - run-tests - Wall time: 1170s; CPU: 13.0%; Read bytes: 8847360; Write bytes: 312025088; Read time: 2728; Write time: 263520 04:29:17 INFO - Running post-run listener: _upload_blobber_files 04:29:17 INFO - Blob upload gear active. 04:29:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:29:17 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:29:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:29:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:29:18 INFO - (blobuploader) - INFO - Open directory for files ... 04:29:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:29:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:29:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:29:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:29:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:29:25 INFO - (blobuploader) - INFO - Done attempting. 04:29:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:29:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:29:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:29:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:29:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:29:26 INFO - (blobuploader) - INFO - Done attempting. 04:29:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:29:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:29:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:29:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:29:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:29:27 INFO - (blobuploader) - INFO - Done attempting. 04:29:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:29:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:29:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:29:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:29:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:29:28 INFO - (blobuploader) - INFO - Done attempting. 04:29:28 INFO - (blobuploader) - INFO - Iteration through files over. 04:29:28 INFO - Return code: 0 04:29:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:29:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:29:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b2c86e8f8405c7ee172653880d5448595426b05c0185b57039137e2e30b6c5b30ef1fd886545ae1107977a6b191eafeb6f95b61495b89fd7add9bdd6af226f04", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c94b89957924029cc7a1b36d2e73e1326150899091670a15c24139465eda3ab4180d8a29167df468160cf3ade3fe9bb07c2f9e825bbf13f165f0885e52494166", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/843295acb272bb09ff89fc6ac093c5626b145d8e08f62f7d5cfac06e72f48b6f8ea1c56009818034c0f8b5342003b372b0065fae190df1f3cefd629f0ab3e54d", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d2ff3daa19d8088c3815e4b9efd7ab354c51cb681d195d0fd1cdb1daf8a3e9b7be68cdc2e39d1a2905d78a638882574d0fb4f79f79b71bd84b109d0e383f4e2"} 04:29:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:29:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:29:28 INFO - Contents: 04:29:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b2c86e8f8405c7ee172653880d5448595426b05c0185b57039137e2e30b6c5b30ef1fd886545ae1107977a6b191eafeb6f95b61495b89fd7add9bdd6af226f04", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c94b89957924029cc7a1b36d2e73e1326150899091670a15c24139465eda3ab4180d8a29167df468160cf3ade3fe9bb07c2f9e825bbf13f165f0885e52494166", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/843295acb272bb09ff89fc6ac093c5626b145d8e08f62f7d5cfac06e72f48b6f8ea1c56009818034c0f8b5342003b372b0065fae190df1f3cefd629f0ab3e54d", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d2ff3daa19d8088c3815e4b9efd7ab354c51cb681d195d0fd1cdb1daf8a3e9b7be68cdc2e39d1a2905d78a638882574d0fb4f79f79b71bd84b109d0e383f4e2"} 04:29:28 INFO - Running post-run listener: copy_logs_to_upload_dir 04:29:28 INFO - Copying logs to upload dir... 04:29:28 INFO - mkdir: /builds/slave/test/build/upload/logs 04:29:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1238.845814 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 38 secs) (at 2017-09-23 04:29:28.435506) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-23 04:29:28.441810) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b2c86e8f8405c7ee172653880d5448595426b05c0185b57039137e2e30b6c5b30ef1fd886545ae1107977a6b191eafeb6f95b61495b89fd7add9bdd6af226f04", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c94b89957924029cc7a1b36d2e73e1326150899091670a15c24139465eda3ab4180d8a29167df468160cf3ade3fe9bb07c2f9e825bbf13f165f0885e52494166", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/843295acb272bb09ff89fc6ac093c5626b145d8e08f62f7d5cfac06e72f48b6f8ea1c56009818034c0f8b5342003b372b0065fae190df1f3cefd629f0ab3e54d", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d2ff3daa19d8088c3815e4b9efd7ab354c51cb681d195d0fd1cdb1daf8a3e9b7be68cdc2e39d1a2905d78a638882574d0fb4f79f79b71bd84b109d0e383f4e2"} build_url:https://queue.taskcluster.net/v1/task/a0jBBM_RSdqJvUB-BgrX8w/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.010966 build_url: 'https://queue.taskcluster.net/v1/task/a0jBBM_RSdqJvUB-BgrX8w/artifacts/public/build/target.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b2c86e8f8405c7ee172653880d5448595426b05c0185b57039137e2e30b6c5b30ef1fd886545ae1107977a6b191eafeb6f95b61495b89fd7add9bdd6af226f04", "resource-usage.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c94b89957924029cc7a1b36d2e73e1326150899091670a15c24139465eda3ab4180d8a29167df468160cf3ade3fe9bb07c2f9e825bbf13f165f0885e52494166", "dromaeojs-e10s_raw.log": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/843295acb272bb09ff89fc6ac093c5626b145d8e08f62f7d5cfac06e72f48b6f8ea1c56009818034c0f8b5342003b372b0065fae190df1f3cefd629f0ab3e54d", "perfherder-data.json": "https://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2d2ff3daa19d8088c3815e4b9efd7ab354c51cb681d195d0fd1cdb1daf8a3e9b7be68cdc2e39d1a2905d78a638882574d0fb4f79f79b71bd84b109d0e383f4e2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-09-23 04:29:28.475428) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 04:29:28.475788) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=9531f05ccb2eabcb9acf5f1b000001c0-1506164926.153711-572263642 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004426 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-09-23 04:29:28.559400) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-23 04:29:28.559808) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-09-23 04:29:28.560250) ========= ========= Total master_lag: 0.23 =========