builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-058 starttime: 1463044698.6 results: success (0) buildid: 20160511232236 builduid: 90e0e96a7dd74771be300ea8dbc6a8e1 revision: 218969cdfaf0a78d9d5d0490b38532782ea902fe ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 02:18:18.597571) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 02:18:18.598056) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 02:18:18.598329) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 02:18:18.611810) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 02:18:18.612133) ========= 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=0e2a256762fed810e2f0f810000001b3-1463044697.319541-1817303771 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-12 02:18:18-- 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: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 228M=0s 2016-05-12 02:18:18 (228 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.192963 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 02:18:18.830366) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 02:18:18.830693) ========= 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=0e2a256762fed810e2f0f810000001b3-1463044697.319541-1817303771 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.089105 ========= master_lag: 0.01 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 02:18:18.931580) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-12 02:18:18.931924) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 218969cdfaf0a78d9d5d0490b38532782ea902fe --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 218969cdfaf0a78d9d5d0490b38532782ea902fe --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=0e2a256762fed810e2f0f810000001b3-1463044697.319541-1817303771 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-12 02:18:18,987 truncating revision to first 12 chars 2016-05-12 02:18:18,987 Setting DEBUG logging. 2016-05-12 02:18:18,987 attempt 1/10 2016-05-12 02:18:18,987 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/218969cdfaf0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 02:18:20,322 unpacking tar archive at: mozilla-inbound-218969cdfaf0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.665531 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-12 02:18:20.610453) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 02:18:20.611093) ========= script_repo_revision: 218969cdfaf0a78d9d5d0490b38532782ea902fe ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 02:18:20.611754) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 02:18:20.612164) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 02:18:20.622813) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-05-12 02:18:20.623215) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=0e2a256762fed810e2f0f810000001b3-1463044697.319541-1817303771 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 02:18:20 INFO - MultiFileLogger online at 20160512 02:18:20 in /builds/slave/test 02:18:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 02:18:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:18:20 INFO - {'append_to_log': False, 02:18:20 INFO - 'base_work_dir': '/builds/slave/test', 02:18:20 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 02:18:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:18:20 INFO - 'branch': 'Mozilla-Inbound', 02:18:20 INFO - 'buildbot_json_path': 'buildprops.json', 02:18:20 INFO - 'config_files': ('talos/linux_config.py',), 02:18:20 INFO - 'default_actions': ('clobber', 02:18:20 INFO - 'read-buildbot-config', 02:18:20 INFO - 'download-and-extract', 02:18:20 INFO - 'populate-webroot', 02:18:20 INFO - 'create-virtualenv', 02:18:20 INFO - 'install', 02:18:20 INFO - 'run-tests'), 02:18:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:18:20 INFO - 'download_minidump_stackwalk': True, 02:18:20 INFO - 'download_symbols': 'ondemand', 02:18:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:18:20 INFO - 'tooltool.py': '/tools/tooltool.py', 02:18:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:18:20 INFO - '/tools/misc-python/virtualenv.py')}, 02:18:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:18:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:18:20 INFO - 'installer_path': 'installer.exe', 02:18:20 INFO - 'log_level': 'info', 02:18:20 INFO - 'log_name': 'talos', 02:18:20 INFO - 'log_to_console': True, 02:18:20 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 02:18:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 02:18:20 INFO - 'opt_config_files': (), 02:18:20 INFO - 'pip_index': False, 02:18:20 INFO - 'sps_profile': False, 02:18:20 INFO - 'sps_profile_interval': 0, 02:18:20 INFO - 'suite': 'dromaeojs', 02:18:20 INFO - 'system_bits': '32', 02:18:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:18:20 INFO - 'title': 'talos-linux64-ix-058', 02:18:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:18:20 INFO - 'use_talos_json': True, 02:18:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:18:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:18:20 INFO - 'work_dir': 'build'} 02:18:20 INFO - ##### 02:18:20 INFO - ##### Running clobber step. 02:18:20 INFO - ##### 02:18:20 INFO - Running pre-action listener: _resource_record_pre_action 02:18:20 INFO - Running main action method: clobber 02:18:20 INFO - rmtree: /builds/slave/test/build 02:18:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:18:26 INFO - Running post-action listener: _resource_record_post_action 02:18:26 INFO - ##### 02:18:26 INFO - ##### Finished clobber step (success) 02:18:26 INFO - ##### 02:18:26 INFO - ##### 02:18:26 INFO - ##### Running read-buildbot-config step. 02:18:26 INFO - ##### 02:18:26 INFO - Running pre-action listener: _resource_record_pre_action 02:18:26 INFO - Running main action method: read_buildbot_config 02:18:26 INFO - Using buildbot properties: 02:18:26 INFO - { 02:18:26 INFO - "project": "", 02:18:26 INFO - "product": "firefox", 02:18:26 INFO - "script_repo_revision": "production", 02:18:26 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 02:18:26 INFO - "repository": "", 02:18:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 02:18:26 INFO - "buildid": "20160511232236", 02:18:26 INFO - "pgo_build": "True", 02:18:26 INFO - "basedir": "/builds/slave/test", 02:18:26 INFO - "buildnumber": 899, 02:18:26 INFO - "slavename": "talos-linux64-ix-058", 02:18:26 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 02:18:26 INFO - "platform": "ubuntu64_hw", 02:18:26 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 02:18:26 INFO - "branch": "mozilla-inbound", 02:18:26 INFO - "repo_path": "integration/mozilla-inbound", 02:18:26 INFO - "revision": "218969cdfaf0a78d9d5d0490b38532782ea902fe", 02:18:26 INFO - "stage_platform": "linux64-pgo", 02:18:26 INFO - "builduid": "90e0e96a7dd74771be300ea8dbc6a8e1", 02:18:26 INFO - "slavebuilddir": "test" 02:18:26 INFO - } 02:18:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 02:18:26 INFO - Running post-action listener: _resource_record_post_action 02:18:26 INFO - ##### 02:18:26 INFO - ##### Finished read-buildbot-config step (success) 02:18:26 INFO - ##### 02:18:26 INFO - ##### 02:18:26 INFO - ##### Running download-and-extract step. 02:18:26 INFO - ##### 02:18:26 INFO - Running pre-action listener: _resource_record_pre_action 02:18:26 INFO - Running main action method: download_and_extract 02:18:26 INFO - mkdir: /builds/slave/test/build/tests 02:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:26 INFO - https://queue.taskcluster.net/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 02:18:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 02:18:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 02:18:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 02:18:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 02:18:28 INFO - Downloaded 1361 bytes. 02:18:28 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 02:18:28 INFO - Using the following test package requirements: 02:18:28 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 02:18:28 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:18:28 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 02:18:28 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:18:28 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 02:18:28 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:18:28 INFO - u'jsshell-linux-x86_64.zip'], 02:18:28 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:18:28 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 02:18:28 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 02:18:28 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:18:28 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 02:18:28 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:18:28 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 02:18:28 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:18:28 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 02:18:28 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 02:18:28 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 02:18:28 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 02:18:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:28 INFO - https://queue.taskcluster.net/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 02:18:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:18:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:18:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:18:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:18:29 INFO - Downloaded 23903079 bytes. 02:18:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:18:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 02:18:30 INFO - Return code: 0 02:18:30 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 02:18:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:30 INFO - https://queue.taskcluster.net/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 02:18:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:18:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:18:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 02:18:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 02:18:31 INFO - Downloaded 23903079 bytes. 02:18:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:18:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 02:18:33 INFO - Return code: 0 02:18:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:33 INFO - https://queue.taskcluster.net/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 02:18:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 02:18:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 02:18:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 02:18:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 02:18:34 INFO - Downloaded 10840239 bytes. 02:18:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:18:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 02:18:35 INFO - Return code: 0 02:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:35 INFO - https://queue.taskcluster.net/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 02:18:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 02:18:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 02:18:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 02:18:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 02:18:36 INFO - Downloaded 58367381 bytes. 02:18:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 02:18:36 INFO - mkdir: /builds/slave/test/properties 02:18:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:18:36 INFO - Writing to file /builds/slave/test/properties/build_url 02:18:36 INFO - Contents: 02:18:36 INFO - build_url:https://queue.taskcluster.net/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 02:18:37 INFO - Running post-action listener: _resource_record_post_action 02:18:37 INFO - Running post-action listener: set_extra_try_arguments 02:18:37 INFO - ##### 02:18:37 INFO - ##### Finished download-and-extract step (success) 02:18:37 INFO - ##### 02:18:37 INFO - ##### 02:18:37 INFO - ##### Running populate-webroot step. 02:18:37 INFO - ##### 02:18:37 INFO - Running pre-action listener: _resource_record_pre_action 02:18:37 INFO - Running main action method: populate_webroot 02:18:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:18:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:18:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:18:37 INFO - '--tppagecycles', 02:18:37 INFO - '7'], 02:18:37 INFO - 'tests': ['tsvgm']}}, 02:18:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:18:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:18:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:18:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:18:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:18:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:18:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:37 INFO - 'talos_options': ['--disable-e10s'], 02:18:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:18:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:18:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:37 INFO - 'talos_options': ['--disable-e10s'], 02:18:37 INFO - 'tests': ['damp', 'tps']}, 02:18:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:37 INFO - 'tests': ['damp', 'tps']}, 02:18:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:18:37 INFO - 'tests': ['dromaeo_dom']}, 02:18:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:18:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:18:37 INFO - 'tests': ['a11yr', 02:18:37 INFO - 'ts_paint', 02:18:37 INFO - 'tpaint', 02:18:37 INFO - 'sessionrestore', 02:18:37 INFO - 'sessionrestore_no_auto_restore', 02:18:37 INFO - 'tabpaint']}, 02:18:37 INFO - 'other-e10s': {'tests': ['a11yr', 02:18:37 INFO - 'ts_paint', 02:18:37 INFO - 'tpaint', 02:18:37 INFO - 'sessionrestore', 02:18:37 INFO - 'sessionrestore_no_auto_restore', 02:18:37 INFO - 'tabpaint']}, 02:18:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:18:37 INFO - 'ts_paint', 02:18:37 INFO - 'tpaint', 02:18:37 INFO - 'sessionrestore', 02:18:37 INFO - 'sessionrestore_no_auto_restore', 02:18:37 INFO - 'tabpaint']}, 02:18:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:18:37 INFO - 'ts_paint', 02:18:37 INFO - 'tpaint', 02:18:37 INFO - 'sessionrestore', 02:18:37 INFO - 'sessionrestore_no_auto_restore', 02:18:37 INFO - 'tabpaint']}, 02:18:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:18:37 INFO - 'tests': ['a11yr', 02:18:37 INFO - 'ts_paint', 02:18:37 INFO - 'tpaint', 02:18:37 INFO - 'sessionrestore', 02:18:37 INFO - 'sessionrestore_no_auto_restore', 02:18:37 INFO - 'tabpaint']}, 02:18:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:18:37 INFO - 'tests': ['a11yr', 02:18:37 INFO - 'ts_paint', 02:18:37 INFO - 'tpaint', 02:18:37 INFO - 'sessionrestore', 02:18:37 INFO - 'sessionrestore_no_auto_restore', 02:18:37 INFO - 'tabpaint']}, 02:18:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:18:37 INFO - 'tests': ['tsvgx', 02:18:37 INFO - 'tsvgr_opacity', 02:18:37 INFO - 'tart', 02:18:37 INFO - 'tscrollx', 02:18:37 INFO - 'cart']}, 02:18:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:18:37 INFO - 'tsvgr_opacity', 02:18:37 INFO - 'tart', 02:18:37 INFO - 'tscrollx', 02:18:37 INFO - 'cart']}, 02:18:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:37 INFO - 'talos_options': ['--disable-e10s'], 02:18:37 INFO - 'tests': ['tp5o']}, 02:18:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:37 INFO - 'tests': ['tp5o']}, 02:18:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:37 INFO - 'talos_options': ['--disable-e10s', 02:18:37 INFO - '--xperf_path', 02:18:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:18:37 INFO - 'tests': ['tp5n']}, 02:18:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:18:37 INFO - 'talos_options': ['--xperf_path', 02:18:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:18:37 INFO - 'tests': ['tp5n']}}, 02:18:37 INFO - 'talos.zip': {'path': '', 02:18:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:18:37 INFO - Running post-action listener: _resource_record_post_action 02:18:37 INFO - ##### 02:18:37 INFO - ##### Finished populate-webroot step (success) 02:18:37 INFO - ##### 02:18:37 INFO - ##### 02:18:37 INFO - ##### Running create-virtualenv step. 02:18:37 INFO - ##### 02:18:37 INFO - Running pre-action listener: _resource_record_pre_action 02:18:37 INFO - Running main action method: create_virtualenv 02:18:37 INFO - Creating virtualenv /builds/slave/test/build/venv 02:18:37 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:18:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:18:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:18:37 INFO - Using real prefix '/usr' 02:18:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:18:37 INFO - Installing distribute.............................................................................................................................................................................................done. 02:18:39 INFO - Installing pip.................done. 02:18:39 INFO - Return code: 0 02:18:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:18:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:39 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f36b5db3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b3e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bcd9c0>, '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 0x1bce8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bced50>, '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': '0e2a256762fed810e2f0f810000001b3-1463044697.319541-1817303771', '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 02:18:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 02:18:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:18:39 INFO - Using env: {'DISPLAY': ':0', 02:18:39 INFO - 'HOME': '/home/cltbld', 02:18:39 INFO - 'LANG': 'en_US.UTF-8', 02:18:39 INFO - 'LANGUAGE': 'en_US:en', 02:18:39 INFO - 'LOGNAME': 'cltbld', 02:18:39 INFO - 'MAIL': '/var/mail/cltbld', 02:18:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:39 INFO - 'MOZ_NO_REMOTE': '1', 02:18:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:18:39 INFO - 'NO_EM_RESTART': '1', 02:18:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:18:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:39 INFO - 'PWD': '/builds/slave/test', 02:18:39 INFO - 'SHELL': '/bin/bash', 02:18:39 INFO - 'SHLVL': '1', 02:18:39 INFO - 'TERM': 'linux', 02:18:39 INFO - 'TMOUT': '86400', 02:18:39 INFO - 'USER': 'cltbld', 02:18:39 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1463044697.319541-1817303771', 02:18:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:39 INFO - '_': '/tools/buildbot/bin/python'} 02:18:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:40 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:18:40 INFO - Cleaning up... 02:18:40 INFO - Return code: 0 02:18:40 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:18:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:40 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f36b5db3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b3e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bcd9c0>, '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 0x1bce8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bced50>, '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': '0e2a256762fed810e2f0f810000001b3-1463044697.319541-1817303771', '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 02:18:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 02:18:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 02:18:40 INFO - Using env: {'DISPLAY': ':0', 02:18:40 INFO - 'HOME': '/home/cltbld', 02:18:40 INFO - 'LANG': 'en_US.UTF-8', 02:18:40 INFO - 'LANGUAGE': 'en_US:en', 02:18:40 INFO - 'LOGNAME': 'cltbld', 02:18:40 INFO - 'MAIL': '/var/mail/cltbld', 02:18:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:40 INFO - 'MOZ_NO_REMOTE': '1', 02:18:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:18:40 INFO - 'NO_EM_RESTART': '1', 02:18:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:18:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:40 INFO - 'PWD': '/builds/slave/test', 02:18:40 INFO - 'SHELL': '/bin/bash', 02:18:40 INFO - 'SHLVL': '1', 02:18:40 INFO - 'TERM': 'linux', 02:18:40 INFO - 'TMOUT': '86400', 02:18:40 INFO - 'USER': 'cltbld', 02:18:40 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1463044697.319541-1817303771', 02:18:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:40 INFO - '_': '/tools/buildbot/bin/python'} 02:18:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:40 INFO - Downloading/unpacking psutil>=0.7.1 02:18:40 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 02:18:40 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 02:18:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:40 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 02:18:40 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 02:18:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:18:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:18:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:18:42 INFO - Installing collected packages: psutil 02:18:42 INFO - Running setup.py install for psutil 02:18:42 INFO - building 'psutil._psutil_linux' extension 02:18:42 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 02:18:43 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 02:18:43 INFO - building 'psutil._psutil_posix' extension 02:18:43 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 02:18:43 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 02:18:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:18:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:18:43 INFO - Successfully installed psutil 02:18:43 INFO - Cleaning up... 02:18:43 INFO - Return code: 0 02:18:43 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:18:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:43 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f36b5db3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b3e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bcd9c0>, '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 0x1bce8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bced50>, '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': '0e2a256762fed810e2f0f810000001b3-1463044697.319541-1817303771', '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 02:18:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 02:18:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 02:18:43 INFO - Using env: {'DISPLAY': ':0', 02:18:43 INFO - 'HOME': '/home/cltbld', 02:18:43 INFO - 'LANG': 'en_US.UTF-8', 02:18:43 INFO - 'LANGUAGE': 'en_US:en', 02:18:43 INFO - 'LOGNAME': 'cltbld', 02:18:43 INFO - 'MAIL': '/var/mail/cltbld', 02:18:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:43 INFO - 'MOZ_NO_REMOTE': '1', 02:18:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:18:43 INFO - 'NO_EM_RESTART': '1', 02:18:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:18:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:43 INFO - 'PWD': '/builds/slave/test', 02:18:43 INFO - 'SHELL': '/bin/bash', 02:18:43 INFO - 'SHLVL': '1', 02:18:43 INFO - 'TERM': 'linux', 02:18:43 INFO - 'TMOUT': '86400', 02:18:43 INFO - 'USER': 'cltbld', 02:18:43 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1463044697.319541-1817303771', 02:18:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:43 INFO - '_': '/tools/buildbot/bin/python'} 02:18:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:44 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:18:44 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 02:18:44 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 02:18:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:44 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 02:18:44 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 02:18:45 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:18:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:18:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:18:46 INFO - Installing collected packages: mozsystemmonitor 02:18:46 INFO - Running setup.py install for mozsystemmonitor 02:18:46 INFO - Successfully installed mozsystemmonitor 02:18:46 INFO - Cleaning up... 02:18:46 INFO - Return code: 0 02:18:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:18:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:46 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f36b5db3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b3e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bcd9c0>, '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 0x1bce8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bced50>, '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': '0e2a256762fed810e2f0f810000001b3-1463044697.319541-1817303771', '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 02:18:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 02:18:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:18:46 INFO - Using env: {'DISPLAY': ':0', 02:18:46 INFO - 'HOME': '/home/cltbld', 02:18:46 INFO - 'LANG': 'en_US.UTF-8', 02:18:46 INFO - 'LANGUAGE': 'en_US:en', 02:18:46 INFO - 'LOGNAME': 'cltbld', 02:18:46 INFO - 'MAIL': '/var/mail/cltbld', 02:18:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:46 INFO - 'MOZ_NO_REMOTE': '1', 02:18:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:18:46 INFO - 'NO_EM_RESTART': '1', 02:18:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:18:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:46 INFO - 'PWD': '/builds/slave/test', 02:18:46 INFO - 'SHELL': '/bin/bash', 02:18:46 INFO - 'SHLVL': '1', 02:18:46 INFO - 'TERM': 'linux', 02:18:46 INFO - 'TMOUT': '86400', 02:18:46 INFO - 'USER': 'cltbld', 02:18:46 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1463044697.319541-1817303771', 02:18:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:46 INFO - '_': '/tools/buildbot/bin/python'} 02:18:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:46 INFO - Downloading/unpacking blobuploader==1.2.4 02:18:46 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 02:18:46 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 02:18:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:46 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 02:18:46 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 02:18:48 INFO - Downloading blobuploader-1.2.4.tar.gz 02:18:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:18:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:18:48 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 02:18:48 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 02:18:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:48 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 02:18:48 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 02:18:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:18:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:18:48 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 02:18:48 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 02:18:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:48 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 02:18:48 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 02:18:48 INFO - Downloading docopt-0.6.1.tar.gz 02:18:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:18:49 INFO - Installing collected packages: blobuploader, requests, docopt 02:18:49 INFO - Running setup.py install for blobuploader 02:18:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:18:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:18:49 INFO - Running setup.py install for requests 02:18:49 INFO - Running setup.py install for docopt 02:18:49 INFO - Successfully installed blobuploader requests docopt 02:18:49 INFO - Cleaning up... 02:18:49 INFO - Return code: 0 02:18:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:49 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f36b5db3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b3e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bcd9c0>, '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 0x1bce8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bced50>, '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': '0e2a256762fed810e2f0f810000001b3-1463044697.319541-1817303771', '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 02:18:49 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 02:18:49 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:18:49 INFO - Using env: {'DISPLAY': ':0', 02:18:49 INFO - 'HOME': '/home/cltbld', 02:18:49 INFO - 'LANG': 'en_US.UTF-8', 02:18:49 INFO - 'LANGUAGE': 'en_US:en', 02:18:49 INFO - 'LOGNAME': 'cltbld', 02:18:49 INFO - 'MAIL': '/var/mail/cltbld', 02:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:49 INFO - 'MOZ_NO_REMOTE': '1', 02:18:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:18:49 INFO - 'NO_EM_RESTART': '1', 02:18:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:49 INFO - 'PWD': '/builds/slave/test', 02:18:49 INFO - 'SHELL': '/bin/bash', 02:18:49 INFO - 'SHLVL': '1', 02:18:49 INFO - 'TERM': 'linux', 02:18:49 INFO - 'TMOUT': '86400', 02:18:49 INFO - 'USER': 'cltbld', 02:18:49 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1463044697.319541-1817303771', 02:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:49 INFO - '_': '/tools/buildbot/bin/python'} 02:18:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:18:49 INFO - Running setup.py (path:/tmp/pip-8S1DeA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:18:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:18:49 INFO - Running setup.py (path:/tmp/pip-DayMtw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:18:50 INFO - Running setup.py (path:/tmp/pip-nhGnmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:18:50 INFO - Running setup.py (path:/tmp/pip-ZAJRA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:18:50 INFO - Running setup.py (path:/tmp/pip-gMWkXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:18:50 INFO - Running setup.py (path:/tmp/pip-yFO3LZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:18:50 INFO - Running setup.py (path:/tmp/pip-krMG5k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:18:50 INFO - Running setup.py (path:/tmp/pip-YjPv4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:18:50 INFO - Running setup.py (path:/tmp/pip-_WuHvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:18:50 INFO - Running setup.py (path:/tmp/pip-pEzXFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:18:50 INFO - Running setup.py (path:/tmp/pip-N9SKOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:18:50 INFO - Running setup.py (path:/tmp/pip-jVeKRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:18:50 INFO - Running setup.py (path:/tmp/pip-7MNoll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:18:50 INFO - Running setup.py (path:/tmp/pip-1x1Z5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:18:50 INFO - Running setup.py (path:/tmp/pip-dAlrPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:18:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:18:50 INFO - Running setup.py (path:/tmp/pip-cBKzzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:18:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:18:51 INFO - Running setup.py (path:/tmp/pip-CyRiVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:18:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:18:51 INFO - Running setup.py install for manifestparser 02:18:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:18:51 INFO - Running setup.py install for mozcrash 02:18:51 INFO - Running setup.py install for mozdebug 02:18:51 INFO - Running setup.py install for mozdevice 02:18:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:18:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:18:51 INFO - Running setup.py install for mozfile 02:18:51 INFO - Running setup.py install for mozhttpd 02:18:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:18:51 INFO - Running setup.py install for mozinfo 02:18:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:18:51 INFO - Running setup.py install for mozInstall 02:18:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:18:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:18:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:18:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:18:51 INFO - Running setup.py install for mozleak 02:18:51 INFO - Running setup.py install for mozlog 02:18:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:18:52 INFO - Running setup.py install for moznetwork 02:18:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:18:52 INFO - Running setup.py install for mozprocess 02:18:52 INFO - Running setup.py install for mozprofile 02:18:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:18:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:18:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:18:52 INFO - Running setup.py install for mozrunner 02:18:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:18:52 INFO - Running setup.py install for mozscreenshot 02:18:52 INFO - Running setup.py install for moztest 02:18:52 INFO - Running setup.py install for mozversion 02:18:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:18:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:18:52 INFO - Cleaning up... 02:18:52 INFO - Return code: 0 02:18:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:52 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f36b5db3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b3e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bcd9c0>, '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 0x1bce8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bced50>, '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': '0e2a256762fed810e2f0f810000001b3-1463044697.319541-1817303771', '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 02:18:52 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 02:18:52 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:18:52 INFO - Using env: {'DISPLAY': ':0', 02:18:52 INFO - 'HOME': '/home/cltbld', 02:18:52 INFO - 'LANG': 'en_US.UTF-8', 02:18:52 INFO - 'LANGUAGE': 'en_US:en', 02:18:52 INFO - 'LOGNAME': 'cltbld', 02:18:52 INFO - 'MAIL': '/var/mail/cltbld', 02:18:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:52 INFO - 'MOZ_NO_REMOTE': '1', 02:18:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:18:52 INFO - 'NO_EM_RESTART': '1', 02:18:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:18:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:52 INFO - 'PWD': '/builds/slave/test', 02:18:52 INFO - 'SHELL': '/bin/bash', 02:18:52 INFO - 'SHLVL': '1', 02:18:52 INFO - 'TERM': 'linux', 02:18:52 INFO - 'TMOUT': '86400', 02:18:52 INFO - 'USER': 'cltbld', 02:18:52 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1463044697.319541-1817303771', 02:18:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:52 INFO - '_': '/tools/buildbot/bin/python'} 02:18:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:18:53 INFO - Running setup.py (path:/tmp/pip-jrnus1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:18:53 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)) 02:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:18:53 INFO - Running setup.py (path:/tmp/pip-px18Em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:18:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 02:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:18:53 INFO - Running setup.py (path:/tmp/pip-k32jMI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:18:53 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)) 02:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:18:53 INFO - Running setup.py (path:/tmp/pip-8KqxHZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:18:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 02:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:18:53 INFO - Running setup.py (path:/tmp/pip-cMfLjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:18:53 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)) 02:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:18:53 INFO - Running setup.py (path:/tmp/pip-9bHQgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:18:53 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)) 02:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:18:53 INFO - Running setup.py (path:/tmp/pip-IoqSyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:18:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 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)) 02:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:18:53 INFO - Running setup.py (path:/tmp/pip-AAuwUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:18:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 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)) 02:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:18:53 INFO - Running setup.py (path:/tmp/pip-7U1Bt6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:18:53 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)) 02:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:18:53 INFO - Running setup.py (path:/tmp/pip-kLtxvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:18:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 02:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:18:53 INFO - Running setup.py (path:/tmp/pip-gRqm8F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:18:53 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)) 02:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:18:53 INFO - Running setup.py (path:/tmp/pip-2zROpi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:18:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 02:18:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:18:53 INFO - Running setup.py (path:/tmp/pip-nptmzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:18:54 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)) 02:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:18:54 INFO - Running setup.py (path:/tmp/pip-ROXY1B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:18:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 02:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:18:54 INFO - Running setup.py (path:/tmp/pip-SzklGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:18:54 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)) 02:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:18:54 INFO - Running setup.py (path:/tmp/pip-2G4_15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:18:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 02:18:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:18:54 INFO - Running setup.py (path:/tmp/pip-JYiUgd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:18:54 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)) 02:18:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:18:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:18:54 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)) 02:18:54 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:18:54 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:18:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:18:54 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 02:18:54 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 02:18:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:54 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 02:18:54 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 02:18:56 INFO - Downloading blessings-1.6.tar.gz 02:18:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:18:56 INFO - Installing collected packages: blessings 02:18:56 INFO - Running setup.py install for blessings 02:18:56 INFO - Successfully installed blessings 02:18:56 INFO - Cleaning up... 02:18:56 INFO - Return code: 0 02:18:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:18:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:18:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:18:56 INFO - Reading from file tmpfile_stdout 02:18:56 INFO - Current package versions: 02:18:56 INFO - argparse == 1.2.1 02:18:56 INFO - blessings == 1.6 02:18:56 INFO - blobuploader == 1.2.4 02:18:56 INFO - docopt == 0.6.1 02:18:56 INFO - manifestparser == 1.1 02:18:56 INFO - mozInstall == 1.12 02:18:56 INFO - mozcrash == 0.17 02:18:56 INFO - mozdebug == 0.1 02:18:56 INFO - mozdevice == 0.48 02:18:56 INFO - mozfile == 1.2 02:18:56 INFO - mozhttpd == 0.7 02:18:56 INFO - mozinfo == 0.9 02:18:56 INFO - mozleak == 0.1 02:18:56 INFO - mozlog == 3.2 02:18:56 INFO - moznetwork == 0.27 02:18:56 INFO - mozprocess == 0.22 02:18:56 INFO - mozprofile == 0.28 02:18:56 INFO - mozrunner == 6.11 02:18:56 INFO - mozscreenshot == 0.1 02:18:56 INFO - mozsystemmonitor == 0.0 02:18:56 INFO - moztest == 0.7 02:18:56 INFO - mozversion == 1.4 02:18:56 INFO - psutil == 3.1.1 02:18:56 INFO - requests == 1.2.3 02:18:56 INFO - wsgiref == 0.1.2 02:18:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:56 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f36b5db3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b3e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bcd9c0>, '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 0x1bce8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bced50>, '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': '0e2a256762fed810e2f0f810000001b3-1463044697.319541-1817303771', '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 02:18:56 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 02:18:56 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.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:18:56 INFO - Using env: {'DISPLAY': ':0', 02:18:56 INFO - 'HOME': '/home/cltbld', 02:18:56 INFO - 'LANG': 'en_US.UTF-8', 02:18:56 INFO - 'LANGUAGE': 'en_US:en', 02:18:56 INFO - 'LOGNAME': 'cltbld', 02:18:56 INFO - 'MAIL': '/var/mail/cltbld', 02:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:56 INFO - 'MOZ_NO_REMOTE': '1', 02:18:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:18:56 INFO - 'NO_EM_RESTART': '1', 02:18:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:56 INFO - 'PWD': '/builds/slave/test', 02:18:56 INFO - 'SHELL': '/bin/bash', 02:18:56 INFO - 'SHLVL': '1', 02:18:56 INFO - 'TERM': 'linux', 02:18:56 INFO - 'TMOUT': '86400', 02:18:56 INFO - 'USER': 'cltbld', 02:18:56 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1463044697.319541-1817303771', 02:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:56 INFO - '_': '/tools/buildbot/bin/python'} 02:18:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:56 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)) 02:18:56 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)) 02:18:56 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)) 02:18:56 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)) 02:18:56 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)) 02:18:56 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)) 02:18:56 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)) 02:18:56 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)) 02:18:56 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)) 02:18:56 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)) 02:18:56 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)) 02:18:56 INFO - Cleaning up... 02:18:56 INFO - Return code: 0 02:18:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:56 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--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 0x7f36b5db3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b3e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bcd9c0>, '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 0x1bce8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bced50>, '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': '0e2a256762fed810e2f0f810000001b3-1463044697.319541-1817303771', '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 02:18:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:18:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:18:56 INFO - Using env: {'DISPLAY': ':0', 02:18:56 INFO - 'HOME': '/home/cltbld', 02:18:56 INFO - 'LANG': 'en_US.UTF-8', 02:18:56 INFO - 'LANGUAGE': 'en_US:en', 02:18:56 INFO - 'LOGNAME': 'cltbld', 02:18:56 INFO - 'MAIL': '/var/mail/cltbld', 02:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:56 INFO - 'MOZ_NO_REMOTE': '1', 02:18:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:18:56 INFO - 'NO_EM_RESTART': '1', 02:18:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:56 INFO - 'PWD': '/builds/slave/test', 02:18:56 INFO - 'SHELL': '/bin/bash', 02:18:56 INFO - 'SHLVL': '1', 02:18:56 INFO - 'TERM': 'linux', 02:18:56 INFO - 'TMOUT': '86400', 02:18:56 INFO - 'USER': 'cltbld', 02:18:56 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1463044697.319541-1817303771', 02:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:56 INFO - '_': '/tools/buildbot/bin/python'} 02:18:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:57 INFO - Downloading/unpacking jsonschema 02:18:57 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 02:18:57 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 02:18:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:57 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 02:18:57 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 02:18:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:18:59 INFO - Downloading/unpacking functools32 (from jsonschema) 02:18:59 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 02:18:59 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 02:18:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:59 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 02:18:59 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 02:18:59 INFO - Downloading functools32-3.2.3-2.tar.gz 02:18:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:18:59 INFO - warning: no files found matching '*.txt' 02:18:59 INFO - no previously-included directories found matching 'build' 02:18:59 INFO - no previously-included directories found matching 'dist' 02:18:59 INFO - no previously-included directories found matching '.git*' 02:18:59 INFO - Installing collected packages: jsonschema, functools32 02:18:59 INFO - Running setup.py install for functools32 02:18:59 INFO - warning: no files found matching '*.txt' 02:18:59 INFO - no previously-included directories found matching 'build' 02:18:59 INFO - no previously-included directories found matching 'dist' 02:18:59 INFO - no previously-included directories found matching '.git*' 02:18:59 INFO - Successfully installed jsonschema functools32 02:18:59 INFO - Cleaning up... 02:18:59 INFO - Return code: 0 02:18:59 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:18:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:18:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:18:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:18:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:18:59 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.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f36b5db3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a738a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b3e770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1bcd9c0>, '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 0x1bce8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bced50>, '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': '0e2a256762fed810e2f0f810000001b3-1463044697.319541-1817303771', '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 02:18:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 02:18:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 02:18:59 INFO - Using env: {'DISPLAY': ':0', 02:18:59 INFO - 'HOME': '/home/cltbld', 02:18:59 INFO - 'LANG': 'en_US.UTF-8', 02:18:59 INFO - 'LANGUAGE': 'en_US:en', 02:18:59 INFO - 'LOGNAME': 'cltbld', 02:18:59 INFO - 'MAIL': '/var/mail/cltbld', 02:18:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:18:59 INFO - 'MOZ_NO_REMOTE': '1', 02:18:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:18:59 INFO - 'NO_EM_RESTART': '1', 02:18:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:18:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:18:59 INFO - 'PWD': '/builds/slave/test', 02:18:59 INFO - 'SHELL': '/bin/bash', 02:18:59 INFO - 'SHLVL': '1', 02:18:59 INFO - 'TERM': 'linux', 02:18:59 INFO - 'TMOUT': '86400', 02:18:59 INFO - 'USER': 'cltbld', 02:18:59 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1463044697.319541-1817303771', 02:18:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:18:59 INFO - '_': '/tools/buildbot/bin/python'} 02:18:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:18:59 INFO - Downloading/unpacking flake8 02:18:59 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 02:18:59 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 02:18:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:18:59 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 02:18:59 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 02:19:01 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:19:01 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:19:01 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 02:19:01 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 02:19:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:01 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 02:19:01 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 02:19:01 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:19:01 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:19:01 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 02:19:01 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 02:19:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:01 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 02:19:01 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 02:19:02 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:19:02 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 02:19:02 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 02:19:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:19:02 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 02:19:02 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 02:19:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:19:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:19:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:19:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:19:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:19:02 INFO - no previously-included directories found matching 'docs/_build' 02:19:02 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:19:02 INFO - Running setup.py install for pep8 02:19:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:19:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:19:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:19:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:19:02 INFO - no previously-included directories found matching 'docs/_build' 02:19:02 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:19:02 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:19:02 INFO - Cleaning up... 02:19:02 INFO - Return code: 0 02:19:02 INFO - Running post-action listener: _resource_record_post_action 02:19:02 INFO - Running post-action listener: _start_resource_monitoring 02:19:02 INFO - Starting resource monitoring. 02:19:02 INFO - ##### 02:19:02 INFO - ##### Finished create-virtualenv step (success) 02:19:02 INFO - ##### 02:19:02 INFO - ##### 02:19:02 INFO - ##### Running install step. 02:19:02 INFO - ##### 02:19:02 INFO - Running pre-action listener: _resource_record_pre_action 02:19:02 INFO - Running main action method: install 02:19:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:19:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:19:02 INFO - Reading from file tmpfile_stdout 02:19:02 INFO - Detecting whether we're running mozinstall >=1.0... 02:19:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:19:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:19:02 INFO - Reading from file tmpfile_stdout 02:19:02 INFO - Output received: 02:19:02 INFO - Usage: mozinstall [options] installer 02:19:02 INFO - Options: 02:19:02 INFO - -h, --help show this help message and exit 02:19:02 INFO - -d DEST, --destination=DEST 02:19:02 INFO - Directory to install application into. [default: 02:19:02 INFO - "/builds/slave/test"] 02:19:02 INFO - --app=APP Application being installed. [default: firefox] 02:19:02 INFO - mkdir: /builds/slave/test/build/application 02:19:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 02:19:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 02:19:19 INFO - Reading from file tmpfile_stdout 02:19:19 INFO - Output received: 02:19:19 INFO - /builds/slave/test/build/application/firefox/firefox 02:19:19 INFO - Running post-action listener: _resource_record_post_action 02:19:19 INFO - ##### 02:19:19 INFO - ##### Finished install step (success) 02:19:19 INFO - ##### 02:19:19 INFO - ##### 02:19:19 INFO - ##### Running run-tests step. 02:19:19 INFO - ##### 02:19:19 INFO - Running pre-action listener: _resource_record_pre_action 02:19:19 INFO - Running main action method: run_tests 02:19:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:19:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:19:19 INFO - Python 2.7.3 02:19:19 INFO - Return code: 0 02:19:19 INFO - grabbing minidump binary from tooltool 02:19:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:19:19 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 0x1bcd9c0>, '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 0x1bce8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bced50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:19:19 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 02:19:19 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 02:19:19 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--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 02:19:19 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:19:19 INFO - Return code: 0 02:19:19 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 02:19:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:19:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:19:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 02:19:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:19:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:19:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:19:20 INFO - Return code: 0 02:19:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 02:19:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-058 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 02:19:20 INFO - Using env: {'DISPLAY': ':0', 02:19:20 INFO - 'HOME': '/home/cltbld', 02:19:20 INFO - 'LANG': 'en_US.UTF-8', 02:19:20 INFO - 'LANGUAGE': 'en_US:en', 02:19:20 INFO - 'LOGNAME': 'cltbld', 02:19:20 INFO - 'MAIL': '/var/mail/cltbld', 02:19:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:19:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 02:19:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:19:20 INFO - 'MOZ_NO_REMOTE': '1', 02:19:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:19:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 02:19:20 INFO - 'NO_EM_RESTART': '1', 02:19:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 02:19:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:19:20 INFO - 'PWD': '/builds/slave/test', 02:19:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:19:20 INFO - 'SHELL': '/bin/bash', 02:19:20 INFO - 'SHLVL': '1', 02:19:20 INFO - 'TERM': 'linux', 02:19:20 INFO - 'TMOUT': '86400', 02:19:20 INFO - 'USER': 'cltbld', 02:19:20 INFO - 'XDG_SESSION_COOKIE': '0e2a256762fed810e2f0f810000001b3-1463044697.319541-1817303771', 02:19:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:19:20 INFO - '_': '/tools/buildbot/bin/python'} 02:19:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-058', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 02:19:20 INFO - mozversion application_buildid: 20160511232236 02:19:20 INFO - mozversion application_changeset: 218969cdfaf0a78d9d5d0490b38532782ea902fe 02:19:20 INFO - mozversion application_display_name: Nightly 02:19:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:19:20 INFO - mozversion application_name: Firefox 02:19:20 INFO - mozversion application_remotingname: firefox 02:19:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 02:19:20 INFO - mozversion application_vendor: Mozilla 02:19:20 INFO - mozversion application_version: 49.0a1 02:19:20 INFO - mozversion platform_buildid: 20160511232236 02:19:20 INFO - mozversion platform_changeset: 218969cdfaf0a78d9d5d0490b38532782ea902fe 02:19:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 02:19:20 INFO - mozversion platform_version: 49.0a1 02:19:20 INFO - using testdate: 1463044760 02:19:20 INFO - actual date: 1463044760 02:19:20 INFO - starting webserver on 'localhost:41517' 02:19:20 INFO - SUITE-START | Running 2 tests 02:19:20 INFO - TEST-START | dromaeo_css 02:19:20 INFO - operating with platform_type : linux_ 02:19:20 INFO - Initialising browser for dromaeo_css test... 02:19:20 INFO - TEST-INFO | started process 3328 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoVEltU/profile http://localhost:41517/getInfo.html) 02:19:25 INFO - PROCESS | 3328 | __metrics Screen width/height:1600/1200 02:19:25 INFO - PROCESS | 3328 | colorDepth:24 02:19:25 INFO - PROCESS | 3328 | Browser inner width/height: 1024/697 02:19:25 INFO - PROCESS | 3328 | __metrics 02:19:26 ERROR - PROCESS | 3328 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:19:27 INFO - TEST-INFO | 3328: exit 0 02:19:27 INFO - Browser initialized. 02:19:27 INFO - Running cycle 1/1 for dromaeo_css test... 02:19:27 INFO - TEST-INFO | started process 3411 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpoVEltU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:19:39 INFO - PROCESS | 3411 | RSS: Main: 152805376 02:19:39 INFO - PROCESS | 3411 | 02:20:30 INFO - PROCESS | 3411 | 1463044830874 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:22:38 INFO - PROCESS | 3411 | Cycle 1(1): loaded http://localhost:41517/tests/dromaeo/cssquery-dojo.html (next: http://localhost:41517/tests/dromaeo/cssquery-ext.html) 02:22:39 INFO - PROCESS | 3411 | RSS: Main: 284880896 02:22:39 INFO - PROCESS | 3411 | 02:25:42 INFO - PROCESS | 3411 | Cycle 1(1): loaded http://localhost:41517/tests/dromaeo/cssquery-ext.html (next: http://localhost:41517/tests/dromaeo/cssquery-jquery.html) 02:25:42 INFO - PROCESS | 3411 | RSS: Main: 237371392 02:25:42 INFO - PROCESS | 3411 | 02:28:44 INFO - PROCESS | 3411 | Cycle 1(1): loaded http://localhost:41517/tests/dromaeo/cssquery-jquery.html (next: http://localhost:41517/tests/dromaeo/cssquery-mootools.html) 02:28:45 INFO - PROCESS | 3411 | RSS: Main: 235405312 02:28:45 INFO - PROCESS | 3411 | 02:31:47 INFO - PROCESS | 3411 | Cycle 1(1): loaded http://localhost:41517/tests/dromaeo/cssquery-mootools.html (next: http://localhost:41517/tests/dromaeo/cssquery-prototype.html) 02:31:47 INFO - PROCESS | 3411 | RSS: Main: 228900864 02:31:47 INFO - PROCESS | 3411 | 02:34:50 INFO - PROCESS | 3411 | Cycle 1(1): loaded http://localhost:41517/tests/dromaeo/cssquery-prototype.html (next: http://localhost:41517/tests/dromaeo/cssquery-yui.html) 02:34:51 INFO - PROCESS | 3411 | RSS: Main: 217632768 02:34:51 INFO - PROCESS | 3411 | 02:37:53 INFO - PROCESS | 3411 | Cycle 1(1): loaded http://localhost:41517/tests/dromaeo/cssquery-yui.html (next: http://localhost:41517/tests/dromaeo/cssquery-dojo.html) 02:37:53 INFO - PROCESS | 3411 | RSS: Main: 268464128 02:37:53 INFO - PROCESS | 3411 | 02:37:53 INFO - PROCESS | 3411 | __start_tp_report 02:37:53 INFO - PROCESS | 3411 | _x_x_mozilla_page_load 02:37:53 INFO - PROCESS | 3411 | _x_x_mozilla_page_load_details 02:37:53 INFO - PROCESS | 3411 | |i|pagename|runs| 02:37:53 INFO - PROCESS | 3411 | |0;dojo.html;3150.653428122907;3174.2381828361185;3186.729128024118;3193.3772914281694;3229;5220.190870415092;5224.190250511177;5280.973595132024;5341.278927344807;5353.732313384335;5257.027449921768;5259.105951988162;5273.472652734725;5320.680759154454;5352.197170424438;4703.553162449562;4800.711957282557;4823.372961514998;4835.323054772335;4995.150824359867;4943.184374578191;4967.10592093423;4972.676776009548;4989.176785830352;4989.675671081386;4736.810527578893;4840.1529732296885;4840.927386089212;4866.46468888422;4887.413510378742;4825.613950883956;4831.05794370098;4836.395450568679;4902.558769710743;4948.935978764583;4872.902541949176;4873.049755297719;4876.902461950741;4895.437024742165;4909.140900342426;5028.270900719375;5077.5684066854365;5186.351706036746;5264.236685680555;5289.603279754034;5789.623674461147;5800.53595712342;5814.3313518945015;5815.302163740336;5820.301563812327;5807.157962095473;5814.912776308359;5815;5852.180694702745;5853.122031695238;5148.150555158394;5323.920141197885;5326.733663316857;5346.97326513365;5362.865928351784;5956.076808094752;5956.225690660273;5959.22530071088;5966.970165149233;5969.313528944227;5652.8869422611315;5664.065429204217;5707.972564938268;5708.600397972102;5715.428457154335;5509.614326997152;5662.207290979266;5668.178114173422;5668.603197776116;5726.341470730919;5717.542596592262;5717.914231286534;5721.599488035881;5724.8282551523525;5735.942640573541;5686.5735069869925;5707.429257074343;5739.483446489836;5740.45465680768;5749.68376739275;4238.660907127422;4269.87190384289;4301.548337424588;4302.118065796504;4340.565943405634;3901.1027463682944;3907.765534067965;3913.6477717005605;3937.2125574885135;3946.723729338919;5237.3453318335205;5426.593005524602;5439.075357189287;5440.455954404607;5443.891122177534;5656.179853921159;5703.771849126071;5704.942950570524;5752.511036561856;5767.682777447197;5403.972980135074;5412.377576578665;5413.160960051198;5414.18787181918;5415.268938693226;4737.502562230916;4828.565429111397;4917.164082106051;4928.310036594879;4966.205407134841;5865.472107510259;5869.031609784338;5871.324797648325;5877.794277200277;5933.554983376264;5707.058335374617;5745.080787074048;5748.338941021836;5751.309842818889;5759.90561793258;5648.57635677334;5783.624064435799;5841.591088623755;5941.940580594139;5944.583879128592;5609.214709940693;5609.579281553818;5611.38274789781;5619.904118696815;5662.85842853932;5652.463016013472;5686.886262274814;5693.3168019837885;5698.772049117989;5702;5378.247045413567;5484.972575137258;5504.834854954358;5505.394406615349;5557.972210138923;5525.530329921841;5568.55451563882;5571.804986825442;5576.1356989666665;5579.302587176603;5480.561555075505;5480.671159730429;5484.91772623403;5489.615726899079;5532.529734972572;5398.35219773614;5524;5533.972330138324;5559.221708960829;5607.971960140173;5487.670739755627;5489.149181876815;5492.533134683436;5500.174973753808;5536.612437129363 02:37:53 INFO - PROCESS | 3411 | |1;ext.html;590.6574186971557;622.4149299658321;634.2800920954718;644.7034364192474;670.9765158219464;43400;44961;45068;45081.999999999956;48520.514794852126;45701;46235.537644623626;46246.537534624644;46249.30626040612;46311.53688463115;8915.732528024153;9054.637814487445;9058.864117038249;9060.864087038699;9062.093790620951;8647.567621618926;8654.48073115612;8665.826683466326;8724.869126963069;8765.123487651215;8839.07189745074;8850.5132217728;8869.778255543599;8872.600732967032;8873.112688731107;8802.64789408423;8815.51514666693;8832.425892316978;8836.293096552261;8847.42491738035;8861.955690221572;8904.332175086894;8917.955410222972;8924.910750892537;8925.821483570357;42276.78861605672;42429.36355954663;42543.787281063705;42612.786936065124;42614.78692606517;3539.628339024391;3549.716022718176;3592.084018575247;3889.4165875118674;4066.3900414937702;31499.212519686964;32053.51919721206;32064.877729279364;32149.83925080383;32666.836665816518;4162.229987452331;4194.622483976456;4210.915781684349;4254.659627229845;4354.54277300882;330510.9999999976;330640;330865;331331;331574;51371.74314128405;56386;57194.7140264296;57994;58157.709211454516;56249.43750562524;61396.693016534635;61636.38363616396;61727.38272617306;61845;16318.836811631969;16552.503424897273;16567.668646627;16577.17114144288;16591.502254932373;10948.56205751761;12209.511619535297;12253.754924901452;12274.570390036306;12286.955608773354;16099.517014489584;16475.258613362428;16626;16751.832481675272;16797.412090577014;6775.424088952395;6865.27914568973;6866.656667166621;6884.173899132136;6984.126984126984;1044.4151275285712;1122.5004872831512;1176.923499972499;1206.3063738350484;1256.2274201366276;31761.523577146363;32309;32376.83811580927;32558.34883302368;32581.18547036343;1129.6215767717742;1159.2928313728619;1201.1531870031781;1224.7611715715527;1331.0682522234474;17321.30714771395;17329.48011559655;17485.7377139343;17499.12504374776;17502.824971750375;455539;457862;458080;458139;458328;57586.136207956915;58361.70819145877;58373;58438.12342814861;58525;1306.764782339188;1429.5425463851675;1434.0606902478894;1448.0732331307972;1454.4327712192253;31502.842485787423;33220.833895830365;33396.83301583477;33464.8287309943;33759.66240337565;49545.50454495553;50077.74961125171;50121.49878501168;50130.248046278604;50169.49830501794;331544;342529;342712;342891;343799;34840.47739283963;36141;40094;41644;42194.36708449437;47838.52161478341;48353.758231208616;48880;48914.75542622407;49246.75376623094;39693.404598931615;40327.193456130124;40534;40910;41503;39283.214335713696;39672.80163599164;40332.798336009306;40602.79698601488;41009.17981640291;40920.795396022826;41491;41682.374764379165;41743.58256417397;42008.78995605002;34897.47653785246;36201.45697814482;36782.816085919396;36914.26171476609;36920;27796.72203278022;27954.86022569874;28166;28265.71734282631;28332.858335708188 02:37:53 INFO - PROCESS | 3411 | |2;jquery.html;411.09558970265414;417.4614746976401;417.58450341909787;419.77961570175654;433.0408145956707;17133.485995420127;18296.90851545742;18878.528036799085;19695.015249237535;19812.90093549532;19056.904715476405;19193.712094318595;19530.804691953075;19895.104720287585;19910.303139390126;5558.722063896809;5613.382527921925;5735.369109397963;5737;5743.9712801435935;5542.362628297757;5587.720613969305;5629.549636029108;5657.405972372882;5673.8297851064535;5330.38700549436;5595.49640532352;5599.384067752544;5631.493165615094;5650.434956504338;5572.331320241557;5572.972135139318;5583.748731307061;5628.971855140719;5631.605787594849;5541.085720856054;5559.805406810742;5566.638168519034;5571.749271282802;5592.496675299202;13073.54242601514;13361.732765344736;13437.328133593379;13671.658208544866;13790.448382064706;2134.775848535897;2229.877356745378;2397.280815755284;2425.55127301448;2455.508898220363;9620.182284505825;9889.75275618108;10331.32846364984;11593.884061159364;15239.542813715607;1851.416803706828;1916.9648389869453;1947.123794292574;1951;1961.4017724593994;85670.57164714199;87176;90101;90398.54800725954;93010;11052.778944421067;11136.832947505794;11219.719507012227;11259.211855170222;11553.24903881245;15896.205189740467;16151.434699785452;16206.513804585882;16279;16292.185390730416;7927.8810817837775;8106.432549721463;8127.196117339508;8129.593520323961;8534.061253262136;7523.435742319348;7782.260685234894;7786.065672119382;8061.919380806117;8178.100408955011;4649.488556258809;4910.607151427948;4917.680350777188;4933.186024305949;5004.524570165829;1871.6350311689362;1874.9625007499774;1885.6228754249205;1910.1117980139302;1922.8750131241425;971.9076687714655;1000.9899901001043;1018.5314755212537;1091.8089334366455;1111.4998250787178;18189.54526136857;18830.71753923692;19134;19216.423507294803;19237.711434328496;1018.5823812236947;1021.6373187518473;1031.2729525684374;1043.436544266103;1083.0685610375072;10235.59057637701;10702;10935.726606834734;10960.28758130719;11027.393493357782;463218;467265;470125;474921;479128;93167;95223;95383;95640;95863;1032.4218437674776;1048.0253364371056;1048.926575139733;1057.434272664121;1070.7858428314212;16461.929974551615;16737.497875063767;16845.747313790056;17165.742513862053;17296.308147673953;29244;38929.221415572094;39208.80395598118;40144.39783403193;40898;55728.442715572324;56186;57775;57831.132533011194;57933.42066579449;7704.884426733492;7804.609769511615;8048.718294859652;8723.825523489622;8970.686025989058;9917.950410247902;11127.721806954567;13546.79679804784;13786.379612917643;14070.437182512585;11772.17594768358;11947.462364193458;12054.939725301318;12080.093992950387;12601.369931503204;11913.761724765283;11979.952120008547;12030.398480075786;12105.878941210474;12167.574134905235;12038.157328986887;12042.819357709466;12072.939635301767;12104.757904841677;12372.257664540157;7617.466777325534;8017.198280171936;8050.194980501903;8081.83836323282;8261.752147435587;8685.172458441568;11673.824892626788;11974.880251197377;12040.458179381774;12154.453049612612 02:37:53 INFO - PROCESS | 3411 | |3;mootools.html;1407.5355132806176;1535.1940231378528;1554.0675594643214;1764.3031002753912;1892.8674992750505;8424.705135320264;8781.253593444557;8816.471011739291;9075.727728168165;9115.635374585008;8705.695300664478;8828.205461508463;8835.779105522364;8852.911470885289;8862.246709029723;2294.403455101669;2319.22306027481;2328.871912044837;2330.5921463743775;2330.7902288794007;2269.387265438331;2277.294038847954;2305.7348404933396;2305.8616483010987;2308.1921327535397;2237.7202849643795;2284.7829456201716;2288.3706980580305;2301.666258392532;2306.9849266322763;2275.1240772302617;2308;2310.3992961829963;2310.5609934112463;2313.2944451942235;2246.2475070851283;2248.1794145136905;2249.3139592424304;2249.910003599854;2250.8086812620945;8459.873101903477;8715.782105447352;8816.382853200279;8887.15572020657;8889.911100889038;5069.822556210552;5160.793568257265;5171.095058364771;5175.715335656538;5202.817901373433;3354.3459025490038;3382;3425.657434256579;3524.6827785499427;3548.8580456781697;1137.3460260350248;1148.488922429519;1165.8834116588357;1171.308927732642;1173.2491205628405;12603.495860165582;12734.745305093938;12737.490500379881;12754.298513581749;12764.425600847891;4557.498675145731;4599.586037256596;4696.319033740088;4726.456457507362;4727.149113159664;9937.850932236022;9943.651972180938;9959.850602240973;9963.352382095141;9968.003199679973;5174.948250517522;5220.686758794409;5231.9476805232225;5259.815906443256;5271.947280527145;4357.520672725997;4760.690555113906;4902.338184345068;4995.475475075064;5008.824691135793;3758;3781.111438811877;3865.1303456722576;3888.183481468867;3897.8635747748694;1173.0791328806845;1184.0764203920955;1187.5368606243571;1196.389841181004;1219.4451524556482;834.8687528398932;873.3580818098692;984.532347135105;1007.9445630490393;1013.7566983923805;5754.338251101093;6004.0693692477735;6019.428154325332;6023.488003519662;6073.240844894388;465.75780594090884;467.3760529692898;467.7544289248172;490.98527044188734;657.0538424668462;3950.2099580083523;4168.437260969791;4352.281873490839;4506.954930450719;4614.838480653161;11187.272827266203;11389.829152562717;11560.248583842023;11606;11615.128865335133;11415.771684566429;11545.249558778652;11563.248388854698;11572.36352000648;11627.186096973131;7885.763427097197;8211.384146189059;8310.418270721113;8442.662293508314;8494.83010339802;5967.76128954837;6126;6179.752809887555;6227.782027629011;6313.936860631335;10248.48757562104;10250.179985600986;10257.743556410851;10528.578856845641;10529.57881684724;10573;10641.627543035956;10657.520411581652;10771;10781.676549703521;7778.183290754389;7839.529628222325;7896.881546776918;7953.125156232694;7976.042874855055;8551.315894728283;8570.65717371323;8600.182982616641;8611.13888611134;8626.051134375339;7702.268284512962;7906.130325664058;7911.881321780293;8027.438079334391;8031.59842007909;7561.546307221584;7736.7292144774665;7748.92251077482;7852.725154619561;7880.290773830154;6799.286074962058;7049.2950704931145;7072.929270707433;7155;7287.8542429152185;5358.33020872391;5382.784688612568;5391.16436952273;5403.243545903655;5440.211169380497;5304.151335786256;5428.7285635718845;5501.752421140978;5690.829275121753;5692.1461780733225 02:37:53 INFO - PROCESS | 3411 | |4;prototype.html;337.52072057678095;347.31405474188483;353.72056075700203;355.71720482216637;360.3729510651467;5109.233614957759;5253.343332083489;5343.866403339918;5349.5987800914945;5358.59810514212;5289.228108583629;5303.840884773454;5319.175527793189;5322.467753224676;5332.786688532454;4215.135897141086;4228.471441069866;4233.491980962273;4238.321868501041;4240;3979.8010099495054;4032.899177520556;4184.058586817957;4219.746815191083;4242.321228603424;4104.302268614328;4127.741038983109;4147.216696820366;4148.398482220076;4165.83336666533;4123.567025462328;4129.69027322952;4136.855210067633;4141.668666506672;4163.979180104096;3447.7069449096857;3701.9444708329397;3709.703223742094;3715.3498137825773;3725.3853114236117;5052.520010599024;5062.54437100659;5067.265246539268;5071.366079240095;5073.873153171164;4696.436427628672;4710.9635880106325;4711.481737008926;4728.219843725816;4730.516478848597;5274.709890955995;5493.890122197574;5497.45025497451;5518.475744804277;5549.445055494459;4012.61880121388;4024.3561030235314;4033.1530378620523;4034.132661477767;4037.596240375939;5768.384852241379;5810.941890581083;5813.215215945836;5828.795992140254;5846.181534585119;3853.6917046636204;4046.736962097454;4046.919061618751;4057;4088.7529303562105;4095.4266402703647;4097.918041639151;4108.856190033394;4123.958760412417;4127.215828992542;4694.155052090657;4697.30896877124;4702.130105930414;4709.105269998689;4710.387649605529;5256.395514515803;5538.147853855267;5539.9169012464845;5635.267415236075;5643.746031428595;1346.9461221551028;1396.4429660927992;1421.6161636358124;1428.6998831063681;1433.2976841347665;871.9433236839585;878.8198419324024;889.9510526920952;893.1648908270788;904.4618452021037;4592.517785632527;4651.116297899356;4685.320628508847;4691.835785747482;4828.444728856156;4942.57988071018;4967.726775027409;5008.347245409006;5059.392872855207;5069.518395752398;4480.686351955397;4740.056576803494;4743.549362810528;4756.429228492534;4758.881027974328;2666.346745047476;2670.572708366652;2687.9193624191307;2696.678627472524;2743.3553115017953;5071.87320316995;5178.352705911761;5202.401723801811;5238.5023422774775;5250.687328167958;4754.524547545287;5075.8935041957175;5131.923021154686;5144.582046438168;5148.4851514848215;5072.492750724898;5073.340465739504;5102.744862756847;5116.027954688598;5146.794128234904;4970.726610036482;4975.875603109951;4988.72562009093;4990.700557966534;5011.9976004798455;4661.137689527312;4883.267509873547;4889.828855990166;4929.186684197068;4931.002943807764;5102.79588816458;5109.8978020438635;5111.795528178831;5148.536631703165;5172.456892026434;3615.204654975901;3618.963810361968;3638.6725194732603;3651;3659.1583935694407;4740.407449068866;4894.241392584155;4917.729524876167;4921.261810728276;4960.900781984412;4803.070127222461;4815.446223684321;4846.36997190363;4850.975745121252;4856;4813.614910807197;4819.783109760139;4833.5499350195505;4843.1524483216;4861.562459378673;4576.146660602461;4781.737004464788;4787.252652781779;4806.543378379049;4813.026659069765;4850.951490485191;4858.465568787501;4861.1979023460735;4885.8045678172475;4889.875328674496;4712.764361781966;4747.477777444409;4800.567948884617;4804.663673542818;4805.207140821865 02:37:53 INFO - PROCESS | 3411 | |5;yui.html;830.8213734047181;849.5921957460419;855.1448551448551;856.5310492505353;887.1793590928389;13475.528356507511;14065.859341406596;14357.0667906586;14376.137431754076;14376.424943002281;14183.432662693505;14248.14511129333;14274.214918179496;14278.92860535696;14301.856981430183;620.8447888027993;644.1786721929531;644.2044075566695;644.3813938618931;685.8114018644875;826.5619221812449;834.5493433545878;840.8822764812932;842.2798507276283;843.2958479669484;709.5352544083607;882.7528292078229;885.8804061451686;886.6674996876171;887.7292425810125;721.6680327049537;725.9709611615529;858.219020691168;876.7676565710092;883.4169448164181;650.4568685147907;650.5706233885613;650.6909218121402;650.7917466410751;651.1437459740473;12314.13801033928;12439.440225189888;12442.626721198289;12442.937785311105;12459;13157.144785588906;13490.932545337211;13494.39275232607;13499.86500134996;13610.251436171007;12130.696732581668;12215.389230538438;12253.264804111783;12421.19262247951;12481.50073997039;4432.71187372823;4504.459464864204;4513.729176249435;4529.660275479319;4540.4551453825425;49286.26070608944;49550.75224623854;49694.254586180876;49773.50226497725;51778;10987.340759554452;11080.778384432266;11089.944550277198;11229.382383968798;11505.252158609663;19421.126049327813;19458.610827783366;19585;19677;20152.39542813718;8103.716369927024;8225.424220304521;8312.25189732927;8409.11704271055;8641.481511109354;4717.811287548459;4846.539578740014;4854.320395144682;4869;4930.408350997904;3281.0812972367776;3453.1194545390763;3459.0660521659515;3468.0289518934737;3476.461148521983;651.8728847874619;663.8871391863395;665.2981104934244;666.1340257665113;668.7692746002622;572.7279542217497;615.6367743031633;674.932506749331;692.1452006771633;723.0708539526788;8918.73243802687;9191.63233470667;9195.494247816434;9206.493642849708;9289.90710092904;678.1455366238575;685.2599192871687;685.8971154326891;695.2282965907838;705.153815421486;4304.859212308741;4318.913621727548;4357.150355680612;4357.607815296575;4416.447944006999;63248.36751632517;63912;64162.358376415636;64469.355306446334;64528.67735661292;46747.532524675;50549;54411.45588544064;54678.45321546813;57756;48814.26778598324;49156.50843491591;49860.75069624629;50047.24929126066;52032.73983630058;9057.820093226326;9108.908910910806;9122.498262595622;9141.5886285116;9181.770455738393;32722.181945451553;32862.83568582238;34016.14959626029;34064.31871362608;34773;40909.181816362914;44937;44986.55013449823;44994.77502612466;45028.09943801171;10245.897541024793;10247.487625618838;10617.946910265398;11980.58067967617;12334.75330493403;12736.87263127394;13188.142770720257;13349.199048057148;13389.062765606313;13425.597232083053;11656.592019279284;11982.64052078439;12096.092793040381;12722.491100356252;12828.807567886302;12394.256344619353;12478.251304921734;12506;12507.74984500323;12801.743965120831;11193.552257909592;11209.831852522055;11451.770964580828;11487;12304.938475307566;10136.341137826017;10528.105111065644;10561.207909406672;10646.307989980627;10908.890911090788;10480.947595261974;10899.237053406185;10900;10987.121030317396;11133.443327833738 02:37:53 INFO - PROCESS | 3411 | __end_tp_report 02:37:53 INFO - PROCESS | 3411 | __start_cc_report 02:37:53 INFO - PROCESS | 3411 | _x_x_mozilla_cycle_collect,1131 02:37:53 INFO - PROCESS | 3411 | __end_cc_report 02:37:53 INFO - PROCESS | 3411 | __startTimestamp1463045873695__endTimestamp 02:37:53 INFO - PROCESS | 3411 | 02:37:53 INFO - PROCESS | 3411 | ------- Summary: start ------- 02:37:53 INFO - PROCESS | 3411 | Number of tests: 6 02:37:53 INFO - PROCESS | 3411 | 02:37:53 INFO - PROCESS | 3411 | [#0] dojo.html Cycles:165 Average:5301.85 Median:5489.15 stddev:596.63 (10.9%) stddev-sans-first:574.10 02:37:53 INFO - PROCESS | 3411 | Values: 3150.7 3174.2 3186.7 3193.4 3229.0 5220.2 5224.2 5281.0 5341.3 5353.7 5257.0 5259.1 5273.5 5320.7 5352.2 4703.6 4800.7 4823.4 4835.3 4995.2 4943.2 4967.1 4972.7 4989.2 4989.7 4736.8 4840.2 4840.9 4866.5 4887.4 4825.6 4831.1 4836.4 4902.6 4948.9 4872.9 4873.0 4876.9 4895.4 4909.1 5028.3 5077.6 5186.4 5264.2 5289.6 5789.6 5800.5 5814.3 5815.3 5820.3 5807.2 5814.9 5815.0 5852.2 5853.1 5148.2 5323.9 5326.7 5347.0 5362.9 5956.1 5956.2 5959.2 5967.0 5969.3 5652.9 5664.1 5708.0 5708.6 5715.4 5509.6 5662.2 5668.2 5668.6 5726.3 5717.5 5717.9 5721.6 5724.8 5735.9 5686.6 5707.4 5739.5 5740.5 5749.7 4238.7 4269.9 4301.5 4302.1 4340.6 3901.1 3907.8 3913.6 3937.2 3946.7 5237.3 5426.6 5439.1 5440.5 5443.9 5656.2 5703.8 5704.9 5752.5 5767.7 5404.0 5412.4 5413.2 5414.2 5415.3 4737.5 4828.6 4917.2 4928.3 4966.2 5865.5 5869.0 5871.3 5877.8 5933.6 5707.1 5745.1 5748.3 5751.3 5759.9 5648.6 5783.6 5841.6 5941.9 5944.6 5609.2 5609.6 5611.4 5619.9 5662.9 5652.5 5686.9 5693.3 5698.8 5702.0 5378.2 5485.0 5504.8 5505.4 5558.0 5525.5 5568.6 5571.8 5576.1 5579.3 5480.6 5480.7 5484.9 5489.6 5532.5 5398.4 5524.0 5534.0 5559.2 5608.0 5487.7 5489.1 5492.5 5500.2 5536.6 02:37:53 INFO - PROCESS | 3411 | 02:37:53 INFO - PROCESS | 3411 | [#1] ext.html Cycles:180 Average:55175.99 Median:22903.11 stddev:100252.47 (437.7%) stddev-sans-first:100449.94 02:37:53 INFO - PROCESS | 3411 | Values: 590.7 622.4 634.3 644.7 671.0 43400.0 44961.0 45068.0 45082.0 48520.5 45701.0 46235.5 46246.5 46249.3 46311.5 8915.7 9054.6 9058.9 9060.9 9062.1 8647.6 8654.5 8665.8 8724.9 8765.1 8839.1 8850.5 8869.8 8872.6 8873.1 8802.6 8815.5 8832.4 8836.3 8847.4 8862.0 8904.3 8918.0 8924.9 8925.8 42276.8 42429.4 42543.8 42612.8 42614.8 3539.6 3549.7 3592.1 3889.4 4066.4 31499.2 32053.5 32064.9 32149.8 32666.8 4162.2 4194.6 4210.9 4254.7 4354.5 330511.0 330640.0 330865.0 331331.0 331574.0 51371.7 56386.0 57194.7 57994.0 58157.7 56249.4 61396.7 61636.4 61727.4 61845.0 16318.8 16552.5 16567.7 16577.2 16591.5 10948.6 12209.5 12253.8 12274.6 12287.0 16099.5 16475.3 16626.0 16751.8 16797.4 6775.4 6865.3 6866.7 6884.2 6984.1 1044.4 1122.5 1176.9 1206.3 1256.2 31761.5 32309.0 32376.8 32558.3 32581.2 1129.6 1159.3 1201.2 1224.8 1331.1 17321.3 17329.5 17485.7 17499.1 17502.8 455539.0 457862.0 458080.0 458139.0 458328.0 57586.1 58361.7 58373.0 58438.1 58525.0 1306.8 1429.5 1434.1 1448.1 1454.4 31502.8 33220.8 33396.8 33464.8 33759.7 49545.5 50077.7 50121.5 50130.2 50169.5 331544.0 342529.0 342712.0 342891.0 343799.0 34840.5 36141.0 40094.0 41644.0 42194.4 47838.5 48353.8 48880.0 48914.8 49246.8 39693.4 40327.2 40534.0 40910.0 41503.0 39283.2 39672.8 40332.8 40602.8 41009.2 40920.8 41491.0 41682.4 41743.6 42008.8 34897.5 36201.5 36782.8 36914.3 36920.0 27796.7 27954.9 28166.0 28265.7 28332.9 02:37:53 INFO - PROCESS | 3411 | 02:37:53 INFO - PROCESS | 3411 | [#2] jquery.html Cycles:180 Average:28390.63 Median:2182.33 stddev:78013.53 (3574.8%) stddev-sans-first:78204.09 02:37:53 INFO - PROCESS | 3411 | Values: 411.1 417.5 417.6 419.8 433.0 17133.5 18296.9 18878.5 19695.0 19812.9 19056.9 19193.7 19530.8 19895.1 19910.3 5558.7 5613.4 5735.4 5737.0 5744.0 5542.4 5587.7 5629.5 5657.4 5673.8 5330.4 5595.5 5599.4 5631.5 5650.4 5572.3 5573.0 5583.7 5629.0 5631.6 5541.1 5559.8 5566.6 5571.7 5592.5 13073.5 13361.7 13437.3 13671.7 13790.4 2134.8 2229.9 2397.3 2425.6 2455.5 9620.2 9889.8 10331.3 11593.9 15239.5 1851.4 1917.0 1947.1 1951.0 1961.4 85670.6 87176.0 90101.0 90398.5 93010.0 11052.8 11136.8 11219.7 11259.2 11553.2 15896.2 16151.4 16206.5 16279.0 16292.2 7927.9 8106.4 8127.2 8129.6 8534.1 7523.4 7782.3 7786.1 8061.9 8178.1 4649.5 4910.6 4917.7 4933.2 5004.5 1871.6 1875.0 1885.6 1910.1 1922.9 971.9 1001.0 1018.5 1091.8 1111.5 18189.5 18830.7 19134.0 19216.4 19237.7 1018.6 1021.6 1031.3 1043.4 1083.1 10235.6 10702.0 10935.7 10960.3 11027.4 463218.0 467265.0 470125.0 474921.0 479128.0 93167.0 95223.0 95383.0 95640.0 95863.0 1032.4 1048.0 1048.9 1057.4 1070.8 16461.9 16737.5 16845.7 17165.7 17296.3 29244.0 38929.2 39208.8 40144.4 40898.0 55728.4 56186.0 57775.0 57831.1 57933.4 7704.9 7804.6 8048.7 8723.8 8970.7 9918.0 11127.7 13546.8 13786.4 14070.4 11772.2 11947.5 12054.9 12080.1 12601.4 11913.8 11980.0 12030.4 12105.9 12167.6 12038.2 12042.8 12072.9 12104.8 12372.3 7617.5 8017.2 8050.2 8081.8 8261.8 8685.2 11673.8 11974.9 12040.5 12154.5 02:37:53 INFO - PROCESS | 3411 | 02:37:53 INFO - PROCESS | 3411 | [#3] mootools.html Cycles:180 Average:5890.15 Median:4743.92 stddev:3420.16 (72.1%) stddev-sans-first:3413.17 02:37:53 INFO - PROCESS | 3411 | Values: 1407.5 1535.2 1554.1 1764.3 1892.9 8424.7 8781.3 8816.5 9075.7 9115.6 8705.7 8828.2 8835.8 8852.9 8862.2 2294.4 2319.2 2328.9 2330.6 2330.8 2269.4 2277.3 2305.7 2305.9 2308.2 2237.7 2284.8 2288.4 2301.7 2307.0 2275.1 2308.0 2310.4 2310.6 2313.3 2246.2 2248.2 2249.3 2249.9 2250.8 8459.9 8715.8 8816.4 8887.2 8889.9 5069.8 5160.8 5171.1 5175.7 5202.8 3354.3 3382.0 3425.7 3524.7 3548.9 1137.3 1148.5 1165.9 1171.3 1173.2 12603.5 12734.7 12737.5 12754.3 12764.4 4557.5 4599.6 4696.3 4726.5 4727.1 9937.9 9943.7 9959.9 9963.4 9968.0 5174.9 5220.7 5231.9 5259.8 5271.9 4357.5 4760.7 4902.3 4995.5 5008.8 3758.0 3781.1 3865.1 3888.2 3897.9 1173.1 1184.1 1187.5 1196.4 1219.4 834.9 873.4 984.5 1007.9 1013.8 5754.3 6004.1 6019.4 6023.5 6073.2 465.8 467.4 467.8 491.0 657.1 3950.2 4168.4 4352.3 4507.0 4614.8 11187.3 11389.8 11560.2 11606.0 11615.1 11415.8 11545.2 11563.2 11572.4 11627.2 7885.8 8211.4 8310.4 8442.7 8494.8 5967.8 6126.0 6179.8 6227.8 6313.9 10248.5 10250.2 10257.7 10528.6 10529.6 10573.0 10641.6 10657.5 10771.0 10781.7 7778.2 7839.5 7896.9 7953.1 7976.0 8551.3 8570.7 8600.2 8611.1 8626.1 7702.3 7906.1 7911.9 8027.4 8031.6 7561.5 7736.7 7748.9 7852.7 7880.3 6799.3 7049.3 7072.9 7155.0 7287.9 5358.3 5382.8 5391.2 5403.2 5440.2 5304.2 5428.7 5501.8 5690.8 5692.1 02:37:53 INFO - PROCESS | 3411 | 02:37:53 INFO - PROCESS | 3411 | [#4] prototype.html Cycles:180 Average:4360.54 Median:4805.88 stddev:1220.76 (25.4%) stddev-sans-first:1186.25 02:37:53 INFO - PROCESS | 3411 | Values: 337.5 347.3 353.7 355.7 360.4 5109.2 5253.3 5343.9 5349.6 5358.6 5289.2 5303.8 5319.2 5322.5 5332.8 4215.1 4228.5 4233.5 4238.3 4240.0 3979.8 4032.9 4184.1 4219.7 4242.3 4104.3 4127.7 4147.2 4148.4 4165.8 4123.6 4129.7 4136.9 4141.7 4164.0 3447.7 3701.9 3709.7 3715.3 3725.4 5052.5 5062.5 5067.3 5071.4 5073.9 4696.4 4711.0 4711.5 4728.2 4730.5 5274.7 5493.9 5497.5 5518.5 5549.4 4012.6 4024.4 4033.2 4034.1 4037.6 5768.4 5810.9 5813.2 5828.8 5846.2 3853.7 4046.7 4046.9 4057.0 4088.8 4095.4 4097.9 4108.9 4124.0 4127.2 4694.2 4697.3 4702.1 4709.1 4710.4 5256.4 5538.1 5539.9 5635.3 5643.7 1346.9 1396.4 1421.6 1428.7 1433.3 871.9 878.8 890.0 893.2 904.5 4592.5 4651.1 4685.3 4691.8 4828.4 4942.6 4967.7 5008.3 5059.4 5069.5 4480.7 4740.1 4743.5 4756.4 4758.9 2666.3 2670.6 2687.9 2696.7 2743.4 5071.9 5178.4 5202.4 5238.5 5250.7 4754.5 5075.9 5131.9 5144.6 5148.5 5072.5 5073.3 5102.7 5116.0 5146.8 4970.7 4975.9 4988.7 4990.7 5012.0 4661.1 4883.3 4889.8 4929.2 4931.0 5102.8 5109.9 5111.8 5148.5 5172.5 3615.2 3619.0 3638.7 3651.0 3659.2 4740.4 4894.2 4917.7 4921.3 4960.9 4803.1 4815.4 4846.4 4851.0 4856.0 4813.6 4819.8 4833.5 4843.2 4861.6 4576.1 4781.7 4787.3 4806.5 4813.0 4851.0 4858.5 4861.2 4885.8 4889.9 4712.8 4747.5 4800.6 4804.7 4805.2 02:37:53 INFO - PROCESS | 3411 | 02:37:53 INFO - PROCESS | 3411 | [#5] yui.html Cycles:180 Average:14572.83 Median:24766.28 stddev:16748.29 (67.6%) stddev-sans-first:16763.48 02:37:53 INFO - PROCESS | 3411 | Values: 830.8 849.6 855.1 856.5 887.2 13475.5 14065.9 14357.1 14376.1 14376.4 14183.4 14248.1 14274.2 14278.9 14301.9 620.8 644.2 644.2 644.4 685.8 826.6 834.5 840.9 842.3 843.3 709.5 882.8 885.9 886.7 887.7 721.7 726.0 858.2 876.8 883.4 650.5 650.6 650.7 650.8 651.1 12314.1 12439.4 12442.6 12442.9 12459.0 13157.1 13490.9 13494.4 13499.9 13610.3 12130.7 12215.4 12253.3 12421.2 12481.5 4432.7 4504.5 4513.7 4529.7 4540.5 49286.3 49550.8 49694.3 49773.5 51778.0 10987.3 11080.8 11089.9 11229.4 11505.3 19421.1 19458.6 19585.0 19677.0 20152.4 8103.7 8225.4 8312.3 8409.1 8641.5 4717.8 4846.5 4854.3 4869.0 4930.4 3281.1 3453.1 3459.1 3468.0 3476.5 651.9 663.9 665.3 666.1 668.8 572.7 615.6 674.9 692.1 723.1 8918.7 9191.6 9195.5 9206.5 9289.9 678.1 685.3 685.9 695.2 705.2 4304.9 4318.9 4357.2 4357.6 4416.4 63248.4 63912.0 64162.4 64469.4 64528.7 46747.5 50549.0 54411.5 54678.5 57756.0 48814.3 49156.5 49860.8 50047.2 52032.7 9057.8 9108.9 9122.5 9141.6 9181.8 32722.2 32862.8 34016.1 34064.3 34773.0 40909.2 44937.0 44986.6 44994.8 45028.1 10245.9 10247.5 10617.9 11980.6 12334.8 12736.9 13188.1 13349.2 13389.1 13425.6 11656.6 11982.6 12096.1 12722.5 12828.8 12394.3 12478.3 12506.0 12507.7 12801.7 11193.6 11209.8 11451.8 11487.0 12304.9 10136.3 10528.1 10561.2 10646.3 10908.9 10480.9 10899.2 10900.0 10987.1 11133.4 02:37:53 INFO - PROCESS | 3411 | -------- Summary: end -------- 02:37:53 INFO - PROCESS | 3411 | 02:37:54 INFO - TEST-INFO | 3411: exit 0 02:37:54 INFO - TEST-OK | dromaeo_css | took 1113572ms 02:37:54 INFO - TEST-START | kraken 02:37:54 INFO - operating with platform_type : linux_ 02:37:54 INFO - Initialising browser for kraken test... 02:37:54 INFO - TEST-INFO | started process 3628 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpM0p_Op/profile http://localhost:41517/getInfo.html) 02:37:58 INFO - PROCESS | 3628 | __metrics Screen width/height:1600/1200 02:37:58 INFO - PROCESS | 3628 | colorDepth:24 02:37:58 INFO - PROCESS | 3628 | Browser inner width/height: 1024/697 02:37:58 INFO - PROCESS | 3628 | __metrics 02:37:59 ERROR - PROCESS | 3628 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:38:00 INFO - TEST-INFO | 3628: exit 0 02:38:00 INFO - Browser initialized. 02:38:00 INFO - Running cycle 1/1 for kraken test... 02:38:00 INFO - TEST-INFO | started process 3712 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpM0p_Op/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:38:12 INFO - PROCESS | 3712 | RSS: Main: 152629248 02:38:12 INFO - PROCESS | 3712 | 02:38:14 INFO - PROCESS | 3712 | Cycle 1(1): loaded http://localhost:41517/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:41517/tests/kraken/driver.html?testName=audio-beat-detection) 02:38:15 INFO - PROCESS | 3712 | RSS: Main: 208551936 02:38:15 INFO - PROCESS | 3712 | 02:38:18 INFO - PROCESS | 3712 | Cycle 1(1): loaded http://localhost:41517/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:41517/tests/kraken/driver.html?testName=audio-dft) 02:38:18 INFO - PROCESS | 3712 | RSS: Main: 197611520 02:38:18 INFO - PROCESS | 3712 | 02:38:22 INFO - PROCESS | 3712 | Cycle 1(1): loaded http://localhost:41517/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:41517/tests/kraken/driver.html?testName=audio-fft) 02:38:22 INFO - PROCESS | 3712 | RSS: Main: 265154560 02:38:22 INFO - PROCESS | 3712 | 02:38:24 INFO - PROCESS | 3712 | Cycle 1(1): loaded http://localhost:41517/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:41517/tests/kraken/driver.html?testName=audio-oscillator) 02:38:25 INFO - PROCESS | 3712 | RSS: Main: 174514176 02:38:25 INFO - PROCESS | 3712 | 02:38:27 INFO - PROCESS | 3712 | Cycle 1(1): loaded http://localhost:41517/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:41517/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:38:28 INFO - PROCESS | 3712 | RSS: Main: 179052544 02:38:28 INFO - PROCESS | 3712 | 02:38:31 INFO - PROCESS | 3712 | Cycle 1(1): loaded http://localhost:41517/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:41517/tests/kraken/driver.html?testName=imaging-darkroom) 02:38:32 INFO - PROCESS | 3712 | RSS: Main: 214380544 02:38:32 INFO - PROCESS | 3712 | 02:38:35 INFO - PROCESS | 3712 | Cycle 1(1): loaded http://localhost:41517/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:41517/tests/kraken/driver.html?testName=imaging-desaturate) 02:38:36 INFO - PROCESS | 3712 | RSS: Main: 212561920 02:38:36 INFO - PROCESS | 3712 | 02:38:39 INFO - PROCESS | 3712 | Cycle 1(1): loaded http://localhost:41517/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:41517/tests/kraken/driver.html?testName=json-parse-financial) 02:38:39 INFO - PROCESS | 3712 | RSS: Main: 213012480 02:38:39 INFO - PROCESS | 3712 | 02:38:41 INFO - PROCESS | 3712 | Cycle 1(1): loaded http://localhost:41517/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:41517/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:38:41 INFO - PROCESS | 3712 | RSS: Main: 202412032 02:38:41 INFO - PROCESS | 3712 | 02:38:43 INFO - PROCESS | 3712 | Cycle 1(1): loaded http://localhost:41517/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:41517/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:38:44 INFO - PROCESS | 3712 | RSS: Main: 205807616 02:38:44 INFO - PROCESS | 3712 | 02:38:46 INFO - PROCESS | 3712 | Cycle 1(1): loaded http://localhost:41517/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:41517/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:38:47 INFO - PROCESS | 3712 | RSS: Main: 214269952 02:38:47 INFO - PROCESS | 3712 | 02:38:49 INFO - PROCESS | 3712 | Cycle 1(1): loaded http://localhost:41517/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:41517/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:38:50 INFO - PROCESS | 3712 | RSS: Main: 221732864 02:38:50 INFO - PROCESS | 3712 | 02:38:53 INFO - PROCESS | 3712 | Cycle 1(1): loaded http://localhost:41517/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:41517/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:38:53 INFO - PROCESS | 3712 | RSS: Main: 217202688 02:38:53 INFO - PROCESS | 3712 | 02:38:55 INFO - PROCESS | 3712 | Cycle 1(1): loaded http://localhost:41517/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:41517/tests/kraken/driver.html?testName=ai-astar) 02:38:55 INFO - PROCESS | 3712 | RSS: Main: 263081984 02:38:55 INFO - PROCESS | 3712 | 02:38:55 INFO - PROCESS | 3712 | __start_tp_report 02:38:55 INFO - PROCESS | 3712 | _x_x_mozilla_page_load 02:38:55 INFO - PROCESS | 3712 | _x_x_mozilla_page_load_details 02:38:55 INFO - PROCESS | 3712 | |i|pagename|runs| 02:38:55 INFO - PROCESS | 3712 | |0;ai-astar;112;107;110;110;110;110;109;110;109;110 02:38:55 INFO - PROCESS | 3712 | |1;audio-beat-detection;156;132;150;204;132;155;162;128;181;125 02:38:55 INFO - PROCESS | 3712 | |2;audio-dft;160;162;160;159;161;161;162;161;163;160 02:38:55 INFO - PROCESS | 3712 | |3;audio-fft;75;75;75;147;75;75;75;76;130;76 02:38:55 INFO - PROCESS | 3712 | |4;audio-oscillator;101;91;152;93;91;91;123;92;92;126 02:38:55 INFO - PROCESS | 3712 | |5;imaging-gaussian-blur;117;117;117;117;117;116;116;117;116;117 02:38:55 INFO - PROCESS | 3712 | |6;imaging-darkroom;146;146;146;145;145;146;145;146;146;145 02:38:55 INFO - PROCESS | 3712 | |7;imaging-desaturate;95;95;94;94;96;95;95;96;95;94 02:38:55 INFO - PROCESS | 3712 | |8;json-parse-financial;57;58;58;56;56;57;57;109;55;55 02:38:55 INFO - PROCESS | 3712 | |9;json-stringify-tinderbox;59;59;58;59;59;59;58;59;59;59 02:38:55 INFO - PROCESS | 3712 | |10;stanford-crypto-aes;79;80;79;78;78;80;80;79;79;79 02:38:55 INFO - PROCESS | 3712 | |11;stanford-crypto-ccm;124;126;130;123;121;119;121;122;236;126 02:38:55 INFO - PROCESS | 3712 | |12;stanford-crypto-pbkdf2;170;169;166;168;167;169;167;169;168;164 02:38:55 INFO - PROCESS | 3712 | |13;stanford-crypto-sha256-iterative;64;65;65;63;64;66;62;63;64;62 02:38:55 INFO - PROCESS | 3712 | __end_tp_report 02:38:55 INFO - PROCESS | 3712 | __start_cc_report 02:38:55 INFO - PROCESS | 3712 | _x_x_mozilla_cycle_collect,1812 02:38:55 INFO - PROCESS | 3712 | __end_cc_report 02:38:55 INFO - PROCESS | 3712 | __startTimestamp1463045935871__endTimestamp 02:38:55 INFO - PROCESS | 3712 | 02:38:55 INFO - PROCESS | 3712 | ------- Summary: start ------- 02:38:55 INFO - PROCESS | 3712 | Number of tests: 14 02:38:55 INFO - PROCESS | 3712 | 02:38:55 INFO - PROCESS | 3712 | [#0] ai-astar Cycles:10 Average:109.70 Median:110.00 stddev:1.25 (1.1%) stddev-sans-first:1.01 02:38:55 INFO - PROCESS | 3712 | Values: 112.0 107.0 110.0 110.0 110.0 110.0 109.0 110.0 109.0 110.0 02:38:55 INFO - PROCESS | 3712 | 02:38:55 INFO - PROCESS | 3712 | [#1] audio-beat-detection Cycles:10 Average:152.50 Median:155.50 stddev:25.33 (16.3%) stddev-sans-first:26.84 02:38:55 INFO - PROCESS | 3712 | Values: 156.0 132.0 150.0 204.0 132.0 155.0 162.0 128.0 181.0 125.0 02:38:55 INFO - PROCESS | 3712 | 02:38:55 INFO - PROCESS | 3712 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:1.20 (0.7%) stddev-sans-first:1.22 02:38:55 INFO - PROCESS | 3712 | Values: 160.0 162.0 160.0 159.0 161.0 161.0 162.0 161.0 163.0 160.0 02:38:55 INFO - PROCESS | 3712 | 02:38:55 INFO - PROCESS | 3712 | [#3] audio-fft Cycles:10 Average:87.90 Median:75.00 stddev:26.97 (36.0%) stddev-sans-first:28.20 02:38:55 INFO - PROCESS | 3712 | Values: 75.0 75.0 75.0 147.0 75.0 75.0 75.0 76.0 130.0 76.0 02:38:55 INFO - PROCESS | 3712 | 02:38:55 INFO - PROCESS | 3712 | [#4] audio-oscillator Cycles:10 Average:105.20 Median:91.00 stddev:21.24 (23.3%) stddev-sans-first:22.47 02:38:55 INFO - PROCESS | 3712 | Values: 101.0 91.0 152.0 93.0 91.0 91.0 123.0 92.0 92.0 126.0 02:38:55 INFO - PROCESS | 3712 | 02:38:55 INFO - PROCESS | 3712 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 02:38:55 INFO - PROCESS | 3712 | Values: 117.0 117.0 117.0 117.0 117.0 116.0 116.0 117.0 116.0 117.0 02:38:55 INFO - PROCESS | 3712 | 02:38:55 INFO - PROCESS | 3712 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 02:38:55 INFO - PROCESS | 3712 | Values: 146.0 146.0 146.0 145.0 145.0 146.0 145.0 146.0 146.0 145.0 02:38:55 INFO - PROCESS | 3712 | 02:38:55 INFO - PROCESS | 3712 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.74 (0.8%) stddev-sans-first:0.78 02:38:55 INFO - PROCESS | 3712 | Values: 95.0 95.0 94.0 94.0 96.0 95.0 95.0 96.0 95.0 94.0 02:38:55 INFO - PROCESS | 3712 | 02:38:55 INFO - PROCESS | 3712 | [#8] json-parse-financial Cycles:10 Average:61.80 Median:57.00 stddev:16.62 (29.2%) stddev-sans-first:17.54 02:38:55 INFO - PROCESS | 3712 | Values: 57.0 58.0 58.0 56.0 56.0 57.0 57.0 109.0 55.0 55.0 02:38:55 INFO - PROCESS | 3712 | 02:38:55 INFO - PROCESS | 3712 | [#9] json-stringify-tinderbox Cycles:10 Average:58.80 Median:59.00 stddev:0.42 (0.7%) stddev-sans-first:0.44 02:38:55 INFO - PROCESS | 3712 | Values: 59.0 59.0 58.0 59.0 59.0 59.0 58.0 59.0 59.0 59.0 02:38:55 INFO - PROCESS | 3712 | 02:38:55 INFO - PROCESS | 3712 | [#10] stanford-crypto-aes Cycles:10 Average:79.10 Median:79.00 stddev:0.74 (0.9%) stddev-sans-first:0.78 02:38:55 INFO - PROCESS | 3712 | Values: 79.0 80.0 79.0 78.0 78.0 80.0 80.0 79.0 79.0 79.0 02:38:55 INFO - PROCESS | 3712 | 02:38:55 INFO - PROCESS | 3712 | [#11] stanford-crypto-ccm Cycles:10 Average:134.80 Median:125.00 stddev:35.70 (28.6%) stddev-sans-first:37.65 02:38:55 INFO - PROCESS | 3712 | Values: 124.0 126.0 130.0 123.0 121.0 119.0 121.0 122.0 236.0 126.0 02:38:55 INFO - PROCESS | 3712 | 02:38:55 INFO - PROCESS | 3712 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.70 Median:168.50 stddev:1.77 (1.0%) stddev-sans-first:1.67 02:38:55 INFO - PROCESS | 3712 | Values: 170.0 169.0 166.0 168.0 167.0 169.0 167.0 169.0 168.0 164.0 02:38:55 INFO - PROCESS | 3712 | 02:38:55 INFO - PROCESS | 3712 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.80 Median:64.00 stddev:1.32 (2.1%) stddev-sans-first:1.39 02:38:55 INFO - PROCESS | 3712 | Values: 64.0 65.0 65.0 63.0 64.0 66.0 62.0 63.0 64.0 62.0 02:38:55 INFO - PROCESS | 3712 | -------- Summary: end -------- 02:38:55 INFO - PROCESS | 3712 | 02:38:56 INFO - TEST-INFO | 3712: exit 0 02:38:56 INFO - TEST-OK | kraken | took 62377ms 02:38:56 INFO - SUITE-END | took 1175s 02:38:57 INFO - Completed test suite (00:19:36) 02:38:57 INFO - javascript benchmark 02:38:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3150.653428122907, 3174.2381828361185, 3186.729128024118, 3193.3772914281694, 3229.0, 5220.190870415092, 5224.190250511177, 5280.973595132024, 5341.278927344807, 5353.732313384335, 5257.027449921768, 5259.105951988162, 5273.472652734725, 5320.680759154454, 5352.197170424438, 4703.553162449562, 4800.711957282557, 4823.372961514998, 4835.323054772335, 4995.150824359867, 4943.184374578191, 4967.10592093423, 4972.676776009548, 4989.176785830352, 4989.675671081386, 4736.810527578893, 4840.1529732296885, 4840.927386089212, 4866.46468888422, 4887.413510378742, 4825.613950883956, 4831.05794370098, 4836.395450568679, 4902.558769710743, 4948.935978764583, 4872.902541949176, 4873.049755297719, 4876.902461950741, 4895.437024742165, 4909.140900342426, 5028.270900719375, 5077.5684066854365, 5186.351706036746, 5264.236685680555, 5289.603279754034, 5789.623674461147, 5800.53595712342, 5814.3313518945015, 5815.302163740336, 5820.301563812327, 5807.157962095473, 5814.912776308359, 5815.0, 5852.180694702745, 5853.122031695238, 5148.150555158394, 5323.920141197885, 5326.733663316857, 5346.97326513365, 5362.865928351784, 5956.076808094752, 5956.225690660273, 5959.22530071088, 5966.970165149233, 5969.313528944227, 5652.8869422611315, 5664.065429204217, 5707.972564938268, 5708.600397972102, 5715.428457154335, 5509.614326997152, 5662.207290979266, 5668.178114173422, 5668.603197776116, 5726.341470730919, 5717.542596592262, 5717.914231286534, 5721.599488035881, 5724.8282551523525, 5735.942640573541, 5686.5735069869925, 5707.429257074343, 5739.483446489836, 5740.45465680768, 5749.68376739275, 4238.660907127422, 4269.87190384289, 4301.548337424588, 4302.118065796504, 4340.565943405634, 3901.1027463682944, 3907.765534067965, 3913.6477717005605, 3937.2125574885135, 3946.723729338919, 5237.3453318335205, 5426.593005524602, 5439.075357189287, 5440.455954404607, 5443.891122177534, 5656.179853921159, 5703.771849126071, 5704.942950570524, 5752.511036561856, 5767.682777447197, 5403.972980135074, 5412.377576578665, 5413.160960051198, 5414.18787181918, 5415.268938693226, 4737.502562230916, 4828.565429111397, 4917.164082106051, 4928.310036594879, 4966.205407134841, 5865.472107510259, 5869.031609784338, 5871.324797648325, 5877.794277200277, 5933.554983376264, 5707.058335374617, 5745.080787074048, 5748.338941021836, 5751.309842818889, 5759.90561793258, 5648.57635677334, 5783.624064435799, 5841.591088623755, 5941.940580594139, 5944.583879128592, 5609.214709940693, 5609.579281553818, 5611.38274789781, 5619.904118696815, 5662.85842853932, 5652.463016013472, 5686.886262274814, 5693.3168019837885, 5698.772049117989, 5702.0, 5378.247045413567, 5484.972575137258, 5504.834854954358, 5505.394406615349, 5557.972210138923, 5525.530329921841, 5568.55451563882, 5571.804986825442, 5576.1356989666665, 5579.302587176603, 5480.561555075505, 5480.671159730429, 5484.91772623403, 5489.615726899079, 5532.529734972572, 5398.35219773614, 5524.0, 5533.972330138324, 5559.221708960829, 5607.971960140173, 5487.670739755627, 5489.149181876815, 5492.533134683436, 5500.174973753808, 5536.612437129363], "value": 5262.6627730902, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.6574186971557, 622.4149299658321, 634.2800920954718, 644.7034364192474, 670.9765158219464, 43400.0, 44961.0, 45068.0, 45081.999999999956, 48520.514794852126, 45701.0, 46235.537644623626, 46246.537534624644, 46249.30626040612, 46311.53688463115, 8915.732528024153, 9054.637814487445, 9058.864117038249, 9060.864087038699, 9062.093790620951, 8647.567621618926, 8654.48073115612, 8665.826683466326, 8724.869126963069, 8765.123487651215, 8839.07189745074, 8850.5132217728, 8869.778255543599, 8872.600732967032, 8873.112688731107, 8802.64789408423, 8815.51514666693, 8832.425892316978, 8836.293096552261, 8847.42491738035, 8861.955690221572, 8904.332175086894, 8917.955410222972, 8924.910750892537, 8925.821483570357, 42276.78861605672, 42429.36355954663, 42543.787281063705, 42612.786936065124, 42614.78692606517, 3539.628339024391, 3549.716022718176, 3592.084018575247, 3889.4165875118674, 4066.3900414937702, 31499.212519686964, 32053.51919721206, 32064.877729279364, 32149.83925080383, 32666.836665816518, 4162.229987452331, 4194.622483976456, 4210.915781684349, 4254.659627229845, 4354.54277300882, 330510.9999999976, 330640.0, 330865.0, 331331.0, 331574.0, 51371.74314128405, 56386.0, 57194.7140264296, 57994.0, 58157.709211454516, 56249.43750562524, 61396.693016534635, 61636.38363616396, 61727.38272617306, 61845.0, 16318.836811631969, 16552.503424897273, 16567.668646627, 16577.17114144288, 16591.502254932373, 10948.56205751761, 12209.511619535297, 12253.754924901452, 12274.570390036306, 12286.955608773354, 16099.517014489584, 16475.258613362428, 16626.0, 16751.832481675272, 16797.412090577014, 6775.424088952395, 6865.27914568973, 6866.656667166621, 6884.173899132136, 6984.126984126984, 1044.4151275285712, 1122.5004872831512, 1176.923499972499, 1206.3063738350484, 1256.2274201366276, 31761.523577146363, 32309.0, 32376.83811580927, 32558.34883302368, 32581.18547036343, 1129.6215767717742, 1159.2928313728619, 1201.1531870031781, 1224.7611715715527, 1331.0682522234474, 17321.30714771395, 17329.48011559655, 17485.7377139343, 17499.12504374776, 17502.824971750375, 455539.0, 457862.0, 458080.0, 458139.0, 458328.0, 57586.136207956915, 58361.70819145877, 58373.0, 58438.12342814861, 58525.0, 1306.764782339188, 1429.5425463851675, 1434.0606902478894, 1448.0732331307972, 1454.4327712192253, 31502.842485787423, 33220.833895830365, 33396.83301583477, 33464.8287309943, 33759.66240337565, 49545.50454495553, 50077.74961125171, 50121.49878501168, 50130.248046278604, 50169.49830501794, 331544.0, 342529.0, 342712.0, 342891.0, 343799.0, 34840.47739283963, 36141.0, 40094.0, 41644.0, 42194.36708449437, 47838.52161478341, 48353.758231208616, 48880.0, 48914.75542622407, 49246.75376623094, 39693.404598931615, 40327.193456130124, 40534.0, 40910.0, 41503.0, 39283.214335713696, 39672.80163599164, 40332.798336009306, 40602.79698601488, 41009.17981640291, 40920.795396022826, 41491.0, 41682.374764379165, 41743.58256417397, 42008.78995605002, 34897.47653785246, 36201.45697814482, 36782.816085919396, 36914.26171476609, 36920.0, 27796.72203278022, 27954.86022569874, 28166.0, 28265.71734282631, 28332.858335708188], "value": 20136.992615726336, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [411.09558970265414, 417.4614746976401, 417.58450341909787, 419.77961570175654, 433.0408145956707, 17133.485995420127, 18296.90851545742, 18878.528036799085, 19695.015249237535, 19812.90093549532, 19056.904715476405, 19193.712094318595, 19530.804691953075, 19895.104720287585, 19910.303139390126, 5558.722063896809, 5613.382527921925, 5735.369109397963, 5737.0, 5743.9712801435935, 5542.362628297757, 5587.720613969305, 5629.549636029108, 5657.405972372882, 5673.8297851064535, 5330.38700549436, 5595.49640532352, 5599.384067752544, 5631.493165615094, 5650.434956504338, 5572.331320241557, 5572.972135139318, 5583.748731307061, 5628.971855140719, 5631.605787594849, 5541.085720856054, 5559.805406810742, 5566.638168519034, 5571.749271282802, 5592.496675299202, 13073.54242601514, 13361.732765344736, 13437.328133593379, 13671.658208544866, 13790.448382064706, 2134.775848535897, 2229.877356745378, 2397.280815755284, 2425.55127301448, 2455.508898220363, 9620.182284505825, 9889.75275618108, 10331.32846364984, 11593.884061159364, 15239.542813715607, 1851.416803706828, 1916.9648389869453, 1947.123794292574, 1951.0, 1961.4017724593994, 85670.57164714199, 87176.0, 90101.0, 90398.54800725954, 93010.0, 11052.778944421067, 11136.832947505794, 11219.719507012227, 11259.211855170222, 11553.24903881245, 15896.205189740467, 16151.434699785452, 16206.513804585882, 16279.0, 16292.185390730416, 7927.8810817837775, 8106.432549721463, 8127.196117339508, 8129.593520323961, 8534.061253262136, 7523.435742319348, 7782.260685234894, 7786.065672119382, 8061.919380806117, 8178.100408955011, 4649.488556258809, 4910.607151427948, 4917.680350777188, 4933.186024305949, 5004.524570165829, 1871.6350311689362, 1874.9625007499774, 1885.6228754249205, 1910.1117980139302, 1922.8750131241425, 971.9076687714655, 1000.9899901001043, 1018.5314755212537, 1091.8089334366455, 1111.4998250787178, 18189.54526136857, 18830.71753923692, 19134.0, 19216.423507294803, 19237.711434328496, 1018.5823812236947, 1021.6373187518473, 1031.2729525684374, 1043.436544266103, 1083.0685610375072, 10235.59057637701, 10702.0, 10935.726606834734, 10960.28758130719, 11027.393493357782, 463218.0, 467265.0, 470125.0, 474921.0, 479128.0, 93167.0, 95223.0, 95383.0, 95640.0, 95863.0, 1032.4218437674776, 1048.0253364371056, 1048.926575139733, 1057.434272664121, 1070.7858428314212, 16461.929974551615, 16737.497875063767, 16845.747313790056, 17165.742513862053, 17296.308147673953, 29244.0, 38929.221415572094, 39208.80395598118, 40144.39783403193, 40898.0, 55728.442715572324, 56186.0, 57775.0, 57831.132533011194, 57933.42066579449, 7704.884426733492, 7804.609769511615, 8048.718294859652, 8723.825523489622, 8970.686025989058, 9917.950410247902, 11127.721806954567, 13546.79679804784, 13786.379612917643, 14070.437182512585, 11772.17594768358, 11947.462364193458, 12054.939725301318, 12080.093992950387, 12601.369931503204, 11913.761724765283, 11979.952120008547, 12030.398480075786, 12105.878941210474, 12167.574134905235, 12038.157328986887, 12042.819357709466, 12072.939635301767, 12104.757904841677, 12372.257664540157, 7617.466777325534, 8017.198280171936, 8050.194980501903, 8081.83836323282, 8261.752147435587, 8685.172458441568, 11673.824892626788, 11974.880251197377, 12040.458179381774, 12154.453049612612], "value": 9110.503867404957, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1407.5355132806176, 1535.1940231378528, 1554.0675594643214, 1764.3031002753912, 1892.8674992750505, 8424.705135320264, 8781.253593444557, 8816.471011739291, 9075.727728168165, 9115.635374585008, 8705.695300664478, 8828.205461508463, 8835.779105522364, 8852.911470885289, 8862.246709029723, 2294.403455101669, 2319.22306027481, 2328.871912044837, 2330.5921463743775, 2330.7902288794007, 2269.387265438331, 2277.294038847954, 2305.7348404933396, 2305.8616483010987, 2308.1921327535397, 2237.7202849643795, 2284.7829456201716, 2288.3706980580305, 2301.666258392532, 2306.9849266322763, 2275.1240772302617, 2308.0, 2310.3992961829963, 2310.5609934112463, 2313.2944451942235, 2246.2475070851283, 2248.1794145136905, 2249.3139592424304, 2249.910003599854, 2250.8086812620945, 8459.873101903477, 8715.782105447352, 8816.382853200279, 8887.15572020657, 8889.911100889038, 5069.822556210552, 5160.793568257265, 5171.095058364771, 5175.715335656538, 5202.817901373433, 3354.3459025490038, 3382.0, 3425.657434256579, 3524.6827785499427, 3548.8580456781697, 1137.3460260350248, 1148.488922429519, 1165.8834116588357, 1171.308927732642, 1173.2491205628405, 12603.495860165582, 12734.745305093938, 12737.490500379881, 12754.298513581749, 12764.425600847891, 4557.498675145731, 4599.586037256596, 4696.319033740088, 4726.456457507362, 4727.149113159664, 9937.850932236022, 9943.651972180938, 9959.850602240973, 9963.352382095141, 9968.003199679973, 5174.948250517522, 5220.686758794409, 5231.9476805232225, 5259.815906443256, 5271.947280527145, 4357.520672725997, 4760.690555113906, 4902.338184345068, 4995.475475075064, 5008.824691135793, 3758.0, 3781.111438811877, 3865.1303456722576, 3888.183481468867, 3897.8635747748694, 1173.0791328806845, 1184.0764203920955, 1187.5368606243571, 1196.389841181004, 1219.4451524556482, 834.8687528398932, 873.3580818098692, 984.532347135105, 1007.9445630490393, 1013.7566983923805, 5754.338251101093, 6004.0693692477735, 6019.428154325332, 6023.488003519662, 6073.240844894388, 465.75780594090884, 467.3760529692898, 467.7544289248172, 490.98527044188734, 657.0538424668462, 3950.2099580083523, 4168.437260969791, 4352.281873490839, 4506.954930450719, 4614.838480653161, 11187.272827266203, 11389.829152562717, 11560.248583842023, 11606.0, 11615.128865335133, 11415.771684566429, 11545.249558778652, 11563.248388854698, 11572.36352000648, 11627.186096973131, 7885.763427097197, 8211.384146189059, 8310.418270721113, 8442.662293508314, 8494.83010339802, 5967.76128954837, 6126.0, 6179.752809887555, 6227.782027629011, 6313.936860631335, 10248.48757562104, 10250.179985600986, 10257.743556410851, 10528.578856845641, 10529.57881684724, 10573.0, 10641.627543035956, 10657.520411581652, 10771.0, 10781.676549703521, 7778.183290754389, 7839.529628222325, 7896.881546776918, 7953.125156232694, 7976.042874855055, 8551.315894728283, 8570.65717371323, 8600.182982616641, 8611.13888611134, 8626.051134375339, 7702.268284512962, 7906.130325664058, 7911.881321780293, 8027.438079334391, 8031.59842007909, 7561.546307221584, 7736.7292144774665, 7748.92251077482, 7852.725154619561, 7880.290773830154, 6799.286074962058, 7049.2950704931145, 7072.929270707433, 7155.0, 7287.8542429152185, 5358.33020872391, 5382.784688612568, 5391.16436952273, 5403.243545903655, 5440.211169380497, 5304.151335786256, 5428.7285635718845, 5501.752421140978, 5690.829275121753, 5692.1461780733225], "value": 4612.882420425435, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.52072057678095, 347.31405474188483, 353.72056075700203, 355.71720482216637, 360.3729510651467, 5109.233614957759, 5253.343332083489, 5343.866403339918, 5349.5987800914945, 5358.59810514212, 5289.228108583629, 5303.840884773454, 5319.175527793189, 5322.467753224676, 5332.786688532454, 4215.135897141086, 4228.471441069866, 4233.491980962273, 4238.321868501041, 4240.0, 3979.8010099495054, 4032.899177520556, 4184.058586817957, 4219.746815191083, 4242.321228603424, 4104.302268614328, 4127.741038983109, 4147.216696820366, 4148.398482220076, 4165.83336666533, 4123.567025462328, 4129.69027322952, 4136.855210067633, 4141.668666506672, 4163.979180104096, 3447.7069449096857, 3701.9444708329397, 3709.703223742094, 3715.3498137825773, 3725.3853114236117, 5052.520010599024, 5062.54437100659, 5067.265246539268, 5071.366079240095, 5073.873153171164, 4696.436427628672, 4710.9635880106325, 4711.481737008926, 4728.219843725816, 4730.516478848597, 5274.709890955995, 5493.890122197574, 5497.45025497451, 5518.475744804277, 5549.445055494459, 4012.61880121388, 4024.3561030235314, 4033.1530378620523, 4034.132661477767, 4037.596240375939, 5768.384852241379, 5810.941890581083, 5813.215215945836, 5828.795992140254, 5846.181534585119, 3853.6917046636204, 4046.736962097454, 4046.919061618751, 4057.0, 4088.7529303562105, 4095.4266402703647, 4097.918041639151, 4108.856190033394, 4123.958760412417, 4127.215828992542, 4694.155052090657, 4697.30896877124, 4702.130105930414, 4709.105269998689, 4710.387649605529, 5256.395514515803, 5538.147853855267, 5539.9169012464845, 5635.267415236075, 5643.746031428595, 1346.9461221551028, 1396.4429660927992, 1421.6161636358124, 1428.6998831063681, 1433.2976841347665, 871.9433236839585, 878.8198419324024, 889.9510526920952, 893.1648908270788, 904.4618452021037, 4592.517785632527, 4651.116297899356, 4685.320628508847, 4691.835785747482, 4828.444728856156, 4942.57988071018, 4967.726775027409, 5008.347245409006, 5059.392872855207, 5069.518395752398, 4480.686351955397, 4740.056576803494, 4743.549362810528, 4756.429228492534, 4758.881027974328, 2666.346745047476, 2670.572708366652, 2687.9193624191307, 2696.678627472524, 2743.3553115017953, 5071.87320316995, 5178.352705911761, 5202.401723801811, 5238.5023422774775, 5250.687328167958, 4754.524547545287, 5075.8935041957175, 5131.923021154686, 5144.582046438168, 5148.4851514848215, 5072.492750724898, 5073.340465739504, 5102.744862756847, 5116.027954688598, 5146.794128234904, 4970.726610036482, 4975.875603109951, 4988.72562009093, 4990.700557966534, 5011.9976004798455, 4661.137689527312, 4883.267509873547, 4889.828855990166, 4929.186684197068, 4931.002943807764, 5102.79588816458, 5109.8978020438635, 5111.795528178831, 5148.536631703165, 5172.456892026434, 3615.204654975901, 3618.963810361968, 3638.6725194732603, 3651.0, 3659.1583935694407, 4740.407449068866, 4894.241392584155, 4917.729524876167, 4921.261810728276, 4960.900781984412, 4803.070127222461, 4815.446223684321, 4846.36997190363, 4850.975745121252, 4856.0, 4813.614910807197, 4819.783109760139, 4833.5499350195505, 4843.1524483216, 4861.562459378673, 4576.146660602461, 4781.737004464788, 4787.252652781779, 4806.543378379049, 4813.026659069765, 4850.951490485191, 4858.465568787501, 4861.1979023460735, 4885.8045678172475, 4889.875328674496, 4712.764361781966, 4747.477777444409, 4800.567948884617, 4804.663673542818, 4805.207140821865], "value": 3982.046713587603, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [830.8213734047181, 849.5921957460419, 855.1448551448551, 856.5310492505353, 887.1793590928389, 13475.528356507511, 14065.859341406596, 14357.0667906586, 14376.137431754076, 14376.424943002281, 14183.432662693505, 14248.14511129333, 14274.214918179496, 14278.92860535696, 14301.856981430183, 620.8447888027993, 644.1786721929531, 644.2044075566695, 644.3813938618931, 685.8114018644875, 826.5619221812449, 834.5493433545878, 840.8822764812932, 842.2798507276283, 843.2958479669484, 709.5352544083607, 882.7528292078229, 885.8804061451686, 886.6674996876171, 887.7292425810125, 721.6680327049537, 725.9709611615529, 858.219020691168, 876.7676565710092, 883.4169448164181, 650.4568685147907, 650.5706233885613, 650.6909218121402, 650.7917466410751, 651.1437459740473, 12314.13801033928, 12439.440225189888, 12442.626721198289, 12442.937785311105, 12459.0, 13157.144785588906, 13490.932545337211, 13494.39275232607, 13499.86500134996, 13610.251436171007, 12130.696732581668, 12215.389230538438, 12253.264804111783, 12421.19262247951, 12481.50073997039, 4432.71187372823, 4504.459464864204, 4513.729176249435, 4529.660275479319, 4540.4551453825425, 49286.26070608944, 49550.75224623854, 49694.254586180876, 49773.50226497725, 51778.0, 10987.340759554452, 11080.778384432266, 11089.944550277198, 11229.382383968798, 11505.252158609663, 19421.126049327813, 19458.610827783366, 19585.0, 19677.0, 20152.39542813718, 8103.716369927024, 8225.424220304521, 8312.25189732927, 8409.11704271055, 8641.481511109354, 4717.811287548459, 4846.539578740014, 4854.320395144682, 4869.0, 4930.408350997904, 3281.0812972367776, 3453.1194545390763, 3459.0660521659515, 3468.0289518934737, 3476.461148521983, 651.8728847874619, 663.8871391863395, 665.2981104934244, 666.1340257665113, 668.7692746002622, 572.7279542217497, 615.6367743031633, 674.932506749331, 692.1452006771633, 723.0708539526788, 8918.73243802687, 9191.63233470667, 9195.494247816434, 9206.493642849708, 9289.90710092904, 678.1455366238575, 685.2599192871687, 685.8971154326891, 695.2282965907838, 705.153815421486, 4304.859212308741, 4318.913621727548, 4357.150355680612, 4357.607815296575, 4416.447944006999, 63248.36751632517, 63912.0, 64162.358376415636, 64469.355306446334, 64528.67735661292, 46747.532524675, 50549.0, 54411.45588544064, 54678.45321546813, 57756.0, 48814.26778598324, 49156.50843491591, 49860.75069624629, 50047.24929126066, 52032.73983630058, 9057.820093226326, 9108.908910910806, 9122.498262595622, 9141.5886285116, 9181.770455738393, 32722.181945451553, 32862.83568582238, 34016.14959626029, 34064.31871362608, 34773.0, 40909.181816362914, 44937.0, 44986.55013449823, 44994.77502612466, 45028.09943801171, 10245.897541024793, 10247.487625618838, 10617.946910265398, 11980.58067967617, 12334.75330493403, 12736.87263127394, 13188.142770720257, 13349.199048057148, 13389.062765606313, 13425.597232083053, 11656.592019279284, 11982.64052078439, 12096.092793040381, 12722.491100356252, 12828.807567886302, 12394.256344619353, 12478.251304921734, 12506.0, 12507.74984500323, 12801.743965120831, 11193.552257909592, 11209.831852522055, 11451.770964580828, 11487.0, 12304.938475307566, 10136.341137826017, 10528.105111065644, 10561.207909406672, 10646.307989980627, 10908.890911090788, 10480.947595261974, 10899.237053406185, 10900.0, 10987.121030317396, 11133.443327833738], "value": 6679.293690216628, "unit": "score"}], "name": "dromaeo_css", "value": 7008.0952669395165, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 107.0, 110.0, 110.0, 110.0, 110.0, 109.0, 110.0, 109.0, 110.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 132.0, 150.0, 204.0, 132.0, 155.0, 162.0, 128.0, 181.0, 125.0], "value": 152.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 162.0, 160.0, 159.0, 161.0, 161.0, 162.0, 161.0, 163.0, 160.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 147.0, 75.0, 75.0, 75.0, 76.0, 130.0, 76.0], "value": 87.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 91.0, 152.0, 93.0, 91.0, 91.0, 123.0, 92.0, 92.0, 126.0], "value": 105.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 145.0, 145.0, 146.0, 145.0, 146.0, 146.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 94.0, 94.0, 96.0, 95.0, 95.0, 96.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 58.0, 56.0, 56.0, 57.0, 57.0, 109.0, 55.0, 55.0], "value": 61.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 58.0, 59.0, 59.0, 59.0, 58.0, 59.0, 59.0, 59.0], "value": 58.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 79.0, 78.0, 78.0, 80.0, 80.0, 79.0, 79.0, 79.0], "value": 79.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 126.0, 130.0, 123.0, 121.0, 119.0, 121.0, 122.0, 236.0, 126.0], "value": 134.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 169.0, 166.0, 168.0, 167.0, 169.0, 167.0, 169.0, 168.0, 164.0], "value": 167.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 65.0, 63.0, 64.0, 66.0, 62.0, 63.0, 64.0, 62.0], "value": 63.8, "unit": "score"}], "name": "kraken", "value": 1539.3999999999999, "alertThreshold": 2.0}]} 02:38:57 INFO - Return code: 0 02:38:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:38:57 ERROR - # TBPL SUCCESS # 02:38:57 INFO - Running post-action listener: _resource_record_post_action 02:38:57 INFO - ##### 02:38:57 INFO - ##### Finished run-tests step (success) 02:38:57 INFO - ##### 02:38:57 INFO - Running post-run listener: _resource_record_post_run 02:38:58 INFO - Total resource usage - Wall time: 1194s; CPU: 13.0%; Read bytes: 10317824; Write bytes: 404701184; Read time: 2708; Write time: 275212 02:38:58 INFO - TinderboxPrint: CPU usage
13.2% 02:38:58 INFO - TinderboxPrint: I/O read bytes / time
10,317,824 / 2,708 02:38:58 INFO - TinderboxPrint: I/O write bytes / time
404,701,184 / 275,212 02:38:58 INFO - TinderboxPrint: CPU idle
8,270.7 (86.8%) 02:38:58 INFO - TinderboxPrint: CPU user
1,211.1 (12.7%) 02:38:58 INFO - TinderboxPrint: Swap in / out
0 / 0 02:38:58 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 95342592; Read time: 0; Write time: 77416 02:38:58 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 8581120; Write bytes: 309358592; Read time: 2620; Write time: 197796 02:38:58 INFO - Running post-run listener: _upload_blobber_files 02:38:58 INFO - Blob upload gear active. 02:38:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:38:58 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 02:38:58 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', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:38:58 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 Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:38:58 INFO - (blobuploader) - INFO - Open directory for files ... 02:38:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:38:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:38:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:38:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:38:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:38:59 INFO - (blobuploader) - INFO - Done attempting. 02:38:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:38:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:38:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:39:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:39:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:39:00 INFO - (blobuploader) - INFO - Done attempting. 02:39:00 INFO - (blobuploader) - INFO - Iteration through files over. 02:39:00 INFO - Return code: 0 02:39:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:39:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:39:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7adda13efa53a9771285d0b7d49cee074acfa78027378ed0ec88bc934ffff1f3cffdbe94b2cde0fca02616169f697a1274a243342bb669c8f1611dd6b8652ad2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/faba80c2455917dd5efab454b7c170f3a915e7477cfb0ef9daa83db61da705bcbab4e2737a52bb11297c6949bd48d9a059b9ee818bec71bdb2918382d600eaa4"} 02:39:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:39:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:39:00 INFO - Contents: 02:39:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7adda13efa53a9771285d0b7d49cee074acfa78027378ed0ec88bc934ffff1f3cffdbe94b2cde0fca02616169f697a1274a243342bb669c8f1611dd6b8652ad2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/faba80c2455917dd5efab454b7c170f3a915e7477cfb0ef9daa83db61da705bcbab4e2737a52bb11297c6949bd48d9a059b9ee818bec71bdb2918382d600eaa4"} 02:39:00 INFO - Running post-run listener: copy_logs_to_upload_dir 02:39:00 INFO - Copying logs to upload dir... 02:39:00 INFO - mkdir: /builds/slave/test/build/upload/logs 02:39:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.635855 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-05-12 02:39:00.280715) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 02:39:00.284140) ========= 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=0e2a256762fed810e2f0f810000001b3-1463044697.319541-1817303771 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7adda13efa53a9771285d0b7d49cee074acfa78027378ed0ec88bc934ffff1f3cffdbe94b2cde0fca02616169f697a1274a243342bb669c8f1611dd6b8652ad2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/faba80c2455917dd5efab454b7c170f3a915e7477cfb0ef9daa83db61da705bcbab4e2737a52bb11297c6949bd48d9a059b9ee818bec71bdb2918382d600eaa4"} build_url:https://queue.taskcluster.net/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011062 build_url: 'https://queue.taskcluster.net/v1/task/D1R5cbGQSSOHi8IxEHb8jA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/7adda13efa53a9771285d0b7d49cee074acfa78027378ed0ec88bc934ffff1f3cffdbe94b2cde0fca02616169f697a1274a243342bb669c8f1611dd6b8652ad2", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/faba80c2455917dd5efab454b7c170f3a915e7477cfb0ef9daa83db61da705bcbab4e2737a52bb11297c6949bd48d9a059b9ee818bec71bdb2918382d600eaa4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 02:39:00.310395) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 02:39:00.310849) ========= 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=0e2a256762fed810e2f0f810000001b3-1463044697.319541-1817303771 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004414 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 02:39:00.394469) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 02:39:00.394927) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 02:39:00.395351) ========= ========= Total master_lag: 0.17 =========