builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-061 starttime: 1473813273.84 results: success (0) buildid: 20160913143812 builduid: f7707af24f03409f81096c74c8cea879 revision: 79429ecdadfa79a101cff2add3d5c1eac5b07e7f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-13 17:34:33.842917) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-13 17:34:33.843853) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-13 17:34:33.844333) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-13 17:34:33.859974) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 17:34:33.860848) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-13 17:34:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 212M=0s 2016-09-13 17:34:34 (212 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.129763 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 17:34:34.006479) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 17:34:34.006791) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013948 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 17:34:34.056079) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 17:34:34.056430) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 79429ecdadfa79a101cff2add3d5c1eac5b07e7f --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 79429ecdadfa79a101cff2add3d5c1eac5b07e7f --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=516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-13 17:34:35,024 truncating revision to first 12 chars 2016-09-13 17:34:35,024 Setting DEBUG logging. 2016-09-13 17:34:35,024 attempt 1/10 2016-09-13 17:34:35,024 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/79429ecdadfa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-13 17:34:35,355 unpacking tar archive at: mozilla-aurora-79429ecdadfa/testing/mozharness/ program finished with exit code 0 elapsedTime=0.661365 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 17:34:34.731647) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-13 17:34:34.732065) ========= script_repo_revision: 79429ecdadfa79a101cff2add3d5c1eac5b07e7f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-13 17:34:34.732517) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-13 17:34:34.732839) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-13 17:34:34.743885) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 32 secs) (at 2016-09-13 17:34:34.744195) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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=516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:34:35 INFO - MultiFileLogger online at 20160913 17:34:35 in /builds/slave/test 17:34:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 17:34:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:34:35 INFO - {'append_to_log': False, 17:34:35 INFO - 'base_work_dir': '/builds/slave/test', 17:34:35 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 17:34:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:34:35 INFO - 'branch': 'Mozilla-Aurora', 17:34:35 INFO - 'buildbot_json_path': 'buildprops.json', 17:34:35 INFO - 'config_files': ('talos/linux_config.py',), 17:34:35 INFO - 'default_actions': ('clobber', 17:34:35 INFO - 'read-buildbot-config', 17:34:35 INFO - 'download-and-extract', 17:34:35 INFO - 'populate-webroot', 17:34:35 INFO - 'create-virtualenv', 17:34:35 INFO - 'install', 17:34:35 INFO - 'run-tests'), 17:34:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:34:35 INFO - 'download_minidump_stackwalk': True, 17:34:35 INFO - 'download_symbols': 'ondemand', 17:34:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:34:35 INFO - 'tooltool.py': '/tools/tooltool.py', 17:34:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:34:35 INFO - '/tools/misc-python/virtualenv.py')}, 17:34:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:34:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:34:35 INFO - 'installer_path': 'installer.exe', 17:34:35 INFO - 'log_level': 'info', 17:34:35 INFO - 'log_name': 'talos', 17:34:35 INFO - 'log_to_console': True, 17:34:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:34:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:34:35 INFO - 'opt_config_files': (), 17:34:35 INFO - 'pip_index': False, 17:34:35 INFO - 'sps_profile': False, 17:34:35 INFO - 'sps_profile_interval': 0, 17:34:35 INFO - 'suite': 'dromaeojs', 17:34:35 INFO - 'system_bits': '32', 17:34:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:34:35 INFO - 'title': 'talos-linux64-ix-061', 17:34:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:34:35 INFO - 'use_talos_json': True, 17:34:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:34:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:34:35 INFO - 'work_dir': 'build'} 17:34:35 INFO - [mozharness: 2016-09-14 00:34:35.806390Z] Running clobber step. 17:34:35 INFO - Running pre-action listener: _resource_record_pre_action 17:34:35 INFO - Running main action method: clobber 17:34:35 INFO - rmtree: /builds/slave/test/build 17:34:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:34:36 INFO - Running post-action listener: _resource_record_post_action 17:34:36 INFO - [mozharness: 2016-09-14 00:34:36.267074Z] Finished clobber step (success) 17:34:36 INFO - [mozharness: 2016-09-14 00:34:36.267175Z] Running read-buildbot-config step. 17:34:36 INFO - Running pre-action listener: _resource_record_pre_action 17:34:36 INFO - Running main action method: read_buildbot_config 17:34:36 INFO - Using buildbot properties: 17:34:36 INFO - { 17:34:36 INFO - "project": "", 17:34:36 INFO - "product": "firefox", 17:34:36 INFO - "script_repo_revision": "production", 17:34:36 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 17:34:36 INFO - "repository": "", 17:34:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 17:34:36 INFO - "buildid": "20160913143812", 17:34:36 INFO - "pgo_build": "True", 17:34:36 INFO - "basedir": "/builds/slave/test", 17:34:36 INFO - "buildnumber": 746, 17:34:36 INFO - "slavename": "talos-linux64-ix-061", 17:34:36 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 17:34:36 INFO - "platform": "ubuntu64_hw", 17:34:36 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 17:34:36 INFO - "branch": "mozilla-aurora", 17:34:36 INFO - "repo_path": "releases/mozilla-aurora", 17:34:36 INFO - "revision": "79429ecdadfa79a101cff2add3d5c1eac5b07e7f", 17:34:36 INFO - "stage_platform": "linux64-pgo", 17:34:36 INFO - "builduid": "f7707af24f03409f81096c74c8cea879", 17:34:36 INFO - "slavebuilddir": "test" 17:34:36 INFO - } 17:34:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 17:34:36 INFO - Running post-action listener: _resource_record_post_action 17:34:36 INFO - [mozharness: 2016-09-14 00:34:36.269628Z] Finished read-buildbot-config step (success) 17:34:36 INFO - [mozharness: 2016-09-14 00:34:36.269718Z] Running download-and-extract step. 17:34:36 INFO - Running pre-action listener: _resource_record_pre_action 17:34:36 INFO - Running main action method: download_and_extract 17:34:36 INFO - mkdir: /builds/slave/test/build/tests 17:34:36 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')]} 17:34:36 INFO - https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 17:34:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:34:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:34:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:34:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:34:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:34:36 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:34:36 INFO - retry: Failed, sleeping 30 seconds before retrying 17:35:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:35:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:35:06 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:35:06 INFO - retry: Failed, sleeping 60 seconds before retrying 17:36:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:36:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:36:06 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:36:06 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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! 17:36:06 INFO - Caught exception: HTTP Error 404: Not Found 17:36:06 INFO - Caught exception: HTTP Error 404: Not Found 17:36:06 INFO - Caught exception: HTTP Error 404: Not Found 17:36:06 INFO - trying https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:36:06 INFO - Downloading https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:36:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:36:06 INFO - Downloaded 1361 bytes. 17:36:06 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 17:36:06 INFO - Using the following test package requirements: 17:36:06 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 17:36:06 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:36:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 17:36:06 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:36:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 17:36:06 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:36:06 INFO - u'jsshell-linux-x86_64.zip'], 17:36:06 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:36:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 17:36:06 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 17:36:06 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:36:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 17:36:06 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:36:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 17:36:06 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:36:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 17:36:06 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 17:36:06 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 17:36:06 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:36:06 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')]} 17:36:06 INFO - https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:36:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:36:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:36:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:36:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:36:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:36:06 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:36:06 INFO - retry: Failed, sleeping 30 seconds before retrying 17:36:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:36:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:36:37 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:36:37 INFO - retry: Failed, sleeping 60 seconds before retrying 17:37:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:37:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:37:37 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:37:37 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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! 17:37:37 INFO - Caught exception: HTTP Error 404: Not Found 17:37:37 INFO - Caught exception: HTTP Error 404: Not Found 17:37:37 INFO - Caught exception: HTTP Error 404: Not Found 17:37:37 INFO - trying https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:37:37 INFO - Downloading https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:37:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:37:37 INFO - Downloaded 21770972 bytes. 17:37:37 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'] 17:37:37 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 17:37:39 INFO - Return code: 0 17:37:39 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 17:37:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:37:39 INFO - https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 17:37:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:37:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:37:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:37:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:37:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:37:39 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:37:39 INFO - retry: Failed, sleeping 30 seconds before retrying 17:38:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:38:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:38:09 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:38:09 INFO - retry: Failed, sleeping 60 seconds before retrying 17:39:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:39:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:39:09 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:39:09 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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! 17:39:09 INFO - Caught exception: HTTP Error 404: Not Found 17:39:09 INFO - Caught exception: HTTP Error 404: Not Found 17:39:09 INFO - Caught exception: HTTP Error 404: Not Found 17:39:09 INFO - trying https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 17:39:09 INFO - Downloading https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:39:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:39:10 INFO - Downloaded 21770972 bytes. 17:39:10 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'] 17:39:10 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 17:39:11 INFO - Return code: 0 17:39:11 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')]} 17:39:11 INFO - https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 17:39:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:39:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:39:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:39:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:39:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:39:11 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:39:11 INFO - retry: Failed, sleeping 30 seconds before retrying 17:39:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:39:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:39:41 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:39:41 INFO - retry: Failed, sleeping 60 seconds before retrying 17:40:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:40:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:40:41 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:40:41 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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! 17:40:41 INFO - Caught exception: HTTP Error 404: Not Found 17:40:41 INFO - Caught exception: HTTP Error 404: Not Found 17:40:41 INFO - Caught exception: HTTP Error 404: Not Found 17:40:41 INFO - trying https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 17:40:41 INFO - Downloading https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:40:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:40:42 INFO - Downloaded 14413740 bytes. 17:40:42 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'] 17:40:42 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 17:40:42 INFO - Return code: 0 17:40:42 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')]} 17:40:42 INFO - https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 17:40:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:40:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:40:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:40:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:40:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:40:42 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 17:40:42 INFO - retry: Failed, sleeping 30 seconds before retrying 17:41:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:41:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:41:12 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 17:41:12 INFO - retry: Failed, sleeping 60 seconds before retrying 17:42:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:42:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:42:12 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 17:42:12 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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! 17:42:12 INFO - Caught exception: HTTP Error 404: Not Found 17:42:12 INFO - Caught exception: HTTP Error 404: Not Found 17:42:12 INFO - Caught exception: HTTP Error 404: Not Found 17:42:12 INFO - trying https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:42:12 INFO - Downloading https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:42:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/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 17:42:13 INFO - Downloaded 63879401 bytes. 17:42:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:42:13 INFO - mkdir: /builds/slave/test/properties 17:42:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:42:13 INFO - Writing to file /builds/slave/test/properties/build_url 17:42:13 INFO - Contents: 17:42:13 INFO - build_url:https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 17:42:14 INFO - Running post-action listener: _resource_record_post_action 17:42:14 INFO - Running post-action listener: set_extra_try_arguments 17:42:14 INFO - [mozharness: 2016-09-14 00:42:14.524714Z] Finished download-and-extract step (success) 17:42:14 INFO - [mozharness: 2016-09-14 00:42:14.524948Z] Running populate-webroot step. 17:42:14 INFO - Running pre-action listener: _resource_record_pre_action 17:42:14 INFO - Running main action method: populate_webroot 17:42:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:42:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:42:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:42:14 INFO - '--tppagecycles', 17:42:14 INFO - '7'], 17:42:14 INFO - 'tests': ['tsvgm']}}, 17:42:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:42:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:42:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:42:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:42:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:42:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:42:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:42:14 INFO - 'talos_options': ['--disable-e10s'], 17:42:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:42:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:42:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:42:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:42:14 INFO - 'talos_options': ['--disable-e10s'], 17:42:14 INFO - 'tests': ['damp', 'tps']}, 17:42:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:42:14 INFO - 'tests': ['damp', 'tps']}, 17:42:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:42:14 INFO - 'tests': ['dromaeo_dom']}, 17:42:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:42:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:42:14 INFO - 'tests': ['basic_compositor_video']}, 17:42:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:42:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:42:14 INFO - 'tests': ['a11yr', 17:42:14 INFO - 'ts_paint', 17:42:14 INFO - 'tpaint', 17:42:14 INFO - 'sessionrestore', 17:42:14 INFO - 'sessionrestore_no_auto_restore', 17:42:14 INFO - 'tabpaint']}, 17:42:14 INFO - 'other-e10s': {'tests': ['a11yr', 17:42:14 INFO - 'ts_paint', 17:42:14 INFO - 'tpaint', 17:42:14 INFO - 'sessionrestore', 17:42:14 INFO - 'sessionrestore_no_auto_restore', 17:42:14 INFO - 'tabpaint']}, 17:42:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:42:14 INFO - 'ts_paint', 17:42:14 INFO - 'tpaint', 17:42:14 INFO - 'sessionrestore', 17:42:14 INFO - 'sessionrestore_no_auto_restore', 17:42:14 INFO - 'tabpaint']}, 17:42:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:42:14 INFO - 'ts_paint', 17:42:14 INFO - 'tpaint', 17:42:14 INFO - 'sessionrestore', 17:42:14 INFO - 'sessionrestore_no_auto_restore', 17:42:14 INFO - 'tabpaint']}, 17:42:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:42:14 INFO - 'tests': ['a11yr', 17:42:14 INFO - 'ts_paint', 17:42:14 INFO - 'tpaint', 17:42:14 INFO - 'sessionrestore', 17:42:14 INFO - 'sessionrestore_no_auto_restore', 17:42:14 INFO - 'tabpaint']}, 17:42:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:42:14 INFO - 'tests': ['a11yr', 17:42:14 INFO - 'ts_paint', 17:42:14 INFO - 'tpaint', 17:42:14 INFO - 'sessionrestore', 17:42:14 INFO - 'sessionrestore_no_auto_restore', 17:42:14 INFO - 'tabpaint']}, 17:42:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:42:14 INFO - 'tests': ['tsvgx', 17:42:14 INFO - 'tsvgr_opacity', 17:42:14 INFO - 'tart', 17:42:14 INFO - 'tscrollx', 17:42:14 INFO - 'cart']}, 17:42:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:42:14 INFO - 'tsvgr_opacity', 17:42:14 INFO - 'tart', 17:42:14 INFO - 'tscrollx', 17:42:14 INFO - 'cart']}, 17:42:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:42:14 INFO - 'talos_options': ['--disable-e10s'], 17:42:14 INFO - 'tests': ['tp5o']}, 17:42:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:42:14 INFO - 'tests': ['tp5o']}, 17:42:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:42:14 INFO - 'talos_options': ['--disable-e10s', 17:42:14 INFO - '--xperf_path', 17:42:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:42:14 INFO - 'tests': ['tp5n']}, 17:42:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:42:14 INFO - 'talos_options': ['--xperf_path', 17:42:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:42:14 INFO - 'tests': ['tp5n']}}, 17:42:14 INFO - 'talos.zip': {'path': '', 17:42:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:42:14 INFO - Running post-action listener: _resource_record_post_action 17:42:14 INFO - [mozharness: 2016-09-14 00:42:14.548980Z] Finished populate-webroot step (success) 17:42:14 INFO - [mozharness: 2016-09-14 00:42:14.549070Z] Running create-virtualenv step. 17:42:14 INFO - Running pre-action listener: _resource_record_pre_action 17:42:14 INFO - Running main action method: create_virtualenv 17:42:14 INFO - Creating virtualenv /builds/slave/test/build/venv 17:42:14 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 17:42:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:42:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:42:14 INFO - Using real prefix '/usr' 17:42:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:42:15 INFO - Installing distribute.............................................................................................................................................................................................done. 17:42:17 INFO - Installing pip.................done. 17:42:17 INFO - Return code: 0 17:42:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:42:17 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')]} 17:42:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:17 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')]} 17:42:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x2100030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bf3b0>, '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 0x23c02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c0740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577', '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 17:42:17 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 17:42:17 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 17:42:17 INFO - Using env: {'DISPLAY': ':0', 17:42:17 INFO - 'HOME': '/home/cltbld', 17:42:17 INFO - 'LANG': 'en_US.UTF-8', 17:42:17 INFO - 'LANGUAGE': 'en_US:en', 17:42:17 INFO - 'LOGNAME': 'cltbld', 17:42:17 INFO - 'MAIL': '/var/mail/cltbld', 17:42:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:17 INFO - 'MOZ_NO_REMOTE': '1', 17:42:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:42:17 INFO - 'NO_EM_RESTART': '1', 17:42:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:42:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:17 INFO - 'PWD': '/builds/slave/test', 17:42:17 INFO - 'SHELL': '/bin/bash', 17:42:17 INFO - 'SHLVL': '1', 17:42:17 INFO - 'TERM': 'linux', 17:42:17 INFO - 'TMOUT': '86400', 17:42:17 INFO - 'USER': 'cltbld', 17:42:17 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577', 17:42:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:17 INFO - '_': '/tools/buildbot/bin/python'} 17:42:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:17 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 17:42:17 INFO - Cleaning up... 17:42:17 INFO - Return code: 0 17:42:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:42:17 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')]} 17:42:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:17 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')]} 17:42:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x2100030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bf3b0>, '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 0x23c02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c0740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577', '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 17:42:17 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 17:42:17 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 17:42:17 INFO - Using env: {'DISPLAY': ':0', 17:42:17 INFO - 'HOME': '/home/cltbld', 17:42:17 INFO - 'LANG': 'en_US.UTF-8', 17:42:17 INFO - 'LANGUAGE': 'en_US:en', 17:42:17 INFO - 'LOGNAME': 'cltbld', 17:42:17 INFO - 'MAIL': '/var/mail/cltbld', 17:42:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:17 INFO - 'MOZ_NO_REMOTE': '1', 17:42:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:42:17 INFO - 'NO_EM_RESTART': '1', 17:42:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:42:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:17 INFO - 'PWD': '/builds/slave/test', 17:42:17 INFO - 'SHELL': '/bin/bash', 17:42:17 INFO - 'SHLVL': '1', 17:42:17 INFO - 'TERM': 'linux', 17:42:17 INFO - 'TMOUT': '86400', 17:42:17 INFO - 'USER': 'cltbld', 17:42:17 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577', 17:42:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:17 INFO - '_': '/tools/buildbot/bin/python'} 17:42:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:17 INFO - Downloading/unpacking psutil>=3.1.1 17:42:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:42:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:42:17 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 17:42:17 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 17:42:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:42:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:42:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:42:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:42:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:42:19 INFO - Installing collected packages: psutil 17:42:19 INFO - Running setup.py install for psutil 17:42:19 INFO - building 'psutil._psutil_linux' extension 17:42:19 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 17:42:19 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 17:42:19 INFO - building 'psutil._psutil_posix' extension 17:42:19 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 17:42:19 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 17:42:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:42:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:42:20 INFO - Successfully installed psutil 17:42:20 INFO - Cleaning up... 17:42:20 INFO - Return code: 0 17:42:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:42:20 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')]} 17:42:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:20 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')]} 17:42:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:20 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 0x2100030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bf3b0>, '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 0x23c02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c0740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577', '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 17:42:20 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 17:42:20 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 17:42:20 INFO - Using env: {'DISPLAY': ':0', 17:42:20 INFO - 'HOME': '/home/cltbld', 17:42:20 INFO - 'LANG': 'en_US.UTF-8', 17:42:20 INFO - 'LANGUAGE': 'en_US:en', 17:42:20 INFO - 'LOGNAME': 'cltbld', 17:42:20 INFO - 'MAIL': '/var/mail/cltbld', 17:42:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:20 INFO - 'MOZ_NO_REMOTE': '1', 17:42:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:42:20 INFO - 'NO_EM_RESTART': '1', 17:42:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:42:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:20 INFO - 'PWD': '/builds/slave/test', 17:42:20 INFO - 'SHELL': '/bin/bash', 17:42:20 INFO - 'SHLVL': '1', 17:42:20 INFO - 'TERM': 'linux', 17:42:20 INFO - 'TMOUT': '86400', 17:42:20 INFO - 'USER': 'cltbld', 17:42:20 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577', 17:42:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:20 INFO - '_': '/tools/buildbot/bin/python'} 17:42:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:42:20 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 17:42:20 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 17:42:20 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 17:42:20 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 17:42:20 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 17:42:20 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 17:42:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:42:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:42:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:42:22 INFO - Installing collected packages: mozsystemmonitor 17:42:22 INFO - Running setup.py install for mozsystemmonitor 17:42:22 INFO - Successfully installed mozsystemmonitor 17:42:22 INFO - Cleaning up... 17:42:22 INFO - Return code: 0 17:42:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:42:22 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')]} 17:42:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:22 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')]} 17:42:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:22 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 0x2100030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bf3b0>, '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 0x23c02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c0740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577', '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 17:42:22 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 17:42:22 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 17:42:22 INFO - Using env: {'DISPLAY': ':0', 17:42:22 INFO - 'HOME': '/home/cltbld', 17:42:22 INFO - 'LANG': 'en_US.UTF-8', 17:42:22 INFO - 'LANGUAGE': 'en_US:en', 17:42:22 INFO - 'LOGNAME': 'cltbld', 17:42:22 INFO - 'MAIL': '/var/mail/cltbld', 17:42:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:22 INFO - 'MOZ_NO_REMOTE': '1', 17:42:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:42:22 INFO - 'NO_EM_RESTART': '1', 17:42:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:42:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:22 INFO - 'PWD': '/builds/slave/test', 17:42:22 INFO - 'SHELL': '/bin/bash', 17:42:22 INFO - 'SHLVL': '1', 17:42:22 INFO - 'TERM': 'linux', 17:42:22 INFO - 'TMOUT': '86400', 17:42:22 INFO - 'USER': 'cltbld', 17:42:22 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577', 17:42:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:22 INFO - '_': '/tools/buildbot/bin/python'} 17:42:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:22 INFO - Downloading/unpacking jsonschema==2.5.1 17:42:22 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 17:42:22 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 17:42:22 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 17:42:22 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 17:42:22 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 17:42:22 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 17:42:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:42:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:42:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:42:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:42:24 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 17:42:24 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 17:42:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:42:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:42:24 INFO - Downloading functools32-3.2.3-2.tar.gz 17:42:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:42:24 INFO - warning: no files found matching '*.txt' 17:42:24 INFO - no previously-included directories found matching 'build' 17:42:24 INFO - no previously-included directories found matching 'dist' 17:42:24 INFO - no previously-included directories found matching '.git*' 17:42:24 INFO - Installing collected packages: jsonschema, functools32 17:42:24 INFO - Running setup.py install for functools32 17:42:24 INFO - warning: no files found matching '*.txt' 17:42:24 INFO - no previously-included directories found matching 'build' 17:42:24 INFO - no previously-included directories found matching 'dist' 17:42:24 INFO - no previously-included directories found matching '.git*' 17:42:24 INFO - Successfully installed jsonschema functools32 17:42:24 INFO - Cleaning up... 17:42:24 INFO - Return code: 0 17:42:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:42:24 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')]} 17:42:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:24 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')]} 17:42:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:24 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 0x2100030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bf3b0>, '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 0x23c02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c0740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577', '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 17:42:24 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 17:42:24 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 17:42:24 INFO - Using env: {'DISPLAY': ':0', 17:42:24 INFO - 'HOME': '/home/cltbld', 17:42:24 INFO - 'LANG': 'en_US.UTF-8', 17:42:24 INFO - 'LANGUAGE': 'en_US:en', 17:42:24 INFO - 'LOGNAME': 'cltbld', 17:42:24 INFO - 'MAIL': '/var/mail/cltbld', 17:42:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:24 INFO - 'MOZ_NO_REMOTE': '1', 17:42:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:42:24 INFO - 'NO_EM_RESTART': '1', 17:42:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:42:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:24 INFO - 'PWD': '/builds/slave/test', 17:42:24 INFO - 'SHELL': '/bin/bash', 17:42:24 INFO - 'SHLVL': '1', 17:42:24 INFO - 'TERM': 'linux', 17:42:24 INFO - 'TMOUT': '86400', 17:42:24 INFO - 'USER': 'cltbld', 17:42:24 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577', 17:42:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:24 INFO - '_': '/tools/buildbot/bin/python'} 17:42:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:42:25 INFO - Cleaning up... 17:42:25 INFO - Return code: 0 17:42:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:42:25 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')]} 17:42:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:25 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')]} 17:42:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:25 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 0x2100030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bf3b0>, '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 0x23c02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c0740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577', '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 17:42:25 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 17:42:25 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 17:42:25 INFO - Using env: {'DISPLAY': ':0', 17:42:25 INFO - 'HOME': '/home/cltbld', 17:42:25 INFO - 'LANG': 'en_US.UTF-8', 17:42:25 INFO - 'LANGUAGE': 'en_US:en', 17:42:25 INFO - 'LOGNAME': 'cltbld', 17:42:25 INFO - 'MAIL': '/var/mail/cltbld', 17:42:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:25 INFO - 'MOZ_NO_REMOTE': '1', 17:42:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:42:25 INFO - 'NO_EM_RESTART': '1', 17:42:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:42:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:25 INFO - 'PWD': '/builds/slave/test', 17:42:25 INFO - 'SHELL': '/bin/bash', 17:42:25 INFO - 'SHLVL': '1', 17:42:25 INFO - 'TERM': 'linux', 17:42:25 INFO - 'TMOUT': '86400', 17:42:25 INFO - 'USER': 'cltbld', 17:42:25 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577', 17:42:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:25 INFO - '_': '/tools/buildbot/bin/python'} 17:42:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:25 INFO - Downloading/unpacking blobuploader==1.2.4 17:42:25 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 17:42:25 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 17:42:25 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 17:42:25 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 17:42:25 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 17:42:25 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 17:42:27 INFO - Downloading blobuploader-1.2.4.tar.gz 17:42:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:42:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:42:27 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 17:42:27 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 17:42:27 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 17:42:27 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 17:42:27 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 17:42:27 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 17:42:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:42:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:42:27 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 17:42:27 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 17:42:27 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 17:42:27 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 17:42:27 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 17:42:27 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 17:42:27 INFO - Downloading docopt-0.6.1.tar.gz 17:42:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:42:27 INFO - Installing collected packages: blobuploader, requests, docopt 17:42:27 INFO - Running setup.py install for blobuploader 17:42:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:42:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:42:28 INFO - Running setup.py install for requests 17:42:28 INFO - Running setup.py install for docopt 17:42:28 INFO - Successfully installed blobuploader requests docopt 17:42:28 INFO - Cleaning up... 17:42:28 INFO - Return code: 0 17:42:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:42:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:42:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:42:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:28 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 0x2100030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bf3b0>, '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 0x23c02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c0740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577', '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 17:42:28 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 17:42:28 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 17:42:28 INFO - Using env: {'DISPLAY': ':0', 17:42:28 INFO - 'HOME': '/home/cltbld', 17:42:28 INFO - 'LANG': 'en_US.UTF-8', 17:42:28 INFO - 'LANGUAGE': 'en_US:en', 17:42:28 INFO - 'LOGNAME': 'cltbld', 17:42:28 INFO - 'MAIL': '/var/mail/cltbld', 17:42:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:28 INFO - 'MOZ_NO_REMOTE': '1', 17:42:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:42:28 INFO - 'NO_EM_RESTART': '1', 17:42:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:42:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:28 INFO - 'PWD': '/builds/slave/test', 17:42:28 INFO - 'SHELL': '/bin/bash', 17:42:28 INFO - 'SHLVL': '1', 17:42:28 INFO - 'TERM': 'linux', 17:42:28 INFO - 'TMOUT': '86400', 17:42:28 INFO - 'USER': 'cltbld', 17:42:28 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577', 17:42:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:28 INFO - '_': '/tools/buildbot/bin/python'} 17:42:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:42:28 INFO - Running setup.py (path:/tmp/pip-p0L1td-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:42:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:42:28 INFO - Running setup.py (path:/tmp/pip-ZkZU1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:42:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:42:28 INFO - Running setup.py (path:/tmp/pip-eV4_WB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:42:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:42:28 INFO - Running setup.py (path:/tmp/pip-G9IORJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:42:29 INFO - Running setup.py (path:/tmp/pip-xGKtek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:42:29 INFO - Running setup.py (path:/tmp/pip-yFihyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:42:29 INFO - Running setup.py (path:/tmp/pip-GifuGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:42:29 INFO - Running setup.py (path:/tmp/pip-Q2_pjp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:42:29 INFO - Running setup.py (path:/tmp/pip-5V1YuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:42:29 INFO - Running setup.py (path:/tmp/pip-rGn_F9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:42:29 INFO - Running setup.py (path:/tmp/pip-A3_9aJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:42:29 INFO - Running setup.py (path:/tmp/pip-Bvli_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:42:29 INFO - Running setup.py (path:/tmp/pip-A4QxGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:42:29 INFO - Running setup.py (path:/tmp/pip-tF0Pch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:42:29 INFO - Running setup.py (path:/tmp/pip-3nfp6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:42:29 INFO - Running setup.py (path:/tmp/pip-FKGGH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:42:29 INFO - Running setup.py (path:/tmp/pip-l8E_4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:42:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:42:29 INFO - Running setup.py install for manifestparser 17:42:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:42:30 INFO - Running setup.py install for mozcrash 17:42:30 INFO - Running setup.py install for mozdebug 17:42:30 INFO - Running setup.py install for mozdevice 17:42:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:42:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:42:30 INFO - Running setup.py install for mozfile 17:42:30 INFO - Running setup.py install for mozhttpd 17:42:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:42:30 INFO - Running setup.py install for mozinfo 17:42:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:42:30 INFO - Running setup.py install for mozInstall 17:42:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:42:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:42:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:42:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:42:30 INFO - Running setup.py install for mozleak 17:42:30 INFO - Running setup.py install for mozlog 17:42:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:42:31 INFO - Running setup.py install for moznetwork 17:42:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:42:31 INFO - Running setup.py install for mozprocess 17:42:31 INFO - Running setup.py install for mozprofile 17:42:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:42:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:42:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:42:31 INFO - Running setup.py install for mozrunner 17:42:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:42:31 INFO - Running setup.py install for mozscreenshot 17:42:31 INFO - Running setup.py install for moztest 17:42:31 INFO - Running setup.py install for mozversion 17:42:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:42:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:42:31 INFO - Cleaning up... 17:42:31 INFO - Return code: 0 17:42:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:42:31 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')]} 17:42:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:31 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')]} 17:42:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:31 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 0x2100030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bf3b0>, '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 0x23c02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c0740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577', '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 17:42:31 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 17:42:31 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 17:42:31 INFO - Using env: {'DISPLAY': ':0', 17:42:31 INFO - 'HOME': '/home/cltbld', 17:42:31 INFO - 'LANG': 'en_US.UTF-8', 17:42:31 INFO - 'LANGUAGE': 'en_US:en', 17:42:31 INFO - 'LOGNAME': 'cltbld', 17:42:31 INFO - 'MAIL': '/var/mail/cltbld', 17:42:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:31 INFO - 'MOZ_NO_REMOTE': '1', 17:42:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:42:31 INFO - 'NO_EM_RESTART': '1', 17:42:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:42:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:31 INFO - 'PWD': '/builds/slave/test', 17:42:31 INFO - 'SHELL': '/bin/bash', 17:42:31 INFO - 'SHLVL': '1', 17:42:31 INFO - 'TERM': 'linux', 17:42:31 INFO - 'TMOUT': '86400', 17:42:31 INFO - 'USER': 'cltbld', 17:42:31 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577', 17:42:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:31 INFO - '_': '/tools/buildbot/bin/python'} 17:42:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:42:31 INFO - Running setup.py (path:/tmp/pip-avYwbl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:42:32 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)) 17:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:42:32 INFO - Running setup.py (path:/tmp/pip-QoP5tV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:42:32 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)) 17:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:42:32 INFO - Running setup.py (path:/tmp/pip-0M33wv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:42:32 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)) 17:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:42:32 INFO - Running setup.py (path:/tmp/pip-IDbAFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:42:32 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)) 17:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:42:32 INFO - Running setup.py (path:/tmp/pip-ovvVux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:42:32 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)) 17:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:42:32 INFO - Running setup.py (path:/tmp/pip-g2NrwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:42:32 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)) 17:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:42:32 INFO - Running setup.py (path:/tmp/pip-l3eocB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:42:32 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)) 17:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:42:32 INFO - Running setup.py (path:/tmp/pip-oyyOHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:42:32 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)) 17:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:42:32 INFO - Running setup.py (path:/tmp/pip-FH3iBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:42:32 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)) 17:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:42:32 INFO - Running setup.py (path:/tmp/pip-0gvWGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:42:32 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)) 17:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:42:32 INFO - Running setup.py (path:/tmp/pip-c6HPve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:42:32 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)) 17:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:42:32 INFO - Running setup.py (path:/tmp/pip-YNrT3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:42:32 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)) 17:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:42:32 INFO - Running setup.py (path:/tmp/pip-oczS04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:42:32 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)) 17:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:42:32 INFO - Running setup.py (path:/tmp/pip-i3xSUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:42:33 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)) 17:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:42:33 INFO - Running setup.py (path:/tmp/pip-pxwiiK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:42:33 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)) 17:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:42:33 INFO - Running setup.py (path:/tmp/pip-8dE0KO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:42:33 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)) 17:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:42:33 INFO - Running setup.py (path:/tmp/pip-N_CCGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:42:33 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)) 17:42:33 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)) 17:42:33 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)) 17:42:33 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)) 17:42:33 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)) 17:42:33 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)) 17:42:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:42:33 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 17:42:33 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 17:42:33 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 17:42:33 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 17:42:33 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 17:42:33 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 17:42:35 INFO - Downloading blessings-1.6.tar.gz 17:42:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:42:35 INFO - Installing collected packages: blessings 17:42:35 INFO - Running setup.py install for blessings 17:42:35 INFO - Successfully installed blessings 17:42:35 INFO - Cleaning up... 17:42:35 INFO - Return code: 0 17:42:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:42:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:42:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:42:35 INFO - Reading from file tmpfile_stdout 17:42:35 INFO - Current package versions: 17:42:35 INFO - argparse == 1.2.1 17:42:35 INFO - blessings == 1.6 17:42:35 INFO - blobuploader == 1.2.4 17:42:35 INFO - docopt == 0.6.1 17:42:35 INFO - functools32 == 3.2.3-2 17:42:35 INFO - jsonschema == 2.5.1 17:42:35 INFO - manifestparser == 1.1 17:42:35 INFO - mozInstall == 1.12 17:42:35 INFO - mozcrash == 0.17 17:42:35 INFO - mozdebug == 0.1 17:42:35 INFO - mozdevice == 0.48 17:42:35 INFO - mozfile == 1.2 17:42:35 INFO - mozhttpd == 0.7 17:42:35 INFO - mozinfo == 0.9 17:42:35 INFO - mozleak == 0.1 17:42:35 INFO - mozlog == 3.3 17:42:35 INFO - moznetwork == 0.27 17:42:35 INFO - mozprocess == 0.23 17:42:35 INFO - mozprofile == 0.28 17:42:35 INFO - mozrunner == 6.12 17:42:35 INFO - mozscreenshot == 0.1 17:42:35 INFO - mozsystemmonitor == 0.3 17:42:35 INFO - moztest == 0.7 17:42:35 INFO - mozversion == 1.4 17:42:35 INFO - psutil == 3.1.1 17:42:35 INFO - requests == 1.2.3 17:42:35 INFO - wsgiref == 0.1.2 17:42:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:42:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:42:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:42:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:35 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 0x2100030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bf3b0>, '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 0x23c02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c0740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577', '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 17:42:35 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 17:42:35 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 17:42:35 INFO - Using env: {'DISPLAY': ':0', 17:42:35 INFO - 'HOME': '/home/cltbld', 17:42:35 INFO - 'LANG': 'en_US.UTF-8', 17:42:35 INFO - 'LANGUAGE': 'en_US:en', 17:42:35 INFO - 'LOGNAME': 'cltbld', 17:42:35 INFO - 'MAIL': '/var/mail/cltbld', 17:42:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:35 INFO - 'MOZ_NO_REMOTE': '1', 17:42:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:42:35 INFO - 'NO_EM_RESTART': '1', 17:42:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:42:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:35 INFO - 'PWD': '/builds/slave/test', 17:42:35 INFO - 'SHELL': '/bin/bash', 17:42:35 INFO - 'SHLVL': '1', 17:42:35 INFO - 'TERM': 'linux', 17:42:35 INFO - 'TMOUT': '86400', 17:42:35 INFO - 'USER': 'cltbld', 17:42:35 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577', 17:42:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:35 INFO - '_': '/tools/buildbot/bin/python'} 17:42:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:35 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)) 17:42:35 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)) 17:42:35 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)) 17:42:35 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)) 17:42:35 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)) 17:42:35 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)) 17:42:35 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)) 17:42:35 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)) 17:42:35 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)) 17:42:35 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)) 17:42:35 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)) 17:42:35 INFO - Cleaning up... 17:42:35 INFO - Return code: 0 17:42:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:42:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:42:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:42:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:42:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:42:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:42:35 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 0x2100030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x22638a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x23c1180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x23bf3b0>, '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 0x23c02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c0740>, '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': '516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577', '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 17:42:35 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 17:42:35 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 17:42:35 INFO - Using env: {'DISPLAY': ':0', 17:42:35 INFO - 'HOME': '/home/cltbld', 17:42:35 INFO - 'LANG': 'en_US.UTF-8', 17:42:35 INFO - 'LANGUAGE': 'en_US:en', 17:42:35 INFO - 'LOGNAME': 'cltbld', 17:42:35 INFO - 'MAIL': '/var/mail/cltbld', 17:42:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:35 INFO - 'MOZ_NO_REMOTE': '1', 17:42:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:42:35 INFO - 'NO_EM_RESTART': '1', 17:42:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:42:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:35 INFO - 'PWD': '/builds/slave/test', 17:42:35 INFO - 'SHELL': '/bin/bash', 17:42:35 INFO - 'SHLVL': '1', 17:42:35 INFO - 'TERM': 'linux', 17:42:35 INFO - 'TMOUT': '86400', 17:42:35 INFO - 'USER': 'cltbld', 17:42:35 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577', 17:42:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:35 INFO - '_': '/tools/buildbot/bin/python'} 17:42:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:42:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:42:36 INFO - Cleaning up... 17:42:36 INFO - Return code: 0 17:42:36 INFO - Running post-action listener: _resource_record_post_action 17:42:36 INFO - Running post-action listener: _start_resource_monitoring 17:42:36 INFO - Starting resource monitoring. 17:42:36 INFO - [mozharness: 2016-09-14 00:42:36.049239Z] Finished create-virtualenv step (success) 17:42:36 INFO - [mozharness: 2016-09-14 00:42:36.049714Z] Running install step. 17:42:36 INFO - Running pre-action listener: _resource_record_pre_action 17:42:36 INFO - Running main action method: install 17:42:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:42:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:42:36 INFO - Reading from file tmpfile_stdout 17:42:36 INFO - Detecting whether we're running mozinstall >=1.0... 17:42:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:42:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:42:36 INFO - Reading from file tmpfile_stdout 17:42:36 INFO - Output received: 17:42:36 INFO - Usage: mozinstall [options] installer 17:42:36 INFO - Options: 17:42:36 INFO - -h, --help show this help message and exit 17:42:36 INFO - -d DEST, --destination=DEST 17:42:36 INFO - Directory to install application into. [default: 17:42:36 INFO - "/builds/slave/test"] 17:42:36 INFO - --app=APP Application being installed. [default: firefox] 17:42:36 INFO - mkdir: /builds/slave/test/build/application 17:42:36 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'] 17:42:36 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 17:42:53 INFO - Reading from file tmpfile_stdout 17:42:53 INFO - Output received: 17:42:53 INFO - /builds/slave/test/build/application/firefox/firefox 17:42:53 INFO - Running post-action listener: _resource_record_post_action 17:42:53 INFO - [mozharness: 2016-09-14 00:42:53.209229Z] Finished install step (success) 17:42:53 INFO - [mozharness: 2016-09-14 00:42:53.209567Z] Running run-tests step. 17:42:53 INFO - Running pre-action listener: _resource_record_pre_action 17:42:53 INFO - Running main action method: run_tests 17:42:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:42:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:42:53 INFO - Python 2.7.3 17:42:53 INFO - Return code: 0 17:42:53 INFO - grabbing minidump binary from tooltool 17:42:53 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')]} 17:42:53 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 0x23bf3b0>, '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 0x23c02b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x23c0740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:42:53 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 17:42:53 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 17:42:53 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 17:42:53 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:42:53 INFO - Return code: 0 17:42:53 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:42:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:42:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:42:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:42:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:42:53 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', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 17:42:53 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 --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-061 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 17:42:53 INFO - Using env: {'DISPLAY': ':0', 17:42:53 INFO - 'HOME': '/home/cltbld', 17:42:53 INFO - 'LANG': 'en_US.UTF-8', 17:42:53 INFO - 'LANGUAGE': 'en_US:en', 17:42:53 INFO - 'LOGNAME': 'cltbld', 17:42:53 INFO - 'MAIL': '/var/mail/cltbld', 17:42:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:42:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:42:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:42:53 INFO - 'MOZ_NO_REMOTE': '1', 17:42:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:42:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:42:53 INFO - 'NO_EM_RESTART': '1', 17:42:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:42:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:42:53 INFO - 'PWD': '/builds/slave/test', 17:42:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:42:53 INFO - 'SHELL': '/bin/bash', 17:42:53 INFO - 'SHLVL': '1', 17:42:53 INFO - 'TERM': 'linux', 17:42:53 INFO - 'TMOUT': '86400', 17:42:53 INFO - 'USER': 'cltbld', 17:42:53 INFO - 'XDG_SESSION_COOKIE': '516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577', 17:42:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:42:53 INFO - '_': '/tools/buildbot/bin/python'} 17:42:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-061', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 17:42:53 INFO - mozversion application_buildid: 20160913143812 17:42:53 INFO - mozversion application_changeset: 79429ecdadfa79a101cff2add3d5c1eac5b07e7f 17:42:53 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:42:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:42:53 INFO - mozversion application_name: Firefox 17:42:53 INFO - mozversion application_remotingname: firefox-dev 17:42:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:42:53 INFO - mozversion application_vendor: Mozilla 17:42:53 INFO - mozversion application_version: 50.0a2 17:42:53 INFO - mozversion platform_buildid: 20160913143812 17:42:53 INFO - mozversion platform_changeset: 79429ecdadfa79a101cff2add3d5c1eac5b07e7f 17:42:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:42:53 INFO - mozversion platform_version: 50.0a2 17:42:53 INFO - using testdate: 1473813773 17:42:53 INFO - actual date: 1473813773 17:42:53 INFO - starting webserver on 'localhost:54446' 17:42:53 INFO - SUITE-START | Running 2 tests 17:42:53 INFO - TEST-START | dromaeo_css 17:42:53 INFO - operating with platform_type : linux_ 17:42:53 INFO - Initialising browser for dromaeo_css test... 17:42:53 INFO - TEST-INFO | started process 9629 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0R11VE/profile http://localhost:54446/getInfo.html) 17:42:57 INFO - PROCESS | 9629 | __metrics Screen width/height:1600/1200 17:42:57 INFO - PROCESS | 9629 | colorDepth:24 17:42:57 INFO - PROCESS | 9629 | Browser inner width/height: 1024/697 17:42:57 INFO - PROCESS | 9629 | __metrics 17:42:58 INFO - PROCESS | 9629 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:42:58 INFO - PROCESS | 9629 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:42:59 INFO - TEST-INFO | 9629: exit 0 17:42:59 INFO - Browser initialized. 17:42:59 INFO - Running cycle 1/1 for dromaeo_css test... 17:42:59 INFO - TEST-INFO | started process 9714 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0R11VE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:43:11 INFO - PROCESS | 9714 | RSS: Main: 145510400 17:43:11 INFO - PROCESS | 9714 | 17:44:02 INFO - PROCESS | 9714 | 1473813842410 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] 17:46:11 INFO - PROCESS | 9714 | Cycle 1(1): loaded http://localhost:54446/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54446/tests/dromaeo/cssquery-ext.html) 17:46:12 INFO - PROCESS | 9714 | RSS: Main: 221921280 17:46:12 INFO - PROCESS | 9714 | 17:49:14 INFO - PROCESS | 9714 | Cycle 1(1): loaded http://localhost:54446/tests/dromaeo/cssquery-ext.html (next: http://localhost:54446/tests/dromaeo/cssquery-jquery.html) 17:49:15 INFO - PROCESS | 9714 | RSS: Main: 232820736 17:49:15 INFO - PROCESS | 9714 | 17:52:17 INFO - PROCESS | 9714 | Cycle 1(1): loaded http://localhost:54446/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54446/tests/dromaeo/cssquery-mootools.html) 17:52:18 INFO - PROCESS | 9714 | RSS: Main: 176463872 17:52:18 INFO - PROCESS | 9714 | 17:55:20 INFO - PROCESS | 9714 | Cycle 1(1): loaded http://localhost:54446/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54446/tests/dromaeo/cssquery-prototype.html) 17:55:20 INFO - PROCESS | 9714 | RSS: Main: 226357248 17:55:20 INFO - PROCESS | 9714 | 17:58:23 INFO - PROCESS | 9714 | Cycle 1(1): loaded http://localhost:54446/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54446/tests/dromaeo/cssquery-yui.html) 17:58:23 INFO - PROCESS | 9714 | RSS: Main: 177811456 17:58:23 INFO - PROCESS | 9714 | 18:01:26 INFO - PROCESS | 9714 | Cycle 1(1): loaded http://localhost:54446/tests/dromaeo/cssquery-yui.html (next: http://localhost:54446/tests/dromaeo/cssquery-dojo.html) 18:01:26 INFO - PROCESS | 9714 | RSS: Main: 260694016 18:01:26 INFO - PROCESS | 9714 | 18:01:26 INFO - PROCESS | 9714 | __start_tp_report 18:01:26 INFO - PROCESS | 9714 | _x_x_mozilla_page_load 18:01:26 INFO - PROCESS | 9714 | _x_x_mozilla_page_load_details 18:01:26 INFO - PROCESS | 9714 | |i|pagename|runs| 18:01:26 INFO - PROCESS | 9714 | |0;dojo.html;3218.533312669663;3228.1606782236618;3243.9675603243977;3262.983685081574;3275.934481310374;5532.391436941938;5590.105583106699;5603.299587551551;5628.043232650449;5634.577406694504;5626.578006649497;5659;5670.3479099903525;5686.800961966332;5718.056520674084;5009.674371165881;5081.542661160494;5114.488551144893;5137.820176293831;5184.611154163434;5087.79648814047;5155.871103222412;5164.147915593922;5164.586833053347;5166.767495462695;5073.137566613666;5126.436092029874;5185.299984502081;5224.817131400402;5227.424983251839;5293.708846013467;5294.655847369909;5299.417064122943;5300.098983172831;5331.2003199519995;4986.67586606873;4992.151334273182;5022.648414610979;5178.97410512945;5180.248863914711;5477.068898287261;5502.944970550283;5509.752061157257;5522.5581953443625;5524.171374293848;6030.306514750816;6035.094735789623;6036.879262414727;6044.758209671608;6045.576809623328;5980.97009514954;6059.242594675666;6069.332373438918;6127.234095738033;6140.84647883802;5723.656580605177;5741.712914354349;5749.8275051748515;5750.45370689788;5770.076787713946;6288.213973253343;6293.402126797947;6294.339094395114;6306.779262725782;6307.432331090132;5866.384029676822;5968.432998865101;6052.152698622196;6059.93951058563;6124.081387791778;6164.198654174928;6175.166352542349;6175.752969881154;6181.165542651775;6183.072539119078;6107.328193898667;6153.077038444179;6162.661053642093;6163.630182189083;6166.321704612489;5852.590318677651;5936.050231962951;5953.255843019622;5995;6037.245344331958;4590.747508887043;4626.282926146453;4626.306054091846;4633.652476064309;4660.603848672901;4127.442795222667;4142.46148000758;4147.81334839933;4160.521540022875;4189.769562674082;5674.460926212003;5745.540356771448;5766.654000759968;5792.275965504312;5837.124431335334;6105.755769769248;6113.908291375633;6118.847028824226;6120.877582448326;6125.173101631222;5790.044642633919;5799.043157878903;5821.2141360916585;5827.4172582741385;5827.7668893244645;5146.356705411824;5184.455632158644;5200.6619569727845;5204.089284375219;5277.445868183862;6228.56400051992;6229.345918678564;6229.937700623026;6233.283172435137;6236.844078898064;6018.21763170785;6069.635821850703;6083.330833608211;6085.2393450818645;6085.269767627736;6122.764110201883;6247.5001999840815;6290.1508296381235;6353.237611486651;6410.775622853178;6100.786472473442;6101.450869421773;6108.75564977396;6112.816615501542;6113.205283313318;6220.688965551613;6234.407731265523;6237.656928868956;6239.064140378798;6241.656708881055;5774.971125144348;5881.882362352814;5883.94116058834;5924.9703751480965;6030.819075427744;5940.881182376242;5950.4644581986095;5958.374370691188;5967.075103358986;5968.373320801254;5865.120231965239;5865.912011319749;5913.02435098204;5926.525877929842;5932.79235226765;5828.6211396259105;5894.970525147518;5938.703064846827;5940.376260492586;6029.125776762433;5832.737526811219;5839;5841.532677385714;5844.298684157929;5847.327557330961 18:01:26 INFO - PROCESS | 9714 | |1;ext.html;601.1343665122224;644.5005121031202;665.657186548927;669.1368135105714;689.8482333886543;35262.2947541049;38651;38676.613233867654;38693.80653096734;50256.49743502564;49033;49333.26000110001;49416.752916235455;49426.25860612085;49431.75284123574;9242.26061915047;9383.43699378036;9383.906160938406;9384.39001464906;9384.953075234615;8556.828863422728;9187.173154416101;9288.117628825285;10888.346699198073;11137.665870023913;11273.605423810168;11309.886901131007;11348.319100853934;11370.431478426088;11387.54449822006;11195.384253866034;11200.047995920357;11243.269187502869;11259.774804503946;11260.605878794202;8430.409871309012;10809.189310801643;11185.776284474347;11191.832122518168;11211.271267367676;45247.54752452466;45377.773111134564;45510.08979820418;45955;45975.31037034447;3834.9041273968096;3887.4751908492276;3935.1736135411597;3970.8014599269923;4272.764997925113;34236.486452703226;34964.12589685253;34980.650193498;36927.63072369269;37363.813180934194;4784.712917224942;4818.445878723956;4848;4873.512648735134;4937.358143441365;391055;393790;394046;394226;394396;68859;69223.30776692269;69260.6536967312;69328.49364791272;69381.65309173422;72584.63707681428;72744.27255727375;72782.63608681923;72786.27213727901;72843.27156728365;17286;17985.640287194183;18009.459716208534;18021.7296740549;18030.1886415109;12178.086643501772;12297.077719171099;12297.754044919231;12314.876851231373;12318.630441086782;18771.15529801162;19237.13432895523;20099.889511601363;20916.686249706265;21035.78964210369;7380.335769780745;7482.775516734507;7586.810329741801;7872.960635196788;7876.488028278143;1002.7944271424341;1011.2769369900557;1019.5768755966191;1039.3192458939407;1150.3155622404656;32956.50565241523;35058.474122888176;35244;35424;35442.64557354394;1092.6503518874042;1173.1084375874398;1198.2017006090714;1248.825164476974;1301.2322729589587;17423.564410889576;18528.258869645062;18624.72062919057;18629.975351355806;18647.533811654546;661081;666637;666943;667529;669955;66680;66964;67050.66474667694;67205;67255.66372168108;1335.9732805343838;1395.8394784599698;1426.5363756779088;1432.7062952095005;1451.3904160252719;33700.831495842365;35696.464553032245;36391;36569.26861462703;36580.451293231155;55621;61499.07751383643;61659.691701541204;61714;68168;540997;541964;542783;545168;545978;30254;31168.688313117487;31427.685723142476;32745;33050.83474582612;54834.72582637061;54954.725226373615;55044.724776375864;55495;56020.71989640026;43855.561444385145;43895.75989325043;45490.31764523601;46371.768141159075;47575;44261;44992.55007449884;46084.30873536832;46697.766511167225;48023.27965080596;46167;46828.721989461774;46943.06113877635;47091.529084710084;47644.523554765394;37693.4345984805;39364.21271574495;39707.130094529;42384.78807605942;43262.78368608137;30072.54891176678;30394.84802575973;30475.39049218959;30730.385392292475;30917.536236956013 18:01:26 INFO - PROCESS | 9714 | |2;jquery.html;401.87140115163123;415.20695471649157;417.8955261184704;422.70410712501246;430.3458742711079;15424;19923.302684406048;19981.300654477098;20111;21840.453988650293;20714.999999999964;20791;20916.372508824723;21056;21316.680249796267;5528.9723551382285;5916.408359164071;5936.436038576328;5958.940410595904;5966.761329546813;4338.84814031509;4405.691601587891;5861.677607731573;5865.413458654143;5925.377835327293;6670.866582668344;6784;6794.320567943216;6798.116244888158;6804.149481314836;6582.539222254444;6745.021971814084;6778.932210677879;6786.151731033621;6799.11611490508;5849.64902105875;6047.788327408563;6054.303755068179;6055.12200730896;6058.636481811105;24513;24576.139835105863;24864.627030594555;24884.253472395856;25218;2679.3837417393916;3112.828794416306;3281.912727344958;3288.5560449339273;3368.562086928708;15483.458078967182;15551.987874315771;15846.286917088759;16298.185090745415;16506.917465412596;1997.0713618167476;2005.4485016620547;2045.539753555453;2049.80526849949;2069.855110142291;66376;80446;81214.59392703058;83196;88763;11050.778984420427;11497.827532587018;11650.825237621442;11738.94130529342;11780.587679431179;16580.419685310957;16589.66820663604;16590.75113873293;16624.168791560372;16649.583760405843;7910.485818421899;8419.494830310201;8531.74404767858;8545.401821872534;8559.786005349915;7911.327537159291;8062.919370806334;8066.11272759996;8085.591074812528;8270.2556769891;5002.424721157114;5005.724685142352;5020.096382651085;5074.441811400743;5092.694438333712;2011.7284166637317;2053.6816793397047;2068.8965551722354;2074.9273775417787;2111.7993790590176;999.6551189839494;1031.3450958641167;1031.8142734307746;1043.7129789308;1054.920880933933;19363.419097426817;20470.283540076027;20924.790752092587;21025.89487052555;21194;1040.2614143957724;1041.6874937518714;1050.6007717067466;1061.3366645846345;1073.5759375046812;10829.29609575375;11680.240784348991;11787.469563869647;11844.348560829238;11912.523499060115;569221;571719;584556;599955;603841;75908.62045689847;77437.61281193559;92576.53711731533;96139;98579;972.1105188752354;1003.94478303694;1007.7581380468635;1014.2646581228697;1021.3310281765456;17863.28546858111;18132.18405171797;18504.444866654023;18565.25738970426;18866.52833679169;37363;38800.41799372955;44688.55311446974;45168.09663806771;45171.774141129084;59407.702961484916;62070;63057.369426306985;63798.68100659467;64506.03240951483;8279.254867061993;8519.829603408021;8681.2186903179;8900.154485323885;9158.129977652112;11969.820452693042;13208.735825283633;13369.26469044173;13522.93238533801;13553.661158471117;11004.724881878017;11292.266002709797;11430.71423214393;11662.88337116618;11749.118816088656;11063;11660.650180494598;11679.416029198675;11711.765764684487;11949.462274197507;11134.832977505506;11167.664970050912;11586.76826463483;11695.88304116948;11922.463489143183;8381.664733410595;8597.82804343897;8670.696525621574;8722.869156962777;8999.505027223566;11370.147238957277;11460.369679667698;11647;11691.356975366436;11732.178747487595 18:01:26 INFO - PROCESS | 9714 | |3;mootools.html;1549.5273941447858;1570.560243131923;1752.526817759205;1831.1851226204344;1873.4566975577075;8510.148985101503;9060.546972651375;9288.21050210732;10047.849282260762;10052.396856188625;9166.770830729236;9793.412395256271;9800.166985806214;9802.411855288688;9811.313208075437;2500.1374525788574;2547.796176305891;2548.171844150649;2549.2989427907287;2549.8342607730533;2230.955380892381;2388.2118900762794;2439.9878000609974;2480.7891329237036;2535.8858851351642;2525.2929179829675;2553.834000789943;2559.5264875997905;2559.5520783862844;2562.461883004571;2542.478791847676;2579.406736450627;2580.2259322203263;2584.134315004476;2585.58630619101;2487.166799122296;2514.371407148213;2579.1359894435386;2581.728918463572;2583.2637698256094;9841.114299712988;9876.45679487628;9881.16010139139;9882.110610045058;9898.158656514204;5470.343558772933;5550.666959982414;5594.804181853657;5609.411011843738;5613.382527921966;3698.260347930425;3712.554493460775;3805.5243094613174;3814.389697648363;3823.0824602095577;1348.2112963916022;1351.6823546466574;1354.8374195096553;1371.5885234429632;1377.6762460821699;12771.61685149447;12882.742345153045;13090.934545327404;13147.934260328637;13157.276349800757;4716.575508204278;4770.546798054179;4854.247591623233;4898.608111351084;5100.38795344561;11017.614383496539;11018.669439916815;11107.83338249927;11110.388928609003;11153;5480.424555421623;5495.972520137454;5502.449755024466;5508.256385388001;5514.228008078872;4998.425181104147;5062.974685126551;5072.54347108762;5161.612879034087;5205.531502164823;4310.245707001325;4346.391505189276;4362.105768317488;4406.49325327585;4409.382686423904;1315.6184706435026;1367.1250399744172;1397.4061024064936;1411.6612013116778;1420.7868819676924;1036.6734478639205;1059.21618002679;1059.7668512927144;1066.861308029951;1082.5507414422925;6419.486441084702;6463.353664633499;6568.441682456949;6606.2072551293195;6731.865362692719;1047.8690163729534;1106.9944650276698;1132.5356603792407;1135.6876858863714;1138.59579849154;4434.667399944952;4468.754218518013;4484.596386325246;4542.636589072867;4572.679912406166;11653.941730291293;11777.705557361134;12045.096617753528;12057.0957178212;12077;11681.532738690357;11875.228110172984;12349.32078735678;12361.690957726129;12372.62882113538;9035.186833185046;9234.215091717146;9234.538273086318;9240.630374784932;9258.814823703487;6337.9366206337345;6412.166418365583;6491.7990171818365;6519.7392104315295;6555.573887697476;11249.268797528135;11343.886561134283;11420.48607812667;11503.424828758361;11507.42462876836;11296.265742726697;11379.203455758017;11544.88455115438;11600.88399115998;11673.066154707773;8302.584870756558;8460.561704510372;8464.407491475538;8571.22858942698;8795.956020220114;9731.270154738564;9872.259580531632;9872.457014863965;9890.109890109925;9906.900930990598;8318.292945099492;8395.118512556093;8473.872891906749;8508;8841.513716745641;8291.751247462586;8405.495670259805;8436.957815210884;8469.407141500282;8580.14198580137;7684.193159718149;7767.572783496962;7792.220777922163;7802.29779319864;7842.764717058498;5648;5661.547076233809;5677.7161141941915;5808.30300363959;5925.514890581066;5633.211350410864;5692.886142277214;5808.767649293981;5821.679807610452;5934.732937017759 18:01:26 INFO - PROCESS | 9714 | |4;prototype.html;348.3207744897451;361.7160528984747;362.1868904309824;365.9908502287444;367.8124156680094;5463.825277565323;5650.378458369576;5661.547076233902;5667.829965101048;5690.288713910766;5564.276644036279;5574.303212098488;5575.219469274305;5579.972100139504;5593;3866.7873266970305;4108.383742438643;4409.669274804402;4420.58004489573;4432.578905004019;4349.26062569364;4354.1074079813725;4355.586219309176;4367.45406824147;4372.3878656988045;4329.675274354436;4330.588594083557;4355.738655680653;4366.56334366564;4370.2133615949115;4121.835126594932;4288.378185163165;4371.934420983704;4373;4388.341748737683;3840.961590384088;3854.2869569129803;3886.727929044968;3889.844406223776;3900.726949113563;5405.324334458192;5421.349438067417;5451.890962180734;5499.532539734128;5501.614886957915;4796.712197268175;4989.176785830334;4997.350344455234;5023.070731914608;5043.218301163325;5815.964412455649;5829.679367634779;5829.679367634779;5838.883222335572;5844.766209351621;4266.360045993095;4276.401303817468;4277.8288868445225;4285.785710714452;4293.8711838644895;5932.762689492415;5945.3650246182215;6039.218430562994;6070.362611925772;6176.8764624707255;4263.637590804754;4268.295731204378;4269.701120921568;4274.508431530352;4291.249031419489;4345.565443455693;4351.456067991501;4351.4987329371315;4352.847650332224;4362.9781851091175;4869.853904382874;4993;4993.650444468925;4996.5003499650475;5005.649604527721;5845.357010728816;5845.766169353178;5849.268841394825;5864.765409383577;5874.383189765013;1499.3552772307794;1508.630385555546;1519.7948276982686;1523.725729368702;1530.1201808959893;969.5055521683992;973.7419583810296;979.4123525884412;984.1536278800226;990.4849478271259;5028.5977121830165;5056.570191533688;5068.378119001884;5076.263941728429;5099.64302498829;5253.369595648546;5300.416954135042;5311.389190243094;5322.121849894801;5389.892202155935;4725.267583524491;5051.292819005343;5089.694618322913;5118.87202819925;5132.255822905658;2850.4726625574335;2859.6568411790718;2865.6131422257727;2865.796365526484;2884.4663737208684;5452.645578037414;5494.917576236359;5504.53211477029;5508.493635395886;5528.55771538276;5184.8963020739375;5294.470552944675;5366.356037275474;5790.565707571949;5970.552208584373;6147.784827531015;6178.9186892293665;6183.258009038854;6184.639379336539;6218.253809542884;5835.44563266489;6008.7296071676;6028.819135425944;6039.728212230548;6056.90914636272;5891.438768726377;5923.844850254159;5970.31341395745;5972.432618901197;5990.430909063632;6136.864680034208;6150.969245153746;6215.81352559424;6216.316205217332;6237.1891654084675;4176.737986531839;4232.174725928415;4268.231718290737;4315.568443155659;4339.479262488521;5959.314678811992;6007;6014.6391216527145;6023.5181185505935;6032.125341825498;5914.911276330945;5917;5917.526597872074;5939.643621382731;5948.434898684618;5628.212050312877;5885.676287804212;5904.3210030845285;5949.6430214187285;5952.285725712942;5806.912896306474;5836.074095548374;5838.386969368155;5847.239858818326;5900.675462849413;5658.264425624642;5905.616134951214;5939.2575928009;6001.519878409804;6008.369121242206;5731.971340143273;5774.538036956949;5784.392638773036;5811.418858114154;5815.94184058154 18:01:26 INFO - PROCESS | 9714 | |5;yui.html;846.8602680557708;860.7675927499575;890.6704519327849;893.2809088683609;923.2844545477259;16378.181090945449;17033.148342582852;17084.06037667931;17101.57246068849;17171.484855454342;14596.635084122901;14634.926825365885;14700.926495367536;14773.556793296191;14778.556643300692;724.905762250908;855.84166929118;884.1909652667833;889.6485888074207;889.7864512517014;866.9826603467927;872.3544577013029;872.8865247517814;876.2114097312406;878.1350369885657;951.4529145741227;952.8189643967659;953.3517208298355;953.5137080089136;953.9236861051133;765.9731909383173;898.8202359528087;906.3700727994046;914.7027216154777;946.0775743649955;669.2003056347667;670.061913321354;671.8320419895026;672.0691841799144;672.0960308385263;14407.567772966828;14429;14445.422183112663;14465.13209207451;14477.565673029825;14640.780388294184;15392.230388480531;15441.691166176613;15530.534083977385;15728.606784830356;14156.292185390792;14343.641408964755;14496.565103046925;14502.637434064127;14678.559643210721;4705.505921878222;4737.336772851803;4791.928121078186;4837.419509658828;4873.220284754422;81747;82916;83134;83262;83327.5833620834;11425.60010399632;11505.942470287595;11594.94202528982;11848.526058957717;11926.344051077274;22861.199858005224;23124;23149.537009259722;23170.420739481648;23179.072837086664;8515;8615.483071015633;8640.963084429783;8773.254273386707;8935.955320223487;4834.564889159992;4862.5380588844;4882.096812089775;4986.376702912136;5144.845654630292;4149.709520333608;4267.16790225903;4267.658587313008;4275.957240427556;4277.251480990814;628.5505863307757;706.0221593093603;706.6608028146519;707.359839345383;708.5429897715944;661.2759028863387;724.7318492157935;728.9489735718555;814.6741303478656;837.5853952293531;10504.789904202025;10809.351438913533;11173.944130279297;11269.943650281695;11326.546938122548;658.4166270030171;767.9846403072019;771.7221800151941;791.1020991174943;794.5153456391597;5111.156659151198;5461.918071228934;5469.781208751685;5501.642393244426;5547.278853749068;133629;134158.32920835528;134278;134395.32802335927;138086.30956845352;114347;115734;117191;121793;124922.37538812248;101352.49323753334;103803.48098259611;103857;105989;106687.9331206678;9872.555734991798;10046.296759226783;10055.597776089167;10059;10111.848322275318;56946.7152664234;57794;58094;58241;59094.40905590889;69628.6518567404;71279;71734.28265717276;80675.59662201651;86891.56554217188;14358.202408485591;14741.55775326742;14873.553793386216;14993.100413975197;15329.846701532842;16596.50210493687;16742.1628918556;17013.319467221667;17149.399771007957;17504.91247543754;14693.55919322422;16313.347466101222;16534.83465165381;16586.58533536671;16750.832491674926;15165.317560709575;15557.611059723598;15639.374425023327;15917.124558149413;16210.189490525663;12944.741105178033;13039.152455090389;13057.542985995444;13121.475140994635;13134;12545.560905368267;13070.215787052808;13182.868171318163;13414.195148291134;13456.5290214842;12977.610671679864;12992.155509891827;13025.413856376676;13208.471661133448;13342.466301347837 18:01:26 INFO - PROCESS | 9714 | __end_tp_report 18:01:26 INFO - PROCESS | 9714 | __start_cc_report 18:01:26 INFO - PROCESS | 9714 | _x_x_mozilla_cycle_collect,1029 18:01:26 INFO - PROCESS | 9714 | __end_cc_report 18:01:26 INFO - PROCESS | 9714 | __startTimestamp1473814886592__endTimestamp 18:01:26 INFO - PROCESS | 9714 | 18:01:26 INFO - PROCESS | 9714 | ------- Summary: start ------- 18:01:26 INFO - PROCESS | 9714 | Number of tests: 6 18:01:26 INFO - PROCESS | 9714 | 18:01:26 INFO - PROCESS | 9714 | [#0] dojo.html Cycles:165 Average:5648.57 Median:5852.59 stddev:656.98 (11.2%) stddev-sans-first:630.74 18:01:26 INFO - PROCESS | 9714 | Values: 3218.5 3228.2 3244.0 3263.0 3275.9 5532.4 5590.1 5603.3 5628.0 5634.6 5626.6 5659.0 5670.3 5686.8 5718.1 5009.7 5081.5 5114.5 5137.8 5184.6 5087.8 5155.9 5164.1 5164.6 5166.8 5073.1 5126.4 5185.3 5224.8 5227.4 5293.7 5294.7 5299.4 5300.1 5331.2 4986.7 4992.2 5022.6 5179.0 5180.2 5477.1 5502.9 5509.8 5522.6 5524.2 6030.3 6035.1 6036.9 6044.8 6045.6 5981.0 6059.2 6069.3 6127.2 6140.8 5723.7 5741.7 5749.8 5750.5 5770.1 6288.2 6293.4 6294.3 6306.8 6307.4 5866.4 5968.4 6052.2 6059.9 6124.1 6164.2 6175.2 6175.8 6181.2 6183.1 6107.3 6153.1 6162.7 6163.6 6166.3 5852.6 5936.1 5953.3 5995.0 6037.2 4590.7 4626.3 4626.3 4633.7 4660.6 4127.4 4142.5 4147.8 4160.5 4189.8 5674.5 5745.5 5766.7 5792.3 5837.1 6105.8 6113.9 6118.8 6120.9 6125.2 5790.0 5799.0 5821.2 5827.4 5827.8 5146.4 5184.5 5200.7 5204.1 5277.4 6228.6 6229.3 6229.9 6233.3 6236.8 6018.2 6069.6 6083.3 6085.2 6085.3 6122.8 6247.5 6290.2 6353.2 6410.8 6100.8 6101.5 6108.8 6112.8 6113.2 6220.7 6234.4 6237.7 6239.1 6241.7 5775.0 5881.9 5883.9 5925.0 6030.8 5940.9 5950.5 5958.4 5967.1 5968.4 5865.1 5865.9 5913.0 5926.5 5932.8 5828.6 5895.0 5938.7 5940.4 6029.1 5832.7 5839.0 5841.5 5844.3 5847.3 18:01:26 INFO - PROCESS | 9714 | 18:01:26 INFO - PROCESS | 9714 | [#1] ext.html Cycles:180 Average:70933.03 Median:21838.97 stddev:145350.68 (665.6%) stddev-sans-first:145662.51 18:01:26 INFO - PROCESS | 9714 | Values: 601.1 644.5 665.7 669.1 689.8 35262.3 38651.0 38676.6 38693.8 50256.5 49033.0 49333.3 49416.8 49426.3 49431.8 9242.3 9383.4 9383.9 9384.4 9385.0 8556.8 9187.2 9288.1 10888.3 11137.7 11273.6 11309.9 11348.3 11370.4 11387.5 11195.4 11200.0 11243.3 11259.8 11260.6 8430.4 10809.2 11185.8 11191.8 11211.3 45247.5 45377.8 45510.1 45955.0 45975.3 3834.9 3887.5 3935.2 3970.8 4272.8 34236.5 34964.1 34980.7 36927.6 37363.8 4784.7 4818.4 4848.0 4873.5 4937.4 391055.0 393790.0 394046.0 394226.0 394396.0 68859.0 69223.3 69260.7 69328.5 69381.7 72584.6 72744.3 72782.6 72786.3 72843.3 17286.0 17985.6 18009.5 18021.7 18030.2 12178.1 12297.1 12297.8 12314.9 12318.6 18771.2 19237.1 20099.9 20916.7 21035.8 7380.3 7482.8 7586.8 7873.0 7876.5 1002.8 1011.3 1019.6 1039.3 1150.3 32956.5 35058.5 35244.0 35424.0 35442.6 1092.7 1173.1 1198.2 1248.8 1301.2 17423.6 18528.3 18624.7 18630.0 18647.5 661081.0 666637.0 666943.0 667529.0 669955.0 66680.0 66964.0 67050.7 67205.0 67255.7 1336.0 1395.8 1426.5 1432.7 1451.4 33700.8 35696.5 36391.0 36569.3 36580.5 55621.0 61499.1 61659.7 61714.0 68168.0 540997.0 541964.0 542783.0 545168.0 545978.0 30254.0 31168.7 31427.7 32745.0 33050.8 54834.7 54954.7 55044.7 55495.0 56020.7 43855.6 43895.8 45490.3 46371.8 47575.0 44261.0 44992.6 46084.3 46697.8 48023.3 46167.0 46828.7 46943.1 47091.5 47644.5 37693.4 39364.2 39707.1 42384.8 43262.8 30072.5 30394.8 30475.4 30730.4 30917.5 18:01:26 INFO - PROCESS | 9714 | 18:01:26 INFO - PROCESS | 9714 | [#2] jquery.html Cycles:180 Average:32173.73 Median:24874.44 stddev:96070.76 (386.2%) stddev-sans-first:96310.65 18:01:26 INFO - PROCESS | 9714 | Values: 401.9 415.2 417.9 422.7 430.3 15424.0 19923.3 19981.3 20111.0 21840.5 20715.0 20791.0 20916.4 21056.0 21316.7 5529.0 5916.4 5936.4 5958.9 5966.8 4338.8 4405.7 5861.7 5865.4 5925.4 6670.9 6784.0 6794.3 6798.1 6804.1 6582.5 6745.0 6778.9 6786.2 6799.1 5849.6 6047.8 6054.3 6055.1 6058.6 24513.0 24576.1 24864.6 24884.3 25218.0 2679.4 3112.8 3281.9 3288.6 3368.6 15483.5 15552.0 15846.3 16298.2 16506.9 1997.1 2005.4 2045.5 2049.8 2069.9 66376.0 80446.0 81214.6 83196.0 88763.0 11050.8 11497.8 11650.8 11738.9 11780.6 16580.4 16589.7 16590.8 16624.2 16649.6 7910.5 8419.5 8531.7 8545.4 8559.8 7911.3 8062.9 8066.1 8085.6 8270.3 5002.4 5005.7 5020.1 5074.4 5092.7 2011.7 2053.7 2068.9 2074.9 2111.8 999.7 1031.3 1031.8 1043.7 1054.9 19363.4 20470.3 20924.8 21025.9 21194.0 1040.3 1041.7 1050.6 1061.3 1073.6 10829.3 11680.2 11787.5 11844.3 11912.5 569221.0 571719.0 584556.0 599955.0 603841.0 75908.6 77437.6 92576.5 96139.0 98579.0 972.1 1003.9 1007.8 1014.3 1021.3 17863.3 18132.2 18504.4 18565.3 18866.5 37363.0 38800.4 44688.6 45168.1 45171.8 59407.7 62070.0 63057.4 63798.7 64506.0 8279.3 8519.8 8681.2 8900.2 9158.1 11969.8 13208.7 13369.3 13522.9 13553.7 11004.7 11292.3 11430.7 11662.9 11749.1 11063.0 11660.7 11679.4 11711.8 11949.5 11134.8 11167.7 11586.8 11695.9 11922.5 8381.7 8597.8 8670.7 8722.9 8999.5 11370.1 11460.4 11647.0 11691.4 11732.2 18:01:26 INFO - PROCESS | 9714 | 18:01:26 INFO - PROCESS | 9714 | [#3] mootools.html Cycles:180 Average:6382.35 Median:4407.94 stddev:3632.24 (82.4%) stddev-sans-first:3624.27 18:01:26 INFO - PROCESS | 9714 | Values: 1549.5 1570.6 1752.5 1831.2 1873.5 8510.1 9060.5 9288.2 10047.8 10052.4 9166.8 9793.4 9800.2 9802.4 9811.3 2500.1 2547.8 2548.2 2549.3 2549.8 2231.0 2388.2 2440.0 2480.8 2535.9 2525.3 2553.8 2559.5 2559.6 2562.5 2542.5 2579.4 2580.2 2584.1 2585.6 2487.2 2514.4 2579.1 2581.7 2583.3 9841.1 9876.5 9881.2 9882.1 9898.2 5470.3 5550.7 5594.8 5609.4 5613.4 3698.3 3712.6 3805.5 3814.4 3823.1 1348.2 1351.7 1354.8 1371.6 1377.7 12771.6 12882.7 13090.9 13147.9 13157.3 4716.6 4770.5 4854.2 4898.6 5100.4 11017.6 11018.7 11107.8 11110.4 11153.0 5480.4 5496.0 5502.4 5508.3 5514.2 4998.4 5063.0 5072.5 5161.6 5205.5 4310.2 4346.4 4362.1 4406.5 4409.4 1315.6 1367.1 1397.4 1411.7 1420.8 1036.7 1059.2 1059.8 1066.9 1082.6 6419.5 6463.4 6568.4 6606.2 6731.9 1047.9 1107.0 1132.5 1135.7 1138.6 4434.7 4468.8 4484.6 4542.6 4572.7 11653.9 11777.7 12045.1 12057.1 12077.0 11681.5 11875.2 12349.3 12361.7 12372.6 9035.2 9234.2 9234.5 9240.6 9258.8 6337.9 6412.2 6491.8 6519.7 6555.6 11249.3 11343.9 11420.5 11503.4 11507.4 11296.3 11379.2 11544.9 11600.9 11673.1 8302.6 8460.6 8464.4 8571.2 8796.0 9731.3 9872.3 9872.5 9890.1 9906.9 8318.3 8395.1 8473.9 8508.0 8841.5 8291.8 8405.5 8437.0 8469.4 8580.1 7684.2 7767.6 7792.2 7802.3 7842.8 5648.0 5661.5 5677.7 5808.3 5925.5 5633.2 5692.9 5808.8 5821.7 5934.7 18:01:26 INFO - PROCESS | 9714 | 18:01:26 INFO - PROCESS | 9714 | [#4] prototype.html Cycles:180 Average:4845.31 Median:5452.27 stddev:1422.49 (26.1%) stddev-sans-first:1385.86 18:01:26 INFO - PROCESS | 9714 | Values: 348.3 361.7 362.2 366.0 367.8 5463.8 5650.4 5661.5 5667.8 5690.3 5564.3 5574.3 5575.2 5580.0 5593.0 3866.8 4108.4 4409.7 4420.6 4432.6 4349.3 4354.1 4355.6 4367.5 4372.4 4329.7 4330.6 4355.7 4366.6 4370.2 4121.8 4288.4 4371.9 4373.0 4388.3 3841.0 3854.3 3886.7 3889.8 3900.7 5405.3 5421.3 5451.9 5499.5 5501.6 4796.7 4989.2 4997.4 5023.1 5043.2 5816.0 5829.7 5829.7 5838.9 5844.8 4266.4 4276.4 4277.8 4285.8 4293.9 5932.8 5945.4 6039.2 6070.4 6176.9 4263.6 4268.3 4269.7 4274.5 4291.2 4345.6 4351.5 4351.5 4352.8 4363.0 4869.9 4993.0 4993.7 4996.5 5005.6 5845.4 5845.8 5849.3 5864.8 5874.4 1499.4 1508.6 1519.8 1523.7 1530.1 969.5 973.7 979.4 984.2 990.5 5028.6 5056.6 5068.4 5076.3 5099.6 5253.4 5300.4 5311.4 5322.1 5389.9 4725.3 5051.3 5089.7 5118.9 5132.3 2850.5 2859.7 2865.6 2865.8 2884.5 5452.6 5494.9 5504.5 5508.5 5528.6 5184.9 5294.5 5366.4 5790.6 5970.6 6147.8 6178.9 6183.3 6184.6 6218.3 5835.4 6008.7 6028.8 6039.7 6056.9 5891.4 5923.8 5970.3 5972.4 5990.4 6136.9 6151.0 6215.8 6216.3 6237.2 4176.7 4232.2 4268.2 4315.6 4339.5 5959.3 6007.0 6014.6 6023.5 6032.1 5914.9 5917.0 5917.5 5939.6 5948.4 5628.2 5885.7 5904.3 5949.6 5952.3 5806.9 5836.1 5838.4 5847.2 5900.7 5658.3 5905.6 5939.3 6001.5 6008.4 5732.0 5774.5 5784.4 5811.4 5815.9 18:01:26 INFO - PROCESS | 9714 | 18:01:26 INFO - PROCESS | 9714 | [#5] yui.html Cycles:180 Average:23475.73 Median:23136.77 stddev:34739.19 (150.1%) stddev-sans-first:34795.09 18:01:26 INFO - PROCESS | 9714 | Values: 846.9 860.8 890.7 893.3 923.3 16378.2 17033.1 17084.1 17101.6 17171.5 14596.6 14634.9 14700.9 14773.6 14778.6 724.9 855.8 884.2 889.6 889.8 867.0 872.4 872.9 876.2 878.1 951.5 952.8 953.4 953.5 953.9 766.0 898.8 906.4 914.7 946.1 669.2 670.1 671.8 672.1 672.1 14407.6 14429.0 14445.4 14465.1 14477.6 14640.8 15392.2 15441.7 15530.5 15728.6 14156.3 14343.6 14496.6 14502.6 14678.6 4705.5 4737.3 4791.9 4837.4 4873.2 81747.0 82916.0 83134.0 83262.0 83327.6 11425.6 11505.9 11594.9 11848.5 11926.3 22861.2 23124.0 23149.5 23170.4 23179.1 8515.0 8615.5 8641.0 8773.3 8936.0 4834.6 4862.5 4882.1 4986.4 5144.8 4149.7 4267.2 4267.7 4276.0 4277.3 628.6 706.0 706.7 707.4 708.5 661.3 724.7 728.9 814.7 837.6 10504.8 10809.4 11173.9 11269.9 11326.5 658.4 768.0 771.7 791.1 794.5 5111.2 5461.9 5469.8 5501.6 5547.3 133629.0 134158.3 134278.0 134395.3 138086.3 114347.0 115734.0 117191.0 121793.0 124922.4 101352.5 103803.5 103857.0 105989.0 106687.9 9872.6 10046.3 10055.6 10059.0 10111.8 56946.7 57794.0 58094.0 58241.0 59094.4 69628.7 71279.0 71734.3 80675.6 86891.6 14358.2 14741.6 14873.6 14993.1 15329.8 16596.5 16742.2 17013.3 17149.4 17504.9 14693.6 16313.3 16534.8 16586.6 16750.8 15165.3 15557.6 15639.4 15917.1 16210.2 12944.7 13039.2 13057.5 13121.5 13134.0 12545.6 13070.2 13182.9 13414.2 13456.5 12977.6 12992.2 13025.4 13208.5 13342.5 18:01:26 INFO - PROCESS | 9714 | -------- Summary: end -------- 18:01:26 INFO - PROCESS | 9714 | 18:01:27 INFO - TEST-INFO | 9714: exit 0 18:01:27 INFO - TEST-OK | dromaeo_css | took 1113850ms 18:01:27 INFO - TEST-START | kraken 18:01:27 INFO - operating with platform_type : linux_ 18:01:27 INFO - Initialising browser for kraken test... 18:01:27 INFO - TEST-INFO | started process 9916 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1ikcv3/profile http://localhost:54446/getInfo.html) 18:01:31 INFO - PROCESS | 9916 | __metrics Screen width/height:1600/1200 18:01:31 INFO - PROCESS | 9916 | colorDepth:24 18:01:31 INFO - PROCESS | 9916 | Browser inner width/height: 1024/697 18:01:31 INFO - PROCESS | 9916 | __metrics 18:01:32 INFO - PROCESS | 9916 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:01:32 INFO - PROCESS | 9916 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:01:33 INFO - TEST-INFO | 9916: exit 0 18:01:33 INFO - Browser initialized. 18:01:33 INFO - Running cycle 1/1 for kraken test... 18:01:33 INFO - TEST-INFO | started process 10004 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1ikcv3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:01:45 INFO - PROCESS | 10004 | RSS: Main: 145780736 18:01:45 INFO - PROCESS | 10004 | 18:01:47 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:54446/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54446/tests/kraken/driver.html?testName=audio-beat-detection) 18:01:48 INFO - PROCESS | 10004 | RSS: Main: 200736768 18:01:48 INFO - PROCESS | 10004 | 18:01:50 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:54446/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54446/tests/kraken/driver.html?testName=audio-dft) 18:01:51 INFO - PROCESS | 10004 | RSS: Main: 184737792 18:01:51 INFO - PROCESS | 10004 | 18:01:54 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:54446/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54446/tests/kraken/driver.html?testName=audio-fft) 18:01:54 INFO - PROCESS | 10004 | RSS: Main: 275972096 18:01:54 INFO - PROCESS | 10004 | 18:01:56 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:54446/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54446/tests/kraken/driver.html?testName=audio-oscillator) 18:01:57 INFO - PROCESS | 10004 | RSS: Main: 181039104 18:01:57 INFO - PROCESS | 10004 | 18:01:59 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:54446/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54446/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:02:00 INFO - PROCESS | 10004 | RSS: Main: 180940800 18:02:00 INFO - PROCESS | 10004 | 18:02:03 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:54446/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54446/tests/kraken/driver.html?testName=imaging-darkroom) 18:02:03 INFO - PROCESS | 10004 | RSS: Main: 216567808 18:02:03 INFO - PROCESS | 10004 | 18:02:07 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:54446/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54446/tests/kraken/driver.html?testName=imaging-desaturate) 18:02:07 INFO - PROCESS | 10004 | RSS: Main: 215339008 18:02:07 INFO - PROCESS | 10004 | 18:02:10 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:54446/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54446/tests/kraken/driver.html?testName=json-parse-financial) 18:02:11 INFO - PROCESS | 10004 | RSS: Main: 215023616 18:02:11 INFO - PROCESS | 10004 | 18:02:12 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:54446/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54446/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:02:13 INFO - PROCESS | 10004 | RSS: Main: 177856512 18:02:13 INFO - PROCESS | 10004 | 18:02:15 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:54446/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54446/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:02:15 INFO - PROCESS | 10004 | RSS: Main: 185044992 18:02:15 INFO - PROCESS | 10004 | 18:02:17 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:54446/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54446/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:02:18 INFO - PROCESS | 10004 | RSS: Main: 186298368 18:02:18 INFO - PROCESS | 10004 | 18:02:20 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:54446/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54446/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:02:21 INFO - PROCESS | 10004 | RSS: Main: 180457472 18:02:21 INFO - PROCESS | 10004 | 18:02:24 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:54446/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54446/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:02:24 INFO - PROCESS | 10004 | RSS: Main: 185020416 18:02:24 INFO - PROCESS | 10004 | 18:02:26 INFO - PROCESS | 10004 | Cycle 1(1): loaded http://localhost:54446/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54446/tests/kraken/driver.html?testName=ai-astar) 18:02:26 INFO - PROCESS | 10004 | RSS: Main: 236142592 18:02:26 INFO - PROCESS | 10004 | 18:02:26 INFO - PROCESS | 10004 | __start_tp_report 18:02:26 INFO - PROCESS | 10004 | _x_x_mozilla_page_load 18:02:26 INFO - PROCESS | 10004 | _x_x_mozilla_page_load_details 18:02:26 INFO - PROCESS | 10004 | |i|pagename|runs| 18:02:26 INFO - PROCESS | 10004 | |0;ai-astar;110;113;120;108;109;111;109;109;109;111 18:02:26 INFO - PROCESS | 10004 | |1;audio-beat-detection;141;121;138;179;117;149;143;119;176;115 18:02:26 INFO - PROCESS | 10004 | |2;audio-dft;161;160;161;160;162;160;160;161;159;160 18:02:26 INFO - PROCESS | 10004 | |3;audio-fft;76;75;75;171;75;75;75;76;167;75 18:02:26 INFO - PROCESS | 10004 | |4;audio-oscillator;82;84;149;84;82;84;148;83;83;143 18:02:26 INFO - PROCESS | 10004 | |5;imaging-gaussian-blur;116;117;117;116;120;116;116;117;118;117 18:02:26 INFO - PROCESS | 10004 | |6;imaging-darkroom;147;146;146;146;147;146;146;147;146;146 18:02:26 INFO - PROCESS | 10004 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;96;96 18:02:26 INFO - PROCESS | 10004 | |8;json-parse-financial;50;50;51;50;51;51;52;51;51;50 18:02:26 INFO - PROCESS | 10004 | |9;json-stringify-tinderbox;59;62;61;60;60;59;59;60;59;61 18:02:26 INFO - PROCESS | 10004 | |10;stanford-crypto-aes;72;72;71;72;80;73;72;72;72;73 18:02:26 INFO - PROCESS | 10004 | |11;stanford-crypto-ccm;118;114;116;125;133;113;124;115;185;117 18:02:26 INFO - PROCESS | 10004 | |12;stanford-crypto-pbkdf2;154;154;151;155;153;150;156;154;156;151 18:02:26 INFO - PROCESS | 10004 | |13;stanford-crypto-sha256-iterative;65;62;65;61;61;62;62;61;62;64 18:02:26 INFO - PROCESS | 10004 | __end_tp_report 18:02:26 INFO - PROCESS | 10004 | __start_cc_report 18:02:26 INFO - PROCESS | 10004 | _x_x_mozilla_cycle_collect,1675 18:02:26 INFO - PROCESS | 10004 | __end_cc_report 18:02:26 INFO - PROCESS | 10004 | __startTimestamp1473814946498__endTimestamp 18:02:26 INFO - PROCESS | 10004 | 18:02:26 INFO - PROCESS | 10004 | ------- Summary: start ------- 18:02:26 INFO - PROCESS | 10004 | Number of tests: 14 18:02:26 INFO - PROCESS | 10004 | 18:02:26 INFO - PROCESS | 10004 | [#0] ai-astar Cycles:10 Average:110.90 Median:110.50 stddev:3.51 (3.2%) stddev-sans-first:3.71 18:02:26 INFO - PROCESS | 10004 | Values: 110.0 113.0 120.0 108.0 109.0 111.0 109.0 109.0 109.0 111.0 18:02:26 INFO - PROCESS | 10004 | 18:02:26 INFO - PROCESS | 10004 | [#1] audio-beat-detection Cycles:10 Average:139.80 Median:142.00 stddev:23.26 (16.4%) stddev-sans-first:24.66 18:02:26 INFO - PROCESS | 10004 | Values: 141.0 121.0 138.0 179.0 117.0 149.0 143.0 119.0 176.0 115.0 18:02:26 INFO - PROCESS | 10004 | 18:02:26 INFO - PROCESS | 10004 | [#2] audio-dft Cycles:10 Average:160.40 Median:160.50 stddev:0.84 (0.5%) stddev-sans-first:0.87 18:02:26 INFO - PROCESS | 10004 | Values: 161.0 160.0 161.0 160.0 162.0 160.0 160.0 161.0 159.0 160.0 18:02:26 INFO - PROCESS | 10004 | 18:02:26 INFO - PROCESS | 10004 | [#3] audio-fft Cycles:10 Average:94.00 Median:75.00 stddev:39.54 (52.7%) stddev-sans-first:41.40 18:02:26 INFO - PROCESS | 10004 | Values: 76.0 75.0 75.0 171.0 75.0 75.0 75.0 76.0 167.0 75.0 18:02:26 INFO - PROCESS | 10004 | 18:02:26 INFO - PROCESS | 10004 | [#4] audio-oscillator Cycles:10 Average:102.20 Median:83.00 stddev:30.73 (37.0%) stddev-sans-first:31.71 18:02:26 INFO - PROCESS | 10004 | Values: 82.0 84.0 149.0 84.0 82.0 84.0 148.0 83.0 83.0 143.0 18:02:26 INFO - PROCESS | 10004 | 18:02:26 INFO - PROCESS | 10004 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:1.25 (1.1%) stddev-sans-first:1.27 18:02:26 INFO - PROCESS | 10004 | Values: 116.0 117.0 117.0 116.0 120.0 116.0 116.0 117.0 118.0 117.0 18:02:26 INFO - PROCESS | 10004 | 18:02:26 INFO - PROCESS | 10004 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.48 (0.3%) stddev-sans-first:0.44 18:02:26 INFO - PROCESS | 10004 | Values: 147.0 146.0 146.0 146.0 147.0 146.0 146.0 147.0 146.0 146.0 18:02:26 INFO - PROCESS | 10004 | 18:02:26 INFO - PROCESS | 10004 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 18:02:26 INFO - PROCESS | 10004 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 96.0 18:02:26 INFO - PROCESS | 10004 | 18:02:26 INFO - PROCESS | 10004 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.00 stddev:0.67 (1.3%) stddev-sans-first:0.67 18:02:26 INFO - PROCESS | 10004 | Values: 50.0 50.0 51.0 50.0 51.0 51.0 52.0 51.0 51.0 50.0 18:02:26 INFO - PROCESS | 10004 | 18:02:26 INFO - PROCESS | 10004 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.00 stddev:1.05 (1.8%) stddev-sans-first:1.05 18:02:26 INFO - PROCESS | 10004 | Values: 59.0 62.0 61.0 60.0 60.0 59.0 59.0 60.0 59.0 61.0 18:02:26 INFO - PROCESS | 10004 | 18:02:26 INFO - PROCESS | 10004 | [#10] stanford-crypto-aes Cycles:10 Average:72.90 Median:72.00 stddev:2.56 (3.6%) stddev-sans-first:2.69 18:02:26 INFO - PROCESS | 10004 | Values: 72.0 72.0 71.0 72.0 80.0 73.0 72.0 72.0 72.0 73.0 18:02:26 INFO - PROCESS | 10004 | 18:02:26 INFO - PROCESS | 10004 | [#11] stanford-crypto-ccm Cycles:10 Average:126.00 Median:121.00 stddev:21.64 (17.9%) stddev-sans-first:22.76 18:02:26 INFO - PROCESS | 10004 | Values: 118.0 114.0 116.0 125.0 133.0 113.0 124.0 115.0 185.0 117.0 18:02:26 INFO - PROCESS | 10004 | 18:02:26 INFO - PROCESS | 10004 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.40 Median:154.00 stddev:2.12 (1.4%) stddev-sans-first:2.24 18:02:26 INFO - PROCESS | 10004 | Values: 154.0 154.0 151.0 155.0 153.0 150.0 156.0 154.0 156.0 151.0 18:02:26 INFO - PROCESS | 10004 | 18:02:26 INFO - PROCESS | 10004 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.50 Median:62.00 stddev:1.58 (2.6%) stddev-sans-first:1.39 18:02:26 INFO - PROCESS | 10004 | Values: 65.0 62.0 65.0 61.0 61.0 62.0 62.0 61.0 62.0 64.0 18:02:26 INFO - PROCESS | 10004 | -------- Summary: end -------- 18:02:26 INFO - PROCESS | 10004 | 18:02:27 INFO - TEST-INFO | 10004: exit 0 18:02:27 INFO - TEST-OK | kraken | took 60190ms 18:02:27 INFO - SUITE-END | took 1174s 18:02:27 INFO - Completed test suite (00:19:34) 18:02:27 INFO - javascript benchmark 18:02:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3218.533312669663, 3228.1606782236618, 3243.9675603243977, 3262.983685081574, 3275.934481310374, 5532.391436941938, 5590.105583106699, 5603.299587551551, 5628.043232650449, 5634.577406694504, 5626.578006649497, 5659.0, 5670.3479099903525, 5686.800961966332, 5718.056520674084, 5009.674371165881, 5081.542661160494, 5114.488551144893, 5137.820176293831, 5184.611154163434, 5087.79648814047, 5155.871103222412, 5164.147915593922, 5164.586833053347, 5166.767495462695, 5073.137566613666, 5126.436092029874, 5185.299984502081, 5224.817131400402, 5227.424983251839, 5293.708846013467, 5294.655847369909, 5299.417064122943, 5300.098983172831, 5331.2003199519995, 4986.67586606873, 4992.151334273182, 5022.648414610979, 5178.97410512945, 5180.248863914711, 5477.068898287261, 5502.944970550283, 5509.752061157257, 5522.5581953443625, 5524.171374293848, 6030.306514750816, 6035.094735789623, 6036.879262414727, 6044.758209671608, 6045.576809623328, 5980.97009514954, 6059.242594675666, 6069.332373438918, 6127.234095738033, 6140.84647883802, 5723.656580605177, 5741.712914354349, 5749.8275051748515, 5750.45370689788, 5770.076787713946, 6288.213973253343, 6293.402126797947, 6294.339094395114, 6306.779262725782, 6307.432331090132, 5866.384029676822, 5968.432998865101, 6052.152698622196, 6059.93951058563, 6124.081387791778, 6164.198654174928, 6175.166352542349, 6175.752969881154, 6181.165542651775, 6183.072539119078, 6107.328193898667, 6153.077038444179, 6162.661053642093, 6163.630182189083, 6166.321704612489, 5852.590318677651, 5936.050231962951, 5953.255843019622, 5995.0, 6037.245344331958, 4590.747508887043, 4626.282926146453, 4626.306054091846, 4633.652476064309, 4660.603848672901, 4127.442795222667, 4142.46148000758, 4147.81334839933, 4160.521540022875, 4189.769562674082, 5674.460926212003, 5745.540356771448, 5766.654000759968, 5792.275965504312, 5837.124431335334, 6105.755769769248, 6113.908291375633, 6118.847028824226, 6120.877582448326, 6125.173101631222, 5790.044642633919, 5799.043157878903, 5821.2141360916585, 5827.4172582741385, 5827.7668893244645, 5146.356705411824, 5184.455632158644, 5200.6619569727845, 5204.089284375219, 5277.445868183862, 6228.56400051992, 6229.345918678564, 6229.937700623026, 6233.283172435137, 6236.844078898064, 6018.21763170785, 6069.635821850703, 6083.330833608211, 6085.2393450818645, 6085.269767627736, 6122.764110201883, 6247.5001999840815, 6290.1508296381235, 6353.237611486651, 6410.775622853178, 6100.786472473442, 6101.450869421773, 6108.75564977396, 6112.816615501542, 6113.205283313318, 6220.688965551613, 6234.407731265523, 6237.656928868956, 6239.064140378798, 6241.656708881055, 5774.971125144348, 5881.882362352814, 5883.94116058834, 5924.9703751480965, 6030.819075427744, 5940.881182376242, 5950.4644581986095, 5958.374370691188, 5967.075103358986, 5968.373320801254, 5865.120231965239, 5865.912011319749, 5913.02435098204, 5926.525877929842, 5932.79235226765, 5828.6211396259105, 5894.970525147518, 5938.703064846827, 5940.376260492586, 6029.125776762433, 5832.737526811219, 5839.0, 5841.532677385714, 5844.298684157929, 5847.327557330961], "value": 5602.99335047361, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [601.1343665122224, 644.5005121031202, 665.657186548927, 669.1368135105714, 689.8482333886543, 35262.2947541049, 38651.0, 38676.613233867654, 38693.80653096734, 50256.49743502564, 49033.0, 49333.26000110001, 49416.752916235455, 49426.25860612085, 49431.75284123574, 9242.26061915047, 9383.43699378036, 9383.906160938406, 9384.39001464906, 9384.953075234615, 8556.828863422728, 9187.173154416101, 9288.117628825285, 10888.346699198073, 11137.665870023913, 11273.605423810168, 11309.886901131007, 11348.319100853934, 11370.431478426088, 11387.54449822006, 11195.384253866034, 11200.047995920357, 11243.269187502869, 11259.774804503946, 11260.605878794202, 8430.409871309012, 10809.189310801643, 11185.776284474347, 11191.832122518168, 11211.271267367676, 45247.54752452466, 45377.773111134564, 45510.08979820418, 45955.0, 45975.31037034447, 3834.9041273968096, 3887.4751908492276, 3935.1736135411597, 3970.8014599269923, 4272.764997925113, 34236.486452703226, 34964.12589685253, 34980.650193498, 36927.63072369269, 37363.813180934194, 4784.712917224942, 4818.445878723956, 4848.0, 4873.512648735134, 4937.358143441365, 391055.0, 393790.0, 394046.0, 394226.0, 394396.0, 68859.0, 69223.30776692269, 69260.6536967312, 69328.49364791272, 69381.65309173422, 72584.63707681428, 72744.27255727375, 72782.63608681923, 72786.27213727901, 72843.27156728365, 17286.0, 17985.640287194183, 18009.459716208534, 18021.7296740549, 18030.1886415109, 12178.086643501772, 12297.077719171099, 12297.754044919231, 12314.876851231373, 12318.630441086782, 18771.15529801162, 19237.13432895523, 20099.889511601363, 20916.686249706265, 21035.78964210369, 7380.335769780745, 7482.775516734507, 7586.810329741801, 7872.960635196788, 7876.488028278143, 1002.7944271424341, 1011.2769369900557, 1019.5768755966191, 1039.3192458939407, 1150.3155622404656, 32956.50565241523, 35058.474122888176, 35244.0, 35424.0, 35442.64557354394, 1092.6503518874042, 1173.1084375874398, 1198.2017006090714, 1248.825164476974, 1301.2322729589587, 17423.564410889576, 18528.258869645062, 18624.72062919057, 18629.975351355806, 18647.533811654546, 661081.0, 666637.0, 666943.0, 667529.0, 669955.0, 66680.0, 66964.0, 67050.66474667694, 67205.0, 67255.66372168108, 1335.9732805343838, 1395.8394784599698, 1426.5363756779088, 1432.7062952095005, 1451.3904160252719, 33700.831495842365, 35696.464553032245, 36391.0, 36569.26861462703, 36580.451293231155, 55621.0, 61499.07751383643, 61659.691701541204, 61714.0, 68168.0, 540997.0, 541964.0, 542783.0, 545168.0, 545978.0, 30254.0, 31168.688313117487, 31427.685723142476, 32745.0, 33050.83474582612, 54834.72582637061, 54954.725226373615, 55044.724776375864, 55495.0, 56020.71989640026, 43855.561444385145, 43895.75989325043, 45490.31764523601, 46371.768141159075, 47575.0, 44261.0, 44992.55007449884, 46084.30873536832, 46697.766511167225, 48023.27965080596, 46167.0, 46828.721989461774, 46943.06113877635, 47091.529084710084, 47644.523554765394, 37693.4345984805, 39364.21271574495, 39707.130094529, 42384.78807605942, 43262.78368608137, 30072.54891176678, 30394.84802575973, 30475.39049218959, 30730.385392292475, 30917.536236956013], "value": 22442.603063051985, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.87140115163123, 415.20695471649157, 417.8955261184704, 422.70410712501246, 430.3458742711079, 15424.0, 19923.302684406048, 19981.300654477098, 20111.0, 21840.453988650293, 20714.999999999964, 20791.0, 20916.372508824723, 21056.0, 21316.680249796267, 5528.9723551382285, 5916.408359164071, 5936.436038576328, 5958.940410595904, 5966.761329546813, 4338.84814031509, 4405.691601587891, 5861.677607731573, 5865.413458654143, 5925.377835327293, 6670.866582668344, 6784.0, 6794.320567943216, 6798.116244888158, 6804.149481314836, 6582.539222254444, 6745.021971814084, 6778.932210677879, 6786.151731033621, 6799.11611490508, 5849.64902105875, 6047.788327408563, 6054.303755068179, 6055.12200730896, 6058.636481811105, 24513.0, 24576.139835105863, 24864.627030594555, 24884.253472395856, 25218.0, 2679.3837417393916, 3112.828794416306, 3281.912727344958, 3288.5560449339273, 3368.562086928708, 15483.458078967182, 15551.987874315771, 15846.286917088759, 16298.185090745415, 16506.917465412596, 1997.0713618167476, 2005.4485016620547, 2045.539753555453, 2049.80526849949, 2069.855110142291, 66376.0, 80446.0, 81214.59392703058, 83196.0, 88763.0, 11050.778984420427, 11497.827532587018, 11650.825237621442, 11738.94130529342, 11780.587679431179, 16580.419685310957, 16589.66820663604, 16590.75113873293, 16624.168791560372, 16649.583760405843, 7910.485818421899, 8419.494830310201, 8531.74404767858, 8545.401821872534, 8559.786005349915, 7911.327537159291, 8062.919370806334, 8066.11272759996, 8085.591074812528, 8270.2556769891, 5002.424721157114, 5005.724685142352, 5020.096382651085, 5074.441811400743, 5092.694438333712, 2011.7284166637317, 2053.6816793397047, 2068.8965551722354, 2074.9273775417787, 2111.7993790590176, 999.6551189839494, 1031.3450958641167, 1031.8142734307746, 1043.7129789308, 1054.920880933933, 19363.419097426817, 20470.283540076027, 20924.790752092587, 21025.89487052555, 21194.0, 1040.2614143957724, 1041.6874937518714, 1050.6007717067466, 1061.3366645846345, 1073.5759375046812, 10829.29609575375, 11680.240784348991, 11787.469563869647, 11844.348560829238, 11912.523499060115, 569221.0, 571719.0, 584556.0, 599955.0, 603841.0, 75908.62045689847, 77437.61281193559, 92576.53711731533, 96139.0, 98579.0, 972.1105188752354, 1003.94478303694, 1007.7581380468635, 1014.2646581228697, 1021.3310281765456, 17863.28546858111, 18132.18405171797, 18504.444866654023, 18565.25738970426, 18866.52833679169, 37363.0, 38800.41799372955, 44688.55311446974, 45168.09663806771, 45171.774141129084, 59407.702961484916, 62070.0, 63057.369426306985, 63798.68100659467, 64506.03240951483, 8279.254867061993, 8519.829603408021, 8681.2186903179, 8900.154485323885, 9158.129977652112, 11969.820452693042, 13208.735825283633, 13369.26469044173, 13522.93238533801, 13553.661158471117, 11004.724881878017, 11292.266002709797, 11430.71423214393, 11662.88337116618, 11749.118816088656, 11063.0, 11660.650180494598, 11679.416029198675, 11711.765764684487, 11949.462274197507, 11134.832977505506, 11167.664970050912, 11586.76826463483, 11695.88304116948, 11922.463489143183, 8381.664733410595, 8597.82804343897, 8670.696525621574, 8722.869156962777, 8999.505027223566, 11370.147238957277, 11460.369679667698, 11647.0, 11691.356975366436, 11732.178747487595], "value": 9784.561628026624, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1549.5273941447858, 1570.560243131923, 1752.526817759205, 1831.1851226204344, 1873.4566975577075, 8510.148985101503, 9060.546972651375, 9288.21050210732, 10047.849282260762, 10052.396856188625, 9166.770830729236, 9793.412395256271, 9800.166985806214, 9802.411855288688, 9811.313208075437, 2500.1374525788574, 2547.796176305891, 2548.171844150649, 2549.2989427907287, 2549.8342607730533, 2230.955380892381, 2388.2118900762794, 2439.9878000609974, 2480.7891329237036, 2535.8858851351642, 2525.2929179829675, 2553.834000789943, 2559.5264875997905, 2559.5520783862844, 2562.461883004571, 2542.478791847676, 2579.406736450627, 2580.2259322203263, 2584.134315004476, 2585.58630619101, 2487.166799122296, 2514.371407148213, 2579.1359894435386, 2581.728918463572, 2583.2637698256094, 9841.114299712988, 9876.45679487628, 9881.16010139139, 9882.110610045058, 9898.158656514204, 5470.343558772933, 5550.666959982414, 5594.804181853657, 5609.411011843738, 5613.382527921966, 3698.260347930425, 3712.554493460775, 3805.5243094613174, 3814.389697648363, 3823.0824602095577, 1348.2112963916022, 1351.6823546466574, 1354.8374195096553, 1371.5885234429632, 1377.6762460821699, 12771.61685149447, 12882.742345153045, 13090.934545327404, 13147.934260328637, 13157.276349800757, 4716.575508204278, 4770.546798054179, 4854.247591623233, 4898.608111351084, 5100.38795344561, 11017.614383496539, 11018.669439916815, 11107.83338249927, 11110.388928609003, 11153.0, 5480.424555421623, 5495.972520137454, 5502.449755024466, 5508.256385388001, 5514.228008078872, 4998.425181104147, 5062.974685126551, 5072.54347108762, 5161.612879034087, 5205.531502164823, 4310.245707001325, 4346.391505189276, 4362.105768317488, 4406.49325327585, 4409.382686423904, 1315.6184706435026, 1367.1250399744172, 1397.4061024064936, 1411.6612013116778, 1420.7868819676924, 1036.6734478639205, 1059.21618002679, 1059.7668512927144, 1066.861308029951, 1082.5507414422925, 6419.486441084702, 6463.353664633499, 6568.441682456949, 6606.2072551293195, 6731.865362692719, 1047.8690163729534, 1106.9944650276698, 1132.5356603792407, 1135.6876858863714, 1138.59579849154, 4434.667399944952, 4468.754218518013, 4484.596386325246, 4542.636589072867, 4572.679912406166, 11653.941730291293, 11777.705557361134, 12045.096617753528, 12057.0957178212, 12077.0, 11681.532738690357, 11875.228110172984, 12349.32078735678, 12361.690957726129, 12372.62882113538, 9035.186833185046, 9234.215091717146, 9234.538273086318, 9240.630374784932, 9258.814823703487, 6337.9366206337345, 6412.166418365583, 6491.7990171818365, 6519.7392104315295, 6555.573887697476, 11249.268797528135, 11343.886561134283, 11420.48607812667, 11503.424828758361, 11507.42462876836, 11296.265742726697, 11379.203455758017, 11544.88455115438, 11600.88399115998, 11673.066154707773, 8302.584870756558, 8460.561704510372, 8464.407491475538, 8571.22858942698, 8795.956020220114, 9731.270154738564, 9872.259580531632, 9872.457014863965, 9890.109890109925, 9906.900930990598, 8318.292945099492, 8395.118512556093, 8473.872891906749, 8508.0, 8841.513716745641, 8291.751247462586, 8405.495670259805, 8436.957815210884, 8469.407141500282, 8580.14198580137, 7684.193159718149, 7767.572783496962, 7792.220777922163, 7802.29779319864, 7842.764717058498, 5648.0, 5661.547076233809, 5677.7161141941915, 5808.30300363959, 5925.514890581066, 5633.211350410864, 5692.886142277214, 5808.767649293981, 5821.679807610452, 5934.732937017759], "value": 5114.531153376677, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.3207744897451, 361.7160528984747, 362.1868904309824, 365.9908502287444, 367.8124156680094, 5463.825277565323, 5650.378458369576, 5661.547076233902, 5667.829965101048, 5690.288713910766, 5564.276644036279, 5574.303212098488, 5575.219469274305, 5579.972100139504, 5593.0, 3866.7873266970305, 4108.383742438643, 4409.669274804402, 4420.58004489573, 4432.578905004019, 4349.26062569364, 4354.1074079813725, 4355.586219309176, 4367.45406824147, 4372.3878656988045, 4329.675274354436, 4330.588594083557, 4355.738655680653, 4366.56334366564, 4370.2133615949115, 4121.835126594932, 4288.378185163165, 4371.934420983704, 4373.0, 4388.341748737683, 3840.961590384088, 3854.2869569129803, 3886.727929044968, 3889.844406223776, 3900.726949113563, 5405.324334458192, 5421.349438067417, 5451.890962180734, 5499.532539734128, 5501.614886957915, 4796.712197268175, 4989.176785830334, 4997.350344455234, 5023.070731914608, 5043.218301163325, 5815.964412455649, 5829.679367634779, 5829.679367634779, 5838.883222335572, 5844.766209351621, 4266.360045993095, 4276.401303817468, 4277.8288868445225, 4285.785710714452, 4293.8711838644895, 5932.762689492415, 5945.3650246182215, 6039.218430562994, 6070.362611925772, 6176.8764624707255, 4263.637590804754, 4268.295731204378, 4269.701120921568, 4274.508431530352, 4291.249031419489, 4345.565443455693, 4351.456067991501, 4351.4987329371315, 4352.847650332224, 4362.9781851091175, 4869.853904382874, 4993.0, 4993.650444468925, 4996.5003499650475, 5005.649604527721, 5845.357010728816, 5845.766169353178, 5849.268841394825, 5864.765409383577, 5874.383189765013, 1499.3552772307794, 1508.630385555546, 1519.7948276982686, 1523.725729368702, 1530.1201808959893, 969.5055521683992, 973.7419583810296, 979.4123525884412, 984.1536278800226, 990.4849478271259, 5028.5977121830165, 5056.570191533688, 5068.378119001884, 5076.263941728429, 5099.64302498829, 5253.369595648546, 5300.416954135042, 5311.389190243094, 5322.121849894801, 5389.892202155935, 4725.267583524491, 5051.292819005343, 5089.694618322913, 5118.87202819925, 5132.255822905658, 2850.4726625574335, 2859.6568411790718, 2865.6131422257727, 2865.796365526484, 2884.4663737208684, 5452.645578037414, 5494.917576236359, 5504.53211477029, 5508.493635395886, 5528.55771538276, 5184.8963020739375, 5294.470552944675, 5366.356037275474, 5790.565707571949, 5970.552208584373, 6147.784827531015, 6178.9186892293665, 6183.258009038854, 6184.639379336539, 6218.253809542884, 5835.44563266489, 6008.7296071676, 6028.819135425944, 6039.728212230548, 6056.90914636272, 5891.438768726377, 5923.844850254159, 5970.31341395745, 5972.432618901197, 5990.430909063632, 6136.864680034208, 6150.969245153746, 6215.81352559424, 6216.316205217332, 6237.1891654084675, 4176.737986531839, 4232.174725928415, 4268.231718290737, 4315.568443155659, 4339.479262488521, 5959.314678811992, 6007.0, 6014.6391216527145, 6023.5181185505935, 6032.125341825498, 5914.911276330945, 5917.0, 5917.526597872074, 5939.643621382731, 5948.434898684618, 5628.212050312877, 5885.676287804212, 5904.3210030845285, 5949.6430214187285, 5952.285725712942, 5806.912896306474, 5836.074095548374, 5838.386969368155, 5847.239858818326, 5900.675462849413, 5658.264425624642, 5905.616134951214, 5939.2575928009, 6001.519878409804, 6008.369121242206, 5731.971340143273, 5774.538036956949, 5784.392638773036, 5811.418858114154, 5815.94184058154], "value": 4397.79864383534, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [846.8602680557708, 860.7675927499575, 890.6704519327849, 893.2809088683609, 923.2844545477259, 16378.181090945449, 17033.148342582852, 17084.06037667931, 17101.57246068849, 17171.484855454342, 14596.635084122901, 14634.926825365885, 14700.926495367536, 14773.556793296191, 14778.556643300692, 724.905762250908, 855.84166929118, 884.1909652667833, 889.6485888074207, 889.7864512517014, 866.9826603467927, 872.3544577013029, 872.8865247517814, 876.2114097312406, 878.1350369885657, 951.4529145741227, 952.8189643967659, 953.3517208298355, 953.5137080089136, 953.9236861051133, 765.9731909383173, 898.8202359528087, 906.3700727994046, 914.7027216154777, 946.0775743649955, 669.2003056347667, 670.061913321354, 671.8320419895026, 672.0691841799144, 672.0960308385263, 14407.567772966828, 14429.0, 14445.422183112663, 14465.13209207451, 14477.565673029825, 14640.780388294184, 15392.230388480531, 15441.691166176613, 15530.534083977385, 15728.606784830356, 14156.292185390792, 14343.641408964755, 14496.565103046925, 14502.637434064127, 14678.559643210721, 4705.505921878222, 4737.336772851803, 4791.928121078186, 4837.419509658828, 4873.220284754422, 81747.0, 82916.0, 83134.0, 83262.0, 83327.5833620834, 11425.60010399632, 11505.942470287595, 11594.94202528982, 11848.526058957717, 11926.344051077274, 22861.199858005224, 23124.0, 23149.537009259722, 23170.420739481648, 23179.072837086664, 8515.0, 8615.483071015633, 8640.963084429783, 8773.254273386707, 8935.955320223487, 4834.564889159992, 4862.5380588844, 4882.096812089775, 4986.376702912136, 5144.845654630292, 4149.709520333608, 4267.16790225903, 4267.658587313008, 4275.957240427556, 4277.251480990814, 628.5505863307757, 706.0221593093603, 706.6608028146519, 707.359839345383, 708.5429897715944, 661.2759028863387, 724.7318492157935, 728.9489735718555, 814.6741303478656, 837.5853952293531, 10504.789904202025, 10809.351438913533, 11173.944130279297, 11269.943650281695, 11326.546938122548, 658.4166270030171, 767.9846403072019, 771.7221800151941, 791.1020991174943, 794.5153456391597, 5111.156659151198, 5461.918071228934, 5469.781208751685, 5501.642393244426, 5547.278853749068, 133629.0, 134158.32920835528, 134278.0, 134395.32802335927, 138086.30956845352, 114347.0, 115734.0, 117191.0, 121793.0, 124922.37538812248, 101352.49323753334, 103803.48098259611, 103857.0, 105989.0, 106687.9331206678, 9872.555734991798, 10046.296759226783, 10055.597776089167, 10059.0, 10111.848322275318, 56946.7152664234, 57794.0, 58094.0, 58241.0, 59094.40905590889, 69628.6518567404, 71279.0, 71734.28265717276, 80675.59662201651, 86891.56554217188, 14358.202408485591, 14741.55775326742, 14873.553793386216, 14993.100413975197, 15329.846701532842, 16596.50210493687, 16742.1628918556, 17013.319467221667, 17149.399771007957, 17504.91247543754, 14693.55919322422, 16313.347466101222, 16534.83465165381, 16586.58533536671, 16750.832491674926, 15165.317560709575, 15557.611059723598, 15639.374425023327, 15917.124558149413, 16210.189490525663, 12944.741105178033, 13039.152455090389, 13057.542985995444, 13121.475140994635, 13134.0, 12545.560905368267, 13070.215787052808, 13182.868171318163, 13414.195148291134, 13456.5290214842, 12977.610671679864, 12992.155509891827, 13025.413856376676, 13208.471661133448, 13342.466301347837], "value": 8341.819823172891, "unit": "score"}], "name": "dromaeo_css", "value": 7832.435489945047, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 113.0, 120.0, 108.0, 109.0, 111.0, 109.0, 109.0, 109.0, 111.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 121.0, 138.0, 179.0, 117.0, 149.0, 143.0, 119.0, 176.0, 115.0], "value": 139.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 160.0, 162.0, 160.0, 160.0, 161.0, 159.0, 160.0], "value": 160.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 75.0, 171.0, 75.0, 75.0, 75.0, 76.0, 167.0, 75.0], "value": 94.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 149.0, 84.0, 82.0, 84.0, 148.0, 83.0, 83.0, 143.0], "value": 102.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 116.0, 120.0, 116.0, 116.0, 117.0, 118.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 147.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 51.0, 50.0, 51.0, 51.0, 52.0, 51.0, 51.0, 50.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 62.0, 61.0, 60.0, 60.0, 59.0, 59.0, 60.0, 59.0, 61.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 71.0, 72.0, 80.0, 73.0, 72.0, 72.0, 72.0, 73.0], "value": 72.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 114.0, 116.0, 125.0, 133.0, 113.0, 124.0, 115.0, 185.0, 117.0], "value": 126.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 154.0, 151.0, 155.0, 153.0, 150.0, 156.0, 154.0, 156.0, 151.0], "value": 153.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 62.0, 65.0, 61.0, 61.0, 62.0, 62.0, 61.0, 62.0, 64.0], "value": 62.5, "unit": "score"}], "name": "kraken", "value": 1491.3000000000004, "alertThreshold": 2.0}]} 18:02:27 INFO - Return code: 0 18:02:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:02:27 INFO - # TBPL SUCCESS # 18:02:27 INFO - Running post-action listener: _resource_record_post_action 18:02:27 INFO - [mozharness: 2016-09-14 01:02:27.802404Z] Finished run-tests step (success) 18:02:27 INFO - Running post-run listener: _resource_record_post_run 18:02:27 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 4096; Write bytes: 327847936; Read time: 12; Write time: 285832 18:02:27 INFO - TinderboxPrint: CPU usage
13.0% 18:02:27 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 18:02:27 INFO - TinderboxPrint: I/O write bytes / time
327,847,936 / 285,832 18:02:27 INFO - TinderboxPrint: CPU idle
8,272.2 (87.0%) 18:02:27 INFO - TinderboxPrint: CPU user
1,185.9 (12.5%) 18:02:27 INFO - TinderboxPrint: Swap in / out
0 / 0 18:02:27 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 77443072; Read time: 0; Write time: 58468 18:02:27 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 247971840; Read time: 0; Write time: 227160 18:02:28 INFO - Running post-run listener: _upload_blobber_files 18:02:28 INFO - Blob upload gear active. 18:02:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:02:28 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 18:02:28 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'] 18:02:28 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 18:02:28 INFO - (blobuploader) - INFO - Open directory for files ... 18:02:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:02:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:02:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:02:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:02:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:02:29 INFO - (blobuploader) - INFO - Done attempting. 18:02:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:02:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:02:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:02:33 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:02:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:02:33 INFO - (blobuploader) - INFO - Done attempting. 18:02:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:02:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:02:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:02:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:02:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:02:34 INFO - (blobuploader) - INFO - Done attempting. 18:02:34 INFO - (blobuploader) - INFO - Iteration through files over. 18:02:34 INFO - Return code: 0 18:02:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:02:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:02:34 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9469baec4823d004a8bd96c1c1284056539da57d0e64c4f36d78361210cbaf480a5e123533412604a1960666c20bfc3dc1e756c8787ce1ad8e5520a766346914", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b9ce162de79275240ad427698611705957439c0390d78f90bca6974be4db58ba1b47ac5c9fd92cd7b1866a313c1b1a1a407c4f7e8b3a67f015de1cfc825e003", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e6b51ae9b93c82e5e855fe9257fdf81404cb085d6387b602d9421c27521bc932eeed2c96a59967b47f4f632d770a1e9eb29a46fd7f5335fd997d90c8413ec03f"} 18:02:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:02:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:02:34 INFO - Contents: 18:02:34 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9469baec4823d004a8bd96c1c1284056539da57d0e64c4f36d78361210cbaf480a5e123533412604a1960666c20bfc3dc1e756c8787ce1ad8e5520a766346914", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b9ce162de79275240ad427698611705957439c0390d78f90bca6974be4db58ba1b47ac5c9fd92cd7b1866a313c1b1a1a407c4f7e8b3a67f015de1cfc825e003", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e6b51ae9b93c82e5e855fe9257fdf81404cb085d6387b602d9421c27521bc932eeed2c96a59967b47f4f632d770a1e9eb29a46fd7f5335fd997d90c8413ec03f"} 18:02:34 INFO - Running post-run listener: copy_logs_to_upload_dir 18:02:34 INFO - Copying logs to upload dir... 18:02:34 INFO - mkdir: /builds/slave/test/build/upload/logs 18:02:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1678.396219 ========= master_lag: 33.69 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 32 secs) (at 2016-09-13 18:03:06.829298) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-13 18:03:06.839356) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9469baec4823d004a8bd96c1c1284056539da57d0e64c4f36d78361210cbaf480a5e123533412604a1960666c20bfc3dc1e756c8787ce1ad8e5520a766346914", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b9ce162de79275240ad427698611705957439c0390d78f90bca6974be4db58ba1b47ac5c9fd92cd7b1866a313c1b1a1a407c4f7e8b3a67f015de1cfc825e003", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e6b51ae9b93c82e5e855fe9257fdf81404cb085d6387b602d9421c27521bc932eeed2c96a59967b47f4f632d770a1e9eb29a46fd7f5335fd997d90c8413ec03f"} build_url:https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011387 build_url: 'https://queue.taskcluster.net/v1/task/MuJZDmgLRaGAYmyJVm8pzA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9469baec4823d004a8bd96c1c1284056539da57d0e64c4f36d78361210cbaf480a5e123533412604a1960666c20bfc3dc1e756c8787ce1ad8e5520a766346914", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b9ce162de79275240ad427698611705957439c0390d78f90bca6974be4db58ba1b47ac5c9fd92cd7b1866a313c1b1a1a407c4f7e8b3a67f015de1cfc825e003", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e6b51ae9b93c82e5e855fe9257fdf81404cb085d6387b602d9421c27521bc932eeed2c96a59967b47f4f632d770a1e9eb29a46fd7f5335fd997d90c8413ec03f"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-13 18:03:06.878199) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 18:03:06.878540) ========= 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=516f8d7ca085b4d087ab0a71000001a4-1473813274.111660-107641577 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004535 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-13 18:03:06.932036) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-13 18:03:06.932732) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-13 18:03:06.933366) ========= ========= Total master_lag: 33.83 =========