builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-041 starttime: 1471347533.28 results: success (0) buildid: 20160816020522 builduid: 97f0bdbb321a4d3daab29bc76dc5bc86 revision: fa8506ec4649acd4ca979fb6039a087d54c1e332 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 04:38:53.280190) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 04:38:53.280862) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 04:38:53.281192) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 04:38:53.296768) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 04:38:53.297093) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-16 04:38:53-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 57.6M=0s 2016-08-16 04:38:53 (57.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.135502 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 04:38:53.447526) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 04:38:53.447923) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016615 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 04:38:53.498379) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 04:38:53.498777) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fa8506ec4649acd4ca979fb6039a087d54c1e332 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fa8506ec4649acd4ca979fb6039a087d54c1e332 --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=8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-16 04:38:53,627 truncating revision to first 12 chars 2016-08-16 04:38:53,627 Setting DEBUG logging. 2016-08-16 04:38:53,627 attempt 1/10 2016-08-16 04:38:53,627 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fa8506ec4649?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-16 04:38:53,929 unpacking tar archive at: mozilla-aurora-fa8506ec4649/testing/mozharness/ program finished with exit code 0 elapsedTime=0.708185 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 04:38:54.221863) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 04:38:54.222225) ========= script_repo_revision: fa8506ec4649acd4ca979fb6039a087d54c1e332 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 04:38:54.222650) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 04:38:54.222936) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 04:38:54.232235) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 2 secs) (at 2016-08-16 04:38:54.232516) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:38:54 INFO - MultiFileLogger online at 20160816 04:38:54 in /builds/slave/test 04:38:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 04:38:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:38:54 INFO - {'append_to_log': False, 04:38:54 INFO - 'base_work_dir': '/builds/slave/test', 04:38:54 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:38:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:38:54 INFO - 'branch': 'Mozilla-Aurora', 04:38:54 INFO - 'buildbot_json_path': 'buildprops.json', 04:38:54 INFO - 'config_files': ('talos/linux_config.py',), 04:38:54 INFO - 'default_actions': ('clobber', 04:38:54 INFO - 'read-buildbot-config', 04:38:54 INFO - 'download-and-extract', 04:38:54 INFO - 'populate-webroot', 04:38:54 INFO - 'create-virtualenv', 04:38:54 INFO - 'install', 04:38:54 INFO - 'run-tests'), 04:38:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:38:54 INFO - 'download_minidump_stackwalk': True, 04:38:54 INFO - 'download_symbols': 'ondemand', 04:38:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:38:54 INFO - 'tooltool.py': '/tools/tooltool.py', 04:38:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:38:54 INFO - '/tools/misc-python/virtualenv.py')}, 04:38:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:38:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:38:54 INFO - 'installer_path': 'installer.exe', 04:38:54 INFO - 'log_level': 'info', 04:38:54 INFO - 'log_name': 'talos', 04:38:54 INFO - 'log_to_console': True, 04:38:54 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:38:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:38:54 INFO - 'opt_config_files': (), 04:38:54 INFO - 'pip_index': False, 04:38:54 INFO - 'sps_profile': False, 04:38:54 INFO - 'sps_profile_interval': 0, 04:38:54 INFO - 'suite': 'dromaeojs-e10s', 04:38:54 INFO - 'system_bits': '32', 04:38:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:38:54 INFO - 'title': 'talos-linux64-ix-041', 04:38:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:38:54 INFO - 'use_talos_json': True, 04:38:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:38:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:38:54 INFO - 'work_dir': 'build'} 04:38:54 INFO - [mozharness: 2016-08-16 11:38:54.449728Z] Running clobber step. 04:38:54 INFO - Running pre-action listener: _resource_record_pre_action 04:38:54 INFO - Running main action method: clobber 04:38:54 INFO - rmtree: /builds/slave/test/build 04:38:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:38:55 INFO - Running post-action listener: _resource_record_post_action 04:38:55 INFO - [mozharness: 2016-08-16 11:38:55.429601Z] Finished clobber step (success) 04:38:55 INFO - [mozharness: 2016-08-16 11:38:55.429700Z] Running read-buildbot-config step. 04:38:55 INFO - Running pre-action listener: _resource_record_pre_action 04:38:55 INFO - Running main action method: read_buildbot_config 04:38:55 INFO - Using buildbot properties: 04:38:55 INFO - { 04:38:55 INFO - "project": "", 04:38:55 INFO - "product": "firefox", 04:38:55 INFO - "script_repo_revision": "production", 04:38:55 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 04:38:55 INFO - "repository": "", 04:38:55 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 04:38:55 INFO - "buildid": "20160816020522", 04:38:55 INFO - "pgo_build": "True", 04:38:55 INFO - "basedir": "/builds/slave/test", 04:38:55 INFO - "buildnumber": 467, 04:38:55 INFO - "slavename": "talos-linux64-ix-041", 04:38:55 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:38:55 INFO - "platform": "ubuntu64_hw", 04:38:55 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 04:38:55 INFO - "branch": "mozilla-aurora", 04:38:55 INFO - "repo_path": "releases/mozilla-aurora", 04:38:55 INFO - "revision": "fa8506ec4649acd4ca979fb6039a087d54c1e332", 04:38:55 INFO - "stage_platform": "linux64-pgo", 04:38:55 INFO - "builduid": "97f0bdbb321a4d3daab29bc76dc5bc86", 04:38:55 INFO - "slavebuilddir": "test" 04:38:55 INFO - } 04:38:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 04:38:55 INFO - Running post-action listener: _resource_record_post_action 04:38:55 INFO - [mozharness: 2016-08-16 11:38:55.432111Z] Finished read-buildbot-config step (success) 04:38:55 INFO - [mozharness: 2016-08-16 11:38:55.432200Z] Running download-and-extract step. 04:38:55 INFO - Running pre-action listener: _resource_record_pre_action 04:38:55 INFO - Running main action method: download_and_extract 04:38:55 INFO - mkdir: /builds/slave/test/build/tests 04:38:55 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')]} 04:38:55 INFO - https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 04:38:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:38:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:38:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:38:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:38:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:38:55 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:38:55 INFO - retry: Failed, sleeping 30 seconds before retrying 04:39:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 04:39:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:39:25 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:39:25 INFO - retry: Failed, sleeping 60 seconds before retrying 04:40:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 04:40:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:40:25 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:40:25 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 04:40:25 INFO - Caught exception: HTTP Error 404: Not Found 04:40:25 INFO - Caught exception: HTTP Error 404: Not Found 04:40:25 INFO - Caught exception: HTTP Error 404: Not Found 04:40:25 INFO - trying https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:40:25 INFO - Downloading https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:40:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 04:40:26 INFO - Downloaded 1361 bytes. 04:40:26 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 04:40:26 INFO - Using the following test package requirements: 04:40:26 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 04:40:26 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:40:26 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 04:40:26 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:40:26 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 04:40:26 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:40:26 INFO - u'jsshell-linux-x86_64.zip'], 04:40:26 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:40:26 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 04:40:26 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 04:40:26 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:40:26 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 04:40:26 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:40:26 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 04:40:26 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:40:26 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 04:40:26 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 04:40:26 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 04:40:26 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 04:40: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')]} 04:40:26 INFO - https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:40:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:40:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:40:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:40:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:40:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:40:26 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:40:26 INFO - retry: Failed, sleeping 30 seconds before retrying 04:40:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 04:40:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:40:56 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:40:56 INFO - retry: Failed, sleeping 60 seconds before retrying 04:41:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 04:41:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:41:56 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:41:56 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 04:41:56 INFO - Caught exception: HTTP Error 404: Not Found 04:41:56 INFO - Caught exception: HTTP Error 404: Not Found 04:41:56 INFO - Caught exception: HTTP Error 404: Not Found 04:41:56 INFO - trying https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:41:56 INFO - Downloading https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:41:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:41:57 INFO - Downloaded 21836351 bytes. 04:41:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:41:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:41:58 INFO - Return code: 0 04:41:58 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 04:41:58 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')]} 04:41:58 INFO - https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 04:41:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:41:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:41:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:41:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:41:58 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:41:58 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:41:58 INFO - retry: Failed, sleeping 30 seconds before retrying 04:42:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 04:42:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:42:28 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:42:28 INFO - retry: Failed, sleeping 60 seconds before retrying 04:43:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 04:43:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:43:28 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:43:28 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 04:43:28 INFO - Caught exception: HTTP Error 404: Not Found 04:43:28 INFO - Caught exception: HTTP Error 404: Not Found 04:43:28 INFO - Caught exception: HTTP Error 404: Not Found 04:43:28 INFO - trying https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:43:28 INFO - Downloading https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 04:43:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 04:43:29 INFO - Downloaded 21836351 bytes. 04:43:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:43:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 04:43:30 INFO - Return code: 0 04:43: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')]} 04:43:30 INFO - https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 04:43:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:43:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:43:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:43:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:43:30 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:43:30 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:43:30 INFO - retry: Failed, sleeping 30 seconds before retrying 04:44:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 04:44:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:44:00 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:44:00 INFO - retry: Failed, sleeping 60 seconds before retrying 04:45:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 04:45:00 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:45:00 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:45:00 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 04:45:00 INFO - Caught exception: HTTP Error 404: Not Found 04:45:00 INFO - Caught exception: HTTP Error 404: Not Found 04:45:00 INFO - Caught exception: HTTP Error 404: Not Found 04:45:00 INFO - trying https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:45:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 04:45:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 04:45:02 INFO - Downloaded 14413723 bytes. 04:45:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:45:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 04:45:03 INFO - Return code: 0 04:45:03 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')]} 04:45:03 INFO - https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 04:45:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:45:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:45:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:45:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:45:03 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:45:03 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 04:45:03 INFO - retry: Failed, sleeping 30 seconds before retrying 04:45:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 04:45:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:45:33 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 04:45:33 INFO - retry: Failed, sleeping 60 seconds before retrying 04:46:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 04:46:33 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:46:33 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 04:46:33 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 04:46:33 INFO - Caught exception: HTTP Error 404: Not Found 04:46:33 INFO - Caught exception: HTTP Error 404: Not Found 04:46:33 INFO - Caught exception: HTTP Error 404: Not Found 04:46:33 INFO - trying https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:46:33 INFO - Downloading https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:46:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 04:46:34 INFO - Downloaded 63772761 bytes. 04:46:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:46:34 INFO - mkdir: /builds/slave/test/properties 04:46:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:46:34 INFO - Writing to file /builds/slave/test/properties/build_url 04:46:34 INFO - Contents: 04:46:34 INFO - build_url:https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 04:46:35 INFO - Running post-action listener: _resource_record_post_action 04:46:35 INFO - Running post-action listener: set_extra_try_arguments 04:46:35 INFO - [mozharness: 2016-08-16 11:46:35.319003Z] Finished download-and-extract step (success) 04:46:35 INFO - [mozharness: 2016-08-16 11:46:35.319234Z] Running populate-webroot step. 04:46:35 INFO - Running pre-action listener: _resource_record_pre_action 04:46:35 INFO - Running main action method: populate_webroot 04:46:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:46:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:46:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:46:35 INFO - '--tppagecycles', 04:46:35 INFO - '7'], 04:46:35 INFO - 'tests': ['tsvgm']}}, 04:46:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:46:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:46:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:46:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:46:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:46:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:46:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:46:35 INFO - 'talos_options': ['--disable-e10s'], 04:46:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:46:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:46:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:46:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:46:35 INFO - 'talos_options': ['--disable-e10s'], 04:46:35 INFO - 'tests': ['damp', 'tps']}, 04:46:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:46:35 INFO - 'tests': ['damp', 'tps']}, 04:46:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:46:35 INFO - 'tests': ['dromaeo_dom']}, 04:46:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:46:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:46:35 INFO - 'tests': ['basic_compositor_video']}, 04:46:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:46:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:46:35 INFO - 'tests': ['a11yr', 04:46:35 INFO - 'ts_paint', 04:46:35 INFO - 'tpaint', 04:46:35 INFO - 'sessionrestore', 04:46:35 INFO - 'sessionrestore_no_auto_restore', 04:46:35 INFO - 'tabpaint']}, 04:46:35 INFO - 'other-e10s': {'tests': ['a11yr', 04:46:35 INFO - 'ts_paint', 04:46:35 INFO - 'tpaint', 04:46:35 INFO - 'sessionrestore', 04:46:35 INFO - 'sessionrestore_no_auto_restore', 04:46:35 INFO - 'tabpaint']}, 04:46:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:46:35 INFO - 'ts_paint', 04:46:35 INFO - 'tpaint', 04:46:35 INFO - 'sessionrestore', 04:46:35 INFO - 'sessionrestore_no_auto_restore', 04:46:35 INFO - 'tabpaint']}, 04:46:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:46:35 INFO - 'ts_paint', 04:46:35 INFO - 'tpaint', 04:46:35 INFO - 'sessionrestore', 04:46:35 INFO - 'sessionrestore_no_auto_restore', 04:46:35 INFO - 'tabpaint']}, 04:46:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:46:35 INFO - 'tests': ['a11yr', 04:46:35 INFO - 'ts_paint', 04:46:35 INFO - 'tpaint', 04:46:35 INFO - 'sessionrestore', 04:46:35 INFO - 'sessionrestore_no_auto_restore', 04:46:35 INFO - 'tabpaint']}, 04:46:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:46:35 INFO - 'tests': ['a11yr', 04:46:35 INFO - 'ts_paint', 04:46:35 INFO - 'tpaint', 04:46:35 INFO - 'sessionrestore', 04:46:35 INFO - 'sessionrestore_no_auto_restore', 04:46:35 INFO - 'tabpaint']}, 04:46:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:46:35 INFO - 'tests': ['tsvgx', 04:46:35 INFO - 'tsvgr_opacity', 04:46:35 INFO - 'tart', 04:46:35 INFO - 'tscrollx', 04:46:35 INFO - 'cart']}, 04:46:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:46:35 INFO - 'tsvgr_opacity', 04:46:35 INFO - 'tart', 04:46:35 INFO - 'tscrollx', 04:46:35 INFO - 'cart']}, 04:46:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:46:35 INFO - 'talos_options': ['--disable-e10s'], 04:46:35 INFO - 'tests': ['tp5o']}, 04:46:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:46:35 INFO - 'tests': ['tp5o']}, 04:46:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:46:35 INFO - 'talos_options': ['--disable-e10s', 04:46:35 INFO - '--xperf_path', 04:46:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:46:35 INFO - 'tests': ['tp5n']}, 04:46:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:46:35 INFO - 'talos_options': ['--xperf_path', 04:46:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:46:35 INFO - 'tests': ['tp5n']}}, 04:46:35 INFO - 'talos.zip': {'path': '', 04:46:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:46:35 INFO - Running post-action listener: _resource_record_post_action 04:46:35 INFO - [mozharness: 2016-08-16 11:46:35.338886Z] Finished populate-webroot step (success) 04:46:35 INFO - [mozharness: 2016-08-16 11:46:35.338975Z] Running create-virtualenv step. 04:46:35 INFO - Running pre-action listener: _resource_record_pre_action 04:46:35 INFO - Running main action method: create_virtualenv 04:46:35 INFO - Creating virtualenv /builds/slave/test/build/venv 04:46:35 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 04:46:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:46:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:46:35 INFO - Using real prefix '/usr' 04:46:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:46:35 INFO - Installing distribute.............................................................................................................................................................................................done. 04:46:37 INFO - Installing pip.................done. 04:46:37 INFO - Return code: 0 04:46:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:46:37 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')]} 04:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:37 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')]} 04:46:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:37 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 0x1f2a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2159400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e8570>, '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 0x21e9470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e9900>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:46:37 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 04:46:37 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 04:46:37 INFO - Using env: {'DISPLAY': ':0', 04:46:37 INFO - 'HOME': '/home/cltbld', 04:46:37 INFO - 'LANG': 'en_US.UTF-8', 04:46:37 INFO - 'LANGUAGE': 'en_US:en', 04:46:37 INFO - 'LOGNAME': 'cltbld', 04:46:37 INFO - 'MAIL': '/var/mail/cltbld', 04:46:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:37 INFO - 'MOZ_NO_REMOTE': '1', 04:46:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:46:37 INFO - 'NO_EM_RESTART': '1', 04:46:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:46:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:37 INFO - 'PWD': '/builds/slave/test', 04:46:37 INFO - 'SHELL': '/bin/bash', 04:46:37 INFO - 'SHLVL': '1', 04:46:37 INFO - 'TERM': 'linux', 04:46:37 INFO - 'TMOUT': '86400', 04:46:37 INFO - 'USER': 'cltbld', 04:46:37 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190', 04:46:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:37 INFO - '_': '/tools/buildbot/bin/python'} 04:46:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:38 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 04:46:38 INFO - Cleaning up... 04:46:38 INFO - Return code: 0 04:46:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:46:38 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')]} 04:46:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:38 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')]} 04:46:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:38 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f2a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2159400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e8570>, '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 0x21e9470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e9900>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:46:38 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>=3.1.1'] in /builds/slave/test/build 04:46:38 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>=3.1.1 04:46:38 INFO - Using env: {'DISPLAY': ':0', 04:46:38 INFO - 'HOME': '/home/cltbld', 04:46:38 INFO - 'LANG': 'en_US.UTF-8', 04:46:38 INFO - 'LANGUAGE': 'en_US:en', 04:46:38 INFO - 'LOGNAME': 'cltbld', 04:46:38 INFO - 'MAIL': '/var/mail/cltbld', 04:46:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:38 INFO - 'MOZ_NO_REMOTE': '1', 04:46:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:46:38 INFO - 'NO_EM_RESTART': '1', 04:46:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:46:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:38 INFO - 'PWD': '/builds/slave/test', 04:46:38 INFO - 'SHELL': '/bin/bash', 04:46:38 INFO - 'SHLVL': '1', 04:46:38 INFO - 'TERM': 'linux', 04:46:38 INFO - 'TMOUT': '86400', 04:46:38 INFO - 'USER': 'cltbld', 04:46:38 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190', 04:46:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:38 INFO - '_': '/tools/buildbot/bin/python'} 04:46:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:38 INFO - Downloading/unpacking psutil>=3.1.1 04:46:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:38 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 04:46:38 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 04:46:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:46:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:46:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:46:40 INFO - Installing collected packages: psutil 04:46:40 INFO - Running setup.py install for psutil 04:46:40 INFO - building 'psutil._psutil_linux' extension 04:46:40 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 04:46:40 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 04:46:40 INFO - building 'psutil._psutil_posix' extension 04:46:40 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 04:46:40 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 04:46:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:46:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:46:40 INFO - Successfully installed psutil 04:46:40 INFO - Cleaning up... 04:46:40 INFO - Return code: 0 04:46:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:46: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')]} 04:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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')]} 04:46:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f2a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2159400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e8570>, '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 0x21e9470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e9900>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:46: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:46: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 mozsystemmonitor==0.3 04:46:40 INFO - Using env: {'DISPLAY': ':0', 04:46:40 INFO - 'HOME': '/home/cltbld', 04:46:40 INFO - 'LANG': 'en_US.UTF-8', 04:46:40 INFO - 'LANGUAGE': 'en_US:en', 04:46:40 INFO - 'LOGNAME': 'cltbld', 04:46:40 INFO - 'MAIL': '/var/mail/cltbld', 04:46:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:40 INFO - 'MOZ_NO_REMOTE': '1', 04:46:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:46:40 INFO - 'NO_EM_RESTART': '1', 04:46:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:46:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:40 INFO - 'PWD': '/builds/slave/test', 04:46:40 INFO - 'SHELL': '/bin/bash', 04:46:40 INFO - 'SHLVL': '1', 04:46:40 INFO - 'TERM': 'linux', 04:46:40 INFO - 'TMOUT': '86400', 04:46:40 INFO - 'USER': 'cltbld', 04:46:40 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190', 04:46:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:40 INFO - '_': '/tools/buildbot/bin/python'} 04:46:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:46:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:41 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 04:46:41 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 04:46:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:46:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:46:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:46:43 INFO - Installing collected packages: mozsystemmonitor 04:46:43 INFO - Running setup.py install for mozsystemmonitor 04:46:43 INFO - Successfully installed mozsystemmonitor 04:46:43 INFO - Cleaning up... 04:46:43 INFO - Return code: 0 04:46:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:46: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')]} 04:46:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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')]} 04:46:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f2a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2159400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e8570>, '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 0x21e9470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e9900>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:46: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:46: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 jsonschema==2.5.1 04:46:43 INFO - Using env: {'DISPLAY': ':0', 04:46:43 INFO - 'HOME': '/home/cltbld', 04:46:43 INFO - 'LANG': 'en_US.UTF-8', 04:46:43 INFO - 'LANGUAGE': 'en_US:en', 04:46:43 INFO - 'LOGNAME': 'cltbld', 04:46:43 INFO - 'MAIL': '/var/mail/cltbld', 04:46:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:43 INFO - 'MOZ_NO_REMOTE': '1', 04:46:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:46:43 INFO - 'NO_EM_RESTART': '1', 04:46:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:46:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:43 INFO - 'PWD': '/builds/slave/test', 04:46:43 INFO - 'SHELL': '/bin/bash', 04:46:43 INFO - 'SHLVL': '1', 04:46:43 INFO - 'TERM': 'linux', 04:46:43 INFO - 'TMOUT': '86400', 04:46:43 INFO - 'USER': 'cltbld', 04:46:43 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190', 04:46:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:43 INFO - '_': '/tools/buildbot/bin/python'} 04:46:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:43 INFO - Downloading/unpacking jsonschema==2.5.1 04:46:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:43 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 04:46:43 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 04:46:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:46:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:45 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 04:46:45 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 04:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:46:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:46:45 INFO - Downloading functools32-3.2.3-2.tar.gz 04:46:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:46:45 INFO - warning: no files found matching '*.txt' 04:46:45 INFO - no previously-included directories found matching 'build' 04:46:45 INFO - no previously-included directories found matching 'dist' 04:46:45 INFO - no previously-included directories found matching '.git*' 04:46:45 INFO - Installing collected packages: jsonschema, functools32 04:46:45 INFO - Running setup.py install for functools32 04:46:45 INFO - warning: no files found matching '*.txt' 04:46:45 INFO - no previously-included directories found matching 'build' 04:46:45 INFO - no previously-included directories found matching 'dist' 04:46:45 INFO - no previously-included directories found matching '.git*' 04:46:45 INFO - Successfully installed jsonschema functools32 04:46:45 INFO - Cleaning up... 04:46:45 INFO - Return code: 0 04:46:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:46:45 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')]} 04:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:45 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')]} 04:46:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:45 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f2a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2159400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e8570>, '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 0x21e9470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e9900>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:46:45 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:46:45 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 functools32==3.2.3-2 04:46:45 INFO - Using env: {'DISPLAY': ':0', 04:46:45 INFO - 'HOME': '/home/cltbld', 04:46:45 INFO - 'LANG': 'en_US.UTF-8', 04:46:45 INFO - 'LANGUAGE': 'en_US:en', 04:46:45 INFO - 'LOGNAME': 'cltbld', 04:46:45 INFO - 'MAIL': '/var/mail/cltbld', 04:46:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:45 INFO - 'MOZ_NO_REMOTE': '1', 04:46:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:46:45 INFO - 'NO_EM_RESTART': '1', 04:46:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:46:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:45 INFO - 'PWD': '/builds/slave/test', 04:46:45 INFO - 'SHELL': '/bin/bash', 04:46:45 INFO - 'SHLVL': '1', 04:46:45 INFO - 'TERM': 'linux', 04:46:45 INFO - 'TMOUT': '86400', 04:46:45 INFO - 'USER': 'cltbld', 04:46:45 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190', 04:46:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:45 INFO - '_': '/tools/buildbot/bin/python'} 04:46:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:46:45 INFO - Cleaning up... 04:46:45 INFO - Return code: 0 04:46:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:46:45 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')]} 04:46:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:45 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')]} 04:46:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:45 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 0x1f2a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2159400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e8570>, '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 0x21e9470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e9900>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:46:45 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 04:46:45 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 04:46:45 INFO - Using env: {'DISPLAY': ':0', 04:46:45 INFO - 'HOME': '/home/cltbld', 04:46:45 INFO - 'LANG': 'en_US.UTF-8', 04:46:45 INFO - 'LANGUAGE': 'en_US:en', 04:46:45 INFO - 'LOGNAME': 'cltbld', 04:46:45 INFO - 'MAIL': '/var/mail/cltbld', 04:46:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:45 INFO - 'MOZ_NO_REMOTE': '1', 04:46:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:46:45 INFO - 'NO_EM_RESTART': '1', 04:46:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:46:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:45 INFO - 'PWD': '/builds/slave/test', 04:46:45 INFO - 'SHELL': '/bin/bash', 04:46:45 INFO - 'SHLVL': '1', 04:46:45 INFO - 'TERM': 'linux', 04:46:45 INFO - 'TMOUT': '86400', 04:46:45 INFO - 'USER': 'cltbld', 04:46:45 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190', 04:46:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:45 INFO - '_': '/tools/buildbot/bin/python'} 04:46:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:46 INFO - Downloading/unpacking blobuploader==1.2.4 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46:48 INFO - Downloading blobuploader-1.2.4.tar.gz 04:46:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:46:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:46:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46:48 INFO - Downloading docopt-0.6.1.tar.gz 04:46:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:46:48 INFO - Installing collected packages: blobuploader, requests, docopt 04:46:48 INFO - Running setup.py install for blobuploader 04:46:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:46:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:46:48 INFO - Running setup.py install for requests 04:46:49 INFO - Running setup.py install for docopt 04:46:49 INFO - Successfully installed blobuploader requests docopt 04:46:49 INFO - Cleaning up... 04:46:49 INFO - Return code: 0 04:46:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:46: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')]} 04:46:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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')]} 04:46:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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 0x1f2a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2159400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e8570>, '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 0x21e9470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e9900>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:46: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 04:46: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 04:46:49 INFO - Using env: {'DISPLAY': ':0', 04:46:49 INFO - 'HOME': '/home/cltbld', 04:46:49 INFO - 'LANG': 'en_US.UTF-8', 04:46:49 INFO - 'LANGUAGE': 'en_US:en', 04:46:49 INFO - 'LOGNAME': 'cltbld', 04:46:49 INFO - 'MAIL': '/var/mail/cltbld', 04:46:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:49 INFO - 'MOZ_NO_REMOTE': '1', 04:46:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:46:49 INFO - 'NO_EM_RESTART': '1', 04:46:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:46:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:49 INFO - 'PWD': '/builds/slave/test', 04:46:49 INFO - 'SHELL': '/bin/bash', 04:46:49 INFO - 'SHLVL': '1', 04:46:49 INFO - 'TERM': 'linux', 04:46:49 INFO - 'TMOUT': '86400', 04:46:49 INFO - 'USER': 'cltbld', 04:46:49 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190', 04:46:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:49 INFO - '_': '/tools/buildbot/bin/python'} 04:46:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:46:49 INFO - Running setup.py (path:/tmp/pip-mWDR8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:46:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:46:49 INFO - Running setup.py (path:/tmp/pip-6oudBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:46:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:46:49 INFO - Running setup.py (path:/tmp/pip-Lr6W5e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:46:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:46:49 INFO - Running setup.py (path:/tmp/pip-_FAvHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:46:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:46:49 INFO - Running setup.py (path:/tmp/pip-KV8X2i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:46:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:46:49 INFO - Running setup.py (path:/tmp/pip-SJQyAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:46:50 INFO - Running setup.py (path:/tmp/pip-yCtTKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:46:50 INFO - Running setup.py (path:/tmp/pip-eEP57T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:46:50 INFO - Running setup.py (path:/tmp/pip-iaChE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:46:50 INFO - Running setup.py (path:/tmp/pip-kmxkzr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:46:50 INFO - Running setup.py (path:/tmp/pip-Lu84po-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:46:50 INFO - Running setup.py (path:/tmp/pip-TR3jwt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:46:50 INFO - Running setup.py (path:/tmp/pip-ndLVt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:46:50 INFO - Running setup.py (path:/tmp/pip-oo68Ui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:46:50 INFO - Running setup.py (path:/tmp/pip-4E_4PQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:46:50 INFO - Running setup.py (path:/tmp/pip-WxJGlo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:46:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:46:50 INFO - Running setup.py (path:/tmp/pip-WEpdQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:46:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:46:50 INFO - Running setup.py install for manifestparser 04:46:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:46:50 INFO - Running setup.py install for mozcrash 04:46:51 INFO - Running setup.py install for mozdebug 04:46:51 INFO - Running setup.py install for mozdevice 04:46:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:46:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:46:51 INFO - Running setup.py install for mozfile 04:46:51 INFO - Running setup.py install for mozhttpd 04:46:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:46:51 INFO - Running setup.py install for mozinfo 04:46:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:46:51 INFO - Running setup.py install for mozInstall 04:46:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:46:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:46:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:46:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:46:51 INFO - Running setup.py install for mozleak 04:46:51 INFO - Running setup.py install for mozlog 04:46:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:46:51 INFO - Running setup.py install for moznetwork 04:46:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:46:51 INFO - Running setup.py install for mozprocess 04:46:52 INFO - Running setup.py install for mozprofile 04:46:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:46:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:46:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:46:52 INFO - Running setup.py install for mozrunner 04:46:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:46:52 INFO - Running setup.py install for mozscreenshot 04:46:52 INFO - Running setup.py install for moztest 04:46:52 INFO - Running setup.py install for mozversion 04:46:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:46:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:46:52 INFO - Cleaning up... 04:46:52 INFO - Return code: 0 04:46:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:46: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')]} 04:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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')]} 04:46:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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 0x1f2a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2159400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e8570>, '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 0x21e9470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e9900>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:46: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 04:46: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 04:46:52 INFO - Using env: {'DISPLAY': ':0', 04:46:52 INFO - 'HOME': '/home/cltbld', 04:46:52 INFO - 'LANG': 'en_US.UTF-8', 04:46:52 INFO - 'LANGUAGE': 'en_US:en', 04:46:52 INFO - 'LOGNAME': 'cltbld', 04:46:52 INFO - 'MAIL': '/var/mail/cltbld', 04:46:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:52 INFO - 'MOZ_NO_REMOTE': '1', 04:46:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:46:52 INFO - 'NO_EM_RESTART': '1', 04:46:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:46:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:52 INFO - 'PWD': '/builds/slave/test', 04:46:52 INFO - 'SHELL': '/bin/bash', 04:46:52 INFO - 'SHLVL': '1', 04:46:52 INFO - 'TERM': 'linux', 04:46:52 INFO - 'TMOUT': '86400', 04:46:52 INFO - 'USER': 'cltbld', 04:46:52 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190', 04:46:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:52 INFO - '_': '/tools/buildbot/bin/python'} 04:46:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:46:52 INFO - Running setup.py (path:/tmp/pip-oBe9Yy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:46:52 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:46:52 INFO - Running setup.py (path:/tmp/pip-Hl27WP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:46:52 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)) 04:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:46:52 INFO - Running setup.py (path:/tmp/pip-31RY8D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:46: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)) 04:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:46:53 INFO - Running setup.py (path:/tmp/pip-UaTUG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:46: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)) 04:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:46:53 INFO - Running setup.py (path:/tmp/pip-xbK5QZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:46: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)) 04:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:46:53 INFO - Running setup.py (path:/tmp/pip-EV20aV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:46: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)) 04:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:46:53 INFO - Running setup.py (path:/tmp/pip-KT34xD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:46: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)) 04:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:46:53 INFO - Running setup.py (path:/tmp/pip-0A49wk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:46: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)) 04:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:46:53 INFO - Running setup.py (path:/tmp/pip-h05peX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:46: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)) 04:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:46:53 INFO - Running setup.py (path:/tmp/pip-O3E37A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:46:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:46:53 INFO - Running setup.py (path:/tmp/pip-krS80j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:46: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)) 04:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:46:53 INFO - Running setup.py (path:/tmp/pip-Ry1qHB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:46:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:46:53 INFO - Running setup.py (path:/tmp/pip-x0KFXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:46:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:46:53 INFO - Running setup.py (path:/tmp/pip-6zlurX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:46:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:46:53 INFO - Running setup.py (path:/tmp/pip-GJNQ71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:46:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:46:53 INFO - Running setup.py (path:/tmp/pip-v4rFn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:46:53 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)) 04:46:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:46:54 INFO - Running setup.py (path:/tmp/pip-7ghv5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:46: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)) 04:46: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)) 04:46: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)) 04:46: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)) 04:46: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)) 04:46: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)) 04:46:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46:55 INFO - Downloading blessings-1.6.tar.gz 04:46:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:46:56 INFO - Installing collected packages: blessings 04:46:56 INFO - Running setup.py install for blessings 04:46:56 INFO - Successfully installed blessings 04:46:56 INFO - Cleaning up... 04:46:56 INFO - Return code: 0 04:46:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:46:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:46:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:46:56 INFO - Reading from file tmpfile_stdout 04:46:56 INFO - Current package versions: 04:46:56 INFO - argparse == 1.2.1 04:46:56 INFO - blessings == 1.6 04:46:56 INFO - blobuploader == 1.2.4 04:46:56 INFO - docopt == 0.6.1 04:46:56 INFO - functools32 == 3.2.3-2 04:46:56 INFO - jsonschema == 2.5.1 04:46:56 INFO - manifestparser == 1.1 04:46:56 INFO - mozInstall == 1.12 04:46:56 INFO - mozcrash == 0.17 04:46:56 INFO - mozdebug == 0.1 04:46:56 INFO - mozdevice == 0.48 04:46:56 INFO - mozfile == 1.2 04:46:56 INFO - mozhttpd == 0.7 04:46:56 INFO - mozinfo == 0.9 04:46:56 INFO - mozleak == 0.1 04:46:56 INFO - mozlog == 3.3 04:46:56 INFO - moznetwork == 0.27 04:46:56 INFO - mozprocess == 0.23 04:46:56 INFO - mozprofile == 0.28 04:46:56 INFO - mozrunner == 6.12 04:46:56 INFO - mozscreenshot == 0.1 04:46:56 INFO - mozsystemmonitor == 0.3 04:46:56 INFO - moztest == 0.7 04:46:56 INFO - mozversion == 1.4 04:46:56 INFO - psutil == 3.1.1 04:46:56 INFO - requests == 1.2.3 04:46:56 INFO - wsgiref == 0.1.2 04:46:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:46: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')]} 04:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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')]} 04:46:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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 0x1f2a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2159400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e8570>, '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 0x21e9470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e9900>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:46: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 04:46: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 04:46:56 INFO - Using env: {'DISPLAY': ':0', 04:46:56 INFO - 'HOME': '/home/cltbld', 04:46:56 INFO - 'LANG': 'en_US.UTF-8', 04:46:56 INFO - 'LANGUAGE': 'en_US:en', 04:46:56 INFO - 'LOGNAME': 'cltbld', 04:46:56 INFO - 'MAIL': '/var/mail/cltbld', 04:46:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:56 INFO - 'MOZ_NO_REMOTE': '1', 04:46:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:46:56 INFO - 'NO_EM_RESTART': '1', 04:46:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:46:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:56 INFO - 'PWD': '/builds/slave/test', 04:46:56 INFO - 'SHELL': '/bin/bash', 04:46:56 INFO - 'SHLVL': '1', 04:46:56 INFO - 'TERM': 'linux', 04:46:56 INFO - 'TMOUT': '86400', 04:46:56 INFO - 'USER': 'cltbld', 04:46:56 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190', 04:46:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:56 INFO - '_': '/tools/buildbot/bin/python'} 04:46:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46: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)) 04:46: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)) 04:46: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)) 04:46: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)) 04:46: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)) 04:46: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)) 04:46: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)) 04:46: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)) 04:46: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)) 04:46: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)) 04:46: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)) 04:46:56 INFO - Cleaning up... 04:46:56 INFO - Return code: 0 04:46:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:46: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')]} 04:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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')]} 04:46:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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 0x1f2a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x208d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2159400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x21e8570>, '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 0x21e9470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e9900>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 04:46: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 04:46: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 04:46:56 INFO - Using env: {'DISPLAY': ':0', 04:46:56 INFO - 'HOME': '/home/cltbld', 04:46:56 INFO - 'LANG': 'en_US.UTF-8', 04:46:56 INFO - 'LANGUAGE': 'en_US:en', 04:46:56 INFO - 'LOGNAME': 'cltbld', 04:46:56 INFO - 'MAIL': '/var/mail/cltbld', 04:46:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:56 INFO - 'MOZ_NO_REMOTE': '1', 04:46:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:46:56 INFO - 'NO_EM_RESTART': '1', 04:46:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:46:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:56 INFO - 'PWD': '/builds/slave/test', 04:46:56 INFO - 'SHELL': '/bin/bash', 04:46:56 INFO - 'SHLVL': '1', 04:46:56 INFO - 'TERM': 'linux', 04:46:56 INFO - 'TMOUT': '86400', 04:46:56 INFO - 'USER': 'cltbld', 04:46:56 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190', 04:46:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:56 INFO - '_': '/tools/buildbot/bin/python'} 04:46:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:46:56 INFO - Cleaning up... 04:46:56 INFO - Return code: 0 04:46:56 INFO - Running post-action listener: _resource_record_post_action 04:46:56 INFO - Running post-action listener: _start_resource_monitoring 04:46:56 INFO - Starting resource monitoring. 04:46:56 INFO - [mozharness: 2016-08-16 11:46:56.912308Z] Finished create-virtualenv step (success) 04:46:56 INFO - [mozharness: 2016-08-16 11:46:56.912739Z] Running install step. 04:46:56 INFO - Running pre-action listener: _resource_record_pre_action 04:46:56 INFO - Running main action method: install 04:46:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:46:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:46:57 INFO - Reading from file tmpfile_stdout 04:46:57 INFO - Detecting whether we're running mozinstall >=1.0... 04:46:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:46:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:46:57 INFO - Reading from file tmpfile_stdout 04:46:57 INFO - Output received: 04:46:57 INFO - Usage: mozinstall [options] installer 04:46:57 INFO - Options: 04:46:57 INFO - -h, --help show this help message and exit 04:46:57 INFO - -d DEST, --destination=DEST 04:46:57 INFO - Directory to install application into. [default: 04:46:57 INFO - "/builds/slave/test"] 04:46:57 INFO - --app=APP Application being installed. [default: firefox] 04:46:57 INFO - mkdir: /builds/slave/test/build/application 04:46:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:46:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 04:47:13 INFO - Reading from file tmpfile_stdout 04:47:13 INFO - Output received: 04:47:13 INFO - /builds/slave/test/build/application/firefox/firefox 04:47:13 INFO - Running post-action listener: _resource_record_post_action 04:47:13 INFO - [mozharness: 2016-08-16 11:47:13.884291Z] Finished install step (success) 04:47:13 INFO - [mozharness: 2016-08-16 11:47:13.884566Z] Running run-tests step. 04:47:13 INFO - Running pre-action listener: _resource_record_pre_action 04:47:13 INFO - Running main action method: run_tests 04:47:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:47:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:47:13 INFO - Python 2.7.3 04:47:13 INFO - Return code: 0 04:47:13 INFO - grabbing minidump binary from tooltool 04:47:13 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')]} 04:47:13 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 0x21e8570>, '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 0x21e9470>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21e9900>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:47:13 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 04:47:13 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 04:47:13 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 04:47:13 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:47:13 INFO - Return code: 0 04:47:13 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:47:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:47:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:47:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:47:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:47:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:47:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-041 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:47:13 INFO - Using env: {'DISPLAY': ':0', 04:47:13 INFO - 'HOME': '/home/cltbld', 04:47:13 INFO - 'LANG': 'en_US.UTF-8', 04:47:13 INFO - 'LANGUAGE': 'en_US:en', 04:47:13 INFO - 'LOGNAME': 'cltbld', 04:47:13 INFO - 'MAIL': '/var/mail/cltbld', 04:47:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:47:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:47:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:13 INFO - 'MOZ_NO_REMOTE': '1', 04:47:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:47:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:47:13 INFO - 'NO_EM_RESTART': '1', 04:47:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:47:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:13 INFO - 'PWD': '/builds/slave/test', 04:47:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:47:13 INFO - 'SHELL': '/bin/bash', 04:47:13 INFO - 'SHLVL': '1', 04:47:13 INFO - 'TERM': 'linux', 04:47:13 INFO - 'TMOUT': '86400', 04:47:13 INFO - 'USER': 'cltbld', 04:47:13 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190', 04:47:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:13 INFO - '_': '/tools/buildbot/bin/python'} 04:47:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:47:14 INFO - mozversion application_buildid: 20160816020522 04:47:14 INFO - mozversion application_changeset: fa8506ec4649acd4ca979fb6039a087d54c1e332 04:47:14 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:47:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:47:14 INFO - mozversion application_name: Firefox 04:47:14 INFO - mozversion application_remotingname: firefox-dev 04:47:14 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:47:14 INFO - mozversion application_vendor: Mozilla 04:47:14 INFO - mozversion application_version: 50.0a2 04:47:14 INFO - mozversion platform_buildid: 20160816020522 04:47:14 INFO - mozversion platform_changeset: fa8506ec4649acd4ca979fb6039a087d54c1e332 04:47:14 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:47:14 INFO - mozversion platform_version: 50.0a2 04:47:14 INFO - using testdate: 1471348034 04:47:14 INFO - actual date: 1471348034 04:47:14 INFO - starting webserver on 'localhost:40137' 04:47:14 INFO - SUITE-START | Running 2 tests 04:47:14 INFO - TEST-START | dromaeo_css 04:47:14 INFO - operating with platform_type : linux_ 04:47:14 INFO - Initialising browser for dromaeo_css test... 04:47:14 INFO - TEST-INFO | started process 7220 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRveaFj/profile http://localhost:40137/getInfo.html) 04:47:18 INFO - PROCESS | 7220 | __metrics Screen width/height:1600/1200 04:47:18 INFO - PROCESS | 7220 | colorDepth:24 04:47:18 INFO - PROCESS | 7220 | Browser inner width/height: 1024/697 04:47:18 INFO - PROCESS | 7220 | __metrics 04:47:19 ERROR - PROCESS | 7220 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 04:47:20 INFO - TEST-INFO | 7220: exit 0 04:47:20 INFO - Browser initialized. 04:47:20 INFO - Running cycle 1/1 for dromaeo_css test... 04:47:20 INFO - TEST-INFO | started process 7344 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpRveaFj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:47:31 INFO - PROCESS | 7344 | RSS: Main: 152895488 04:47:31 INFO - PROCESS | 7344 | 04:48:21 INFO - PROCESS | 7344 | 1471348101552 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] 04:50:29 INFO - PROCESS | 7344 | Cycle 1(1): loaded http://localhost:40137/tests/dromaeo/cssquery-dojo.html (next: http://localhost:40137/tests/dromaeo/cssquery-ext.html) 04:50:31 INFO - PROCESS | 7344 | RSS: Main: 157593600 04:50:31 INFO - PROCESS | 7344 | 04:53:33 INFO - PROCESS | 7344 | Cycle 1(1): loaded http://localhost:40137/tests/dromaeo/cssquery-ext.html (next: http://localhost:40137/tests/dromaeo/cssquery-jquery.html) 04:53:33 INFO - PROCESS | 7344 | RSS: Main: 154140672 04:53:33 INFO - PROCESS | 7344 | 04:56:35 INFO - PROCESS | 7344 | Cycle 1(1): loaded http://localhost:40137/tests/dromaeo/cssquery-jquery.html (next: http://localhost:40137/tests/dromaeo/cssquery-mootools.html) 04:56:36 INFO - PROCESS | 7344 | RSS: Main: 154746880 04:56:36 INFO - PROCESS | 7344 | 04:59:37 INFO - PROCESS | 7344 | Cycle 1(1): loaded http://localhost:40137/tests/dromaeo/cssquery-mootools.html (next: http://localhost:40137/tests/dromaeo/cssquery-prototype.html) 04:59:38 INFO - PROCESS | 7344 | RSS: Main: 152707072 04:59:38 INFO - PROCESS | 7344 | 05:02:40 INFO - PROCESS | 7344 | Cycle 1(1): loaded http://localhost:40137/tests/dromaeo/cssquery-prototype.html (next: http://localhost:40137/tests/dromaeo/cssquery-yui.html) 05:02:41 INFO - PROCESS | 7344 | RSS: Main: 155197440 05:02:41 INFO - PROCESS | 7344 | 05:05:43 INFO - PROCESS | 7344 | Cycle 1(1): loaded http://localhost:40137/tests/dromaeo/cssquery-yui.html (next: http://localhost:40137/tests/dromaeo/cssquery-dojo.html) 05:05:44 INFO - PROCESS | 7344 | RSS: Main: 156172288 05:05:44 INFO - PROCESS | 7344 | 05:05:44 INFO - PROCESS | 7344 | __start_tp_report 05:05:44 INFO - PROCESS | 7344 | _x_x_mozilla_page_load 05:05:44 INFO - PROCESS | 7344 | _x_x_mozilla_page_load_details 05:05:44 INFO - PROCESS | 7344 | |i|pagename|runs| 05:05:44 INFO - PROCESS | 7344 | |0;dojo.html;3274.377868205041;3304.140923359927;3317.386283537547;3317.5023746438033;3337.9833100834508;5623.971880140599;5702.030654788686;5722.055860782966;5735;5740.913886291709;5681.147827825818;5724.828255152342;5744.75954377793;5761.337446193689;5775.797847075342;5182.4299327073995;5209.2707021016895;5232.3459567554055;5266.578673706094;5276.868078298054;5056.039352523027;5063.03802277568;5114.181730923053;5121.487851214886;5204.531592156705;5192.922106168392;5200.32395788547;5215.217717342391;5216.66091704038;5282.260483532308;5198.45416231296;5219.295395121667;5236.136037553799;5240.292560504321;5260.132078207092;4929.827456039022;5100.209467532539;5153.201253805666;5210.583153347723;5222.32109825724;5535.833924982257;5546.251256080418;5548.833534993957;5653.406392328788;5696.2594862668;6032.095185722133;6061.454469097803;6071;6077.787277445268;6088.878222435571;5899.203607512974;6128.785492507741;6137.570370074097;6140.600860944024;6150.108234306001;5650.406707295716;5677.034904066319;5689.431056894319;5743.827685169452;5793.710314484259;6273.37266273369;6279.717412716439;6282.340354262828;6284.780032698834;6288.811335659938;5784;5972.044472884318;6026.186464827192;6042.365551617105;6045.939540604626;5972.641641501524;5992.310884248367;6010.699465026732;6049.062395328731;6087.239095113111;5853.795117170964;5986.820395388058;5989.970050149721;6001.729922153513;6005.129256257906;5807.332156801937;5964.373740757244;5998.940010599838;5999.400059993965;6023.156758053876;4641.118187544355;4641.419822522184;4644.071185762861;4689.929651055237;4707.105649926503;4130.814113364906;4144.7513149211145;4170.749755014709;4173.6661067114555;4200;5522.91715624266;5860.443257890493;5865.3254875689845;5866.589338746332;5867.794627188028;6036.939630603725;6064.4238797315065;6074.969625151846;6113.932424433873;6120.17287048654;5817.7091145442555;5825.213596164548;5844.47399734026;5863.912041319383;5886.352501224862;5080.542751152414;5283.815066472655;5286.788528458896;5291.523762861284;5311.044012077786;6130.673316708229;6210.31686514483;6212.316645169028;6288.182536270256;6299;6057.242844644419;6059.6364218147055;6065.514758819284;6071.494517888488;6088.726007329592;5910.408959104056;6207.503399728102;6295.087212354275;6298.842528936813;6307.274663413583;5936.406359364202;6028.065649824284;6030.065339872327;6076.331603523698;6089.33017368098;6101.755929762938;6132.570720049732;6138.355472675305;6146.446819786241;6163.075538669235;5629.240052592848;5894.970525147347;5989.401059893976;5995.88008239824;6002.489788367863;5914.497267732118;6006.819795406145;6013.188219590299;6020.458158765732;6028.758849645965;5928.703564821828;5948.434898684618;5953.642781433128;5958.582899197015;5961.821145365472;5755.827325180251;5880.588358814842;5973.970130149321;5991.67045812467;6010.398960103955;5778.884422311614;5855.092460668603;5855.7364918579615;5862.882742345215;5887.322957859729 05:05:44 INFO - PROCESS | 7344 | |1;ext.html;587.7002728608411;614.6281499692686;654.908312836203;656.8029591122663;703.9225685174633;42976.57023429769;46441.07117857645;46456.535434645644;46558.30162547556;46588.3011754824;46973.29540056902;47518.287225691645;47526.0494790104;47532.28701569479;47685;9107.225885799715;9223.400478968879;9227.630894764236;9236.21492173166;9242.075792420772;9188.12712792287;9286;9324.393914395543;9334.859977100348;9335.11316424942;9352.953235233814;9420.670276540322;9421.717348479557;9432.339736218468;9432.434053956751;9228.538573071319;9289.256859451227;9297.209737172348;9305.906940930572;9310.162085412312;9189;9208.769780755467;9280.489573073479;9283.58223879927;9294;45676.77161614204;45943.77028114872;45964.54035459636;45971.77014114908;46185.30722039172;3601.3157500074904;3798.2213646202463;3855.6722678572355;4445.844395446176;4531.093781243764;35209;35293.47059794105;35489.82255088734;35746.28507429837;35852.820735896414;4708.458527269374;4737.549932756383;4766.142094423002;4788.832390866303;4806.807727690888;380487;380663.0966845176;381739;382502;384574;65387;65613.34386656168;65803.67098164478;65813;65947.67026164838;37062;37389.43915841264;37446.81276593654;38782.41826372607;71616;19525.121369538403;19888.403347899588;19946.601067978558;19980.60038799245;20004.599908001757;14969.700605987819;15030.849691503163;15051.473198438222;15066.623334416727;15135.167565783768;21415;21561.245356412765;21646.56706865854;21695.891520542296;21718.674219886714;7882.7241046563095;7886.7017475476005;8006.798130514155;8055.63749631268;8075.434719569574;1052.1214785654065;1065.2064212161833;1083.3716444462193;1167.544657583543;1249.7500499900057;35168;35505.28989420249;35531;35690.821545892104;35702.28595428077;1160.3038177093845;1165.4172913543227;1175.623800383869;1187.8158885372782;1266.094742259289;18705.251789928523;18747.437576872715;18817.341393051178;18831.71752423715;18995.43013709591;585365;660339;667437;668616;893931;66712.66643666751;66769.33230667631;67325;67466.325336746;67695;1445.4290555230834;1483.6587584855545;1485.413261761598;1491.4630732936134;1505.4505105636365;35842.169647323266;35864.28271434609;36052.898680518156;36347.45478817767;36528.817355914114;57059.429405705414;57573.424265758484;57885.421145788;57920.71039644943;58026.419735802105;394288;395354;396255;397440;397987;31215.68784312128;31504.684953150176;31794.52308215425;32048.839755801073;32089.839550802095;51992.480075198764;52561.7371913138;52606.47393526016;52697.7365113172;52878.73560632172;43081.845371170646;45390;45585.54414455813;45731;46211.76894115508;43832.342514861666;45306.32040519329;45781.31328030016;46089.308660369454;46806.53193468022;44703.776481117384;45056;45452.77273613611;45933.540664594264;46143.3078503816;37376.252474949804;39188.216235674554;40045.39931900966;40645.79677101596;40746;30176.396472069995;30224.395512090075;30959.226019349695;31222.843885781334;31251.84374078115 05:05:44 INFO - PROCESS | 7344 | |2;jquery.html;399.8620475935801;402.6858950834629;415.8502938941981;427.28429879951074;432.2457311990575;19317.903410482944;20673.896630516825;20865.374038778824;21391.57216855662;21533.784662153375;17007.914960425212;19958.201671933104;20147.194112235495;20513.58972820543;21109.472263193427;5952.821415357535;6052.727627256784;6123.020316749321;6125.050617154326;6127.203463549732;5653.293338332708;5889.2049573307695;5959.1359252908305;5983.730732117044;6026;5956.076808094731;5965.731542080617;6003.0395136778125;6006.21919150512;6029.969850150743;6050.576459647805;6210.720517576698;6237.906431403532;6424.93575064248;6889.655517224119;5989.101634754778;6129.0499972503885;6131.509479241695;6134.325224225332;6139.631622102688;13933.30333483332;14011.719765604732;14099.647508812259;14163.512831152786;14551.70896582073;2443.4746529496065;2499.562576549097;2532.6580911576884;2536.8351057181408;2579.161772423951;10323.63867264642;10356;10800.1899857511;11126.276792008493;11245.662630121027;1968.5964377302619;2106.368089573122;2108.5572029873747;2114.407965769587;2134.466383404149;86924;88872;88975.55512222463;90375.54812225897;90837.54581227117;11315.886841131483;11514.596989105508;11565;11728.94135529317;11927.105467090003;16237.918810405872;16248.431304904272;16266.349346026027;16299.348026079062;16331.591710207102;8036.959815200887;8515.105913879077;8592.914070859337;8624.611892464854;8656;9132.452052876848;9208.90791092094;9251.583678734472;9360.625574976924;9453.480058596842;4906.515636445876;4912.926306105412;4920.926186107211;5029.7736601853;5063.13926632466;2049.118878882065;2067.658836292025;2083.801813956981;2101.0860275780083;2105.336818902041;1021.9131373833159;1025.084718773483;1070.3845288958723;1077.4128100185417;1125.4316570132194;20301.89849050775;21191.68212476783;21275.255366062112;21463.141474341162;21504.78495215028;1045.1638689048882;1059.4649701900646;1068.2255364860414;1114.5931734916696;1142.8400023996646;11319.37743424103;11674;11739.178257521891;11797.292162470108;11849.940750296193;503205;513901;516672;517792;526820;59076.70461647664;88333.55833220793;96021;98183.50908245413;99408;1035.3632516002754;1076.6662334676275;1102.9724256893642;1105.657246253664;1123.1295745797072;18744.6251074975;18895.905520472308;19053;19293.324733634254;19411.2235510578;32049.67950320467;35930.64069359273;44926.3261051091;45339;46690;66306.66846665736;66984;68034;68228.6588567054;70031;7782.143964163824;8788.91211087881;8929.955350223207;9026.277897768032;9072.274218062672;12667.923226525849;14287.57137285883;14382.35279412408;14574.562763117123;14687.045342052732;12578.496860125859;12762.48950042025;12822.87177128254;13060.281684507063;13176.077674562688;6685.665716714242;11548.48031838586;12017.519299227934;12192.08559358034;12198.390080495763;12670.176438531465;12748.171368860994;12822.166559173624;12975.351232438528;13067.869321306665;8372.623231954698;8384.496930184208;8452.704155354533;8489.235968762841;8643.91356086431;8932;12068.275903445821;12643.051771117387;12698.746025079632;12713.682157946125 05:05:44 INFO - PROCESS | 7344 | |3;mootools.html;1543.1204213598248;1545.5749668841067;1732.3936622182239;1829.871908966373;1838.76096107506;8009.639566219519;8503.2347088762;8649.69726059588;9152.130547597975;9206.723798286053;8692;9044.819103617923;9058.139476749699;9069.047749986255;9076.546172691373;2335.8598484090967;2381.940451488718;2390.95218095638;2392.3899405651537;2400.2439231642065;2343.6015877300815;2362.70466191726;2396.7483414241515;2397.5564520563753;2406.362313986795;2355.3640517060385;2365.266767302142;2381;2393.186316652338;2420.685310909579;2311.2488441256573;2338.438774694061;2342.9531409371803;2347.8239132065164;2349.635806450003;2336.3224664847176;2336.8714720690355;2340.590396680574;2346.8122550195944;2357.9410514737096;9031.45811251327;9037.68368107113;9078.77303067422;9094;9130.863037054383;5328.786848526054;5440.510354068153;5493.093639549469;5505.366882808488;5506.889862202734;3227;3298.208429976812;3301.834908254578;3310.139363765414;3347.464405695077;1044.2011860926398;1060.5015642647943;1061.6868023932882;1075.9946200269028;1076.3649446826332;11892.989095926856;11948;11955.701107472296;11989.160758746979;12034.097442691833;4550.817967281272;4560.384348113029;4661.743604101807;4684.273937539619;4772.665913386029;10388.792224155475;10391.960803919548;10399.688009359732;10420.270581059405;10443.11233545142;5486.506214440719;5487.862803429946;5489.176623506426;5492.258545096361;5498.3127109111365;4394.318880573516;4812.831550895702;4971.975140124277;4995.400551933792;5045.9243111352625;3943.92112157761;4047.4131250968444;4075.3683179107284;4113.239050775616;4139.8965025874595;1276.719121793204;1316.2760481735008;1318.8021796730568;1322.0679421008358;1322.1273959187083;1080.3301952789163;1110.9944450277858;1111.9332840029624;1118.3345909184022;1121.0247085035908;6261.405166509175;6319.873602527924;6462.063000864849;6501.122348482989;6558.639274839834;447.7089891570505;458.90592428552065;459.56111913122885;464.7560030983693;470.1818835226749;4542.841000564964;4548.681592288508;4550;4568.040711450566;4627.8611641650805;10653.467326633638;10925.289856159325;11023;11073.557057717762;11114.333140011626;10855.40295283767;10860.67417977462;10877;10882.564697412015;10990.725231869268;7883.36933045355;8230.588470576447;8282.751517454486;8283.04744954338;8404.957975210207;6386.201724784402;6410.647414392253;6540.37866402691;6589.505787066085;6637.900431493435;9643.421394716339;9933.900660993297;9934.403935763588;10070.8489372658;10140.174768541734;10221.693349199535;10249.38503689781;10262.281640285108;10274.537645805807;10354.637587684394;7115.430765538848;7641.503302285334;7692.34615057704;7834.255745704149;7934.087579928382;8415.789605259917;8448.535330556879;8609.311255099452;8640.35197360213;8659.740207793777;7437.888431673421;7493.363064139391;7514.436417268836;7533.058367704037;7717.421193410629;7414.666340014604;7821.335186509211;7861.567613781297;7877.960610196912;7891.408144389079;7379.151397589346;7443.776686699408;7488.5506869588;7512.173660897188;7516.060492438445;5454.372747134131;5456.154296084113;5469.75386107634;5561.582881284001;5609.579281553982;5609.102543593006;5721.713914304351;5726;5763.855903602276;5781.8554536136935 05:05:44 INFO - PROCESS | 7344 | |4;prototype.html;344.05728304445825;347.4042017939237;349.168977832758;350.92279698466336;351.13270222550307;5839.562032847538;5918.644881307125;5940.673262970529;5952.375000624932;5996.52027837773;5757.769689212437;5771.971140144294;5799.710014499279;5811.157382179582;5852;4113.65033972112;4464.642828573723;4474.261746811789;4480.708753930984;4482.932756008662;4488.326750987361;4491.595756381925;4516.570925762064;4517.616002639779;4545.772711364435;4457.888552786174;4481.820727170909;4513.187626227278;4520.593146616804;4524.97737511312;4234.555371685975;4460.4424446944295;4474.597286244254;4489.820407183709;4499.505054444008;3923.4703315052675;3929.941050884239;3945.782981935992;3949.881503554898;3954.228925359556;5658.575606829463;5682.920245153449;5707.229524014247;5710.885782284331;5728.140778883159;5053.216751403502;5088.567471764904;5142.151544995071;5147.433782283946;5204.739763011834;6047.606905551125;6077.4226448487325;6102.786402475892;6108.602940808833;6151.200343955302;4204.516480604739;4351.673624478145;4353.62994145498;4355.107203023373;4356.564343565649;6235.657038862861;6306.815058395527;6317.210348706411;6338.524610654219;6367.713452894631;4455.997400584907;4480.775961201927;4485.686001979895;4488.887777805581;4497.977510112429;4415.933760993587;4420.883277289503;4529.84145554904;4533.342665313511;4545.545445455428;5099.847004589868;5103.209002604603;5103.795848166106;5130.4356520782685;5144.254083157921;6051.152838602599;6082.817515474543;6118.847028824226;6122.112293717386;6130.63216207029;1522.7791970164485;1528.5414375687249;1558.758392449172;1558.9454369097027;1560.6254498920405;976.3995142987012;979.6865003198908;988.8121256961155;1022.4478781458079;1061.9433663504863;5768.798092066758;5796.88406231873;5851.648901065864;5863.5309175265875;5863.70681465925;6163.7842675506145;6194.4115309045565;6211.751529938842;6216.160818289563;6226.346233645492;5572.554195664337;5817.912731309034;5825.824925415659;5832.562557808182;5833.358330583633;3271.72190363817;3287.3260981498734;3311.65227651098;3320.9833950830093;3358.277970236411;6243.188385509855;6506.154199953976;6535.640539770358;6592.604443733391;6614.404703576702;6427.839304017343;6439.903401448982;6443.323451037667;6475;6506.674666266667;6284.905726414107;6488.870222595521;6615.470762339001;6664.566803157779;6678.53250272486;6271.31015588294;6283.403076707706;6287.905681414691;6303.873922521432;6323.525735569759;6060.696935164609;6182.969085154546;6193.752249909953;6218.725161342005;6219.191505104308;6471.838204044937;6509.251436084911;6595;6621.602703837592;6633.6019838809825;4427.335899615084;4464.374987501687;4475;4484.798184081789;4520.62121053082;6181.907271390843;6328.810135695937;6349.206349206349;6363.87272254543;6375.968120159369;6142.6314421134875;6158.661273629993;6178.536609754196;6198.535109866688;6212.565120441525;5935.614185077956;6154;6160.476359509492;6179.907301390573;6231.158793562811;5928.4960778334325;6068.211132552917;6104.4505994460715;6108.664023478574;6133.89589873827;6145.907811382744;6219;6238.968805155945;6239.75040998355;6247.437730604267;5956.404359564009;5997.430244126801;6066.786642671572;6102.877942441215;6110 05:05:44 INFO - PROCESS | 7344 | |5;yui.html;776.3595034096868;815.9918400815992;818.1123481023089;827.8716798896173;949.3212353167486;16221.18894055297;17164.313427462886;17171;17276.82723172768;17278.136093195353;16178.19109044546;17084.060376679277;17335.739963900553;17343.566410839736;17381.30474781007;873.6811063961671;885.7121435533445;895.4135041547764;896.5472436419599;898.6001229452896;896.9013408525057;897.7441429192681;899.5412339706764;899.6311512279966;903.5211337990876;921.806420651664;927.1516562345446;930.9022552631977;932.645594674023;934.9345545811796;930.9953450232739;932.7668082979255;936.1855185988165;938.713692323841;941.0448394879203;687.151368060445;689.1144878830678;689.9724011039552;691.3604915453186;692.9168499780009;16267.51197464078;16280.75578866318;16337.509874703659;16338.18309084541;16352.345906163739;16067.517974460785;16477.58806029847;16713.916430417892;16873.194541288947;16893.662126757517;12949.223046617233;13996.160230386107;14167;14284.57146285613;14720;4194.119234960662;4204.26425375558;4213.9578604213875;4273.038566322583;4297.011687311904;89071.55464222637;91213;91381.54309228412;93844;94742.5262873688;12810.167339122927;12933.224006559449;13004.739905201843;13028;13363.33183340829;23548;23769.524609507713;23781.405464863517;23807.761922380556;24732.629010564855;9016;9341.766455838522;9436.669716560025;9709.66016189444;9894.901050989398;5189.299444575009;5206.3752349717515;5233.775296580569;5247.9737601311745;5282.867928301746;3766.133789228438;3855.4602355670227;3886.3004659161056;3929.115948911472;3930.292547341506;636.147562266565;646.867392184601;662.9734810607522;668.8796016717038;671.0638659070569;691.6610860678231;732.3079689693295;801.3188789528971;821.6631181215673;839.2824135364176;10095.545700443497;10701.304415212986;10748.892511074946;10980.121590272758;11150.275232109887;641.582971068809;733.1898252430987;775.9534427934342;821.8945518277907;861.1990848510819;4898.951010489921;5367.355917289872;5406.107992181246;5426.75579598919;5470.480304371078;110954;112053;112346;114001.4299928495;115500;60667.96190209843;77994.61002694951;104539.47730261453;106203;108998.45500772445;83791.58104209563;92276.53861730649;92492.53753731189;93066.53466732622;96304;10176;10191;10327.225458090823;10343;10489.160867130764;38049.42925856059;46802;50539.49460505348;51354.74322628363;52797;68147.3185268141;74476.2552374491;76259;76479;78504;13009.284489352798;13084.149530280501;13163;13223.537176198786;13680;14475.638109047359;14820.258987050389;14845.92577037108;15386.384544618537;16068.67862642764;13450.798238026242;13526.18842869431;13769.724605507634;14064.296785160497;14148.292585370897;13481.932590336986;13979.09135906163;14008.43966241339;14036.508722194674;14383.712325753635;13630.795538067136;13772;13825.10136841102;13851.515196968057;14172.574822755334;11159.107271418105;11287.661370158909;11403.201775875941;11436.313821170756;11572;10027.949860250652;10338.12125969301;10343.94828025855;10364.378137311785;10592.099671528007 05:05:44 INFO - PROCESS | 7344 | __end_tp_report 05:05:44 INFO - PROCESS | 7344 | __start_cc_report 05:05:44 INFO - PROCESS | 7344 | _x_x_mozilla_cycle_collect,337 05:05:44 INFO - PROCESS | 7344 | __end_cc_report 05:05:44 INFO - PROCESS | 7344 | __startTimestamp1471349144031__endTimestamp 05:05:44 INFO - PROCESS | 7344 | 05:05:44 INFO - PROCESS | 7344 | ------- Summary: start ------- 05:05:44 INFO - PROCESS | 7344 | Number of tests: 6 05:05:44 INFO - PROCESS | 7344 | 05:05:44 INFO - PROCESS | 7344 | [#0] dojo.html Cycles:165 Average:5649.61 Median:5867.79 stddev:632.43 (10.8%) stddev-sans-first:606.30 05:05:44 INFO - PROCESS | 7344 | Values: 3274.4 3304.1 3317.4 3317.5 3338.0 5624.0 5702.0 5722.1 5735.0 5740.9 5681.1 5724.8 5744.8 5761.3 5775.8 5182.4 5209.3 5232.3 5266.6 5276.9 5056.0 5063.0 5114.2 5121.5 5204.5 5192.9 5200.3 5215.2 5216.7 5282.3 5198.5 5219.3 5236.1 5240.3 5260.1 4929.8 5100.2 5153.2 5210.6 5222.3 5535.8 5546.3 5548.8 5653.4 5696.3 6032.1 6061.5 6071.0 6077.8 6088.9 5899.2 6128.8 6137.6 6140.6 6150.1 5650.4 5677.0 5689.4 5743.8 5793.7 6273.4 6279.7 6282.3 6284.8 6288.8 5784.0 5972.0 6026.2 6042.4 6045.9 5972.6 5992.3 6010.7 6049.1 6087.2 5853.8 5986.8 5990.0 6001.7 6005.1 5807.3 5964.4 5998.9 5999.4 6023.2 4641.1 4641.4 4644.1 4689.9 4707.1 4130.8 4144.8 4170.7 4173.7 4200.0 5522.9 5860.4 5865.3 5866.6 5867.8 6036.9 6064.4 6075.0 6113.9 6120.2 5817.7 5825.2 5844.5 5863.9 5886.4 5080.5 5283.8 5286.8 5291.5 5311.0 6130.7 6210.3 6212.3 6288.2 6299.0 6057.2 6059.6 6065.5 6071.5 6088.7 5910.4 6207.5 6295.1 6298.8 6307.3 5936.4 6028.1 6030.1 6076.3 6089.3 6101.8 6132.6 6138.4 6146.4 6163.1 5629.2 5895.0 5989.4 5995.9 6002.5 5914.5 6006.8 6013.2 6020.5 6028.8 5928.7 5948.4 5953.6 5958.6 5961.8 5755.8 5880.6 5974.0 5991.7 6010.4 5778.9 5855.1 5855.7 5862.9 5887.3 05:05:44 INFO - PROCESS | 7344 | 05:05:44 INFO - PROCESS | 7344 | [#1] ext.html Cycles:180 Average:66353.13 Median:45421.39 stddev:137575.34 (302.9%) stddev-sans-first:137872.67 05:05:44 INFO - PROCESS | 7344 | Values: 587.7 614.6 654.9 656.8 703.9 42976.6 46441.1 46456.5 46558.3 46588.3 46973.3 47518.3 47526.0 47532.3 47685.0 9107.2 9223.4 9227.6 9236.2 9242.1 9188.1 9286.0 9324.4 9334.9 9335.1 9353.0 9420.7 9421.7 9432.3 9432.4 9228.5 9289.3 9297.2 9305.9 9310.2 9189.0 9208.8 9280.5 9283.6 9294.0 45676.8 45943.8 45964.5 45971.8 46185.3 3601.3 3798.2 3855.7 4445.8 4531.1 35209.0 35293.5 35489.8 35746.3 35852.8 4708.5 4737.5 4766.1 4788.8 4806.8 380487.0 380663.1 381739.0 382502.0 384574.0 65387.0 65613.3 65803.7 65813.0 65947.7 37062.0 37389.4 37446.8 38782.4 71616.0 19525.1 19888.4 19946.6 19980.6 20004.6 14969.7 15030.8 15051.5 15066.6 15135.2 21415.0 21561.2 21646.6 21695.9 21718.7 7882.7 7886.7 8006.8 8055.6 8075.4 1052.1 1065.2 1083.4 1167.5 1249.8 35168.0 35505.3 35531.0 35690.8 35702.3 1160.3 1165.4 1175.6 1187.8 1266.1 18705.3 18747.4 18817.3 18831.7 18995.4 585365.0 660339.0 667437.0 668616.0 893931.0 66712.7 66769.3 67325.0 67466.3 67695.0 1445.4 1483.7 1485.4 1491.5 1505.5 35842.2 35864.3 36052.9 36347.5 36528.8 57059.4 57573.4 57885.4 57920.7 58026.4 394288.0 395354.0 396255.0 397440.0 397987.0 31215.7 31504.7 31794.5 32048.8 32089.8 51992.5 52561.7 52606.5 52697.7 52878.7 43081.8 45390.0 45585.5 45731.0 46211.8 43832.3 45306.3 45781.3 46089.3 46806.5 44703.8 45056.0 45452.8 45933.5 46143.3 37376.3 39188.2 40045.4 40645.8 40746.0 30176.4 30224.4 30959.2 31222.8 31251.8 05:05:44 INFO - PROCESS | 7344 | 05:05:44 INFO - PROCESS | 7344 | [#2] jquery.html Cycles:180 Average:30291.56 Median:21483.96 stddev:85066.81 (396.0%) stddev-sans-first:85275.83 05:05:44 INFO - PROCESS | 7344 | Values: 399.9 402.7 415.9 427.3 432.2 19317.9 20673.9 20865.4 21391.6 21533.8 17007.9 19958.2 20147.2 20513.6 21109.5 5952.8 6052.7 6123.0 6125.1 6127.2 5653.3 5889.2 5959.1 5983.7 6026.0 5956.1 5965.7 6003.0 6006.2 6030.0 6050.6 6210.7 6237.9 6424.9 6889.7 5989.1 6129.0 6131.5 6134.3 6139.6 13933.3 14011.7 14099.6 14163.5 14551.7 2443.5 2499.6 2532.7 2536.8 2579.2 10323.6 10356.0 10800.2 11126.3 11245.7 1968.6 2106.4 2108.6 2114.4 2134.5 86924.0 88872.0 88975.6 90375.5 90837.5 11315.9 11514.6 11565.0 11728.9 11927.1 16237.9 16248.4 16266.3 16299.3 16331.6 8037.0 8515.1 8592.9 8624.6 8656.0 9132.5 9208.9 9251.6 9360.6 9453.5 4906.5 4912.9 4920.9 5029.8 5063.1 2049.1 2067.7 2083.8 2101.1 2105.3 1021.9 1025.1 1070.4 1077.4 1125.4 20301.9 21191.7 21275.3 21463.1 21504.8 1045.2 1059.5 1068.2 1114.6 1142.8 11319.4 11674.0 11739.2 11797.3 11849.9 503205.0 513901.0 516672.0 517792.0 526820.0 59076.7 88333.6 96021.0 98183.5 99408.0 1035.4 1076.7 1103.0 1105.7 1123.1 18744.6 18895.9 19053.0 19293.3 19411.2 32049.7 35930.6 44926.3 45339.0 46690.0 66306.7 66984.0 68034.0 68228.7 70031.0 7782.1 8788.9 8930.0 9026.3 9072.3 12667.9 14287.6 14382.4 14574.6 14687.0 12578.5 12762.5 12822.9 13060.3 13176.1 6685.7 11548.5 12017.5 12192.1 12198.4 12670.2 12748.2 12822.2 12975.4 13067.9 8372.6 8384.5 8452.7 8489.2 8643.9 8932.0 12068.3 12643.1 12698.7 12713.7 05:05:44 INFO - PROCESS | 7344 | 05:05:44 INFO - PROCESS | 7344 | [#3] mootools.html Cycles:180 Average:5900.01 Median:459.23 stddev:3292.91 (717.0%) stddev-sans-first:3285.87 05:05:44 INFO - PROCESS | 7344 | Values: 1543.1 1545.6 1732.4 1829.9 1838.8 8009.6 8503.2 8649.7 9152.1 9206.7 8692.0 9044.8 9058.1 9069.0 9076.5 2335.9 2381.9 2391.0 2392.4 2400.2 2343.6 2362.7 2396.7 2397.6 2406.4 2355.4 2365.3 2381.0 2393.2 2420.7 2311.2 2338.4 2343.0 2347.8 2349.6 2336.3 2336.9 2340.6 2346.8 2357.9 9031.5 9037.7 9078.8 9094.0 9130.9 5328.8 5440.5 5493.1 5505.4 5506.9 3227.0 3298.2 3301.8 3310.1 3347.5 1044.2 1060.5 1061.7 1076.0 1076.4 11893.0 11948.0 11955.7 11989.2 12034.1 4550.8 4560.4 4661.7 4684.3 4772.7 10388.8 10392.0 10399.7 10420.3 10443.1 5486.5 5487.9 5489.2 5492.3 5498.3 4394.3 4812.8 4972.0 4995.4 5045.9 3943.9 4047.4 4075.4 4113.2 4139.9 1276.7 1316.3 1318.8 1322.1 1322.1 1080.3 1111.0 1111.9 1118.3 1121.0 6261.4 6319.9 6462.1 6501.1 6558.6 447.7 458.9 459.6 464.8 470.2 4542.8 4548.7 4550.0 4568.0 4627.9 10653.5 10925.3 11023.0 11073.6 11114.3 10855.4 10860.7 10877.0 10882.6 10990.7 7883.4 8230.6 8282.8 8283.0 8405.0 6386.2 6410.6 6540.4 6589.5 6637.9 9643.4 9933.9 9934.4 10070.8 10140.2 10221.7 10249.4 10262.3 10274.5 10354.6 7115.4 7641.5 7692.3 7834.3 7934.1 8415.8 8448.5 8609.3 8640.4 8659.7 7437.9 7493.4 7514.4 7533.1 7717.4 7414.7 7821.3 7861.6 7878.0 7891.4 7379.2 7443.8 7488.6 7512.2 7516.1 5454.4 5456.2 5469.8 5561.6 5609.6 5609.1 5721.7 5726.0 5763.9 5781.9 05:05:44 INFO - PROCESS | 7344 | 05:05:44 INFO - PROCESS | 7344 | [#4] prototype.html Cycles:180 Average:5146.08 Median:5857.77 stddev:1537.27 (26.2%) stddev-sans-first:1498.73 05:05:44 INFO - PROCESS | 7344 | Values: 344.1 347.4 349.2 350.9 351.1 5839.6 5918.6 5940.7 5952.4 5996.5 5757.8 5772.0 5799.7 5811.2 5852.0 4113.7 4464.6 4474.3 4480.7 4482.9 4488.3 4491.6 4516.6 4517.6 4545.8 4457.9 4481.8 4513.2 4520.6 4525.0 4234.6 4460.4 4474.6 4489.8 4499.5 3923.5 3929.9 3945.8 3949.9 3954.2 5658.6 5682.9 5707.2 5710.9 5728.1 5053.2 5088.6 5142.2 5147.4 5204.7 6047.6 6077.4 6102.8 6108.6 6151.2 4204.5 4351.7 4353.6 4355.1 4356.6 6235.7 6306.8 6317.2 6338.5 6367.7 4456.0 4480.8 4485.7 4488.9 4498.0 4415.9 4420.9 4529.8 4533.3 4545.5 5099.8 5103.2 5103.8 5130.4 5144.3 6051.2 6082.8 6118.8 6122.1 6130.6 1522.8 1528.5 1558.8 1558.9 1560.6 976.4 979.7 988.8 1022.4 1061.9 5768.8 5796.9 5851.6 5863.5 5863.7 6163.8 6194.4 6211.8 6216.2 6226.3 5572.6 5817.9 5825.8 5832.6 5833.4 3271.7 3287.3 3311.7 3321.0 3358.3 6243.2 6506.2 6535.6 6592.6 6614.4 6427.8 6439.9 6443.3 6475.0 6506.7 6284.9 6488.9 6615.5 6664.6 6678.5 6271.3 6283.4 6287.9 6303.9 6323.5 6060.7 6183.0 6193.8 6218.7 6219.2 6471.8 6509.3 6595.0 6621.6 6633.6 4427.3 4464.4 4475.0 4484.8 4520.6 6181.9 6328.8 6349.2 6363.9 6376.0 6142.6 6158.7 6178.5 6198.5 6212.6 5935.6 6154.0 6160.5 6179.9 6231.2 5928.5 6068.2 6104.5 6108.7 6133.9 6145.9 6219.0 6239.0 6239.8 6247.4 5956.4 5997.4 6066.8 6102.9 6110.0 05:05:44 INFO - PROCESS | 7344 | 05:05:44 INFO - PROCESS | 7344 | [#5] yui.html Cycles:180 Average:21588.69 Median:3870.88 stddev:30477.37 (787.3%) stddev-sans-first:30522.80 05:05:44 INFO - PROCESS | 7344 | Values: 776.4 816.0 818.1 827.9 949.3 16221.2 17164.3 17171.0 17276.8 17278.1 16178.2 17084.1 17335.7 17343.6 17381.3 873.7 885.7 895.4 896.5 898.6 896.9 897.7 899.5 899.6 903.5 921.8 927.2 930.9 932.6 934.9 931.0 932.8 936.2 938.7 941.0 687.2 689.1 690.0 691.4 692.9 16267.5 16280.8 16337.5 16338.2 16352.3 16067.5 16477.6 16713.9 16873.2 16893.7 12949.2 13996.2 14167.0 14284.6 14720.0 4194.1 4204.3 4214.0 4273.0 4297.0 89071.6 91213.0 91381.5 93844.0 94742.5 12810.2 12933.2 13004.7 13028.0 13363.3 23548.0 23769.5 23781.4 23807.8 24732.6 9016.0 9341.8 9436.7 9709.7 9894.9 5189.3 5206.4 5233.8 5248.0 5282.9 3766.1 3855.5 3886.3 3929.1 3930.3 636.1 646.9 663.0 668.9 671.1 691.7 732.3 801.3 821.7 839.3 10095.5 10701.3 10748.9 10980.1 11150.3 641.6 733.2 776.0 821.9 861.2 4899.0 5367.4 5406.1 5426.8 5470.5 110954.0 112053.0 112346.0 114001.4 115500.0 60668.0 77994.6 104539.5 106203.0 108998.5 83791.6 92276.5 92492.5 93066.5 96304.0 10176.0 10191.0 10327.2 10343.0 10489.2 38049.4 46802.0 50539.5 51354.7 52797.0 68147.3 74476.3 76259.0 76479.0 78504.0 13009.3 13084.1 13163.0 13223.5 13680.0 14475.6 14820.3 14845.9 15386.4 16068.7 13450.8 13526.2 13769.7 14064.3 14148.3 13481.9 13979.1 14008.4 14036.5 14383.7 13630.8 13772.0 13825.1 13851.5 14172.6 11159.1 11287.7 11403.2 11436.3 11572.0 10027.9 10338.1 10343.9 10364.4 10592.1 05:05:44 INFO - PROCESS | 7344 | -------- Summary: end -------- 05:05:44 INFO - PROCESS | 7344 | 05:05:44 INFO - TEST-INFO | 7344: exit 0 05:05:44 INFO - TEST-OK | dromaeo_css | took 1110684ms 05:05:44 INFO - TEST-START | kraken 05:05:44 INFO - operating with platform_type : linux_ 05:05:44 INFO - Initialising browser for kraken test... 05:05:44 INFO - TEST-INFO | started process 7616 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp06ydAm/profile http://localhost:40137/getInfo.html) 05:05:49 INFO - PROCESS | 7616 | __metrics Screen width/height:1600/1200 05:05:49 INFO - PROCESS | 7616 | colorDepth:24 05:05:49 INFO - PROCESS | 7616 | Browser inner width/height: 1024/697 05:05:49 INFO - PROCESS | 7616 | __metrics 05:05:49 ERROR - PROCESS | 7616 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 05:05:50 INFO - TEST-INFO | 7616: exit 0 05:05:50 INFO - Browser initialized. 05:05:50 INFO - Running cycle 1/1 for kraken test... 05:05:50 INFO - TEST-INFO | started process 7742 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp06ydAm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:06:02 INFO - PROCESS | 7742 | RSS: Main: 153133056 05:06:02 INFO - PROCESS | 7742 | 05:06:05 INFO - PROCESS | 7742 | Cycle 1(1): loaded http://localhost:40137/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:40137/tests/kraken/driver.html?testName=audio-beat-detection) 05:06:05 INFO - PROCESS | 7742 | RSS: Main: 158527488 05:06:05 INFO - PROCESS | 7742 | 05:06:08 INFO - PROCESS | 7742 | Cycle 1(1): loaded http://localhost:40137/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:40137/tests/kraken/driver.html?testName=audio-dft) 05:06:09 INFO - PROCESS | 7742 | RSS: Main: 156803072 05:06:09 INFO - PROCESS | 7742 | 05:06:12 INFO - PROCESS | 7742 | Cycle 1(1): loaded http://localhost:40137/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:40137/tests/kraken/driver.html?testName=audio-fft) 05:06:12 INFO - PROCESS | 7742 | RSS: Main: 157712384 05:06:12 INFO - PROCESS | 7742 | 05:06:14 INFO - PROCESS | 7742 | Cycle 1(1): loaded http://localhost:40137/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:40137/tests/kraken/driver.html?testName=audio-oscillator) 05:06:15 INFO - PROCESS | 7742 | RSS: Main: 156827648 05:06:15 INFO - PROCESS | 7742 | 05:06:17 INFO - PROCESS | 7742 | Cycle 1(1): loaded http://localhost:40137/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:40137/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:06:18 INFO - PROCESS | 7742 | RSS: Main: 156684288 05:06:18 INFO - PROCESS | 7742 | 05:06:21 INFO - PROCESS | 7742 | Cycle 1(1): loaded http://localhost:40137/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:40137/tests/kraken/driver.html?testName=imaging-darkroom) 05:06:21 INFO - PROCESS | 7742 | RSS: Main: 155815936 05:06:21 INFO - PROCESS | 7742 | 05:06:25 INFO - PROCESS | 7742 | Cycle 1(1): loaded http://localhost:40137/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:40137/tests/kraken/driver.html?testName=imaging-desaturate) 05:06:25 INFO - PROCESS | 7742 | RSS: Main: 156450816 05:06:25 INFO - PROCESS | 7742 | 05:06:28 INFO - PROCESS | 7742 | Cycle 1(1): loaded http://localhost:40137/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:40137/tests/kraken/driver.html?testName=json-parse-financial) 05:06:29 INFO - PROCESS | 7742 | RSS: Main: 157515776 05:06:29 INFO - PROCESS | 7742 | 05:06:30 INFO - PROCESS | 7742 | Cycle 1(1): loaded http://localhost:40137/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:40137/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:06:31 INFO - PROCESS | 7742 | RSS: Main: 156897280 05:06:31 INFO - PROCESS | 7742 | 05:06:33 INFO - PROCESS | 7742 | Cycle 1(1): loaded http://localhost:40137/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:40137/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:06:33 INFO - PROCESS | 7742 | RSS: Main: 157044736 05:06:33 INFO - PROCESS | 7742 | 05:06:35 INFO - PROCESS | 7742 | Cycle 1(1): loaded http://localhost:40137/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:40137/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:06:36 INFO - PROCESS | 7742 | RSS: Main: 157073408 05:06:36 INFO - PROCESS | 7742 | 05:06:39 INFO - PROCESS | 7742 | Cycle 1(1): loaded http://localhost:40137/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:40137/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:06:39 INFO - PROCESS | 7742 | RSS: Main: 157687808 05:06:39 INFO - PROCESS | 7742 | 05:06:42 INFO - PROCESS | 7742 | Cycle 1(1): loaded http://localhost:40137/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:40137/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:06:43 INFO - PROCESS | 7742 | RSS: Main: 157564928 05:06:43 INFO - PROCESS | 7742 | 05:06:44 INFO - PROCESS | 7742 | Cycle 1(1): loaded http://localhost:40137/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:40137/tests/kraken/driver.html?testName=ai-astar) 05:06:44 INFO - PROCESS | 7742 | RSS: Main: 158179328 05:06:44 INFO - PROCESS | 7742 | 05:06:44 INFO - PROCESS | 7742 | __start_tp_report 05:06:44 INFO - PROCESS | 7742 | _x_x_mozilla_page_load 05:06:44 INFO - PROCESS | 7742 | _x_x_mozilla_page_load_details 05:06:44 INFO - PROCESS | 7742 | |i|pagename|runs| 05:06:44 INFO - PROCESS | 7742 | |0;ai-astar;100;110;105;111;113;112;111;111;110;112 05:06:44 INFO - PROCESS | 7742 | |1;audio-beat-detection;145;116;141;181;118;144;147;119;183;118 05:06:44 INFO - PROCESS | 7742 | |2;audio-dft;163;163;163;162;166;162;161;163;162;163 05:06:44 INFO - PROCESS | 7742 | |3;audio-fft;76;75;76;171;76;76;75;75;169;76 05:06:44 INFO - PROCESS | 7742 | |4;audio-oscillator;84;84;153;83;84;84;134;84;82;144 05:06:44 INFO - PROCESS | 7742 | |5;imaging-gaussian-blur;117;117;117;120;117;120;118;118;119;118 05:06:44 INFO - PROCESS | 7742 | |6;imaging-darkroom;148;149;146;148;147;147;147;148;148;147 05:06:44 INFO - PROCESS | 7742 | |7;imaging-desaturate;96;95;95;95;96;95;96;96;94;94 05:06:44 INFO - PROCESS | 7742 | |8;json-parse-financial;54;51;51;53;60;53;52;57;51;51 05:06:44 INFO - PROCESS | 7742 | |9;json-stringify-tinderbox;59;59;60;59;59;59;60;59;60;59 05:06:44 INFO - PROCESS | 7742 | |10;stanford-crypto-aes;73;82;73;72;73;72;72;81;72;73 05:06:44 INFO - PROCESS | 7742 | |11;stanford-crypto-ccm;136;118;118;122;117;118;123;134;148;131 05:06:44 INFO - PROCESS | 7742 | |12;stanford-crypto-pbkdf2;155;151;159;152;155;150;153;152;150;153 05:06:44 INFO - PROCESS | 7742 | |13;stanford-crypto-sha256-iterative;67;64;64;63;65;63;67;65;62;66 05:06:44 INFO - PROCESS | 7742 | __end_tp_report 05:06:44 INFO - PROCESS | 7742 | __start_cc_report 05:06:44 INFO - PROCESS | 7742 | _x_x_mozilla_cycle_collect,850 05:06:44 INFO - PROCESS | 7742 | __end_cc_report 05:06:44 INFO - PROCESS | 7742 | __startTimestamp1471349204950__endTimestamp 05:06:44 INFO - PROCESS | 7742 | 05:06:44 INFO - PROCESS | 7742 | ------- Summary: start ------- 05:06:44 INFO - PROCESS | 7742 | Number of tests: 14 05:06:44 INFO - PROCESS | 7742 | 05:06:44 INFO - PROCESS | 7742 | [#0] ai-astar Cycles:10 Average:109.50 Median:111.00 stddev:3.98 (3.6%) stddev-sans-first:2.30 05:06:44 INFO - PROCESS | 7742 | Values: 100.0 110.0 105.0 111.0 113.0 112.0 111.0 111.0 110.0 112.0 05:06:44 INFO - PROCESS | 7742 | 05:06:44 INFO - PROCESS | 7742 | [#1] audio-beat-detection Cycles:10 Average:141.20 Median:144.50 stddev:24.93 (17.2%) stddev-sans-first:26.40 05:06:44 INFO - PROCESS | 7742 | Values: 145.0 116.0 141.0 181.0 118.0 144.0 147.0 119.0 183.0 118.0 05:06:44 INFO - PROCESS | 7742 | 05:06:44 INFO - PROCESS | 7742 | [#2] audio-dft Cycles:10 Average:162.80 Median:163.00 stddev:1.32 (0.8%) stddev-sans-first:1.39 05:06:44 INFO - PROCESS | 7742 | Values: 163.0 163.0 163.0 162.0 166.0 162.0 161.0 163.0 162.0 163.0 05:06:44 INFO - PROCESS | 7742 | 05:06:44 INFO - PROCESS | 7742 | [#3] audio-fft Cycles:10 Average:94.50 Median:76.00 stddev:39.80 (52.4%) stddev-sans-first:41.64 05:06:44 INFO - PROCESS | 7742 | Values: 76.0 75.0 76.0 171.0 76.0 76.0 75.0 75.0 169.0 76.0 05:06:44 INFO - PROCESS | 7742 | 05:06:44 INFO - PROCESS | 7742 | [#4] audio-oscillator Cycles:10 Average:101.60 Median:84.00 stddev:29.38 (35.0%) stddev-sans-first:30.46 05:06:44 INFO - PROCESS | 7742 | Values: 84.0 84.0 153.0 83.0 84.0 84.0 134.0 84.0 82.0 144.0 05:06:44 INFO - PROCESS | 7742 | 05:06:44 INFO - PROCESS | 7742 | [#5] imaging-gaussian-blur Cycles:10 Average:118.10 Median:118.00 stddev:1.20 (1.0%) stddev-sans-first:1.20 05:06:44 INFO - PROCESS | 7742 | Values: 117.0 117.0 117.0 120.0 117.0 120.0 118.0 118.0 119.0 118.0 05:06:44 INFO - PROCESS | 7742 | 05:06:44 INFO - PROCESS | 7742 | [#6] imaging-darkroom Cycles:10 Average:147.50 Median:148.00 stddev:0.85 (0.6%) stddev-sans-first:0.88 05:06:44 INFO - PROCESS | 7742 | Values: 148.0 149.0 146.0 148.0 147.0 147.0 147.0 148.0 148.0 147.0 05:06:44 INFO - PROCESS | 7742 | 05:06:44 INFO - PROCESS | 7742 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.50 stddev:0.79 (0.8%) stddev-sans-first:0.78 05:06:44 INFO - PROCESS | 7742 | Values: 96.0 95.0 95.0 95.0 96.0 95.0 96.0 96.0 94.0 94.0 05:06:44 INFO - PROCESS | 7742 | 05:06:44 INFO - PROCESS | 7742 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:53.00 stddev:3.02 (5.7%) stddev-sans-first:3.19 05:06:44 INFO - PROCESS | 7742 | Values: 54.0 51.0 51.0 53.0 60.0 53.0 52.0 57.0 51.0 51.0 05:06:44 INFO - PROCESS | 7742 | 05:06:44 INFO - PROCESS | 7742 | [#9] json-stringify-tinderbox Cycles:10 Average:59.30 Median:59.00 stddev:0.48 (0.8%) stddev-sans-first:0.50 05:06:44 INFO - PROCESS | 7742 | Values: 59.0 59.0 60.0 59.0 59.0 59.0 60.0 59.0 60.0 59.0 05:06:44 INFO - PROCESS | 7742 | 05:06:44 INFO - PROCESS | 7742 | [#10] stanford-crypto-aes Cycles:10 Average:74.30 Median:73.00 stddev:3.83 (5.2%) stddev-sans-first:4.03 05:06:44 INFO - PROCESS | 7742 | Values: 73.0 82.0 73.0 72.0 73.0 72.0 72.0 81.0 72.0 73.0 05:06:44 INFO - PROCESS | 7742 | 05:06:44 INFO - PROCESS | 7742 | [#11] stanford-crypto-ccm Cycles:10 Average:126.50 Median:127.00 stddev:10.37 (8.2%) stddev-sans-first:10.42 05:06:44 INFO - PROCESS | 7742 | Values: 136.0 118.0 118.0 122.0 117.0 118.0 123.0 134.0 148.0 131.0 05:06:44 INFO - PROCESS | 7742 | 05:06:44 INFO - PROCESS | 7742 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.00 Median:153.00 stddev:2.75 (1.8%) stddev-sans-first:2.82 05:06:44 INFO - PROCESS | 7742 | Values: 155.0 151.0 159.0 152.0 155.0 150.0 153.0 152.0 150.0 153.0 05:06:44 INFO - PROCESS | 7742 | 05:06:44 INFO - PROCESS | 7742 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.60 Median:65.00 stddev:1.71 (2.6%) stddev-sans-first:1.58 05:06:44 INFO - PROCESS | 7742 | Values: 67.0 64.0 64.0 63.0 65.0 63.0 67.0 65.0 62.0 66.0 05:06:44 INFO - PROCESS | 7742 | -------- Summary: end -------- 05:06:44 INFO - PROCESS | 7742 | 05:06:45 INFO - TEST-INFO | 7742: exit 0 05:06:45 INFO - TEST-OK | kraken | took 61151ms 05:06:45 INFO - SUITE-END | took 1171s 05:06:46 INFO - Completed test suite (00:19:31) 05:06:46 INFO - javascript benchmark 05:06:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7696.350320732855, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3274.377868205041, 3304.140923359927, 3317.386283537547, 3317.5023746438033, 3337.9833100834508, 5623.971880140599, 5702.030654788686, 5722.055860782966, 5735.0, 5740.913886291709, 5681.147827825818, 5724.828255152342, 5744.75954377793, 5761.337446193689, 5775.797847075342, 5182.4299327073995, 5209.2707021016895, 5232.3459567554055, 5266.578673706094, 5276.868078298054, 5056.039352523027, 5063.03802277568, 5114.181730923053, 5121.487851214886, 5204.531592156705, 5192.922106168392, 5200.32395788547, 5215.217717342391, 5216.66091704038, 5282.260483532308, 5198.45416231296, 5219.295395121667, 5236.136037553799, 5240.292560504321, 5260.132078207092, 4929.827456039022, 5100.209467532539, 5153.201253805666, 5210.583153347723, 5222.32109825724, 5535.833924982257, 5546.251256080418, 5548.833534993957, 5653.406392328788, 5696.2594862668, 6032.095185722133, 6061.454469097803, 6071.0, 6077.787277445268, 6088.878222435571, 5899.203607512974, 6128.785492507741, 6137.570370074097, 6140.600860944024, 6150.108234306001, 5650.406707295716, 5677.034904066319, 5689.431056894319, 5743.827685169452, 5793.710314484259, 6273.37266273369, 6279.717412716439, 6282.340354262828, 6284.780032698834, 6288.811335659938, 5784.0, 5972.044472884318, 6026.186464827192, 6042.365551617105, 6045.939540604626, 5972.641641501524, 5992.310884248367, 6010.699465026732, 6049.062395328731, 6087.239095113111, 5853.795117170964, 5986.820395388058, 5989.970050149721, 6001.729922153513, 6005.129256257906, 5807.332156801937, 5964.373740757244, 5998.940010599838, 5999.400059993965, 6023.156758053876, 4641.118187544355, 4641.419822522184, 4644.071185762861, 4689.929651055237, 4707.105649926503, 4130.814113364906, 4144.7513149211145, 4170.749755014709, 4173.6661067114555, 4200.0, 5522.91715624266, 5860.443257890493, 5865.3254875689845, 5866.589338746332, 5867.794627188028, 6036.939630603725, 6064.4238797315065, 6074.969625151846, 6113.932424433873, 6120.17287048654, 5817.7091145442555, 5825.213596164548, 5844.47399734026, 5863.912041319383, 5886.352501224862, 5080.542751152414, 5283.815066472655, 5286.788528458896, 5291.523762861284, 5311.044012077786, 6130.673316708229, 6210.31686514483, 6212.316645169028, 6288.182536270256, 6299.0, 6057.242844644419, 6059.6364218147055, 6065.514758819284, 6071.494517888488, 6088.726007329592, 5910.408959104056, 6207.503399728102, 6295.087212354275, 6298.842528936813, 6307.274663413583, 5936.406359364202, 6028.065649824284, 6030.065339872327, 6076.331603523698, 6089.33017368098, 6101.755929762938, 6132.570720049732, 6138.355472675305, 6146.446819786241, 6163.075538669235, 5629.240052592848, 5894.970525147347, 5989.401059893976, 5995.88008239824, 6002.489788367863, 5914.497267732118, 6006.819795406145, 6013.188219590299, 6020.458158765732, 6028.758849645965, 5928.703564821828, 5948.434898684618, 5953.642781433128, 5958.582899197015, 5961.821145365472, 5755.827325180251, 5880.588358814842, 5973.970130149321, 5991.67045812467, 6010.398960103955, 5778.884422311614, 5855.092460668603, 5855.7364918579615, 5862.882742345215, 5887.322957859729], "value": 5607.600701545257, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [587.7002728608411, 614.6281499692686, 654.908312836203, 656.8029591122663, 703.9225685174633, 42976.57023429769, 46441.07117857645, 46456.535434645644, 46558.30162547556, 46588.3011754824, 46973.29540056902, 47518.287225691645, 47526.0494790104, 47532.28701569479, 47685.0, 9107.225885799715, 9223.400478968879, 9227.630894764236, 9236.21492173166, 9242.075792420772, 9188.12712792287, 9286.0, 9324.393914395543, 9334.859977100348, 9335.11316424942, 9352.953235233814, 9420.670276540322, 9421.717348479557, 9432.339736218468, 9432.434053956751, 9228.538573071319, 9289.256859451227, 9297.209737172348, 9305.906940930572, 9310.162085412312, 9189.0, 9208.769780755467, 9280.489573073479, 9283.58223879927, 9294.0, 45676.77161614204, 45943.77028114872, 45964.54035459636, 45971.77014114908, 46185.30722039172, 3601.3157500074904, 3798.2213646202463, 3855.6722678572355, 4445.844395446176, 4531.093781243764, 35209.0, 35293.47059794105, 35489.82255088734, 35746.28507429837, 35852.820735896414, 4708.458527269374, 4737.549932756383, 4766.142094423002, 4788.832390866303, 4806.807727690888, 380487.0, 380663.0966845176, 381739.0, 382502.0, 384574.0, 65387.0, 65613.34386656168, 65803.67098164478, 65813.0, 65947.67026164838, 37062.0, 37389.43915841264, 37446.81276593654, 38782.41826372607, 71616.0, 19525.121369538403, 19888.403347899588, 19946.601067978558, 19980.60038799245, 20004.599908001757, 14969.700605987819, 15030.849691503163, 15051.473198438222, 15066.623334416727, 15135.167565783768, 21415.0, 21561.245356412765, 21646.56706865854, 21695.891520542296, 21718.674219886714, 7882.7241046563095, 7886.7017475476005, 8006.798130514155, 8055.63749631268, 8075.434719569574, 1052.1214785654065, 1065.2064212161833, 1083.3716444462193, 1167.544657583543, 1249.7500499900057, 35168.0, 35505.28989420249, 35531.0, 35690.821545892104, 35702.28595428077, 1160.3038177093845, 1165.4172913543227, 1175.623800383869, 1187.8158885372782, 1266.094742259289, 18705.251789928523, 18747.437576872715, 18817.341393051178, 18831.71752423715, 18995.43013709591, 585365.0, 660339.0, 667437.0, 668616.0, 893931.0, 66712.66643666751, 66769.33230667631, 67325.0, 67466.325336746, 67695.0, 1445.4290555230834, 1483.6587584855545, 1485.413261761598, 1491.4630732936134, 1505.4505105636365, 35842.169647323266, 35864.28271434609, 36052.898680518156, 36347.45478817767, 36528.817355914114, 57059.429405705414, 57573.424265758484, 57885.421145788, 57920.71039644943, 58026.419735802105, 394288.0, 395354.0, 396255.0, 397440.0, 397987.0, 31215.68784312128, 31504.684953150176, 31794.52308215425, 32048.839755801073, 32089.839550802095, 51992.480075198764, 52561.7371913138, 52606.47393526016, 52697.7365113172, 52878.73560632172, 43081.845371170646, 45390.0, 45585.54414455813, 45731.0, 46211.76894115508, 43832.342514861666, 45306.32040519329, 45781.31328030016, 46089.308660369454, 46806.53193468022, 44703.776481117384, 45056.0, 45452.77273613611, 45933.540664594264, 46143.3078503816, 37376.252474949804, 39188.216235674554, 40045.39931900966, 40645.79677101596, 40746.0, 30176.396472069995, 30224.395512090075, 30959.226019349695, 31222.843885781334, 31251.84374078115], "value": 21868.13159636545, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.8620475935801, 402.6858950834629, 415.8502938941981, 427.28429879951074, 432.2457311990575, 19317.903410482944, 20673.896630516825, 20865.374038778824, 21391.57216855662, 21533.784662153375, 17007.914960425212, 19958.201671933104, 20147.194112235495, 20513.58972820543, 21109.472263193427, 5952.821415357535, 6052.727627256784, 6123.020316749321, 6125.050617154326, 6127.203463549732, 5653.293338332708, 5889.2049573307695, 5959.1359252908305, 5983.730732117044, 6026.0, 5956.076808094731, 5965.731542080617, 6003.0395136778125, 6006.21919150512, 6029.969850150743, 6050.576459647805, 6210.720517576698, 6237.906431403532, 6424.93575064248, 6889.655517224119, 5989.101634754778, 6129.0499972503885, 6131.509479241695, 6134.325224225332, 6139.631622102688, 13933.30333483332, 14011.719765604732, 14099.647508812259, 14163.512831152786, 14551.70896582073, 2443.4746529496065, 2499.562576549097, 2532.6580911576884, 2536.8351057181408, 2579.161772423951, 10323.63867264642, 10356.0, 10800.1899857511, 11126.276792008493, 11245.662630121027, 1968.5964377302619, 2106.368089573122, 2108.5572029873747, 2114.407965769587, 2134.466383404149, 86924.0, 88872.0, 88975.55512222463, 90375.54812225897, 90837.54581227117, 11315.886841131483, 11514.596989105508, 11565.0, 11728.94135529317, 11927.105467090003, 16237.918810405872, 16248.431304904272, 16266.349346026027, 16299.348026079062, 16331.591710207102, 8036.959815200887, 8515.105913879077, 8592.914070859337, 8624.611892464854, 8656.0, 9132.452052876848, 9208.90791092094, 9251.583678734472, 9360.625574976924, 9453.480058596842, 4906.515636445876, 4912.926306105412, 4920.926186107211, 5029.7736601853, 5063.13926632466, 2049.118878882065, 2067.658836292025, 2083.801813956981, 2101.0860275780083, 2105.336818902041, 1021.9131373833159, 1025.084718773483, 1070.3845288958723, 1077.4128100185417, 1125.4316570132194, 20301.89849050775, 21191.68212476783, 21275.255366062112, 21463.141474341162, 21504.78495215028, 1045.1638689048882, 1059.4649701900646, 1068.2255364860414, 1114.5931734916696, 1142.8400023996646, 11319.37743424103, 11674.0, 11739.178257521891, 11797.292162470108, 11849.940750296193, 503205.0, 513901.0, 516672.0, 517792.0, 526820.0, 59076.70461647664, 88333.55833220793, 96021.0, 98183.50908245413, 99408.0, 1035.3632516002754, 1076.6662334676275, 1102.9724256893642, 1105.657246253664, 1123.1295745797072, 18744.6251074975, 18895.905520472308, 19053.0, 19293.324733634254, 19411.2235510578, 32049.67950320467, 35930.64069359273, 44926.3261051091, 45339.0, 46690.0, 66306.66846665736, 66984.0, 68034.0, 68228.6588567054, 70031.0, 7782.143964163824, 8788.91211087881, 8929.955350223207, 9026.277897768032, 9072.274218062672, 12667.923226525849, 14287.57137285883, 14382.35279412408, 14574.562763117123, 14687.045342052732, 12578.496860125859, 12762.48950042025, 12822.87177128254, 13060.281684507063, 13176.077674562688, 6685.665716714242, 11548.48031838586, 12017.519299227934, 12192.08559358034, 12198.390080495763, 12670.176438531465, 12748.171368860994, 12822.166559173624, 12975.351232438528, 13067.869321306665, 8372.623231954698, 8384.496930184208, 8452.704155354533, 8489.235968762841, 8643.91356086431, 8932.0, 12068.275903445821, 12643.051771117387, 12698.746025079632, 12713.682157946125], "value": 9635.595394078864, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1543.1204213598248, 1545.5749668841067, 1732.3936622182239, 1829.871908966373, 1838.76096107506, 8009.639566219519, 8503.2347088762, 8649.69726059588, 9152.130547597975, 9206.723798286053, 8692.0, 9044.819103617923, 9058.139476749699, 9069.047749986255, 9076.546172691373, 2335.8598484090967, 2381.940451488718, 2390.95218095638, 2392.3899405651537, 2400.2439231642065, 2343.6015877300815, 2362.70466191726, 2396.7483414241515, 2397.5564520563753, 2406.362313986795, 2355.3640517060385, 2365.266767302142, 2381.0, 2393.186316652338, 2420.685310909579, 2311.2488441256573, 2338.438774694061, 2342.9531409371803, 2347.8239132065164, 2349.635806450003, 2336.3224664847176, 2336.8714720690355, 2340.590396680574, 2346.8122550195944, 2357.9410514737096, 9031.45811251327, 9037.68368107113, 9078.77303067422, 9094.0, 9130.863037054383, 5328.786848526054, 5440.510354068153, 5493.093639549469, 5505.366882808488, 5506.889862202734, 3227.0, 3298.208429976812, 3301.834908254578, 3310.139363765414, 3347.464405695077, 1044.2011860926398, 1060.5015642647943, 1061.6868023932882, 1075.9946200269028, 1076.3649446826332, 11892.989095926856, 11948.0, 11955.701107472296, 11989.160758746979, 12034.097442691833, 4550.817967281272, 4560.384348113029, 4661.743604101807, 4684.273937539619, 4772.665913386029, 10388.792224155475, 10391.960803919548, 10399.688009359732, 10420.270581059405, 10443.11233545142, 5486.506214440719, 5487.862803429946, 5489.176623506426, 5492.258545096361, 5498.3127109111365, 4394.318880573516, 4812.831550895702, 4971.975140124277, 4995.400551933792, 5045.9243111352625, 3943.92112157761, 4047.4131250968444, 4075.3683179107284, 4113.239050775616, 4139.8965025874595, 1276.719121793204, 1316.2760481735008, 1318.8021796730568, 1322.0679421008358, 1322.1273959187083, 1080.3301952789163, 1110.9944450277858, 1111.9332840029624, 1118.3345909184022, 1121.0247085035908, 6261.405166509175, 6319.873602527924, 6462.063000864849, 6501.122348482989, 6558.639274839834, 447.7089891570505, 458.90592428552065, 459.56111913122885, 464.7560030983693, 470.1818835226749, 4542.841000564964, 4548.681592288508, 4550.0, 4568.040711450566, 4627.8611641650805, 10653.467326633638, 10925.289856159325, 11023.0, 11073.557057717762, 11114.333140011626, 10855.40295283767, 10860.67417977462, 10877.0, 10882.564697412015, 10990.725231869268, 7883.36933045355, 8230.588470576447, 8282.751517454486, 8283.04744954338, 8404.957975210207, 6386.201724784402, 6410.647414392253, 6540.37866402691, 6589.505787066085, 6637.900431493435, 9643.421394716339, 9933.900660993297, 9934.403935763588, 10070.8489372658, 10140.174768541734, 10221.693349199535, 10249.38503689781, 10262.281640285108, 10274.537645805807, 10354.637587684394, 7115.430765538848, 7641.503302285334, 7692.34615057704, 7834.255745704149, 7934.087579928382, 8415.789605259917, 8448.535330556879, 8609.311255099452, 8640.35197360213, 8659.740207793777, 7437.888431673421, 7493.363064139391, 7514.436417268836, 7533.058367704037, 7717.421193410629, 7414.666340014604, 7821.335186509211, 7861.567613781297, 7877.960610196912, 7891.408144389079, 7379.151397589346, 7443.776686699408, 7488.5506869588, 7512.173660897188, 7516.060492438445, 5454.372747134131, 5456.154296084113, 5469.75386107634, 5561.582881284001, 5609.579281553982, 5609.102543593006, 5721.713914304351, 5726.0, 5763.855903602276, 5781.8554536136935], "value": 4669.1307513110605, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [344.05728304445825, 347.4042017939237, 349.168977832758, 350.92279698466336, 351.13270222550307, 5839.562032847538, 5918.644881307125, 5940.673262970529, 5952.375000624932, 5996.52027837773, 5757.769689212437, 5771.971140144294, 5799.710014499279, 5811.157382179582, 5852.0, 4113.65033972112, 4464.642828573723, 4474.261746811789, 4480.708753930984, 4482.932756008662, 4488.326750987361, 4491.595756381925, 4516.570925762064, 4517.616002639779, 4545.772711364435, 4457.888552786174, 4481.820727170909, 4513.187626227278, 4520.593146616804, 4524.97737511312, 4234.555371685975, 4460.4424446944295, 4474.597286244254, 4489.820407183709, 4499.505054444008, 3923.4703315052675, 3929.941050884239, 3945.782981935992, 3949.881503554898, 3954.228925359556, 5658.575606829463, 5682.920245153449, 5707.229524014247, 5710.885782284331, 5728.140778883159, 5053.216751403502, 5088.567471764904, 5142.151544995071, 5147.433782283946, 5204.739763011834, 6047.606905551125, 6077.4226448487325, 6102.786402475892, 6108.602940808833, 6151.200343955302, 4204.516480604739, 4351.673624478145, 4353.62994145498, 4355.107203023373, 4356.564343565649, 6235.657038862861, 6306.815058395527, 6317.210348706411, 6338.524610654219, 6367.713452894631, 4455.997400584907, 4480.775961201927, 4485.686001979895, 4488.887777805581, 4497.977510112429, 4415.933760993587, 4420.883277289503, 4529.84145554904, 4533.342665313511, 4545.545445455428, 5099.847004589868, 5103.209002604603, 5103.795848166106, 5130.4356520782685, 5144.254083157921, 6051.152838602599, 6082.817515474543, 6118.847028824226, 6122.112293717386, 6130.63216207029, 1522.7791970164485, 1528.5414375687249, 1558.758392449172, 1558.9454369097027, 1560.6254498920405, 976.3995142987012, 979.6865003198908, 988.8121256961155, 1022.4478781458079, 1061.9433663504863, 5768.798092066758, 5796.88406231873, 5851.648901065864, 5863.5309175265875, 5863.70681465925, 6163.7842675506145, 6194.4115309045565, 6211.751529938842, 6216.160818289563, 6226.346233645492, 5572.554195664337, 5817.912731309034, 5825.824925415659, 5832.562557808182, 5833.358330583633, 3271.72190363817, 3287.3260981498734, 3311.65227651098, 3320.9833950830093, 3358.277970236411, 6243.188385509855, 6506.154199953976, 6535.640539770358, 6592.604443733391, 6614.404703576702, 6427.839304017343, 6439.903401448982, 6443.323451037667, 6475.0, 6506.674666266667, 6284.905726414107, 6488.870222595521, 6615.470762339001, 6664.566803157779, 6678.53250272486, 6271.31015588294, 6283.403076707706, 6287.905681414691, 6303.873922521432, 6323.525735569759, 6060.696935164609, 6182.969085154546, 6193.752249909953, 6218.725161342005, 6219.191505104308, 6471.838204044937, 6509.251436084911, 6595.0, 6621.602703837592, 6633.6019838809825, 4427.335899615084, 4464.374987501687, 4475.0, 4484.798184081789, 4520.62121053082, 6181.907271390843, 6328.810135695937, 6349.206349206349, 6363.87272254543, 6375.968120159369, 6142.6314421134875, 6158.661273629993, 6178.536609754196, 6198.535109866688, 6212.565120441525, 5935.614185077956, 6154.0, 6160.476359509492, 6179.907301390573, 6231.158793562811, 5928.4960778334325, 6068.211132552917, 6104.4505994460715, 6108.664023478574, 6133.89589873827, 6145.907811382744, 6219.0, 6238.968805155945, 6239.75040998355, 6247.437730604267, 5956.404359564009, 5997.430244126801, 6066.786642671572, 6102.877942441215, 6110.0], "value": 4648.605285629439, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [776.3595034096868, 815.9918400815992, 818.1123481023089, 827.8716798896173, 949.3212353167486, 16221.18894055297, 17164.313427462886, 17171.0, 17276.82723172768, 17278.136093195353, 16178.19109044546, 17084.060376679277, 17335.739963900553, 17343.566410839736, 17381.30474781007, 873.6811063961671, 885.7121435533445, 895.4135041547764, 896.5472436419599, 898.6001229452896, 896.9013408525057, 897.7441429192681, 899.5412339706764, 899.6311512279966, 903.5211337990876, 921.806420651664, 927.1516562345446, 930.9022552631977, 932.645594674023, 934.9345545811796, 930.9953450232739, 932.7668082979255, 936.1855185988165, 938.713692323841, 941.0448394879203, 687.151368060445, 689.1144878830678, 689.9724011039552, 691.3604915453186, 692.9168499780009, 16267.51197464078, 16280.75578866318, 16337.509874703659, 16338.18309084541, 16352.345906163739, 16067.517974460785, 16477.58806029847, 16713.916430417892, 16873.194541288947, 16893.662126757517, 12949.223046617233, 13996.160230386107, 14167.0, 14284.57146285613, 14720.0, 4194.119234960662, 4204.26425375558, 4213.9578604213875, 4273.038566322583, 4297.011687311904, 89071.55464222637, 91213.0, 91381.54309228412, 93844.0, 94742.5262873688, 12810.167339122927, 12933.224006559449, 13004.739905201843, 13028.0, 13363.33183340829, 23548.0, 23769.524609507713, 23781.405464863517, 23807.761922380556, 24732.629010564855, 9016.0, 9341.766455838522, 9436.669716560025, 9709.66016189444, 9894.901050989398, 5189.299444575009, 5206.3752349717515, 5233.775296580569, 5247.9737601311745, 5282.867928301746, 3766.133789228438, 3855.4602355670227, 3886.3004659161056, 3929.115948911472, 3930.292547341506, 636.147562266565, 646.867392184601, 662.9734810607522, 668.8796016717038, 671.0638659070569, 691.6610860678231, 732.3079689693295, 801.3188789528971, 821.6631181215673, 839.2824135364176, 10095.545700443497, 10701.304415212986, 10748.892511074946, 10980.121590272758, 11150.275232109887, 641.582971068809, 733.1898252430987, 775.9534427934342, 821.8945518277907, 861.1990848510819, 4898.951010489921, 5367.355917289872, 5406.107992181246, 5426.75579598919, 5470.480304371078, 110954.0, 112053.0, 112346.0, 114001.4299928495, 115500.0, 60667.96190209843, 77994.61002694951, 104539.47730261453, 106203.0, 108998.45500772445, 83791.58104209563, 92276.53861730649, 92492.53753731189, 93066.53466732622, 96304.0, 10176.0, 10191.0, 10327.225458090823, 10343.0, 10489.160867130764, 38049.42925856059, 46802.0, 50539.49460505348, 51354.74322628363, 52797.0, 68147.3185268141, 74476.2552374491, 76259.0, 76479.0, 78504.0, 13009.284489352798, 13084.149530280501, 13163.0, 13223.537176198786, 13680.0, 14475.638109047359, 14820.258987050389, 14845.92577037108, 15386.384544618537, 16068.67862642764, 13450.798238026242, 13526.18842869431, 13769.724605507634, 14064.296785160497, 14148.292585370897, 13481.932590336986, 13979.09135906163, 14008.43966241339, 14036.508722194674, 14383.712325753635, 13630.795538067136, 13772.0, 13825.10136841102, 13851.515196968057, 14172.574822755334, 11159.107271418105, 11287.661370158909, 11403.201775875941, 11436.313821170756, 11572.0, 10027.949860250652, 10338.12125969301, 10343.94828025855, 10364.378137311785, 10592.099671528007], "value": 8102.874456203083, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1501.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 110.0, 105.0, 111.0, 113.0, 112.0, 111.0, 111.0, 110.0, 112.0], "value": 109.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 116.0, 141.0, 181.0, 118.0, 144.0, 147.0, 119.0, 183.0, 118.0], "value": 141.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 163.0, 162.0, 166.0, 162.0, 161.0, 163.0, 162.0, 163.0], "value": 162.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 171.0, 76.0, 76.0, 75.0, 75.0, 169.0, 76.0], "value": 94.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 153.0, 83.0, 84.0, 84.0, 134.0, 84.0, 82.0, 144.0], "value": 101.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 120.0, 117.0, 120.0, 118.0, 118.0, 119.0, 118.0], "value": 118.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 146.0, 148.0, 147.0, 147.0, 147.0, 148.0, 148.0, 147.0], "value": 147.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 96.0, 96.0, 94.0, 94.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 51.0, 51.0, 53.0, 60.0, 53.0, 52.0, 57.0, 51.0, 51.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 60.0, 59.0, 59.0, 59.0, 60.0, 59.0, 60.0, 59.0], "value": 59.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 82.0, 73.0, 72.0, 73.0, 72.0, 72.0, 81.0, 72.0, 73.0], "value": 74.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 118.0, 118.0, 122.0, 117.0, 118.0, 123.0, 134.0, 148.0, 131.0], "value": 126.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 151.0, 159.0, 152.0, 155.0, 150.0, 153.0, 152.0, 150.0, 153.0], "value": 153.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 64.0, 63.0, 65.0, 63.0, 67.0, 65.0, 62.0, 66.0], "value": 64.6, "unit": "score"}]}]} 05:06:46 INFO - Return code: 0 05:06:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:06:46 ERROR - # TBPL SUCCESS # 05:06:46 INFO - Running post-action listener: _resource_record_post_action 05:06:46 INFO - [mozharness: 2016-08-16 12:06:46.227857Z] Finished run-tests step (success) 05:06:46 INFO - Running post-run listener: _resource_record_post_run 05:06:46 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 266240; Write bytes: 328298496; Read time: 32; Write time: 263852 05:06:46 INFO - TinderboxPrint: CPU usage
13.2% 05:06:46 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 32 05:06:46 INFO - TinderboxPrint: I/O write bytes / time
328,298,496 / 263,852 05:06:46 INFO - TinderboxPrint: CPU idle
8,233.7 (86.8%) 05:06:46 INFO - TinderboxPrint: CPU user
1,196.2 (12.6%) 05:06:46 INFO - TinderboxPrint: Swap in / out
0 / 0 05:06:46 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 78696448; Read time: 0; Write time: 59984 05:06:46 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 266240; Write bytes: 249303040; Read time: 32; Write time: 203844 05:06:46 INFO - Running post-run listener: _upload_blobber_files 05:06:46 INFO - Blob upload gear active. 05:06:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:06:46 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 05:06:46 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-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:06:46 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-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:06:47 INFO - (blobuploader) - INFO - Open directory for files ... 05:06:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:06:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:06:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:06:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:06:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:06:54 INFO - (blobuploader) - INFO - Done attempting. 05:06:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:06:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:06:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:06:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:06:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:06:55 INFO - (blobuploader) - INFO - Done attempting. 05:06:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:06:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:06:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:06:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:06:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:06:56 INFO - (blobuploader) - INFO - Done attempting. 05:06:56 INFO - (blobuploader) - INFO - Iteration through files over. 05:06:56 INFO - Return code: 0 05:06:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:06:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:06:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d0768a57e3a56c234b23454276f56256e5529cc1e511845a62434210781dbbe7e7c1849e840d67e6743cc3a4f7ec0a2a22860be6aa09342a1839b4cdd69aa697", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/752f43ff12a89d09b991805d3a48ce03380764a094250957ce6e607b9da80eddcf6be776d6ed37f5da4ab81067695831eee411d961b90d3b35a15a9b6992f4f2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/48e237a8273e10bcc355544b553df948b372e706b4ca892b58249c3cd94310945ddc012aa614982ec52353f17033145019a6162d1b395bddb4b9557a6f7cd4a8"} 05:06:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:06:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:06:56 INFO - Contents: 05:06:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d0768a57e3a56c234b23454276f56256e5529cc1e511845a62434210781dbbe7e7c1849e840d67e6743cc3a4f7ec0a2a22860be6aa09342a1839b4cdd69aa697", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/752f43ff12a89d09b991805d3a48ce03380764a094250957ce6e607b9da80eddcf6be776d6ed37f5da4ab81067695831eee411d961b90d3b35a15a9b6992f4f2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/48e237a8273e10bcc355544b553df948b372e706b4ca892b58249c3cd94310945ddc012aa614982ec52353f17033145019a6162d1b395bddb4b9557a6f7cd4a8"} 05:06:56 INFO - Running post-run listener: copy_logs_to_upload_dir 05:06:56 INFO - Copying logs to upload dir... 05:06:56 INFO - mkdir: /builds/slave/test/build/upload/logs 05:06:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1682.267214 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 2 secs) (at 2016-08-16 05:06:56.511027) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 05:06:56.514631) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d0768a57e3a56c234b23454276f56256e5529cc1e511845a62434210781dbbe7e7c1849e840d67e6743cc3a4f7ec0a2a22860be6aa09342a1839b4cdd69aa697", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/752f43ff12a89d09b991805d3a48ce03380764a094250957ce6e607b9da80eddcf6be776d6ed37f5da4ab81067695831eee411d961b90d3b35a15a9b6992f4f2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/48e237a8273e10bcc355544b553df948b372e706b4ca892b58249c3cd94310945ddc012aa614982ec52353f17033145019a6162d1b395bddb4b9557a6f7cd4a8"} build_url:https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011098 build_url: 'https://queue.taskcluster.net/v1/task/Kzeh5r5xT5-iPm0rBCWkvg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d0768a57e3a56c234b23454276f56256e5529cc1e511845a62434210781dbbe7e7c1849e840d67e6743cc3a4f7ec0a2a22860be6aa09342a1839b4cdd69aa697", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/752f43ff12a89d09b991805d3a48ce03380764a094250957ce6e607b9da80eddcf6be776d6ed37f5da4ab81067695831eee411d961b90d3b35a15a9b6992f4f2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/48e237a8273e10bcc355544b553df948b372e706b4ca892b58249c3cd94310945ddc012aa614982ec52353f17033145019a6162d1b395bddb4b9557a6f7cd4a8"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 05:06:56.540512) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 05:06:56.540827) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1471347532.538481-670916190 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004415 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 05:06:56.622376) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 05:06:56.622705) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 05:06:56.623116) ========= ========= Total master_lag: 0.17 =========