builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-032 starttime: 1472588244.6 results: success (0) buildid: 20160830092722 builduid: 5f528c0965ae4a258b65786f9f492890 revision: f6d69919d05a774439b18fd0818fa927504289b9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 13:17:24.604662) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 13:17:24.605194) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 13:17:24.605485) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 13:17:24.639411) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 13:17:24.639667) ========= 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=745a4373a2a16df560f216e000000264-1472588091.610660-570207028 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-30 13:17:24-- 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% 115M=0s 2016-08-30 13:17:24 (115 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.091702 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 13:17:24.747838) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 13:17:24.748151) ========= 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=745a4373a2a16df560f216e000000264-1472588091.610660-570207028 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019984 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 13:17:24.798052) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 13:17:24.798353) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f6d69919d05a774439b18fd0818fa927504289b9 --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 f6d69919d05a774439b18fd0818fa927504289b9 --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=745a4373a2a16df560f216e000000264-1472588091.610660-570207028 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-30 13:17:25,065 truncating revision to first 12 chars 2016-08-30 13:17:25,065 Setting DEBUG logging. 2016-08-30 13:17:25,065 attempt 1/10 2016-08-30 13:17:25,065 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f6d69919d05a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-30 13:17:25,350 unpacking tar archive at: mozilla-aurora-f6d69919d05a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.549729 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 13:17:25.362920) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 13:17:25.363208) ========= script_repo_revision: f6d69919d05a774439b18fd0818fa927504289b9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 13:17:25.363579) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 13:17:25.363870) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 13:17:25.374334) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 1 secs) (at 2016-08-30 13:17:25.374600) ========= /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=745a4373a2a16df560f216e000000264-1472588091.610660-570207028 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:17:25 INFO - MultiFileLogger online at 20160830 13:17:25 in /builds/slave/test 13:17:25 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 13:17:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:17:25 INFO - {'append_to_log': False, 13:17:25 INFO - 'base_work_dir': '/builds/slave/test', 13:17:25 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:17:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:17:25 INFO - 'branch': 'Mozilla-Aurora', 13:17:25 INFO - 'buildbot_json_path': 'buildprops.json', 13:17:25 INFO - 'config_files': ('talos/linux_config.py',), 13:17:25 INFO - 'default_actions': ('clobber', 13:17:25 INFO - 'read-buildbot-config', 13:17:25 INFO - 'download-and-extract', 13:17:25 INFO - 'populate-webroot', 13:17:25 INFO - 'create-virtualenv', 13:17:25 INFO - 'install', 13:17:25 INFO - 'run-tests'), 13:17:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:17:25 INFO - 'download_minidump_stackwalk': True, 13:17:25 INFO - 'download_symbols': 'ondemand', 13:17:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:17:25 INFO - 'tooltool.py': '/tools/tooltool.py', 13:17:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:17:25 INFO - '/tools/misc-python/virtualenv.py')}, 13:17:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:17:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:17:25 INFO - 'installer_path': 'installer.exe', 13:17:25 INFO - 'log_level': 'info', 13:17:25 INFO - 'log_name': 'talos', 13:17:25 INFO - 'log_to_console': True, 13:17:25 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:17:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:17:25 INFO - 'opt_config_files': (), 13:17:25 INFO - 'pip_index': False, 13:17:25 INFO - 'sps_profile': False, 13:17:25 INFO - 'sps_profile_interval': 0, 13:17:25 INFO - 'suite': 'dromaeojs', 13:17:25 INFO - 'system_bits': '32', 13:17:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:17:25 INFO - 'title': 'talos-linux64-ix-032', 13:17:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:17:25 INFO - 'use_talos_json': True, 13:17:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:17:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:17:25 INFO - 'work_dir': 'build'} 13:17:25 INFO - [mozharness: 2016-08-30 20:17:25.738016Z] Running clobber step. 13:17:25 INFO - Running pre-action listener: _resource_record_pre_action 13:17:25 INFO - Running main action method: clobber 13:17:25 INFO - rmtree: /builds/slave/test/build 13:17:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:17:26 INFO - Running post-action listener: _resource_record_post_action 13:17:26 INFO - [mozharness: 2016-08-30 20:17:26.195660Z] Finished clobber step (success) 13:17:26 INFO - [mozharness: 2016-08-30 20:17:26.195760Z] Running read-buildbot-config step. 13:17:26 INFO - Running pre-action listener: _resource_record_pre_action 13:17:26 INFO - Running main action method: read_buildbot_config 13:17:26 INFO - Using buildbot properties: 13:17:26 INFO - { 13:17:26 INFO - "project": "", 13:17:26 INFO - "product": "firefox", 13:17:26 INFO - "script_repo_revision": "production", 13:17:26 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 13:17:26 INFO - "repository": "", 13:17:26 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 13:17:26 INFO - "buildid": "20160830092722", 13:17:26 INFO - "pgo_build": "True", 13:17:26 INFO - "basedir": "/builds/slave/test", 13:17:26 INFO - "buildnumber": 432, 13:17:26 INFO - "slavename": "talos-linux64-ix-032", 13:17:26 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 13:17:26 INFO - "platform": "ubuntu64_hw", 13:17:26 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 13:17:26 INFO - "branch": "mozilla-aurora", 13:17:26 INFO - "repo_path": "releases/mozilla-aurora", 13:17:26 INFO - "revision": "f6d69919d05a774439b18fd0818fa927504289b9", 13:17:26 INFO - "stage_platform": "linux64-pgo", 13:17:26 INFO - "builduid": "5f528c0965ae4a258b65786f9f492890", 13:17:26 INFO - "slavebuilddir": "test" 13:17:26 INFO - } 13:17:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 13:17:26 INFO - Running post-action listener: _resource_record_post_action 13:17:26 INFO - [mozharness: 2016-08-30 20:17:26.198183Z] Finished read-buildbot-config step (success) 13:17:26 INFO - [mozharness: 2016-08-30 20:17:26.198271Z] Running download-and-extract step. 13:17:26 INFO - Running pre-action listener: _resource_record_pre_action 13:17:26 INFO - Running main action method: download_and_extract 13:17:26 INFO - mkdir: /builds/slave/test/build/tests 13:17:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:17:26 INFO - https://queue.taskcluster.net/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 13:17:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:17:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:17:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:17:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:17:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:17:26 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:17:26 INFO - retry: Failed, sleeping 30 seconds before retrying 13:17:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:17:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:17:56 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:17:56 INFO - retry: Failed, sleeping 60 seconds before retrying 13:18:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:18:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:18:56 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:18:56 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/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! 13:18:56 INFO - Caught exception: HTTP Error 404: Not Found 13:18:56 INFO - Caught exception: HTTP Error 404: Not Found 13:18:56 INFO - Caught exception: HTTP Error 404: Not Found 13:18:56 INFO - trying https://queue.taskcluster.net/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:18:56 INFO - Downloading https://queue.taskcluster.net/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:18:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:18:56 INFO - Downloaded 1361 bytes. 13:18:56 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:18:56 INFO - Using the following test package requirements: 13:18:56 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 13:18:56 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:18:56 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 13:18:56 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:18:56 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 13:18:56 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:18:56 INFO - u'jsshell-linux-x86_64.zip'], 13:18:56 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:18:56 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 13:18:56 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 13:18:56 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:18:56 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 13:18:56 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:18:56 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 13:18:56 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:18:56 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 13:18:56 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:18:56 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 13:18:56 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:18:56 INFO - https://queue.taskcluster.net/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:18:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:18:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:18:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:18:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:18:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:18:56 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:18:56 INFO - retry: Failed, sleeping 30 seconds before retrying 13:19:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:19:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:19:26 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:19:26 INFO - retry: Failed, sleeping 60 seconds before retrying 13:20:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:20:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:20:26 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:20:26 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/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! 13:20:26 INFO - Caught exception: HTTP Error 404: Not Found 13:20:26 INFO - Caught exception: HTTP Error 404: Not Found 13:20:26 INFO - Caught exception: HTTP Error 404: Not Found 13:20:26 INFO - trying https://queue.taskcluster.net/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:20:26 INFO - Downloading https://queue.taskcluster.net/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:20:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:20:27 INFO - Downloaded 21768900 bytes. 13:20:27 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'] 13:20:27 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 13:20:28 INFO - Return code: 0 13:20:28 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 13:20: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')]} 13:20:28 INFO - https://queue.taskcluster.net/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:20:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:20:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:20:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:20:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:20:28 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:20:28 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:20:28 INFO - retry: Failed, sleeping 30 seconds before retrying 13:20:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:20:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:20:59 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:20:59 INFO - retry: Failed, sleeping 60 seconds before retrying 13:21:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:21:59 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:21:59 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:21:59 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/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! 13:21:59 INFO - Caught exception: HTTP Error 404: Not Found 13:21:59 INFO - Caught exception: HTTP Error 404: Not Found 13:21:59 INFO - Caught exception: HTTP Error 404: Not Found 13:21:59 INFO - trying https://queue.taskcluster.net/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:21:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:21:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:21:59 INFO - Downloaded 21768900 bytes. 13:21:59 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'] 13:21:59 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 13:22:01 INFO - Return code: 0 13:22:01 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')]} 13:22:01 INFO - https://queue.taskcluster.net/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 13:22:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:22:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:22:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:22:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:22:01 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:22:01 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:22:01 INFO - retry: Failed, sleeping 30 seconds before retrying 13:22:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:22:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:22:31 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:22:31 INFO - retry: Failed, sleeping 60 seconds before retrying 13:23:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:23:31 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:23:31 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:23:31 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/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! 13:23:31 INFO - Caught exception: HTTP Error 404: Not Found 13:23:31 INFO - Caught exception: HTTP Error 404: Not Found 13:23:31 INFO - Caught exception: HTTP Error 404: Not Found 13:23:31 INFO - trying https://queue.taskcluster.net/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:23:31 INFO - Downloading https://queue.taskcluster.net/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:23:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:23:32 INFO - Downloaded 14413723 bytes. 13:23:32 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'] 13:23:32 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 13:23:32 INFO - Return code: 0 13:23:32 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')]} 13:23:32 INFO - https://queue.taskcluster.net/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 13:23:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:23:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:23:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:23:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:23:32 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:23:32 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:23:32 INFO - retry: Failed, sleeping 30 seconds before retrying 13:24:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:24:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:24:02 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:24:02 INFO - retry: Failed, sleeping 60 seconds before retrying 13:25:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:25:02 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:25:02 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:25:02 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Nte9js__SDyn3Q_hUVpIMA/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! 13:25:02 INFO - Caught exception: HTTP Error 404: Not Found 13:25:02 INFO - Caught exception: HTTP Error 404: Not Found 13:25:02 INFO - Caught exception: HTTP Error 404: Not Found 13:25:02 INFO - trying https://queue.taskcluster.net/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:25:02 INFO - Downloading https://queue.taskcluster.net/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:25:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nte9js__SDyn3Q_hUVpIMA/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 13:25:04 INFO - Downloaded 63878776 bytes. 13:25:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:25:04 INFO - mkdir: /builds/slave/test/properties 13:25:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:25:04 INFO - Writing to file /builds/slave/test/properties/build_url 13:25:04 INFO - Contents: 13:25:04 INFO - build_url:https://queue.taskcluster.net/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:25:04 INFO - Running post-action listener: _resource_record_post_action 13:25:04 INFO - Running post-action listener: set_extra_try_arguments 13:25:04 INFO - [mozharness: 2016-08-30 20:25:04.681784Z] Finished download-and-extract step (success) 13:25:04 INFO - [mozharness: 2016-08-30 20:25:04.682021Z] Running populate-webroot step. 13:25:04 INFO - Running pre-action listener: _resource_record_pre_action 13:25:04 INFO - Running main action method: populate_webroot 13:25:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:25:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:25:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:25:04 INFO - '--tppagecycles', 13:25:04 INFO - '7'], 13:25:04 INFO - 'tests': ['tsvgm']}}, 13:25:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:25:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:25:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:25:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:25:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:25:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:25:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:25:04 INFO - 'talos_options': ['--disable-e10s'], 13:25:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:25:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:25:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:25:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:25:04 INFO - 'talos_options': ['--disable-e10s'], 13:25:04 INFO - 'tests': ['damp', 'tps']}, 13:25:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:25:04 INFO - 'tests': ['damp', 'tps']}, 13:25:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:25:04 INFO - 'tests': ['dromaeo_dom']}, 13:25:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:25:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:25:04 INFO - 'tests': ['basic_compositor_video']}, 13:25:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:25:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:25:04 INFO - 'tests': ['a11yr', 13:25:04 INFO - 'ts_paint', 13:25:04 INFO - 'tpaint', 13:25:04 INFO - 'sessionrestore', 13:25:04 INFO - 'sessionrestore_no_auto_restore', 13:25:04 INFO - 'tabpaint']}, 13:25:04 INFO - 'other-e10s': {'tests': ['a11yr', 13:25:04 INFO - 'ts_paint', 13:25:04 INFO - 'tpaint', 13:25:04 INFO - 'sessionrestore', 13:25:04 INFO - 'sessionrestore_no_auto_restore', 13:25:04 INFO - 'tabpaint']}, 13:25:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:25:04 INFO - 'ts_paint', 13:25:04 INFO - 'tpaint', 13:25:04 INFO - 'sessionrestore', 13:25:04 INFO - 'sessionrestore_no_auto_restore', 13:25:04 INFO - 'tabpaint']}, 13:25:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:25:04 INFO - 'ts_paint', 13:25:04 INFO - 'tpaint', 13:25:04 INFO - 'sessionrestore', 13:25:04 INFO - 'sessionrestore_no_auto_restore', 13:25:04 INFO - 'tabpaint']}, 13:25:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:25:04 INFO - 'tests': ['a11yr', 13:25:04 INFO - 'ts_paint', 13:25:04 INFO - 'tpaint', 13:25:04 INFO - 'sessionrestore', 13:25:04 INFO - 'sessionrestore_no_auto_restore', 13:25:04 INFO - 'tabpaint']}, 13:25:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:25:04 INFO - 'tests': ['a11yr', 13:25:04 INFO - 'ts_paint', 13:25:04 INFO - 'tpaint', 13:25:04 INFO - 'sessionrestore', 13:25:04 INFO - 'sessionrestore_no_auto_restore', 13:25:04 INFO - 'tabpaint']}, 13:25:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:25:04 INFO - 'tests': ['tsvgx', 13:25:04 INFO - 'tsvgr_opacity', 13:25:04 INFO - 'tart', 13:25:04 INFO - 'tscrollx', 13:25:04 INFO - 'cart']}, 13:25:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:25:04 INFO - 'tsvgr_opacity', 13:25:04 INFO - 'tart', 13:25:04 INFO - 'tscrollx', 13:25:04 INFO - 'cart']}, 13:25:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:25:04 INFO - 'talos_options': ['--disable-e10s'], 13:25:04 INFO - 'tests': ['tp5o']}, 13:25:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:25:04 INFO - 'tests': ['tp5o']}, 13:25:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:25:04 INFO - 'talos_options': ['--disable-e10s', 13:25:04 INFO - '--xperf_path', 13:25:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:25:04 INFO - 'tests': ['tp5n']}, 13:25:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:25:04 INFO - 'talos_options': ['--xperf_path', 13:25:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:25:04 INFO - 'tests': ['tp5n']}}, 13:25:04 INFO - 'talos.zip': {'path': '', 13:25:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:25:04 INFO - Running post-action listener: _resource_record_post_action 13:25:04 INFO - [mozharness: 2016-08-30 20:25:04.700118Z] Finished populate-webroot step (success) 13:25:04 INFO - [mozharness: 2016-08-30 20:25:04.700207Z] Running create-virtualenv step. 13:25:04 INFO - Running pre-action listener: _resource_record_pre_action 13:25:04 INFO - Running main action method: create_virtualenv 13:25:04 INFO - Creating virtualenv /builds/slave/test/build/venv 13:25:04 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 13:25:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:25:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:25:04 INFO - Using real prefix '/usr' 13:25:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:25:05 INFO - Installing distribute.............................................................................................................................................................................................done. 13:25:07 INFO - Installing pip.................done. 13:25:07 INFO - Return code: 0 13:25:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:25:07 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')]} 13:25:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:25:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25:07 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')]} 13:25:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:25:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25:07 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 0x1105030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c43b0>, '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 0x13c52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c5740>, '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': '745a4373a2a16df560f216e000000264-1472588091.610660-570207028', '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 13:25:07 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 13:25:07 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 13:25:07 INFO - Using env: {'DISPLAY': ':0', 13:25:07 INFO - 'HOME': '/home/cltbld', 13:25:07 INFO - 'LANG': 'en_US.UTF-8', 13:25:07 INFO - 'LANGUAGE': 'en_US:en', 13:25:07 INFO - 'LOGNAME': 'cltbld', 13:25:07 INFO - 'MAIL': '/var/mail/cltbld', 13:25:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:25:07 INFO - 'MOZ_NO_REMOTE': '1', 13:25:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:25:07 INFO - 'NO_EM_RESTART': '1', 13:25:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:25:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:25:07 INFO - 'PWD': '/builds/slave/test', 13:25:07 INFO - 'SHELL': '/bin/bash', 13:25:07 INFO - 'SHLVL': '1', 13:25:07 INFO - 'TERM': 'linux', 13:25:07 INFO - 'TMOUT': '86400', 13:25:07 INFO - 'USER': 'cltbld', 13:25:07 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472588091.610660-570207028', 13:25:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:25:07 INFO - '_': '/tools/buildbot/bin/python'} 13:25:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:25:07 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 13:25:07 INFO - Cleaning up... 13:25:07 INFO - Return code: 0 13:25:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:25:07 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')]} 13:25:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:25:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25:07 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')]} 13:25:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:25:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25:07 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 0x1105030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c43b0>, '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 0x13c52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c5740>, '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': '745a4373a2a16df560f216e000000264-1472588091.610660-570207028', '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 13:25:07 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 13:25:07 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 13:25:07 INFO - Using env: {'DISPLAY': ':0', 13:25:07 INFO - 'HOME': '/home/cltbld', 13:25:07 INFO - 'LANG': 'en_US.UTF-8', 13:25:07 INFO - 'LANGUAGE': 'en_US:en', 13:25:07 INFO - 'LOGNAME': 'cltbld', 13:25:07 INFO - 'MAIL': '/var/mail/cltbld', 13:25:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:25:07 INFO - 'MOZ_NO_REMOTE': '1', 13:25:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:25:07 INFO - 'NO_EM_RESTART': '1', 13:25:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:25:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:25:07 INFO - 'PWD': '/builds/slave/test', 13:25:07 INFO - 'SHELL': '/bin/bash', 13:25:07 INFO - 'SHLVL': '1', 13:25:07 INFO - 'TERM': 'linux', 13:25:07 INFO - 'TMOUT': '86400', 13:25:07 INFO - 'USER': 'cltbld', 13:25:07 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472588091.610660-570207028', 13:25:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:25:07 INFO - '_': '/tools/buildbot/bin/python'} 13:25:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:25:07 INFO - Downloading/unpacking psutil>=3.1.1 13:25:07 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 13:25:07 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 13:25:07 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 13:25:07 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 13:25:07 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 13:25:07 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 13:25:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:25:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:25:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:25:09 INFO - Installing collected packages: psutil 13:25:09 INFO - Running setup.py install for psutil 13:25:09 INFO - building 'psutil._psutil_linux' extension 13:25:09 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 13:25:09 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 13:25:09 INFO - building 'psutil._psutil_posix' extension 13:25:09 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 13:25:10 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 13:25:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:25:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:25:10 INFO - Successfully installed psutil 13:25:10 INFO - Cleaning up... 13:25:10 INFO - Return code: 0 13:25:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:25:10 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')]} 13:25:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:25:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25:10 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')]} 13:25:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:25:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25:10 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 0x1105030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c43b0>, '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 0x13c52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c5740>, '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': '745a4373a2a16df560f216e000000264-1472588091.610660-570207028', '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 13:25:10 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 13:25:10 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 13:25:10 INFO - Using env: {'DISPLAY': ':0', 13:25:10 INFO - 'HOME': '/home/cltbld', 13:25:10 INFO - 'LANG': 'en_US.UTF-8', 13:25:10 INFO - 'LANGUAGE': 'en_US:en', 13:25:10 INFO - 'LOGNAME': 'cltbld', 13:25:10 INFO - 'MAIL': '/var/mail/cltbld', 13:25:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:25:10 INFO - 'MOZ_NO_REMOTE': '1', 13:25:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:25:10 INFO - 'NO_EM_RESTART': '1', 13:25:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:25:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:25:10 INFO - 'PWD': '/builds/slave/test', 13:25:10 INFO - 'SHELL': '/bin/bash', 13:25:10 INFO - 'SHLVL': '1', 13:25:10 INFO - 'TERM': 'linux', 13:25:10 INFO - 'TMOUT': '86400', 13:25:10 INFO - 'USER': 'cltbld', 13:25:10 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472588091.610660-570207028', 13:25:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:25:10 INFO - '_': '/tools/buildbot/bin/python'} 13:25:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:25:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:25:10 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 13:25:10 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 13:25:10 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 13:25:10 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 13:25:10 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 13:25:10 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 13:25:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:25:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:25:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:25:12 INFO - Installing collected packages: mozsystemmonitor 13:25:12 INFO - Running setup.py install for mozsystemmonitor 13:25:12 INFO - Successfully installed mozsystemmonitor 13:25:12 INFO - Cleaning up... 13:25:12 INFO - Return code: 0 13:25:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:25:12 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')]} 13:25:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:25:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25:12 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')]} 13:25:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:25:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25:12 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 0x1105030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c43b0>, '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 0x13c52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c5740>, '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': '745a4373a2a16df560f216e000000264-1472588091.610660-570207028', '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 13:25:12 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 13:25:12 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 13:25:12 INFO - Using env: {'DISPLAY': ':0', 13:25:12 INFO - 'HOME': '/home/cltbld', 13:25:12 INFO - 'LANG': 'en_US.UTF-8', 13:25:12 INFO - 'LANGUAGE': 'en_US:en', 13:25:12 INFO - 'LOGNAME': 'cltbld', 13:25:12 INFO - 'MAIL': '/var/mail/cltbld', 13:25:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:25:12 INFO - 'MOZ_NO_REMOTE': '1', 13:25:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:25:12 INFO - 'NO_EM_RESTART': '1', 13:25:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:25:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:25:12 INFO - 'PWD': '/builds/slave/test', 13:25:12 INFO - 'SHELL': '/bin/bash', 13:25:12 INFO - 'SHLVL': '1', 13:25:12 INFO - 'TERM': 'linux', 13:25:12 INFO - 'TMOUT': '86400', 13:25:12 INFO - 'USER': 'cltbld', 13:25:12 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472588091.610660-570207028', 13:25:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:25:12 INFO - '_': '/tools/buildbot/bin/python'} 13:25:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:25:12 INFO - Downloading/unpacking jsonschema==2.5.1 13:25:12 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 13:25:12 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 13:25:12 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 13:25:12 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 13:25:12 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 13:25:12 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 13:25:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:25:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:25:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:25:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:25:14 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 13:25:14 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 13:25:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:25:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:25:14 INFO - Downloading functools32-3.2.3-2.tar.gz 13:25:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:25:14 INFO - warning: no files found matching '*.txt' 13:25:14 INFO - no previously-included directories found matching 'build' 13:25:14 INFO - no previously-included directories found matching 'dist' 13:25:14 INFO - no previously-included directories found matching '.git*' 13:25:14 INFO - Installing collected packages: jsonschema, functools32 13:25:14 INFO - Running setup.py install for functools32 13:25:15 INFO - warning: no files found matching '*.txt' 13:25:15 INFO - no previously-included directories found matching 'build' 13:25:15 INFO - no previously-included directories found matching 'dist' 13:25:15 INFO - no previously-included directories found matching '.git*' 13:25:15 INFO - Successfully installed jsonschema functools32 13:25:15 INFO - Cleaning up... 13:25:15 INFO - Return code: 0 13:25:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:25:15 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')]} 13:25:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:25:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25:15 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')]} 13:25:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:25:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x1105030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c43b0>, '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 0x13c52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c5740>, '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': '745a4373a2a16df560f216e000000264-1472588091.610660-570207028', '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 13:25:15 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 13:25:15 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 13:25:15 INFO - Using env: {'DISPLAY': ':0', 13:25:15 INFO - 'HOME': '/home/cltbld', 13:25:15 INFO - 'LANG': 'en_US.UTF-8', 13:25:15 INFO - 'LANGUAGE': 'en_US:en', 13:25:15 INFO - 'LOGNAME': 'cltbld', 13:25:15 INFO - 'MAIL': '/var/mail/cltbld', 13:25:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:25:15 INFO - 'MOZ_NO_REMOTE': '1', 13:25:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:25:15 INFO - 'NO_EM_RESTART': '1', 13:25:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:25:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:25:15 INFO - 'PWD': '/builds/slave/test', 13:25:15 INFO - 'SHELL': '/bin/bash', 13:25:15 INFO - 'SHLVL': '1', 13:25:15 INFO - 'TERM': 'linux', 13:25:15 INFO - 'TMOUT': '86400', 13:25:15 INFO - 'USER': 'cltbld', 13:25:15 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472588091.610660-570207028', 13:25:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:25:15 INFO - '_': '/tools/buildbot/bin/python'} 13:25:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:25:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:25:15 INFO - Cleaning up... 13:25:15 INFO - Return code: 0 13:25:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:25:15 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')]} 13:25:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:25:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25:15 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')]} 13:25:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:25:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x1105030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c43b0>, '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 0x13c52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c5740>, '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': '745a4373a2a16df560f216e000000264-1472588091.610660-570207028', '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 13:25:15 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 13:25:15 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 13:25:15 INFO - Using env: {'DISPLAY': ':0', 13:25:15 INFO - 'HOME': '/home/cltbld', 13:25:15 INFO - 'LANG': 'en_US.UTF-8', 13:25:15 INFO - 'LANGUAGE': 'en_US:en', 13:25:15 INFO - 'LOGNAME': 'cltbld', 13:25:15 INFO - 'MAIL': '/var/mail/cltbld', 13:25:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:25:15 INFO - 'MOZ_NO_REMOTE': '1', 13:25:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:25:15 INFO - 'NO_EM_RESTART': '1', 13:25:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:25:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:25:15 INFO - 'PWD': '/builds/slave/test', 13:25:15 INFO - 'SHELL': '/bin/bash', 13:25:15 INFO - 'SHLVL': '1', 13:25:15 INFO - 'TERM': 'linux', 13:25:15 INFO - 'TMOUT': '86400', 13:25:15 INFO - 'USER': 'cltbld', 13:25:15 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472588091.610660-570207028', 13:25:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:25:15 INFO - '_': '/tools/buildbot/bin/python'} 13:25:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:25:15 INFO - Downloading/unpacking blobuploader==1.2.4 13:25:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:25:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:25:15 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 13:25:15 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 13:25:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:25:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:25:17 INFO - Downloading blobuploader-1.2.4.tar.gz 13:25:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:25:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:25: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 13:25: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 13:25: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 13:25: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 13:25: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 13:25: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 13:25:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:25:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:25:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:25:18 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 13:25:18 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 13:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:25:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:25:18 INFO - Downloading docopt-0.6.1.tar.gz 13:25:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:25:18 INFO - Installing collected packages: blobuploader, requests, docopt 13:25:18 INFO - Running setup.py install for blobuploader 13:25:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:25:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:25:18 INFO - Running setup.py install for requests 13:25:18 INFO - Running setup.py install for docopt 13:25:18 INFO - Successfully installed blobuploader requests docopt 13:25:18 INFO - Cleaning up... 13:25:18 INFO - Return code: 0 13:25:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:25:18 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')]} 13:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:25:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25:18 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')]} 13:25:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:25:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25:18 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 0x1105030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c43b0>, '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 0x13c52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c5740>, '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': '745a4373a2a16df560f216e000000264-1472588091.610660-570207028', '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 13:25:18 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 13:25:18 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 13:25:18 INFO - Using env: {'DISPLAY': ':0', 13:25:18 INFO - 'HOME': '/home/cltbld', 13:25:18 INFO - 'LANG': 'en_US.UTF-8', 13:25:18 INFO - 'LANGUAGE': 'en_US:en', 13:25:18 INFO - 'LOGNAME': 'cltbld', 13:25:18 INFO - 'MAIL': '/var/mail/cltbld', 13:25:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:25:18 INFO - 'MOZ_NO_REMOTE': '1', 13:25:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:25:18 INFO - 'NO_EM_RESTART': '1', 13:25:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:25:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:25:18 INFO - 'PWD': '/builds/slave/test', 13:25:18 INFO - 'SHELL': '/bin/bash', 13:25:18 INFO - 'SHLVL': '1', 13:25:18 INFO - 'TERM': 'linux', 13:25:18 INFO - 'TMOUT': '86400', 13:25:18 INFO - 'USER': 'cltbld', 13:25:18 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472588091.610660-570207028', 13:25:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:25:18 INFO - '_': '/tools/buildbot/bin/python'} 13:25:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:25:19 INFO - Running setup.py (path:/tmp/pip-Okx0KJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:25:19 INFO - Running setup.py (path:/tmp/pip-nFAINm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:25:19 INFO - Running setup.py (path:/tmp/pip-bs5SIN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:25:19 INFO - Running setup.py (path:/tmp/pip-rqyfZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:25:19 INFO - Running setup.py (path:/tmp/pip-5weRDz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:25:19 INFO - Running setup.py (path:/tmp/pip-I5lwXx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:25:19 INFO - Running setup.py (path:/tmp/pip-xewoZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:25:19 INFO - Running setup.py (path:/tmp/pip-Qf2CwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:25:19 INFO - Running setup.py (path:/tmp/pip-NG_jw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:25:19 INFO - Running setup.py (path:/tmp/pip-GJ1QtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:25:19 INFO - Running setup.py (path:/tmp/pip-IQEATb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:25:19 INFO - Running setup.py (path:/tmp/pip-6yQE3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:25:19 INFO - Running setup.py (path:/tmp/pip-IuzcBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:25:20 INFO - Running setup.py (path:/tmp/pip-18G3y3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:25:20 INFO - Running setup.py (path:/tmp/pip-5EcRgy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:25:20 INFO - Running setup.py (path:/tmp/pip-7fp4yN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:25:20 INFO - Running setup.py (path:/tmp/pip-s5sq0R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:25:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:25:20 INFO - Running setup.py install for manifestparser 13:25:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:25:20 INFO - Running setup.py install for mozcrash 13:25:20 INFO - Running setup.py install for mozdebug 13:25:20 INFO - Running setup.py install for mozdevice 13:25:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:25:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:25:20 INFO - Running setup.py install for mozfile 13:25:20 INFO - Running setup.py install for mozhttpd 13:25:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:25:20 INFO - Running setup.py install for mozinfo 13:25:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:25:21 INFO - Running setup.py install for mozInstall 13:25:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:25:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:25:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:25:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:25:21 INFO - Running setup.py install for mozleak 13:25:21 INFO - Running setup.py install for mozlog 13:25:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:25:21 INFO - Running setup.py install for moznetwork 13:25:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:25:21 INFO - Running setup.py install for mozprocess 13:25:21 INFO - Running setup.py install for mozprofile 13:25:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:25:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:25:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:25:21 INFO - Running setup.py install for mozrunner 13:25:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:25:21 INFO - Running setup.py install for mozscreenshot 13:25:21 INFO - Running setup.py install for moztest 13:25:22 INFO - Running setup.py install for mozversion 13:25:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:25:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:25:22 INFO - Cleaning up... 13:25:22 INFO - Return code: 0 13:25:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:25: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')]} 13:25:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:25:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25: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')]} 13:25:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:25:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25:22 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 0x1105030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c43b0>, '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 0x13c52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c5740>, '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': '745a4373a2a16df560f216e000000264-1472588091.610660-570207028', '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 13:25:22 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 13:25:22 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 13:25:22 INFO - Using env: {'DISPLAY': ':0', 13:25:22 INFO - 'HOME': '/home/cltbld', 13:25:22 INFO - 'LANG': 'en_US.UTF-8', 13:25:22 INFO - 'LANGUAGE': 'en_US:en', 13:25:22 INFO - 'LOGNAME': 'cltbld', 13:25:22 INFO - 'MAIL': '/var/mail/cltbld', 13:25:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:25:22 INFO - 'MOZ_NO_REMOTE': '1', 13:25:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:25:22 INFO - 'NO_EM_RESTART': '1', 13:25:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:25:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:25:22 INFO - 'PWD': '/builds/slave/test', 13:25:22 INFO - 'SHELL': '/bin/bash', 13:25:22 INFO - 'SHLVL': '1', 13:25:22 INFO - 'TERM': 'linux', 13:25:22 INFO - 'TMOUT': '86400', 13:25:22 INFO - 'USER': 'cltbld', 13:25:22 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472588091.610660-570207028', 13:25:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:25:22 INFO - '_': '/tools/buildbot/bin/python'} 13:25:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:25:22 INFO - Running setup.py (path:/tmp/pip-PqwgD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:25:22 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)) 13:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:25:22 INFO - Running setup.py (path:/tmp/pip-DAaFvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:25:22 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)) 13:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:25:22 INFO - Running setup.py (path:/tmp/pip-oC5m48-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:25:22 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)) 13:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:25:22 INFO - Running setup.py (path:/tmp/pip-T75_xz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:25:22 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)) 13:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:25:22 INFO - Running setup.py (path:/tmp/pip-aZtSni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:25:22 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)) 13:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:25:22 INFO - Running setup.py (path:/tmp/pip-N68fFr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:25:22 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)) 13:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:25:22 INFO - Running setup.py (path:/tmp/pip-0JXBZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:25:22 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)) 13:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:25:22 INFO - Running setup.py (path:/tmp/pip-G6I6O3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:25:22 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)) 13:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:25:22 INFO - Running setup.py (path:/tmp/pip-m_EHR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:25:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 13:25:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:25:23 INFO - Running setup.py (path:/tmp/pip-jnyt8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:25:23 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)) 13:25:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:25:23 INFO - Running setup.py (path:/tmp/pip-y9kdZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:25:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 13:25:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:25:23 INFO - Running setup.py (path:/tmp/pip-fjX7Tf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:25:23 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)) 13:25:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:25:23 INFO - Running setup.py (path:/tmp/pip-y_z3zK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:25:23 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)) 13:25:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:25:23 INFO - Running setup.py (path:/tmp/pip-uuzWMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:25:23 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)) 13:25:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:25:23 INFO - Running setup.py (path:/tmp/pip-20R1rH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:25:23 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)) 13:25:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:25:23 INFO - Running setup.py (path:/tmp/pip-pE7i3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:25:23 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)) 13:25:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:25:23 INFO - Running setup.py (path:/tmp/pip-zMnteE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:25:23 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)) 13:25:23 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)) 13:25:23 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)) 13:25:23 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)) 13:25:23 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)) 13:25:23 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)) 13:25:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:25:23 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 13:25:23 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 13:25:23 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 13:25:23 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 13:25:23 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 13:25:23 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 13:25:25 INFO - Downloading blessings-1.6.tar.gz 13:25:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:25:25 INFO - Installing collected packages: blessings 13:25:25 INFO - Running setup.py install for blessings 13:25:25 INFO - Successfully installed blessings 13:25:25 INFO - Cleaning up... 13:25:25 INFO - Return code: 0 13:25:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:25:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:25:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:25:25 INFO - Reading from file tmpfile_stdout 13:25:25 INFO - Current package versions: 13:25:25 INFO - argparse == 1.2.1 13:25:25 INFO - blessings == 1.6 13:25:25 INFO - blobuploader == 1.2.4 13:25:25 INFO - docopt == 0.6.1 13:25:25 INFO - functools32 == 3.2.3-2 13:25:25 INFO - jsonschema == 2.5.1 13:25:25 INFO - manifestparser == 1.1 13:25:25 INFO - mozInstall == 1.12 13:25:25 INFO - mozcrash == 0.17 13:25:25 INFO - mozdebug == 0.1 13:25:25 INFO - mozdevice == 0.48 13:25:25 INFO - mozfile == 1.2 13:25:25 INFO - mozhttpd == 0.7 13:25:25 INFO - mozinfo == 0.9 13:25:25 INFO - mozleak == 0.1 13:25:25 INFO - mozlog == 3.3 13:25:25 INFO - moznetwork == 0.27 13:25:25 INFO - mozprocess == 0.23 13:25:25 INFO - mozprofile == 0.28 13:25:25 INFO - mozrunner == 6.12 13:25:25 INFO - mozscreenshot == 0.1 13:25:25 INFO - mozsystemmonitor == 0.3 13:25:25 INFO - moztest == 0.7 13:25:25 INFO - mozversion == 1.4 13:25:25 INFO - psutil == 3.1.1 13:25:25 INFO - requests == 1.2.3 13:25:25 INFO - wsgiref == 0.1.2 13:25:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:25: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')]} 13:25:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:25:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25: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')]} 13:25:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:25:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25:25 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 0x1105030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c43b0>, '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 0x13c52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c5740>, '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': '745a4373a2a16df560f216e000000264-1472588091.610660-570207028', '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 13:25:25 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 13:25:25 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 13:25:25 INFO - Using env: {'DISPLAY': ':0', 13:25:25 INFO - 'HOME': '/home/cltbld', 13:25:25 INFO - 'LANG': 'en_US.UTF-8', 13:25:25 INFO - 'LANGUAGE': 'en_US:en', 13:25:25 INFO - 'LOGNAME': 'cltbld', 13:25:25 INFO - 'MAIL': '/var/mail/cltbld', 13:25:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:25:25 INFO - 'MOZ_NO_REMOTE': '1', 13:25:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:25:25 INFO - 'NO_EM_RESTART': '1', 13:25:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:25:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:25:25 INFO - 'PWD': '/builds/slave/test', 13:25:25 INFO - 'SHELL': '/bin/bash', 13:25:25 INFO - 'SHLVL': '1', 13:25:25 INFO - 'TERM': 'linux', 13:25:25 INFO - 'TMOUT': '86400', 13:25:25 INFO - 'USER': 'cltbld', 13:25:25 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472588091.610660-570207028', 13:25:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:25:25 INFO - '_': '/tools/buildbot/bin/python'} 13:25:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 13:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 13:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 13:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 13:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 13:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 13:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 13:25:26 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)) 13:25:26 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)) 13:25:26 INFO - Cleaning up... 13:25:26 INFO - Return code: 0 13:25:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:25:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:25:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:25:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:25:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:25:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:25:26 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 0x1105030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x12688a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13c6180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13c43b0>, '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 0x13c52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c5740>, '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': '745a4373a2a16df560f216e000000264-1472588091.610660-570207028', '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 13:25:26 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 13:25:26 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 13:25:26 INFO - Using env: {'DISPLAY': ':0', 13:25:26 INFO - 'HOME': '/home/cltbld', 13:25:26 INFO - 'LANG': 'en_US.UTF-8', 13:25:26 INFO - 'LANGUAGE': 'en_US:en', 13:25:26 INFO - 'LOGNAME': 'cltbld', 13:25:26 INFO - 'MAIL': '/var/mail/cltbld', 13:25:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:25:26 INFO - 'MOZ_NO_REMOTE': '1', 13:25:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:25:26 INFO - 'NO_EM_RESTART': '1', 13:25:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:25:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:25:26 INFO - 'PWD': '/builds/slave/test', 13:25:26 INFO - 'SHELL': '/bin/bash', 13:25:26 INFO - 'SHLVL': '1', 13:25:26 INFO - 'TERM': 'linux', 13:25:26 INFO - 'TMOUT': '86400', 13:25:26 INFO - 'USER': 'cltbld', 13:25:26 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472588091.610660-570207028', 13:25:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:25:26 INFO - '_': '/tools/buildbot/bin/python'} 13:25:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:25:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:25:26 INFO - Cleaning up... 13:25:26 INFO - Return code: 0 13:25:26 INFO - Running post-action listener: _resource_record_post_action 13:25:26 INFO - Running post-action listener: _start_resource_monitoring 13:25:26 INFO - Starting resource monitoring. 13:25:26 INFO - [mozharness: 2016-08-30 20:25:26.402778Z] Finished create-virtualenv step (success) 13:25:26 INFO - [mozharness: 2016-08-30 20:25:26.403202Z] Running install step. 13:25:26 INFO - Running pre-action listener: _resource_record_pre_action 13:25:26 INFO - Running main action method: install 13:25:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:25:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:25:26 INFO - Reading from file tmpfile_stdout 13:25:26 INFO - Detecting whether we're running mozinstall >=1.0... 13:25:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:25:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:25:26 INFO - Reading from file tmpfile_stdout 13:25:26 INFO - Output received: 13:25:26 INFO - Usage: mozinstall [options] installer 13:25:26 INFO - Options: 13:25:26 INFO - -h, --help show this help message and exit 13:25:26 INFO - -d DEST, --destination=DEST 13:25:26 INFO - Directory to install application into. [default: 13:25:26 INFO - "/builds/slave/test"] 13:25:26 INFO - --app=APP Application being installed. [default: firefox] 13:25:26 INFO - mkdir: /builds/slave/test/build/application 13:25:26 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'] 13:25:26 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 13:25:44 INFO - Reading from file tmpfile_stdout 13:25:44 INFO - Output received: 13:25:44 INFO - /builds/slave/test/build/application/firefox/firefox 13:25:44 INFO - Running post-action listener: _resource_record_post_action 13:25:44 INFO - [mozharness: 2016-08-30 20:25:44.024683Z] Finished install step (success) 13:25:44 INFO - [mozharness: 2016-08-30 20:25:44.024955Z] Running run-tests step. 13:25:44 INFO - Running pre-action listener: _resource_record_pre_action 13:25:44 INFO - Running main action method: run_tests 13:25:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:25:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:25:44 INFO - Python 2.7.3 13:25:44 INFO - Return code: 0 13:25:44 INFO - grabbing minidump binary from tooltool 13:25:44 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')]} 13:25:44 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 0x13c43b0>, '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 0x13c52b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x13c5740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:25:44 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 13:25:44 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 13:25:44 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 13:25:44 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:25:44 INFO - Return code: 0 13:25:44 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:25:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:25:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:25:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:25:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:25:44 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/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 13:25:44 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/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-032 --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 13:25:44 INFO - Using env: {'DISPLAY': ':0', 13:25:44 INFO - 'HOME': '/home/cltbld', 13:25:44 INFO - 'LANG': 'en_US.UTF-8', 13:25:44 INFO - 'LANGUAGE': 'en_US:en', 13:25:44 INFO - 'LOGNAME': 'cltbld', 13:25:44 INFO - 'MAIL': '/var/mail/cltbld', 13:25:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:25:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:25:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:25:44 INFO - 'MOZ_NO_REMOTE': '1', 13:25:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:25:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:25:44 INFO - 'NO_EM_RESTART': '1', 13:25:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:25:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:25:44 INFO - 'PWD': '/builds/slave/test', 13:25:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:25:44 INFO - 'SHELL': '/bin/bash', 13:25:44 INFO - 'SHLVL': '1', 13:25:44 INFO - 'TERM': 'linux', 13:25:44 INFO - 'TMOUT': '86400', 13:25:44 INFO - 'USER': 'cltbld', 13:25:44 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1472588091.610660-570207028', 13:25:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:25:44 INFO - '_': '/tools/buildbot/bin/python'} 13:25:44 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/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--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 13:25:44 INFO - mozversion application_buildid: 20160830092722 13:25:44 INFO - mozversion application_changeset: f6d69919d05a774439b18fd0818fa927504289b9 13:25:44 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:25:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:25:44 INFO - mozversion application_name: Firefox 13:25:44 INFO - mozversion application_remotingname: firefox-dev 13:25:44 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:25:44 INFO - mozversion application_vendor: Mozilla 13:25:44 INFO - mozversion application_version: 50.0a2 13:25:44 INFO - mozversion platform_buildid: 20160830092722 13:25:44 INFO - mozversion platform_changeset: f6d69919d05a774439b18fd0818fa927504289b9 13:25:44 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:25:44 INFO - mozversion platform_version: 50.0a2 13:25:44 INFO - using testdate: 1472588744 13:25:44 INFO - actual date: 1472588744 13:25:44 INFO - starting webserver on 'localhost:60030' 13:25:44 INFO - SUITE-START | Running 2 tests 13:25:44 INFO - TEST-START | dromaeo_css 13:25:44 INFO - operating with platform_type : linux_ 13:25:44 INFO - Initialising browser for dromaeo_css test... 13:25:44 INFO - TEST-INFO | started process 20371 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9LUXHL/profile http://localhost:60030/getInfo.html) 13:25:48 INFO - PROCESS | 20371 | __metrics Screen width/height:1600/1200 13:25:48 INFO - PROCESS | 20371 | colorDepth:24 13:25:48 INFO - PROCESS | 20371 | Browser inner width/height: 1024/697 13:25:48 INFO - PROCESS | 20371 | __metrics 13:25:49 INFO - PROCESS | 20371 | 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" 13:25:49 INFO - PROCESS | 20371 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:25:50 INFO - PROCESS | 20371 | console.error: PushService: 13:25:50 INFO - PROCESS | 20371 | stateChangeProcessEnqueue: Error transitioning state 13:25:50 INFO - PROCESS | 20371 | UnknownError 13:25:50 INFO - TEST-INFO | 20371: exit 0 13:25:50 INFO - Browser initialized. 13:25:50 INFO - Running cycle 1/1 for dromaeo_css test... 13:25:50 INFO - TEST-INFO | started process 20459 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9LUXHL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:26:02 INFO - PROCESS | 20459 | RSS: Main: 145584128 13:26:02 INFO - PROCESS | 20459 | 13:26:53 INFO - PROCESS | 20459 | 1472588813386 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] 13:29:02 INFO - PROCESS | 20459 | Cycle 1(1): loaded http://localhost:60030/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60030/tests/dromaeo/cssquery-ext.html) 13:29:03 INFO - PROCESS | 20459 | RSS: Main: 218755072 13:29:03 INFO - PROCESS | 20459 | 13:32:05 INFO - PROCESS | 20459 | Cycle 1(1): loaded http://localhost:60030/tests/dromaeo/cssquery-ext.html (next: http://localhost:60030/tests/dromaeo/cssquery-jquery.html) 13:32:06 INFO - PROCESS | 20459 | RSS: Main: 231665664 13:32:06 INFO - PROCESS | 20459 | 13:35:08 INFO - PROCESS | 20459 | Cycle 1(1): loaded http://localhost:60030/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60030/tests/dromaeo/cssquery-mootools.html) 13:35:08 INFO - PROCESS | 20459 | RSS: Main: 175194112 13:35:08 INFO - PROCESS | 20459 | 13:38:10 INFO - PROCESS | 20459 | Cycle 1(1): loaded http://localhost:60030/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60030/tests/dromaeo/cssquery-prototype.html) 13:38:11 INFO - PROCESS | 20459 | RSS: Main: 175915008 13:38:11 INFO - PROCESS | 20459 | 13:41:14 INFO - PROCESS | 20459 | Cycle 1(1): loaded http://localhost:60030/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60030/tests/dromaeo/cssquery-yui.html) 13:41:14 INFO - PROCESS | 20459 | RSS: Main: 177197056 13:41:14 INFO - PROCESS | 20459 | 13:44:17 INFO - PROCESS | 20459 | Cycle 1(1): loaded http://localhost:60030/tests/dromaeo/cssquery-yui.html (next: http://localhost:60030/tests/dromaeo/cssquery-dojo.html) 13:44:17 INFO - PROCESS | 20459 | RSS: Main: 264011776 13:44:17 INFO - PROCESS | 20459 | 13:44:17 INFO - PROCESS | 20459 | __start_tp_report 13:44:17 INFO - PROCESS | 20459 | _x_x_mozilla_page_load 13:44:17 INFO - PROCESS | 20459 | _x_x_mozilla_page_load_details 13:44:17 INFO - PROCESS | 20459 | |i|pagename|runs| 13:44:17 INFO - PROCESS | 20459 | |0;dojo.html;3217.9195520111994;3228.257500774822;3232.9654510556616;3251.67483251675;3271.6728327167284;5445.156000819869;5507.008738427086;5544.085225937725;5550.056490396632;5558.555315574755;5501.532369748566;5552.944470555293;5561.332640083186;5604.607677462579;5607.775688972436;4967.279744437073;5029.170186919163;5047.167217409123;5103.2855400244;5129.025485157808;4968.478309777475;5091.719955402451;5129.358830146231;5151.871203219931;5152.201408781626;5061;5084.898302033957;5101.15830887903;5110.28456016158;5155.76799044042;5172.612054095939;5193.896122077537;5205.245239440298;5212.765425555859;5236.266922630834;4914.140025495524;4990.4011518617635;5023.648344615878;5094.465081166498;5098.923516147261;5495.917561236584;5541.196526503634;5569.387367389584;5593.74828132735;5601.159826026088;6058.39416058395;6066.272047354346;6067.848303792396;6071.908921366183;6073.848153796146;5957.702114894281;5986.880262394771;6000.399960004008;6071.058985857199;6109.5723299369065;5629.099344104923;5684.857878553028;5691.544676425876;5691.573132015033;5691.573132015115;5871.3541510433815;6142.078688196806;6144.293406258337;6154.476869466145;6167.568270221132;5761.251037365116;5782.739776710058;5801.506871915934;5873.853153671193;5876.353601103875;5945.40545945402;5970.462658360769;5979.19280897082;5985.371535988658;5992.101184822224;5764.365919748824;5895.322037965603;5907.556933230025;5909.8227053188475;5912.526997840162;5924.31870334917;5955.047192449274;5967.104934259895;6022.879542409128;6052.54605904559;4641.303804429363;4641.466231383367;4643.3963584733765;4646.395968524071;4672.883177920511;4029.17401932609;4128.38074288853;4145.419641250227;4149.771762553089;4162.62536371728;5729.9713501432225;5761.9135712964335;5764.106563482667;5815.360310365856;5845.590808643439;6000.21997140369;6041.9395806041375;6046.093086037042;6048.788292409745;6072.210612620331;5629.521490673257;5787.276590426197;5790.594658373958;5795.246617939641;5804.448577385142;5038.79844806211;5048.3184770056305;5055.241713742968;5095.490450954875;5129.179331306973;6267.843303917439;6319.399657032574;6326.557141000178;6340.3342649022115;6343.714532845941;5813.215215945962;5991.550633702577;6005.849853753516;6008.339082700812;6012.278526576664;6146.016637338004;6262.686865656608;6274.090256912631;6322.462590679844;6369.617822930639;5933.465988061095;5986.760529578768;5990.251218597676;5998.850028749316;6032.849178770566;6085.482733967485;6094.39056094387;6101.694915254131;6105.145279660762;6106.419890110433;5746.798862039976;5795.130730390475;5845.561582881216;5846.444587764156;5931.436513531207;5845.824625261249;5850.3857095006115;5859.296884373903;5861.32594751609;5868.3251426086545;5763.366029736641;5798.739056742373;5815.534757219497;5816.127580862904;5823.272090988627;5766.538676905921;5790.507806836466;5832.037713777179;5833.591648584558;5876.236089308533;5692.715364231689;5701.059325211294;5703.144528320785;5705.572082093777;5706.629069110494 13:44:17 INFO - PROCESS | 20459 | |1;ext.html;602.4728362682652;605.5821483176608;625.9968700156497;671.1543455246388;677.0352248046532;35114.47328290075;37892.43161352582;38123.80938095309;46149.769251153695;47022.29466558004;48520.51479485204;48947.26579101316;48971.51028489723;48988.75505622467;49049;9231.630734770619;9375.10936461035;9389.154976052154;9391.295652826031;9395.295352848529;8733.912660873373;10994.725131871688;11251.156163287746;11255.606053788119;11660.300381977066;10931.344119352865;11010.669679909575;11022.559097636085;11022.559097636125;11025.173112016591;8676.566171691453;11224.438778061065;11320.207585469021;11343.943280283629;11348.14888883333;10757.677269681923;11160.665180044612;11163.944180279046;11465.656030319104;11491.655250342503;45497.54502454966;45907.77046114781;45969.54030459686;45978.31032534515;46011.9294631739;3628.328759179534;3628.600853906068;3759.436084587334;3835.7314987950854;3896.5713771485116;35199.1200219994;35296.82351588226;35420.11449713752;35421.82289088538;35446;4649.790759415834;4692.1084993851055;4729.385179926622;4730.716157030589;4838.153323168432;389842;390646.0467697672;391991;394515;396967.9999999971;64288.35711642917;64600.35399645943;64691.67654161699;64740.35259647343;64906.35093649003;39432.21135577272;40141.1971760569;40147;66948.33051669519;67302.32697673059;19325;19448.90275548613;19461.318853840046;19469.318573849847;19481;13797.586072417844;15040.699186016438;15126.470573530094;15189.9240503799;15228.695426091415;17590.472285831445;18117.36589219371;18228.908855455902;18237.270509179485;18414.26342946294;7329.926700733031;7372.262773722692;7516.549007059484;7557.206493318233;7563.281488258606;993.3295025857661;1052.163529993657;1085.9565617375217;1139.6524060161564;1141.8173092305192;34548.481772773426;34921.82539087288;34944.475832862525;34981.82509087438;35107.473387899205;1129.249049382168;1163.5636636261402;1168.3048586091263;1192.1952681939829;1227.030645789816;18123;18173.363932262306;18420.631587368447;18427.170777315052;18500.814991850177;617834;618092;618686;619028;622125;64509.67745161244;64874.351256487775;65007.67496162584;65045.349546504876;65056;1205.3310412721023;1419.8864090872705;1428.0931608428723;1452.6658868460102;1456.2500312339155;33944;36320.636793631726;36466.46329384786;36486.817565912;36629.450558242184;45238.77380613208;45295.773521132185;45589.544104559856;45646.31530526978;45666.315005274286;430594;431421;431990;432437;433126;30091;30911;31321.8433907829;32212.19469513281;32475.35049299048;44996.32505512486;45069.09861802811;45168.32247516224;45227.321590176834;45475.31787023263;44468.33297500605;44761;44853.77573112244;44875.775621121684;46396.53603464057;44107.55892441163;44396.33405498985;44598.55401445944;44896.32655510235;45884;43523.78238108789;44353;44574.3313850286;44701.32948005848;44780.55219447894;38232.42651360288;40235;40417.39373909335;40486.59513404828;40646.39030414487;29353;29575;29833.85083074571;29956.814081889526;30068.849655751583 13:44:17 INFO - PROCESS | 20459 | |2;jquery.html;395.55697618667097;412.14891249569655;432.14219773749113;433.8047878454695;438.48697024481356;15452.768208476868;20249.898750506265;20901.268455604037;20935.790642093594;21301.615394999353;20200.29298974536;20253.392398228076;20525.897370513165;20537.691934620994;20605.587888242226;5829.708514574254;5852.151438041481;5964.552658550604;6000;6016.729247183887;5874.588778785486;5942.227510423639;5945.197398351211;5949.6727679977585;5950.523958083343;5701.372848986629;5865.296164460271;5873.119032145191;5881.705914704269;5889.293284805829;5764.971175144139;5828.504577110908;5868.9413105868825;5914.2015827863115;5915.290165180184;5267.473252674702;5278.181881808326;5311.521963023308;5316.6544174628525;5318.25544423781;22257.220997265016;24207.152749653676;24477.510449791083;24536.386590335205;24663.506729865483;2381.5594115088593;2482.379405148713;2824.8841707525617;3042.4827779277352;3060.1890499017777;10004.699859004242;10046.296759226858;10085.394876307371;10359.32664376813;10438.32150910196;1903.9048047597703;1929.21866644008;1954.1206457094365;1990.4725247809342;2044.703517989898;91527.54236228843;91906.80594496714;93050;93618;96099.51950240273;11426.485808138654;11436.142289328334;11481.196316257781;11520.481578328996;11618.94190529042;16884.324627015027;16952.406665766637;16968.74546881798;16975.236114374882;16976.83023169777;8110.229528194812;8309.6676132954;8335.333173346118;8454.830903381899;8722.34582406322;8121.634526446324;8129.959350203329;8132.959335203286;8150.429469937166;8277.95861020691;5041.546260836542;5053.0904437200925;5055.848324550269;5071.594272458195;5201.08980928336;1982.6332128556264;2049;2092.968605470919;2106.926257580977;2112.292382051999;917.1928702741545;965.5413678502796;990.0891180114312;1057.5241141486363;1066.7013236293851;18112.959654422622;19712.802871971384;20205.89897050505;21196.15215391367;21230.36308910735;930.7068273493828;988.3180605382407;1023.6417253961054;1031.1905154453718;1032.6901929421144;10716.73208169802;11324.547018119347;11357.488912998935;11375.260608060615;11527.827082593767;496000;497198;504119;570069;570191;94989.5250523743;97551.51224243976;98631;99828;100117;1077.2943721862193;1108.778244351133;1117.9552817887193;1130.5534313945946;1136.534021051365;17761.200745966205;17784.288628454717;18036.729449058537;18552.165152567897;18766.155523001224;42492.36261456019;44789.32816007828;45138.77430612826;46069;47185.52814471949;55103.44896550983;57778;58862;60058.69970650119;60703.69648151731;8493.186010989655;8541.957290213757;8841.911580884365;8878.37851350425;8900.688475903313;11440.713982150513;12710.237385756884;12986.610401687965;13387.732245354844;13668.794968075288;11511.53953841837;11579.13156513248;11889.82165267539;11907.226030308002;12507.437165327765;10862.619808306672;11361.659150225507;11844.407779610812;11960;11978.401079945794;11251.549938002716;11805.82291265649;11879.643610691694;12202.572909948109;12524.874751252735;7166.928330716834;7482.401407887247;8465.746027619181;8657;8884.689035883714;10898.801131875329;11423.714407139889;11523.596674116367;11526.538938442369;11596.304221746723 13:44:17 INFO - PROCESS | 20459 | |3;mootools.html;1396.0646366934154;1454.2946670864633;1519.1720512320785;1656.271240654113;1829.926802927883;8254.215849494296;8617.13828617138;8783.077776833436;8814.074522175175;9054.139856713618;8951.686690965818;9012.81974360514;9020.594073266702;9027.95486022569;9034.54827258636;2424.8423852449623;2444.0712529238954;2452.2275483222816;2454.3864033991504;2455.054803900502;2439.0975339124548;2442.426029882976;2498.850068995857;2499.200255918107;2502.4244423782543;2417.516496700658;2434.4887573609562;2455.7175924768703;2456.8648724320246;2460.434100156965;2399.448126930807;2404.1224952892157;2405.7473965233576;2406.362313986795;2442.3405680466353;2336.33414476875;2337.836351455399;2341.8946147423408;2342.7422983471806;2343.8124950003958;9065.546722663907;9291.070892910722;9301.116393942564;9399.060093990614;9411.341206115507;5593.188987596775;5731.627444216113;5734.455226753452;5739.225204597409;5744.913826292609;3614.6927511161584;3686.6681998619983;3704;3746.812659367021;3747.4191500317493;1248.719038216403;1249.8312727781724;1250.6560695808635;1273.8535068467152;1274.9536993125992;13501.729965400735;13633.522826701017;13638;13644.658883527893;13658.180509169482;4542.409486766699;4671.906561868812;4784.425868895685;4837.95162048382;4852.199387101159;10103.343282686601;10110.039546243095;10111;10129;10143.492825358702;5565.1095824667855;5582.553395728332;5586.1062230043;5588.636738611977;5591.329040515164;4464.3973063635995;4596.563326483979;4997.175466048061;5094.083065048327;5204.115300398941;4026.335654617014;4085.8978525536504;4173;4188.183304255641;4195;1211.3156066822216;1231.2735486063266;1235.8516977962702;1239.8016317389174;1249.1131296779388;1063.2716589136467;1063.7340664833791;1065.5577935156823;1065.7122576904192;1066.8453074304182;6467.32093130224;6472.935270647327;6624.238212605515;6692.364225398579;6696.8995465068065;479.84405068352925;487.4443134826301;867.2324992381662;869.1395518436702;1005.5776573839005;4368.148211098805;4438.378626992224;4450.265706158512;4460.7323560586465;4489.753063581469;11864.22882512634;12242.204256723284;12300.507979680713;12313.322767247888;12313.938430307791;12188.085893558018;12206.938965305295;12243.449044793006;12243.632691019106;12251.877481225252;8429.32565394767;8800.823983520293;8801.691940782042;8940.5082720451;8964.68623598184;6482.513811464253;6495.772647957488;6511;6551.639659818756;6556.311587283267;11074.501647426046;11103.722406939569;11153.442327883735;11179.888201117885;11214.775704486028;11108.500117494894;11177.608783692533;11419.0864730823;11500;11601.535938562363;7715.614219289126;8218.21926916942;8225.629846657035;8344.290735287568;8361.289290410139;9589.42463452195;9686.515674216402;9694.806103878023;9696.806063878541;9701;8293.95853020731;8339.45793523419;8373.748787536384;8417.158284171534;8497.74506764798;8138.755837324889;8219.095899451175;8402.537860417735;8429.662813487637;8622.913770862462;7503.737369192052;7834.138244793182;7884.053913530183;7890.48711833729;7926.088499822364;5713;5821.09772985188;5897.587168898308;5923.141144534105;5937.376575459514;5823.126531020381;5839.5036421904615;5866.208061911758;5943.167956486009;5994.250718660168 13:44:17 INFO - PROCESS | 20459 | |4;prototype.html;332.1116014660782;335.9412102881995;336.1981673708206;339.5161894300621;342.9485577163426;5424.8535849207465;5508.950921073013;5522.254495643087;5534.806281780138;5537.55699544036;5395.865103372408;5421.156806685726;5440.3743569489525;5446.863828404292;5456.9181462278;3859.4982652255167;4075.877723668287;4158.878971724427;4196.712791528609;4256.914861702764;4220.029393239542;4227.32362821947;4233.74597524148;4235.2800023996;4239.236937351276;4221.514525829522;4248.660107191417;4252.532221455637;4261.552536983619;4266.274733295331;4096.692748043879;4116.711830171889;4230.936535951963;4287.442632457776;4298;3714.0900479382726;3731.645493678096;3743.625637436262;3762.6425489578446;3784.6593806557544;5266.3943646480375;5286.81496147633;5287.391949925731;5353.411124776271;5393.568514518829;4801.759912004386;4869.196582563908;4901.926471102936;4920.212765957465;4934.358533390637;5586.413426590231;5607.214989901417;5610.915836262459;5622.606417550773;5642.238297829823;4159.604837540429;4160.563140870226;4163.167366526707;4170.270202714512;4193.51774545928;5672.9432705672825;5923.348431672513;5928.170056192136;5979.671118088547;5990.281166260033;4130.607592278729;4165.166966606691;4181.351890456923;4187.958120418818;4205.516365617932;4232.873013809591;4238.978805106017;4251.702380833374;4254.532001479834;4276.337167739005;4777.737224452689;4794.832180873653;4813.181759100961;4899.436564795023;4901.926471102936;5698.17376480408;5704.486596206361;5705.971470142623;5726.770929162787;5742.569307301969;1450.2386247220293;1465.677550938813;1466.2302291297153;1468.302556285777;1471.6681403330003;887.0558739940367;943.7165454981221;949.9810003799886;951.0299494515556;958.4297343080856;5090.847274581768;5104.310918026019;5105.33630628016;5107.6169287302855;5120.539151476385;5272.578193744491;5276.314079169761;5280.287161233261;5292.311999440125;5295.470452954673;5068.746562671851;5081.212412076134;5133.563647089964;5309.5752339812725;5705.343885453143;3122.625284965773;3135.5576434840227;3140.3248301615263;3145.3552021835926;3149.6062992125985;6024.256701147855;6050.848728781815;6112.174856394418;6202.379762023852;6203.325102222375;5640.887182256414;5909.8227053188475;5950.256217972754;5956.195913551702;5964.850878728066;5933.9406605934255;6078.331383547807;6160.229971253593;6174.506039516918;6175.752969881154;5823.534117270693;5840.802635459806;5856.12158176277;5871.970640146772;5892.263467066617;5574.804881829116;5776.364599894092;5776.508996735324;5816;5831.591788574929;6163.599366041193;6187.20571034413;6193.504519638328;6201.658908760061;6205.906911396423;4021.798910054544;4275.957240427556;4283.886189590667;4310.698251122391;4314.137172565437;5824.417558244311;5842.152887831326;5860.150278209721;5861.3845546217035;5867.589268751147;5725.942740572707;5742.5405967521665;5744.3681195067675;5802.767889284381;5803.8549036274435;5609.158626206102;5682.261306030189;5693.373728889902;5726.134858970096;5796.652200867961;5610.43895610452;5626.352969408406;5630.18362337467;5636.718164091697;5648.85877853057;5555.2778138841695;5725.198472213977;5733.827985160452;5756.539476841926;5791;5453.700511190673;5540.667559946416;5549.861253468695;5551.944480555143;5561.805336813192 13:44:17 INFO - PROCESS | 20459 | |5;yui.html;737.310614575372;787.3622365883631;804.0632662947667;818.8730746734254;900.9594568244428;15390.15354155521;16354.936929099615;16853;17974.640507189848;18172.909135454338;16241.269142888568;16423.507294781146;16424;16427.67144657106;16544.420945266917;737.8118579762153;858.5363903492107;879.7800549862518;882.1046637662778;888.7866911941152;880.4057261348596;888.5424006636588;888.866669999502;888.9865553269278;889.168627333442;917.3165991336464;918.0544039639149;918.1919910478803;918.3892711346946;919.117647058821;759.3848982324307;888.1562515610132;911.1799380557518;912.7626817027555;914.4421902639384;676.100785954679;676.1446769836161;676.2155899156954;676.4858707382375;676.9627670478121;14746.336414861249;14765.926170369186;14779;14783.186924719135;14804.481843135547;15241.847581524155;15714.214289285605;15788.526344209693;16121.343013968504;16136.435224767078;14041.157530548098;14085.507007254799;14142;14338.139711617336;14341;4677.321788340706;4684.46128695201;4687.789049492781;4730.0776348612035;4757.286407038937;89963.55018224932;90291.09708902959;92496.53751731265;93678.53160734221;94288;12521.436535355931;12537.93731031339;12592.244465332109;12634.87365126337;12930;24022.39944001414;24049.398765031016;24077.879610601834;24105.397365065663;24124.27627171188;8857.64569417216;8953.776155596031;9221.53892305382;9411.858822117674;9456.67669887532;4855.8057677693205;4877.4908372992895;4890.364252647204;4892.510748925079;5072.442031376546;3520.471929210639;3660.5424321959754;3664.3953747631344;3664.59689434162;3667.156553992597;672.1732269308679;673.8248055505604;679.4598294355999;694.4236283884364;716.0941409117471;774.8256642255448;797.9807013882613;798.3214267872262;809.0938149272852;811.7808191788303;10480.68557943263;10639.202059845542;10686.51910664022;11151.776964460665;11270.211085223955;687.6596084937888;703.4442790195791;790.9288164065262;796.4066770256196;796.9481983671043;4883.438404583448;5073.010078565185;5329.520343169133;5381.78472861089;5394.622376433612;76331;79033;127665.36167319104;135367;136545.317273413;111777;113892;114908.42545787385;115578.4221078906;116734.41632791782;96903;98023;99481;99611.50194249128;101736;10069.093781559695;10113.494325283853;10175.389476631426;10176.847347289648;10258.692239232834;60682.69658651679;60974;61771;62147.378526215965;62623;84050.57974710331;86729;87161.56419217863;87238;94542.52728736312;12745.49018039268;13127.343632818513;13252.469901203844;13464.663383415493;13729.176249425067;13575.52485662997;13912.732297201444;14013.75013106052;14655.633609159857;14756.557303280919;10719.785604288027;11075.05862001739;13078.476860925457;13207;14093.436262549792;15278.618034549225;15358.769618455955;15427.84572154264;15525.301361438536;16181.271842767339;14184.29078546048;14565.92717036408;15240.161791101596;15460;15463.768043479582;11854.52581896749;12052.879471205175;12100.75798484043;12787.296698681661;13024.804627930778;12404.875951240372;12475.625731228078;12669.239845609292;12770.936145319214;12977.935110324388 13:44:17 INFO - PROCESS | 20459 | __end_tp_report 13:44:17 INFO - PROCESS | 20459 | __start_cc_report 13:44:17 INFO - PROCESS | 20459 | _x_x_mozilla_cycle_collect,1052 13:44:17 INFO - PROCESS | 20459 | __end_cc_report 13:44:17 INFO - PROCESS | 20459 | __startTimestamp1472589857647__endTimestamp 13:44:17 INFO - PROCESS | 20459 | 13:44:17 INFO - PROCESS | 20459 | ------- Summary: start ------- 13:44:17 INFO - PROCESS | 20459 | Number of tests: 6 13:44:17 INFO - PROCESS | 20459 | 13:44:17 INFO - PROCESS | 20459 | [#0] dojo.html Cycles:165 Average:5575.45 Median:5790.59 stddev:637.33 (11.0%) stddev-sans-first:611.86 13:44:17 INFO - PROCESS | 20459 | Values: 3217.9 3228.3 3233.0 3251.7 3271.7 5445.2 5507.0 5544.1 5550.1 5558.6 5501.5 5552.9 5561.3 5604.6 5607.8 4967.3 5029.2 5047.2 5103.3 5129.0 4968.5 5091.7 5129.4 5151.9 5152.2 5061.0 5084.9 5101.2 5110.3 5155.8 5172.6 5193.9 5205.2 5212.8 5236.3 4914.1 4990.4 5023.6 5094.5 5098.9 5495.9 5541.2 5569.4 5593.7 5601.2 6058.4 6066.3 6067.8 6071.9 6073.8 5957.7 5986.9 6000.4 6071.1 6109.6 5629.1 5684.9 5691.5 5691.6 5691.6 5871.4 6142.1 6144.3 6154.5 6167.6 5761.3 5782.7 5801.5 5873.9 5876.4 5945.4 5970.5 5979.2 5985.4 5992.1 5764.4 5895.3 5907.6 5909.8 5912.5 5924.3 5955.0 5967.1 6022.9 6052.5 4641.3 4641.5 4643.4 4646.4 4672.9 4029.2 4128.4 4145.4 4149.8 4162.6 5730.0 5761.9 5764.1 5815.4 5845.6 6000.2 6041.9 6046.1 6048.8 6072.2 5629.5 5787.3 5790.6 5795.2 5804.4 5038.8 5048.3 5055.2 5095.5 5129.2 6267.8 6319.4 6326.6 6340.3 6343.7 5813.2 5991.6 6005.8 6008.3 6012.3 6146.0 6262.7 6274.1 6322.5 6369.6 5933.5 5986.8 5990.3 5998.9 6032.8 6085.5 6094.4 6101.7 6105.1 6106.4 5746.8 5795.1 5845.6 5846.4 5931.4 5845.8 5850.4 5859.3 5861.3 5868.3 5763.4 5798.7 5815.5 5816.1 5823.3 5766.5 5790.5 5832.0 5833.6 5876.2 5692.7 5701.1 5703.1 5705.6 5706.6 13:44:17 INFO - PROCESS | 20459 | 13:44:17 INFO - PROCESS | 20459 | [#1] ext.html Cycles:180 Average:64895.77 Median:395741.50 stddev:130448.81 (33.0%) stddev-sans-first:130725.44 13:44:17 INFO - PROCESS | 20459 | Values: 602.5 605.6 626.0 671.2 677.0 35114.5 37892.4 38123.8 46149.8 47022.3 48520.5 48947.3 48971.5 48988.8 49049.0 9231.6 9375.1 9389.2 9391.3 9395.3 8733.9 10994.7 11251.2 11255.6 11660.3 10931.3 11010.7 11022.6 11022.6 11025.2 8676.6 11224.4 11320.2 11343.9 11348.1 10757.7 11160.7 11163.9 11465.7 11491.7 45497.5 45907.8 45969.5 45978.3 46011.9 3628.3 3628.6 3759.4 3835.7 3896.6 35199.1 35296.8 35420.1 35421.8 35446.0 4649.8 4692.1 4729.4 4730.7 4838.2 389842.0 390646.0 391991.0 394515.0 396968.0 64288.4 64600.4 64691.7 64740.4 64906.4 39432.2 40141.2 40147.0 66948.3 67302.3 19325.0 19448.9 19461.3 19469.3 19481.0 13797.6 15040.7 15126.5 15189.9 15228.7 17590.5 18117.4 18228.9 18237.3 18414.3 7329.9 7372.3 7516.5 7557.2 7563.3 993.3 1052.2 1086.0 1139.7 1141.8 34548.5 34921.8 34944.5 34981.8 35107.5 1129.2 1163.6 1168.3 1192.2 1227.0 18123.0 18173.4 18420.6 18427.2 18500.8 617834.0 618092.0 618686.0 619028.0 622125.0 64509.7 64874.4 65007.7 65045.3 65056.0 1205.3 1419.9 1428.1 1452.7 1456.3 33944.0 36320.6 36466.5 36486.8 36629.5 45238.8 45295.8 45589.5 45646.3 45666.3 430594.0 431421.0 431990.0 432437.0 433126.0 30091.0 30911.0 31321.8 32212.2 32475.4 44996.3 45069.1 45168.3 45227.3 45475.3 44468.3 44761.0 44853.8 44875.8 46396.5 44107.6 44396.3 44598.6 44896.3 45884.0 43523.8 44353.0 44574.3 44701.3 44780.6 38232.4 40235.0 40417.4 40486.6 40646.4 29353.0 29575.0 29833.9 29956.8 30068.8 13:44:17 INFO - PROCESS | 20459 | 13:44:17 INFO - PROCESS | 20459 | [#2] jquery.html Cycles:180 Average:30898.30 Median:24599.95 stddev:87312.07 (354.9%) stddev-sans-first:87526.96 13:44:17 INFO - PROCESS | 20459 | Values: 395.6 412.1 432.1 433.8 438.5 15452.8 20249.9 20901.3 20935.8 21301.6 20200.3 20253.4 20525.9 20537.7 20605.6 5829.7 5852.2 5964.6 6000.0 6016.7 5874.6 5942.2 5945.2 5949.7 5950.5 5701.4 5865.3 5873.1 5881.7 5889.3 5765.0 5828.5 5868.9 5914.2 5915.3 5267.5 5278.2 5311.5 5316.7 5318.3 22257.2 24207.2 24477.5 24536.4 24663.5 2381.6 2482.4 2824.9 3042.5 3060.2 10004.7 10046.3 10085.4 10359.3 10438.3 1903.9 1929.2 1954.1 1990.5 2044.7 91527.5 91906.8 93050.0 93618.0 96099.5 11426.5 11436.1 11481.2 11520.5 11618.9 16884.3 16952.4 16968.7 16975.2 16976.8 8110.2 8309.7 8335.3 8454.8 8722.3 8121.6 8130.0 8133.0 8150.4 8278.0 5041.5 5053.1 5055.8 5071.6 5201.1 1982.6 2049.0 2093.0 2106.9 2112.3 917.2 965.5 990.1 1057.5 1066.7 18113.0 19712.8 20205.9 21196.2 21230.4 930.7 988.3 1023.6 1031.2 1032.7 10716.7 11324.5 11357.5 11375.3 11527.8 496000.0 497198.0 504119.0 570069.0 570191.0 94989.5 97551.5 98631.0 99828.0 100117.0 1077.3 1108.8 1118.0 1130.6 1136.5 17761.2 17784.3 18036.7 18552.2 18766.2 42492.4 44789.3 45138.8 46069.0 47185.5 55103.4 57778.0 58862.0 60058.7 60703.7 8493.2 8542.0 8841.9 8878.4 8900.7 11440.7 12710.2 12986.6 13387.7 13668.8 11511.5 11579.1 11889.8 11907.2 12507.4 10862.6 11361.7 11844.4 11960.0 11978.4 11251.5 11805.8 11879.6 12202.6 12524.9 7166.9 7482.4 8465.7 8657.0 8884.7 10898.8 11423.7 11523.6 11526.5 11596.3 13:44:17 INFO - PROCESS | 20459 | 13:44:17 INFO - PROCESS | 20459 | [#3] mootools.html Cycles:180 Average:6247.21 Median:4477.08 stddev:3620.52 (80.9%) stddev-sans-first:3612.32 13:44:17 INFO - PROCESS | 20459 | Values: 1396.1 1454.3 1519.2 1656.3 1829.9 8254.2 8617.1 8783.1 8814.1 9054.1 8951.7 9012.8 9020.6 9028.0 9034.5 2424.8 2444.1 2452.2 2454.4 2455.1 2439.1 2442.4 2498.9 2499.2 2502.4 2417.5 2434.5 2455.7 2456.9 2460.4 2399.4 2404.1 2405.7 2406.4 2442.3 2336.3 2337.8 2341.9 2342.7 2343.8 9065.5 9291.1 9301.1 9399.1 9411.3 5593.2 5731.6 5734.5 5739.2 5744.9 3614.7 3686.7 3704.0 3746.8 3747.4 1248.7 1249.8 1250.7 1273.9 1275.0 13501.7 13633.5 13638.0 13644.7 13658.2 4542.4 4671.9 4784.4 4838.0 4852.2 10103.3 10110.0 10111.0 10129.0 10143.5 5565.1 5582.6 5586.1 5588.6 5591.3 4464.4 4596.6 4997.2 5094.1 5204.1 4026.3 4085.9 4173.0 4188.2 4195.0 1211.3 1231.3 1235.9 1239.8 1249.1 1063.3 1063.7 1065.6 1065.7 1066.8 6467.3 6472.9 6624.2 6692.4 6696.9 479.8 487.4 867.2 869.1 1005.6 4368.1 4438.4 4450.3 4460.7 4489.8 11864.2 12242.2 12300.5 12313.3 12313.9 12188.1 12206.9 12243.4 12243.6 12251.9 8429.3 8800.8 8801.7 8940.5 8964.7 6482.5 6495.8 6511.0 6551.6 6556.3 11074.5 11103.7 11153.4 11179.9 11214.8 11108.5 11177.6 11419.1 11500.0 11601.5 7715.6 8218.2 8225.6 8344.3 8361.3 9589.4 9686.5 9694.8 9696.8 9701.0 8294.0 8339.5 8373.7 8417.2 8497.7 8138.8 8219.1 8402.5 8429.7 8622.9 7503.7 7834.1 7884.1 7890.5 7926.1 5713.0 5821.1 5897.6 5923.1 5937.4 5823.1 5839.5 5866.2 5943.2 5994.3 13:44:17 INFO - PROCESS | 20459 | 13:44:17 INFO - PROCESS | 20459 | [#4] prototype.html Cycles:180 Average:4783.74 Median:5423.01 stddev:1405.81 (25.9%) stddev-sans-first:1369.47 13:44:17 INFO - PROCESS | 20459 | Values: 332.1 335.9 336.2 339.5 342.9 5424.9 5509.0 5522.3 5534.8 5537.6 5395.9 5421.2 5440.4 5446.9 5456.9 3859.5 4075.9 4158.9 4196.7 4256.9 4220.0 4227.3 4233.7 4235.3 4239.2 4221.5 4248.7 4252.5 4261.6 4266.3 4096.7 4116.7 4230.9 4287.4 4298.0 3714.1 3731.6 3743.6 3762.6 3784.7 5266.4 5286.8 5287.4 5353.4 5393.6 4801.8 4869.2 4901.9 4920.2 4934.4 5586.4 5607.2 5610.9 5622.6 5642.2 4159.6 4160.6 4163.2 4170.3 4193.5 5672.9 5923.3 5928.2 5979.7 5990.3 4130.6 4165.2 4181.4 4188.0 4205.5 4232.9 4239.0 4251.7 4254.5 4276.3 4777.7 4794.8 4813.2 4899.4 4901.9 5698.2 5704.5 5706.0 5726.8 5742.6 1450.2 1465.7 1466.2 1468.3 1471.7 887.1 943.7 950.0 951.0 958.4 5090.8 5104.3 5105.3 5107.6 5120.5 5272.6 5276.3 5280.3 5292.3 5295.5 5068.7 5081.2 5133.6 5309.6 5705.3 3122.6 3135.6 3140.3 3145.4 3149.6 6024.3 6050.8 6112.2 6202.4 6203.3 5640.9 5909.8 5950.3 5956.2 5964.9 5933.9 6078.3 6160.2 6174.5 6175.8 5823.5 5840.8 5856.1 5872.0 5892.3 5574.8 5776.4 5776.5 5816.0 5831.6 6163.6 6187.2 6193.5 6201.7 6205.9 4021.8 4276.0 4283.9 4310.7 4314.1 5824.4 5842.2 5860.2 5861.4 5867.6 5725.9 5742.5 5744.4 5802.8 5803.9 5609.2 5682.3 5693.4 5726.1 5796.7 5610.4 5626.4 5630.2 5636.7 5648.9 5555.3 5725.2 5733.8 5756.5 5791.0 5453.7 5540.7 5549.9 5551.9 5561.8 13:44:17 INFO - PROCESS | 20459 | 13:44:17 INFO - PROCESS | 20459 | [#5] yui.html Cycles:180 Average:23129.16 Median:3662.47 stddev:33631.45 (918.3%) stddev-sans-first:33683.77 13:44:17 INFO - PROCESS | 20459 | Values: 737.3 787.4 804.1 818.9 901.0 15390.2 16354.9 16853.0 17974.6 18172.9 16241.3 16423.5 16424.0 16427.7 16544.4 737.8 858.5 879.8 882.1 888.8 880.4 888.5 888.9 889.0 889.2 917.3 918.1 918.2 918.4 919.1 759.4 888.2 911.2 912.8 914.4 676.1 676.1 676.2 676.5 677.0 14746.3 14765.9 14779.0 14783.2 14804.5 15241.8 15714.2 15788.5 16121.3 16136.4 14041.2 14085.5 14142.0 14338.1 14341.0 4677.3 4684.5 4687.8 4730.1 4757.3 89963.6 90291.1 92496.5 93678.5 94288.0 12521.4 12537.9 12592.2 12634.9 12930.0 24022.4 24049.4 24077.9 24105.4 24124.3 8857.6 8953.8 9221.5 9411.9 9456.7 4855.8 4877.5 4890.4 4892.5 5072.4 3520.5 3660.5 3664.4 3664.6 3667.2 672.2 673.8 679.5 694.4 716.1 774.8 798.0 798.3 809.1 811.8 10480.7 10639.2 10686.5 11151.8 11270.2 687.7 703.4 790.9 796.4 796.9 4883.4 5073.0 5329.5 5381.8 5394.6 76331.0 79033.0 127665.4 135367.0 136545.3 111777.0 113892.0 114908.4 115578.4 116734.4 96903.0 98023.0 99481.0 99611.5 101736.0 10069.1 10113.5 10175.4 10176.8 10258.7 60682.7 60974.0 61771.0 62147.4 62623.0 84050.6 86729.0 87161.6 87238.0 94542.5 12745.5 13127.3 13252.5 13464.7 13729.2 13575.5 13912.7 14013.8 14655.6 14756.6 10719.8 11075.1 13078.5 13207.0 14093.4 15278.6 15358.8 15427.8 15525.3 16181.3 14184.3 14565.9 15240.2 15460.0 15463.8 11854.5 12052.9 12100.8 12787.3 13024.8 12404.9 12475.6 12669.2 12770.9 12977.9 13:44:17 INFO - PROCESS | 20459 | -------- Summary: end -------- 13:44:17 INFO - PROCESS | 20459 | 13:44:18 INFO - TEST-INFO | 20459: exit 0 13:44:18 INFO - TEST-OK | dromaeo_css | took 1114174ms 13:44:18 INFO - TEST-START | kraken 13:44:18 INFO - operating with platform_type : linux_ 13:44:18 INFO - Initialising browser for kraken test... 13:44:18 INFO - TEST-INFO | started process 20664 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6jW1az/profile http://localhost:60030/getInfo.html) 13:44:22 INFO - PROCESS | 20664 | __metrics Screen width/height:1600/1200 13:44:22 INFO - PROCESS | 20664 | colorDepth:24 13:44:22 INFO - PROCESS | 20664 | Browser inner width/height: 1024/697 13:44:22 INFO - PROCESS | 20664 | __metrics 13:44:23 INFO - PROCESS | 20664 | 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" 13:44:23 INFO - PROCESS | 20664 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:44:24 INFO - TEST-INFO | 20664: exit 0 13:44:24 INFO - Browser initialized. 13:44:24 INFO - Running cycle 1/1 for kraken test... 13:44:24 INFO - TEST-INFO | started process 20756 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6jW1az/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:44:36 INFO - PROCESS | 20756 | RSS: Main: 145272832 13:44:36 INFO - PROCESS | 20756 | 13:44:39 INFO - PROCESS | 20756 | Cycle 1(1): loaded http://localhost:60030/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60030/tests/kraken/driver.html?testName=audio-beat-detection) 13:44:39 INFO - PROCESS | 20756 | RSS: Main: 191471616 13:44:39 INFO - PROCESS | 20756 | 13:44:42 INFO - PROCESS | 20756 | Cycle 1(1): loaded http://localhost:60030/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60030/tests/kraken/driver.html?testName=audio-dft) 13:44:42 INFO - PROCESS | 20756 | RSS: Main: 182087680 13:44:42 INFO - PROCESS | 20756 | 13:44:45 INFO - PROCESS | 20756 | Cycle 1(1): loaded http://localhost:60030/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60030/tests/kraken/driver.html?testName=audio-fft) 13:44:46 INFO - PROCESS | 20756 | RSS: Main: 270475264 13:44:46 INFO - PROCESS | 20756 | 13:44:48 INFO - PROCESS | 20756 | Cycle 1(1): loaded http://localhost:60030/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60030/tests/kraken/driver.html?testName=audio-oscillator) 13:44:49 INFO - PROCESS | 20756 | RSS: Main: 175124480 13:44:49 INFO - PROCESS | 20756 | 13:44:51 INFO - PROCESS | 20756 | Cycle 1(1): loaded http://localhost:60030/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60030/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:44:51 INFO - PROCESS | 20756 | RSS: Main: 176857088 13:44:51 INFO - PROCESS | 20756 | 13:44:54 INFO - PROCESS | 20756 | Cycle 1(1): loaded http://localhost:60030/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60030/tests/kraken/driver.html?testName=imaging-darkroom) 13:44:55 INFO - PROCESS | 20756 | RSS: Main: 212779008 13:44:55 INFO - PROCESS | 20756 | 13:44:58 INFO - PROCESS | 20756 | Cycle 1(1): loaded http://localhost:60030/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60030/tests/kraken/driver.html?testName=imaging-desaturate) 13:44:59 INFO - PROCESS | 20756 | RSS: Main: 212226048 13:44:59 INFO - PROCESS | 20756 | 13:45:02 INFO - PROCESS | 20756 | Cycle 1(1): loaded http://localhost:60030/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60030/tests/kraken/driver.html?testName=json-parse-financial) 13:45:02 INFO - PROCESS | 20756 | RSS: Main: 211783680 13:45:02 INFO - PROCESS | 20756 | 13:45:04 INFO - PROCESS | 20756 | Cycle 1(1): loaded http://localhost:60030/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60030/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:45:04 INFO - PROCESS | 20756 | RSS: Main: 175869952 13:45:04 INFO - PROCESS | 20756 | 13:45:06 INFO - PROCESS | 20756 | Cycle 1(1): loaded http://localhost:60030/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60030/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:45:07 INFO - PROCESS | 20756 | RSS: Main: 181690368 13:45:07 INFO - PROCESS | 20756 | 13:45:09 INFO - PROCESS | 20756 | Cycle 1(1): loaded http://localhost:60030/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60030/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:45:09 INFO - PROCESS | 20756 | RSS: Main: 186044416 13:45:09 INFO - PROCESS | 20756 | 13:45:12 INFO - PROCESS | 20756 | Cycle 1(1): loaded http://localhost:60030/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60030/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:45:12 INFO - PROCESS | 20756 | RSS: Main: 180715520 13:45:12 INFO - PROCESS | 20756 | 13:45:15 INFO - PROCESS | 20756 | Cycle 1(1): loaded http://localhost:60030/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60030/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:45:16 INFO - PROCESS | 20756 | RSS: Main: 184397824 13:45:16 INFO - PROCESS | 20756 | 13:45:17 INFO - PROCESS | 20756 | Cycle 1(1): loaded http://localhost:60030/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60030/tests/kraken/driver.html?testName=ai-astar) 13:45:18 INFO - PROCESS | 20756 | RSS: Main: 235667456 13:45:18 INFO - PROCESS | 20756 | 13:45:18 INFO - PROCESS | 20756 | __start_tp_report 13:45:18 INFO - PROCESS | 20756 | _x_x_mozilla_page_load 13:45:18 INFO - PROCESS | 20756 | _x_x_mozilla_page_load_details 13:45:18 INFO - PROCESS | 20756 | |i|pagename|runs| 13:45:18 INFO - PROCESS | 20756 | |0;ai-astar;100;110;110;110;110;109;111;110;110;108 13:45:18 INFO - PROCESS | 20756 | |1;audio-beat-detection;157;115;117;157;116;121;140;115;154;115 13:45:18 INFO - PROCESS | 20756 | |2;audio-dft;161;160;161;160;161;161;162;160;161;160 13:45:18 INFO - PROCESS | 20756 | |3;audio-fft;75;75;76;111;76;75;75;76;98;74 13:45:18 INFO - PROCESS | 20756 | |4;audio-oscillator;83;83;138;84;83;84;123;83;83;139 13:45:18 INFO - PROCESS | 20756 | |5;imaging-gaussian-blur;116;118;118;118;117;117;117;118;117;118 13:45:18 INFO - PROCESS | 20756 | |6;imaging-darkroom;145;146;146;145;147;145;146;146;146;146 13:45:18 INFO - PROCESS | 20756 | |7;imaging-desaturate;95;94;95;95;95;95;95;95;95;94 13:45:18 INFO - PROCESS | 20756 | |8;json-parse-financial;51;50;51;50;50;50;51;50;53;50 13:45:18 INFO - PROCESS | 20756 | |9;json-stringify-tinderbox;73;59;59;59;59;60;59;59;60;59 13:45:18 INFO - PROCESS | 20756 | |10;stanford-crypto-aes;72;72;73;72;71;72;72;71;81;71 13:45:18 INFO - PROCESS | 20756 | |11;stanford-crypto-ccm;122;126;124;126;120;121;115;116;158;135 13:45:18 INFO - PROCESS | 20756 | |12;stanford-crypto-pbkdf2;154;153;157;154;156;153;156;156;153;153 13:45:18 INFO - PROCESS | 20756 | |13;stanford-crypto-sha256-iterative;64;74;63;63;62;62;63;65;62;62 13:45:18 INFO - PROCESS | 20756 | __end_tp_report 13:45:18 INFO - PROCESS | 20756 | __start_cc_report 13:45:18 INFO - PROCESS | 20756 | _x_x_mozilla_cycle_collect,1628 13:45:18 INFO - PROCESS | 20756 | __end_cc_report 13:45:18 INFO - PROCESS | 20756 | __startTimestamp1472589918086__endTimestamp 13:45:18 INFO - PROCESS | 20756 | 13:45:18 INFO - PROCESS | 20756 | ------- Summary: start ------- 13:45:18 INFO - PROCESS | 20756 | Number of tests: 14 13:45:18 INFO - PROCESS | 20756 | 13:45:18 INFO - PROCESS | 20756 | [#0] ai-astar Cycles:10 Average:108.80 Median:110.00 stddev:3.19 (2.9%) stddev-sans-first:0.83 13:45:18 INFO - PROCESS | 20756 | Values: 100.0 110.0 110.0 110.0 110.0 109.0 111.0 110.0 110.0 108.0 13:45:18 INFO - PROCESS | 20756 | 13:45:18 INFO - PROCESS | 20756 | [#1] audio-beat-detection Cycles:10 Average:130.70 Median:130.50 stddev:19.00 (14.6%) stddev-sans-first:17.61 13:45:18 INFO - PROCESS | 20756 | Values: 157.0 115.0 117.0 157.0 116.0 121.0 140.0 115.0 154.0 115.0 13:45:18 INFO - PROCESS | 20756 | 13:45:18 INFO - PROCESS | 20756 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 13:45:18 INFO - PROCESS | 20756 | Values: 161.0 160.0 161.0 160.0 161.0 161.0 162.0 160.0 161.0 160.0 13:45:18 INFO - PROCESS | 20756 | 13:45:18 INFO - PROCESS | 20756 | [#3] audio-fft Cycles:10 Average:81.10 Median:75.50 stddev:12.72 (16.9%) stddev-sans-first:13.30 13:45:18 INFO - PROCESS | 20756 | Values: 75.0 75.0 76.0 111.0 76.0 75.0 75.0 76.0 98.0 74.0 13:45:18 INFO - PROCESS | 20756 | 13:45:18 INFO - PROCESS | 20756 | [#4] audio-oscillator Cycles:10 Average:98.30 Median:83.00 stddev:24.54 (29.6%) stddev-sans-first:25.40 13:45:18 INFO - PROCESS | 20756 | Values: 83.0 83.0 138.0 84.0 83.0 84.0 123.0 83.0 83.0 139.0 13:45:18 INFO - PROCESS | 20756 | 13:45:18 INFO - PROCESS | 20756 | [#5] imaging-gaussian-blur Cycles:10 Average:117.40 Median:118.00 stddev:0.70 (0.6%) stddev-sans-first:0.53 13:45:18 INFO - PROCESS | 20756 | Values: 116.0 118.0 118.0 118.0 117.0 117.0 117.0 118.0 117.0 118.0 13:45:18 INFO - PROCESS | 20756 | 13:45:18 INFO - PROCESS | 20756 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.60 13:45:18 INFO - PROCESS | 20756 | Values: 145.0 146.0 146.0 145.0 147.0 145.0 146.0 146.0 146.0 146.0 13:45:18 INFO - PROCESS | 20756 | 13:45:18 INFO - PROCESS | 20756 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 13:45:18 INFO - PROCESS | 20756 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 94.0 13:45:18 INFO - PROCESS | 20756 | 13:45:18 INFO - PROCESS | 20756 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:50.50 stddev:0.97 (1.9%) stddev-sans-first:1.01 13:45:18 INFO - PROCESS | 20756 | Values: 51.0 50.0 51.0 50.0 50.0 50.0 51.0 50.0 53.0 50.0 13:45:18 INFO - PROCESS | 20756 | 13:45:18 INFO - PROCESS | 20756 | [#9] json-stringify-tinderbox Cycles:10 Average:60.60 Median:59.00 stddev:4.38 (7.4%) stddev-sans-first:0.44 13:45:18 INFO - PROCESS | 20756 | Values: 73.0 59.0 59.0 59.0 59.0 60.0 59.0 59.0 60.0 59.0 13:45:18 INFO - PROCESS | 20756 | 13:45:18 INFO - PROCESS | 20756 | [#10] stanford-crypto-aes Cycles:10 Average:72.70 Median:72.00 stddev:2.98 (4.1%) stddev-sans-first:3.15 13:45:18 INFO - PROCESS | 20756 | Values: 72.0 72.0 73.0 72.0 71.0 72.0 72.0 71.0 81.0 71.0 13:45:18 INFO - PROCESS | 20756 | 13:45:18 INFO - PROCESS | 20756 | [#11] stanford-crypto-ccm Cycles:10 Average:126.30 Median:125.00 stddev:12.50 (10.0%) stddev-sans-first:13.16 13:45:18 INFO - PROCESS | 20756 | Values: 122.0 126.0 124.0 126.0 120.0 121.0 115.0 116.0 158.0 135.0 13:45:18 INFO - PROCESS | 20756 | 13:45:18 INFO - PROCESS | 20756 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.50 Median:155.00 stddev:1.58 (1.0%) stddev-sans-first:1.67 13:45:18 INFO - PROCESS | 20756 | Values: 154.0 153.0 157.0 154.0 156.0 153.0 156.0 156.0 153.0 153.0 13:45:18 INFO - PROCESS | 20756 | 13:45:18 INFO - PROCESS | 20756 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.00 Median:63.00 stddev:3.65 (5.8%) stddev-sans-first:3.87 13:45:18 INFO - PROCESS | 20756 | Values: 64.0 74.0 63.0 63.0 62.0 62.0 63.0 65.0 62.0 62.0 13:45:18 INFO - PROCESS | 20756 | -------- Summary: end -------- 13:45:18 INFO - PROCESS | 20756 | 13:45:19 INFO - TEST-INFO | 20756: exit 0 13:45:19 INFO - TEST-OK | kraken | took 60611ms 13:45:19 INFO - SUITE-END | took 1174s 13:45:19 INFO - Completed test suite (00:19:34) 13:45:19 INFO - javascript benchmark 13:45:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3217.9195520111994, 3228.257500774822, 3232.9654510556616, 3251.67483251675, 3271.6728327167284, 5445.156000819869, 5507.008738427086, 5544.085225937725, 5550.056490396632, 5558.555315574755, 5501.532369748566, 5552.944470555293, 5561.332640083186, 5604.607677462579, 5607.775688972436, 4967.279744437073, 5029.170186919163, 5047.167217409123, 5103.2855400244, 5129.025485157808, 4968.478309777475, 5091.719955402451, 5129.358830146231, 5151.871203219931, 5152.201408781626, 5061.0, 5084.898302033957, 5101.15830887903, 5110.28456016158, 5155.76799044042, 5172.612054095939, 5193.896122077537, 5205.245239440298, 5212.765425555859, 5236.266922630834, 4914.140025495524, 4990.4011518617635, 5023.648344615878, 5094.465081166498, 5098.923516147261, 5495.917561236584, 5541.196526503634, 5569.387367389584, 5593.74828132735, 5601.159826026088, 6058.39416058395, 6066.272047354346, 6067.848303792396, 6071.908921366183, 6073.848153796146, 5957.702114894281, 5986.880262394771, 6000.399960004008, 6071.058985857199, 6109.5723299369065, 5629.099344104923, 5684.857878553028, 5691.544676425876, 5691.573132015033, 5691.573132015115, 5871.3541510433815, 6142.078688196806, 6144.293406258337, 6154.476869466145, 6167.568270221132, 5761.251037365116, 5782.739776710058, 5801.506871915934, 5873.853153671193, 5876.353601103875, 5945.40545945402, 5970.462658360769, 5979.19280897082, 5985.371535988658, 5992.101184822224, 5764.365919748824, 5895.322037965603, 5907.556933230025, 5909.8227053188475, 5912.526997840162, 5924.31870334917, 5955.047192449274, 5967.104934259895, 6022.879542409128, 6052.54605904559, 4641.303804429363, 4641.466231383367, 4643.3963584733765, 4646.395968524071, 4672.883177920511, 4029.17401932609, 4128.38074288853, 4145.419641250227, 4149.771762553089, 4162.62536371728, 5729.9713501432225, 5761.9135712964335, 5764.106563482667, 5815.360310365856, 5845.590808643439, 6000.21997140369, 6041.9395806041375, 6046.093086037042, 6048.788292409745, 6072.210612620331, 5629.521490673257, 5787.276590426197, 5790.594658373958, 5795.246617939641, 5804.448577385142, 5038.79844806211, 5048.3184770056305, 5055.241713742968, 5095.490450954875, 5129.179331306973, 6267.843303917439, 6319.399657032574, 6326.557141000178, 6340.3342649022115, 6343.714532845941, 5813.215215945962, 5991.550633702577, 6005.849853753516, 6008.339082700812, 6012.278526576664, 6146.016637338004, 6262.686865656608, 6274.090256912631, 6322.462590679844, 6369.617822930639, 5933.465988061095, 5986.760529578768, 5990.251218597676, 5998.850028749316, 6032.849178770566, 6085.482733967485, 6094.39056094387, 6101.694915254131, 6105.145279660762, 6106.419890110433, 5746.798862039976, 5795.130730390475, 5845.561582881216, 5846.444587764156, 5931.436513531207, 5845.824625261249, 5850.3857095006115, 5859.296884373903, 5861.32594751609, 5868.3251426086545, 5763.366029736641, 5798.739056742373, 5815.534757219497, 5816.127580862904, 5823.272090988627, 5766.538676905921, 5790.507806836466, 5832.037713777179, 5833.591648584558, 5876.236089308533, 5692.715364231689, 5701.059325211294, 5703.144528320785, 5705.572082093777, 5706.629069110494], "value": 5532.260091133723, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [602.4728362682652, 605.5821483176608, 625.9968700156497, 671.1543455246388, 677.0352248046532, 35114.47328290075, 37892.43161352582, 38123.80938095309, 46149.769251153695, 47022.29466558004, 48520.51479485204, 48947.26579101316, 48971.51028489723, 48988.75505622467, 49049.0, 9231.630734770619, 9375.10936461035, 9389.154976052154, 9391.295652826031, 9395.295352848529, 8733.912660873373, 10994.725131871688, 11251.156163287746, 11255.606053788119, 11660.300381977066, 10931.344119352865, 11010.669679909575, 11022.559097636085, 11022.559097636125, 11025.173112016591, 8676.566171691453, 11224.438778061065, 11320.207585469021, 11343.943280283629, 11348.14888883333, 10757.677269681923, 11160.665180044612, 11163.944180279046, 11465.656030319104, 11491.655250342503, 45497.54502454966, 45907.77046114781, 45969.54030459686, 45978.31032534515, 46011.9294631739, 3628.328759179534, 3628.600853906068, 3759.436084587334, 3835.7314987950854, 3896.5713771485116, 35199.1200219994, 35296.82351588226, 35420.11449713752, 35421.82289088538, 35446.0, 4649.790759415834, 4692.1084993851055, 4729.385179926622, 4730.716157030589, 4838.153323168432, 389842.0, 390646.0467697672, 391991.0, 394515.0, 396967.9999999971, 64288.35711642917, 64600.35399645943, 64691.67654161699, 64740.35259647343, 64906.35093649003, 39432.21135577272, 40141.1971760569, 40147.0, 66948.33051669519, 67302.32697673059, 19325.0, 19448.90275548613, 19461.318853840046, 19469.318573849847, 19481.0, 13797.586072417844, 15040.699186016438, 15126.470573530094, 15189.9240503799, 15228.695426091415, 17590.472285831445, 18117.36589219371, 18228.908855455902, 18237.270509179485, 18414.26342946294, 7329.926700733031, 7372.262773722692, 7516.549007059484, 7557.206493318233, 7563.281488258606, 993.3295025857661, 1052.163529993657, 1085.9565617375217, 1139.6524060161564, 1141.8173092305192, 34548.481772773426, 34921.82539087288, 34944.475832862525, 34981.82509087438, 35107.473387899205, 1129.249049382168, 1163.5636636261402, 1168.3048586091263, 1192.1952681939829, 1227.030645789816, 18123.0, 18173.363932262306, 18420.631587368447, 18427.170777315052, 18500.814991850177, 617834.0, 618092.0, 618686.0, 619028.0, 622125.0, 64509.67745161244, 64874.351256487775, 65007.67496162584, 65045.349546504876, 65056.0, 1205.3310412721023, 1419.8864090872705, 1428.0931608428723, 1452.6658868460102, 1456.2500312339155, 33944.0, 36320.636793631726, 36466.46329384786, 36486.817565912, 36629.450558242184, 45238.77380613208, 45295.773521132185, 45589.544104559856, 45646.31530526978, 45666.315005274286, 430594.0, 431421.0, 431990.0, 432437.0, 433126.0, 30091.0, 30911.0, 31321.8433907829, 32212.19469513281, 32475.35049299048, 44996.32505512486, 45069.09861802811, 45168.32247516224, 45227.321590176834, 45475.31787023263, 44468.33297500605, 44761.0, 44853.77573112244, 44875.775621121684, 46396.53603464057, 44107.55892441163, 44396.33405498985, 44598.55401445944, 44896.32655510235, 45884.0, 43523.78238108789, 44353.0, 44574.3313850286, 44701.32948005848, 44780.55219447894, 38232.42651360288, 40235.0, 40417.39373909335, 40486.59513404828, 40646.39030414487, 29353.0, 29575.0, 29833.85083074571, 29956.814081889526, 30068.849655751583], "value": 21659.0230460344, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.55697618667097, 412.14891249569655, 432.14219773749113, 433.8047878454695, 438.48697024481356, 15452.768208476868, 20249.898750506265, 20901.268455604037, 20935.790642093594, 21301.615394999353, 20200.29298974536, 20253.392398228076, 20525.897370513165, 20537.691934620994, 20605.587888242226, 5829.708514574254, 5852.151438041481, 5964.552658550604, 6000.0, 6016.729247183887, 5874.588778785486, 5942.227510423639, 5945.197398351211, 5949.6727679977585, 5950.523958083343, 5701.372848986629, 5865.296164460271, 5873.119032145191, 5881.705914704269, 5889.293284805829, 5764.971175144139, 5828.504577110908, 5868.9413105868825, 5914.2015827863115, 5915.290165180184, 5267.473252674702, 5278.181881808326, 5311.521963023308, 5316.6544174628525, 5318.25544423781, 22257.220997265016, 24207.152749653676, 24477.510449791083, 24536.386590335205, 24663.506729865483, 2381.5594115088593, 2482.379405148713, 2824.8841707525617, 3042.4827779277352, 3060.1890499017777, 10004.699859004242, 10046.296759226858, 10085.394876307371, 10359.32664376813, 10438.32150910196, 1903.9048047597703, 1929.21866644008, 1954.1206457094365, 1990.4725247809342, 2044.703517989898, 91527.54236228843, 91906.80594496714, 93050.0, 93618.0, 96099.51950240273, 11426.485808138654, 11436.142289328334, 11481.196316257781, 11520.481578328996, 11618.94190529042, 16884.324627015027, 16952.406665766637, 16968.74546881798, 16975.236114374882, 16976.83023169777, 8110.229528194812, 8309.6676132954, 8335.333173346118, 8454.830903381899, 8722.34582406322, 8121.634526446324, 8129.959350203329, 8132.959335203286, 8150.429469937166, 8277.95861020691, 5041.546260836542, 5053.0904437200925, 5055.848324550269, 5071.594272458195, 5201.08980928336, 1982.6332128556264, 2049.0, 2092.968605470919, 2106.926257580977, 2112.292382051999, 917.1928702741545, 965.5413678502796, 990.0891180114312, 1057.5241141486363, 1066.7013236293851, 18112.959654422622, 19712.802871971384, 20205.89897050505, 21196.15215391367, 21230.36308910735, 930.7068273493828, 988.3180605382407, 1023.6417253961054, 1031.1905154453718, 1032.6901929421144, 10716.73208169802, 11324.547018119347, 11357.488912998935, 11375.260608060615, 11527.827082593767, 496000.0, 497198.0, 504119.0, 570069.0, 570191.0, 94989.5250523743, 97551.51224243976, 98631.0, 99828.0, 100117.0, 1077.2943721862193, 1108.778244351133, 1117.9552817887193, 1130.5534313945946, 1136.534021051365, 17761.200745966205, 17784.288628454717, 18036.729449058537, 18552.165152567897, 18766.155523001224, 42492.36261456019, 44789.32816007828, 45138.77430612826, 46069.0, 47185.52814471949, 55103.44896550983, 57778.0, 58862.0, 60058.69970650119, 60703.69648151731, 8493.186010989655, 8541.957290213757, 8841.911580884365, 8878.37851350425, 8900.688475903313, 11440.713982150513, 12710.237385756884, 12986.610401687965, 13387.732245354844, 13668.794968075288, 11511.53953841837, 11579.13156513248, 11889.82165267539, 11907.226030308002, 12507.437165327765, 10862.619808306672, 11361.659150225507, 11844.407779610812, 11960.0, 11978.401079945794, 11251.549938002716, 11805.82291265649, 11879.643610691694, 12202.572909948109, 12524.874751252735, 7166.928330716834, 7482.401407887247, 8465.746027619181, 8657.0, 8884.689035883714, 10898.801131875329, 11423.714407139889, 11523.596674116367, 11526.538938442369, 11596.304221746723], "value": 9567.46104242431, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1396.0646366934154, 1454.2946670864633, 1519.1720512320785, 1656.271240654113, 1829.926802927883, 8254.215849494296, 8617.13828617138, 8783.077776833436, 8814.074522175175, 9054.139856713618, 8951.686690965818, 9012.81974360514, 9020.594073266702, 9027.95486022569, 9034.54827258636, 2424.8423852449623, 2444.0712529238954, 2452.2275483222816, 2454.3864033991504, 2455.054803900502, 2439.0975339124548, 2442.426029882976, 2498.850068995857, 2499.200255918107, 2502.4244423782543, 2417.516496700658, 2434.4887573609562, 2455.7175924768703, 2456.8648724320246, 2460.434100156965, 2399.448126930807, 2404.1224952892157, 2405.7473965233576, 2406.362313986795, 2442.3405680466353, 2336.33414476875, 2337.836351455399, 2341.8946147423408, 2342.7422983471806, 2343.8124950003958, 9065.546722663907, 9291.070892910722, 9301.116393942564, 9399.060093990614, 9411.341206115507, 5593.188987596775, 5731.627444216113, 5734.455226753452, 5739.225204597409, 5744.913826292609, 3614.6927511161584, 3686.6681998619983, 3704.0, 3746.812659367021, 3747.4191500317493, 1248.719038216403, 1249.8312727781724, 1250.6560695808635, 1273.8535068467152, 1274.9536993125992, 13501.729965400735, 13633.522826701017, 13638.0, 13644.658883527893, 13658.180509169482, 4542.409486766699, 4671.906561868812, 4784.425868895685, 4837.95162048382, 4852.199387101159, 10103.343282686601, 10110.039546243095, 10111.0, 10129.0, 10143.492825358702, 5565.1095824667855, 5582.553395728332, 5586.1062230043, 5588.636738611977, 5591.329040515164, 4464.3973063635995, 4596.563326483979, 4997.175466048061, 5094.083065048327, 5204.115300398941, 4026.335654617014, 4085.8978525536504, 4173.0, 4188.183304255641, 4195.0, 1211.3156066822216, 1231.2735486063266, 1235.8516977962702, 1239.8016317389174, 1249.1131296779388, 1063.2716589136467, 1063.7340664833791, 1065.5577935156823, 1065.7122576904192, 1066.8453074304182, 6467.32093130224, 6472.935270647327, 6624.238212605515, 6692.364225398579, 6696.8995465068065, 479.84405068352925, 487.4443134826301, 867.2324992381662, 869.1395518436702, 1005.5776573839005, 4368.148211098805, 4438.378626992224, 4450.265706158512, 4460.7323560586465, 4489.753063581469, 11864.22882512634, 12242.204256723284, 12300.507979680713, 12313.322767247888, 12313.938430307791, 12188.085893558018, 12206.938965305295, 12243.449044793006, 12243.632691019106, 12251.877481225252, 8429.32565394767, 8800.823983520293, 8801.691940782042, 8940.5082720451, 8964.68623598184, 6482.513811464253, 6495.772647957488, 6511.0, 6551.639659818756, 6556.311587283267, 11074.501647426046, 11103.722406939569, 11153.442327883735, 11179.888201117885, 11214.775704486028, 11108.500117494894, 11177.608783692533, 11419.0864730823, 11500.0, 11601.535938562363, 7715.614219289126, 8218.21926916942, 8225.629846657035, 8344.290735287568, 8361.289290410139, 9589.42463452195, 9686.515674216402, 9694.806103878023, 9696.806063878541, 9701.0, 8293.95853020731, 8339.45793523419, 8373.748787536384, 8417.158284171534, 8497.74506764798, 8138.755837324889, 8219.095899451175, 8402.537860417735, 8429.662813487637, 8622.913770862462, 7503.737369192052, 7834.138244793182, 7884.053913530183, 7890.48711833729, 7926.088499822364, 5713.0, 5821.09772985188, 5897.587168898308, 5923.141144534105, 5937.376575459514, 5823.126531020381, 5839.5036421904615, 5866.208061911758, 5943.167956486009, 5994.250718660168], "value": 4928.303023916572, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.1116014660782, 335.9412102881995, 336.1981673708206, 339.5161894300621, 342.9485577163426, 5424.8535849207465, 5508.950921073013, 5522.254495643087, 5534.806281780138, 5537.55699544036, 5395.865103372408, 5421.156806685726, 5440.3743569489525, 5446.863828404292, 5456.9181462278, 3859.4982652255167, 4075.877723668287, 4158.878971724427, 4196.712791528609, 4256.914861702764, 4220.029393239542, 4227.32362821947, 4233.74597524148, 4235.2800023996, 4239.236937351276, 4221.514525829522, 4248.660107191417, 4252.532221455637, 4261.552536983619, 4266.274733295331, 4096.692748043879, 4116.711830171889, 4230.936535951963, 4287.442632457776, 4298.0, 3714.0900479382726, 3731.645493678096, 3743.625637436262, 3762.6425489578446, 3784.6593806557544, 5266.3943646480375, 5286.81496147633, 5287.391949925731, 5353.411124776271, 5393.568514518829, 4801.759912004386, 4869.196582563908, 4901.926471102936, 4920.212765957465, 4934.358533390637, 5586.413426590231, 5607.214989901417, 5610.915836262459, 5622.606417550773, 5642.238297829823, 4159.604837540429, 4160.563140870226, 4163.167366526707, 4170.270202714512, 4193.51774545928, 5672.9432705672825, 5923.348431672513, 5928.170056192136, 5979.671118088547, 5990.281166260033, 4130.607592278729, 4165.166966606691, 4181.351890456923, 4187.958120418818, 4205.516365617932, 4232.873013809591, 4238.978805106017, 4251.702380833374, 4254.532001479834, 4276.337167739005, 4777.737224452689, 4794.832180873653, 4813.181759100961, 4899.436564795023, 4901.926471102936, 5698.17376480408, 5704.486596206361, 5705.971470142623, 5726.770929162787, 5742.569307301969, 1450.2386247220293, 1465.677550938813, 1466.2302291297153, 1468.302556285777, 1471.6681403330003, 887.0558739940367, 943.7165454981221, 949.9810003799886, 951.0299494515556, 958.4297343080856, 5090.847274581768, 5104.310918026019, 5105.33630628016, 5107.6169287302855, 5120.539151476385, 5272.578193744491, 5276.314079169761, 5280.287161233261, 5292.311999440125, 5295.470452954673, 5068.746562671851, 5081.212412076134, 5133.563647089964, 5309.5752339812725, 5705.343885453143, 3122.625284965773, 3135.5576434840227, 3140.3248301615263, 3145.3552021835926, 3149.6062992125985, 6024.256701147855, 6050.848728781815, 6112.174856394418, 6202.379762023852, 6203.325102222375, 5640.887182256414, 5909.8227053188475, 5950.256217972754, 5956.195913551702, 5964.850878728066, 5933.9406605934255, 6078.331383547807, 6160.229971253593, 6174.506039516918, 6175.752969881154, 5823.534117270693, 5840.802635459806, 5856.12158176277, 5871.970640146772, 5892.263467066617, 5574.804881829116, 5776.364599894092, 5776.508996735324, 5816.0, 5831.591788574929, 6163.599366041193, 6187.20571034413, 6193.504519638328, 6201.658908760061, 6205.906911396423, 4021.798910054544, 4275.957240427556, 4283.886189590667, 4310.698251122391, 4314.137172565437, 5824.417558244311, 5842.152887831326, 5860.150278209721, 5861.3845546217035, 5867.589268751147, 5725.942740572707, 5742.5405967521665, 5744.3681195067675, 5802.767889284381, 5803.8549036274435, 5609.158626206102, 5682.261306030189, 5693.373728889902, 5726.134858970096, 5796.652200867961, 5610.43895610452, 5626.352969408406, 5630.18362337467, 5636.718164091697, 5648.85877853057, 5555.2778138841695, 5725.198472213977, 5733.827985160452, 5756.539476841926, 5791.0, 5453.700511190673, 5540.667559946416, 5549.861253468695, 5551.944480555143, 5561.805336813192], "value": 4333.591726065861, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.310614575372, 787.3622365883631, 804.0632662947667, 818.8730746734254, 900.9594568244428, 15390.15354155521, 16354.936929099615, 16853.0, 17974.640507189848, 18172.909135454338, 16241.269142888568, 16423.507294781146, 16424.0, 16427.67144657106, 16544.420945266917, 737.8118579762153, 858.5363903492107, 879.7800549862518, 882.1046637662778, 888.7866911941152, 880.4057261348596, 888.5424006636588, 888.866669999502, 888.9865553269278, 889.168627333442, 917.3165991336464, 918.0544039639149, 918.1919910478803, 918.3892711346946, 919.117647058821, 759.3848982324307, 888.1562515610132, 911.1799380557518, 912.7626817027555, 914.4421902639384, 676.100785954679, 676.1446769836161, 676.2155899156954, 676.4858707382375, 676.9627670478121, 14746.336414861249, 14765.926170369186, 14779.0, 14783.186924719135, 14804.481843135547, 15241.847581524155, 15714.214289285605, 15788.526344209693, 16121.343013968504, 16136.435224767078, 14041.157530548098, 14085.507007254799, 14142.0, 14338.139711617336, 14341.0, 4677.321788340706, 4684.46128695201, 4687.789049492781, 4730.0776348612035, 4757.286407038937, 89963.55018224932, 90291.09708902959, 92496.53751731265, 93678.53160734221, 94288.0, 12521.436535355931, 12537.93731031339, 12592.244465332109, 12634.87365126337, 12930.0, 24022.39944001414, 24049.398765031016, 24077.879610601834, 24105.397365065663, 24124.27627171188, 8857.64569417216, 8953.776155596031, 9221.53892305382, 9411.858822117674, 9456.67669887532, 4855.8057677693205, 4877.4908372992895, 4890.364252647204, 4892.510748925079, 5072.442031376546, 3520.471929210639, 3660.5424321959754, 3664.3953747631344, 3664.59689434162, 3667.156553992597, 672.1732269308679, 673.8248055505604, 679.4598294355999, 694.4236283884364, 716.0941409117471, 774.8256642255448, 797.9807013882613, 798.3214267872262, 809.0938149272852, 811.7808191788303, 10480.68557943263, 10639.202059845542, 10686.51910664022, 11151.776964460665, 11270.211085223955, 687.6596084937888, 703.4442790195791, 790.9288164065262, 796.4066770256196, 796.9481983671043, 4883.438404583448, 5073.010078565185, 5329.520343169133, 5381.78472861089, 5394.622376433612, 76331.0, 79033.0, 127665.36167319104, 135367.0, 136545.317273413, 111777.0, 113892.0, 114908.42545787385, 115578.4221078906, 116734.41632791782, 96903.0, 98023.0, 99481.0, 99611.50194249128, 101736.0, 10069.093781559695, 10113.494325283853, 10175.389476631426, 10176.847347289648, 10258.692239232834, 60682.69658651679, 60974.0, 61771.0, 62147.378526215965, 62623.0, 84050.57974710331, 86729.0, 87161.56419217863, 87238.0, 94542.52728736312, 12745.49018039268, 13127.343632818513, 13252.469901203844, 13464.663383415493, 13729.176249425067, 13575.52485662997, 13912.732297201444, 14013.75013106052, 14655.633609159857, 14756.557303280919, 10719.785604288027, 11075.05862001739, 13078.476860925457, 13207.0, 14093.436262549792, 15278.618034549225, 15358.769618455955, 15427.84572154264, 15525.301361438536, 16181.271842767339, 14184.29078546048, 14565.92717036408, 15240.161791101596, 15460.0, 15463.768043479582, 11854.52581896749, 12052.879471205175, 12100.75798484043, 12787.296698681661, 13024.804627930778, 12404.875951240372, 12475.625731228078, 12669.239845609292, 12770.936145319214, 12977.935110324388], "value": 8237.318006446254, "unit": "score"}], "name": "dromaeo_css", "value": 7658.064491854656, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 110.0, 110.0, 110.0, 110.0, 109.0, 111.0, 110.0, 110.0, 108.0], "value": 108.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 115.0, 117.0, 157.0, 116.0, 121.0, 140.0, 115.0, 154.0, 115.0], "value": 130.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 160.0, 161.0, 161.0, 162.0, 160.0, 161.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 111.0, 76.0, 75.0, 75.0, 76.0, 98.0, 74.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 138.0, 84.0, 83.0, 84.0, 123.0, 83.0, 83.0, 139.0], "value": 98.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 118.0, 118.0, 118.0, 117.0, 117.0, 117.0, 118.0, 117.0, 118.0], "value": 117.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 145.0, 147.0, 145.0, 146.0, 146.0, 146.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 51.0, 50.0, 50.0, 50.0, 51.0, 50.0, 53.0, 50.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 59.0, 59.0, 59.0, 59.0, 60.0, 59.0, 59.0, 60.0, 59.0], "value": 60.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 73.0, 72.0, 71.0, 72.0, 72.0, 71.0, 81.0, 71.0], "value": 72.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 126.0, 124.0, 126.0, 120.0, 121.0, 115.0, 116.0, 158.0, 135.0], "value": 126.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 153.0, 157.0, 154.0, 156.0, 153.0, 156.0, 156.0, 153.0, 153.0], "value": 154.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 74.0, 63.0, 63.0, 62.0, 62.0, 63.0, 65.0, 62.0, 62.0], "value": 64.0, "unit": "score"}], "name": "kraken", "value": 1466.3, "alertThreshold": 2.0}]} 13:45:19 INFO - Return code: 0 13:45:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:45:19 INFO - # TBPL SUCCESS # 13:45:19 INFO - Running post-action listener: _resource_record_post_action 13:45:19 INFO - [mozharness: 2016-08-30 20:45:19.378030Z] Finished run-tests step (success) 13:45:19 INFO - Running post-run listener: _resource_record_post_run 13:45:19 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 266240; Write bytes: 326955008; Read time: 44; Write time: 416044 13:45:19 INFO - TinderboxPrint: CPU usage
13.0% 13:45:19 INFO - TinderboxPrint: I/O read bytes / time
266,240 / 44 13:45:19 INFO - TinderboxPrint: I/O write bytes / time
326,955,008 / 416,044 13:45:19 INFO - TinderboxPrint: CPU idle
8,279.7 (87.0%) 13:45:19 INFO - TinderboxPrint: CPU user
1,183.0 (12.4%) 13:45:19 INFO - TinderboxPrint: Swap in / out
0 / 0 13:45:19 INFO - install - Wall time: 18s; CPU: 13.0%; Read bytes: 0; Write bytes: 78647296; Read time: 0; Write time: 94676 13:45:19 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 262144; Write bytes: 247693312; Read time: 28; Write time: 321360 13:45:20 INFO - Running post-run listener: _upload_blobber_files 13:45:20 INFO - Blob upload gear active. 13:45:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:45:20 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 13:45:20 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'] 13:45:20 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 13:45:20 INFO - (blobuploader) - INFO - Open directory for files ... 13:45:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:45:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:45:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:45:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:45:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:45:25 INFO - (blobuploader) - INFO - Done attempting. 13:45:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:45:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:45:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:45:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:45:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:45:26 INFO - (blobuploader) - INFO - Done attempting. 13:45:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:45:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:45:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:45:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:45:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:45:26 INFO - (blobuploader) - INFO - Done attempting. 13:45:26 INFO - (blobuploader) - INFO - Iteration through files over. 13:45:26 INFO - Return code: 0 13:45:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:45:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:45:26 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0000195d38a91e3891891387e23c9db23b99138aa5ac3e95795b9cf7829428e6e1672e8fd3775c43fc343821f2aaa8c72eda106399fac42b26e3fedd468d2774", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/576f641ebc2f09d5efe703ced72711048a046e0bc6c61f87d29680813dae8a89adeb155304b2781237f7a13200453f0e0f543b7781a7f354b36200f62d085c1e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ce11af51da81b0fad70824db021cea1483f14a7a283f77c56bc435726eeb85beca45f7d15ded793aeb96bb6ac0145dbc754355e169fd36b76717229a878f6ad3"} 13:45:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:45:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:45:26 INFO - Contents: 13:45:26 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0000195d38a91e3891891387e23c9db23b99138aa5ac3e95795b9cf7829428e6e1672e8fd3775c43fc343821f2aaa8c72eda106399fac42b26e3fedd468d2774", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/576f641ebc2f09d5efe703ced72711048a046e0bc6c61f87d29680813dae8a89adeb155304b2781237f7a13200453f0e0f543b7781a7f354b36200f62d085c1e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ce11af51da81b0fad70824db021cea1483f14a7a283f77c56bc435726eeb85beca45f7d15ded793aeb96bb6ac0145dbc754355e169fd36b76717229a878f6ad3"} 13:45:26 INFO - Running post-run listener: copy_logs_to_upload_dir 13:45:26 INFO - Copying logs to upload dir... 13:45:26 INFO - mkdir: /builds/slave/test/build/upload/logs 13:45:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1681.181254 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 1 secs) (at 2016-08-30 13:45:26.566124) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 13:45:26.569491) ========= 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=745a4373a2a16df560f216e000000264-1472588091.610660-570207028 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/0000195d38a91e3891891387e23c9db23b99138aa5ac3e95795b9cf7829428e6e1672e8fd3775c43fc343821f2aaa8c72eda106399fac42b26e3fedd468d2774", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/576f641ebc2f09d5efe703ced72711048a046e0bc6c61f87d29680813dae8a89adeb155304b2781237f7a13200453f0e0f543b7781a7f354b36200f62d085c1e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ce11af51da81b0fad70824db021cea1483f14a7a283f77c56bc435726eeb85beca45f7d15ded793aeb96bb6ac0145dbc754355e169fd36b76717229a878f6ad3"} build_url:https://queue.taskcluster.net/v1/task/Nte9js__SDyn3Q_hUVpIMA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011305 build_url: 'https://queue.taskcluster.net/v1/task/Nte9js__SDyn3Q_hUVpIMA/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/0000195d38a91e3891891387e23c9db23b99138aa5ac3e95795b9cf7829428e6e1672e8fd3775c43fc343821f2aaa8c72eda106399fac42b26e3fedd468d2774", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/576f641ebc2f09d5efe703ced72711048a046e0bc6c61f87d29680813dae8a89adeb155304b2781237f7a13200453f0e0f543b7781a7f354b36200f62d085c1e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ce11af51da81b0fad70824db021cea1483f14a7a283f77c56bc435726eeb85beca45f7d15ded793aeb96bb6ac0145dbc754355e169fd36b76717229a878f6ad3"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 13:45:26.595550) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 13:45:26.595890) ========= 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=745a4373a2a16df560f216e000000264-1472588091.610660-570207028 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004452 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 13:45:26.679991) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 13:45:26.680366) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 13:45:26.680669) ========= ========= Total master_lag: 0.17 =========