builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-052 starttime: 1476135940.56 results: success (0) buildid: 20161010125124 builduid: f407af9dce9f40ba8e09920a26980d4e revision: 109ee76d7cdec816e0f93411678b034eec8116a2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-10 14:45:40.557079) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-10 14:45:40.557520) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-10 14:45:40.557805) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-10 14:45:40.572252) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 14:45:40.572520) ========= 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=826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-10-10 14:45:42-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 218M=0s 2016-10-10 14:45:42 (218 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.086976 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 14:45:40.673063) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 14:45:40.673434) ========= 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=826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014747 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 14:45:40.722586) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 14:45:40.722940) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 109ee76d7cdec816e0f93411678b034eec8116a2 --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-beta --rev 109ee76d7cdec816e0f93411678b034eec8116a2 --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=826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-10-10 14:45:43,016 truncating revision to first 12 chars 2016-10-10 14:45:43,016 Setting DEBUG logging. 2016-10-10 14:45:43,016 attempt 1/10 2016-10-10 14:45:43,016 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/109ee76d7cde?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-10 14:45:43,314 unpacking tar archive at: mozilla-beta-109ee76d7cde/testing/mozharness/ program finished with exit code 0 elapsedTime=0.652542 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 14:45:41.388574) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-10 14:45:41.388902) ========= script_repo_revision: 109ee76d7cdec816e0f93411678b034eec8116a2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-10 14:45:41.389310) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-10 14:45:41.389577) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-10 14:45:41.399902) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 53 secs) (at 2016-10-10 14:45:41.400515) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:45:43 INFO - MultiFileLogger online at 20161010 14:45:43 in /builds/slave/test 14:45:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 14:45:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:45:43 INFO - {'append_to_log': False, 14:45:43 INFO - 'base_work_dir': '/builds/slave/test', 14:45:43 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:45:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:45:43 INFO - 'branch': 'Mozilla-Beta', 14:45:43 INFO - 'buildbot_json_path': 'buildprops.json', 14:45:43 INFO - 'config_files': ('talos/linux_config.py',), 14:45:43 INFO - 'default_actions': ('clobber', 14:45:43 INFO - 'read-buildbot-config', 14:45:43 INFO - 'download-and-extract', 14:45:43 INFO - 'populate-webroot', 14:45:43 INFO - 'create-virtualenv', 14:45:43 INFO - 'install', 14:45:43 INFO - 'run-tests'), 14:45:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:45:43 INFO - 'download_minidump_stackwalk': True, 14:45:43 INFO - 'download_symbols': 'ondemand', 14:45:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:45:43 INFO - 'tooltool.py': '/tools/tooltool.py', 14:45:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:45:43 INFO - '/tools/misc-python/virtualenv.py')}, 14:45:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:45:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:45:43 INFO - 'installer_path': 'installer.exe', 14:45:43 INFO - 'log_level': 'info', 14:45:43 INFO - 'log_name': 'talos', 14:45:43 INFO - 'log_to_console': True, 14:45:43 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:45:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:45:43 INFO - 'opt_config_files': (), 14:45:43 INFO - 'pip_index': False, 14:45:43 INFO - 'sps_profile': False, 14:45:43 INFO - 'sps_profile_interval': 0, 14:45:43 INFO - 'suite': 'dromaeojs', 14:45:43 INFO - 'system_bits': '32', 14:45:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:45:43 INFO - 'title': 'talos-linux64-ix-052', 14:45:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:45:43 INFO - 'use_talos_json': True, 14:45:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:45:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:45:43 INFO - 'work_dir': 'build'} 14:45:43 INFO - [mozharness: 2016-10-10 21:45:43.787371Z] Running clobber step. 14:45:43 INFO - Running pre-action listener: _resource_record_pre_action 14:45:43 INFO - Running main action method: clobber 14:45:43 INFO - rmtree: /builds/slave/test/build 14:45:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:45:44 INFO - Running post-action listener: _resource_record_post_action 14:45:44 INFO - [mozharness: 2016-10-10 21:45:44.789162Z] Finished clobber step (success) 14:45:44 INFO - [mozharness: 2016-10-10 21:45:44.789261Z] Running read-buildbot-config step. 14:45:44 INFO - Running pre-action listener: _resource_record_pre_action 14:45:44 INFO - Running main action method: read_buildbot_config 14:45:44 INFO - Using buildbot properties: 14:45:44 INFO - { 14:45:44 INFO - "project": "", 14:45:44 INFO - "product": "firefox", 14:45:44 INFO - "script_repo_revision": "production", 14:45:44 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 14:45:44 INFO - "repository": "", 14:45:44 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 14:45:44 INFO - "buildid": "20161010125124", 14:45:44 INFO - "pgo_build": "True", 14:45:44 INFO - "basedir": "/builds/slave/test", 14:45:44 INFO - "buildnumber": 269, 14:45:44 INFO - "slavename": "talos-linux64-ix-052", 14:45:44 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 14:45:44 INFO - "platform": "ubuntu64_hw", 14:45:44 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 14:45:44 INFO - "branch": "mozilla-beta", 14:45:44 INFO - "repo_path": "releases/mozilla-beta", 14:45:44 INFO - "revision": "109ee76d7cdec816e0f93411678b034eec8116a2", 14:45:44 INFO - "stage_platform": "linux64-pgo", 14:45:44 INFO - "builduid": "f407af9dce9f40ba8e09920a26980d4e", 14:45:44 INFO - "slavebuilddir": "test" 14:45:44 INFO - } 14:45:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 14:45:44 INFO - Running post-action listener: _resource_record_post_action 14:45:44 INFO - [mozharness: 2016-10-10 21:45:44.791705Z] Finished read-buildbot-config step (success) 14:45:44 INFO - [mozharness: 2016-10-10 21:45:44.791793Z] Running download-and-extract step. 14:45:44 INFO - Running pre-action listener: _resource_record_pre_action 14:45:44 INFO - Running main action method: download_and_extract 14:45:44 INFO - mkdir: /builds/slave/test/build/tests 14:45: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')]} 14:45:44 INFO - https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:45:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:45:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:45:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:45:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:45:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:45:44 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:45:44 INFO - retry: Failed, sleeping 30 seconds before retrying 14:46:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 14:46:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:46:14 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:46:14 INFO - retry: Failed, sleeping 60 seconds before retrying 14:47:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 14:47:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:47:14 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:47:14 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 14:47:14 INFO - Caught exception: HTTP Error 404: Not Found 14:47:14 INFO - Caught exception: HTTP Error 404: Not Found 14:47:14 INFO - Caught exception: HTTP Error 404: Not Found 14:47:14 INFO - trying https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:47:14 INFO - Downloading https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:47:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:47:15 INFO - Downloaded 1327 bytes. 14:47:15 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 14:47:15 INFO - Using the following test package requirements: 14:47:15 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 14:47:15 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:47:15 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 14:47:15 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:47:15 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 14:47:15 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:47:15 INFO - u'jsshell-linux-x86_64.zip'], 14:47:15 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:47:15 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 14:47:15 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 14:47:15 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:47:15 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 14:47:15 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:47:15 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 14:47:15 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:47:15 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 14:47:15 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 14:47:15 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 14:47:15 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:47: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')]} 14:47:15 INFO - https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:47:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:47:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:47:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:47:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:47:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:47:15 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:47:15 INFO - retry: Failed, sleeping 30 seconds before retrying 14:47:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 14:47:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:47:45 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:47:45 INFO - retry: Failed, sleeping 60 seconds before retrying 14:48:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 14:48:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:48:45 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:48:45 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 14:48:45 INFO - Caught exception: HTTP Error 404: Not Found 14:48:45 INFO - Caught exception: HTTP Error 404: Not Found 14:48:45 INFO - Caught exception: HTTP Error 404: Not Found 14:48:45 INFO - trying https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:48:45 INFO - Downloading https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:48:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:48:46 INFO - Downloaded 21773756 bytes. 14:48:46 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:48:46 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:48:47 INFO - Return code: 0 14:48:47 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:48:47 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')]} 14:48:47 INFO - https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:48:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:48:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:48:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:48:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:48:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:48:47 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:48:47 INFO - retry: Failed, sleeping 30 seconds before retrying 14:49:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 14:49:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:49:17 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:49:17 INFO - retry: Failed, sleeping 60 seconds before retrying 14:50:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 14:50:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:50:17 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:50:17 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 14:50:17 INFO - Caught exception: HTTP Error 404: Not Found 14:50:17 INFO - Caught exception: HTTP Error 404: Not Found 14:50:17 INFO - Caught exception: HTTP Error 404: Not Found 14:50:17 INFO - trying https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:50:17 INFO - Downloading https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 14:50:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:50:18 INFO - Downloaded 21773756 bytes. 14:50:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:50:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:50:19 INFO - Return code: 0 14:50:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:50:19 INFO - https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:50:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 14:50:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 14:50:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 14:50:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:50:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 14:50:19 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:50:19 INFO - retry: Failed, sleeping 30 seconds before retrying 14:50:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 14:50:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 14:50:49 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:50:49 INFO - retry: Failed, sleeping 60 seconds before retrying 14:51:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 14:51:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 14:51:50 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:51:50 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 14:51:50 INFO - Caught exception: HTTP Error 404: Not Found 14:51:50 INFO - Caught exception: HTTP Error 404: Not Found 14:51:50 INFO - Caught exception: HTTP Error 404: Not Found 14:51:50 INFO - trying https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 14:51:50 INFO - Downloading https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 14:51:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:51:50 INFO - Downloaded 14413740 bytes. 14:51:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:51:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 14:51:51 INFO - Return code: 0 14:51:51 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')]} 14:51:51 INFO - https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:51:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:51:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:51:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:51:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:51:51 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:51:51 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:51:51 INFO - retry: Failed, sleeping 30 seconds before retrying 14:52:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 14:52:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:52:21 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:52:21 INFO - retry: Failed, sleeping 60 seconds before retrying 14:53:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 14:53:21 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:53:21 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:53:21 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 14:53:21 INFO - Caught exception: HTTP Error 404: Not Found 14:53:21 INFO - Caught exception: HTTP Error 404: Not Found 14:53:21 INFO - Caught exception: HTTP Error 404: Not Found 14:53:21 INFO - trying https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:53:21 INFO - Downloading https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:53:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:53:22 INFO - Downloaded 56784897 bytes. 14:53:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:53:22 INFO - mkdir: /builds/slave/test/properties 14:53:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:53:22 INFO - Writing to file /builds/slave/test/properties/build_url 14:53:22 INFO - Contents: 14:53:22 INFO - build_url:https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 14:53:22 INFO - Running post-action listener: _resource_record_post_action 14:53:22 INFO - Running post-action listener: set_extra_try_arguments 14:53:22 INFO - [mozharness: 2016-10-10 21:53:22.898069Z] Finished download-and-extract step (success) 14:53:22 INFO - [mozharness: 2016-10-10 21:53:22.898341Z] Running populate-webroot step. 14:53:22 INFO - Running pre-action listener: _resource_record_pre_action 14:53:22 INFO - Running main action method: populate_webroot 14:53:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:53:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:53:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:53:22 INFO - '--tppagecycles', 14:53:22 INFO - '7'], 14:53:22 INFO - 'tests': ['tsvgm']}}, 14:53:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:53:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:53:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:53:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:53:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:53:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:53:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:53:22 INFO - 'talos_options': ['--disable-e10s'], 14:53:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:53:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:53:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:53:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:53:22 INFO - 'talos_options': ['--disable-e10s'], 14:53:22 INFO - 'tests': ['damp', 'tps']}, 14:53:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:53:22 INFO - 'tests': ['damp', 'tps']}, 14:53:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:53:22 INFO - 'tests': ['dromaeo_dom']}, 14:53:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:53:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:53:22 INFO - 'tests': ['basic_compositor_video']}, 14:53:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:53:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:53:22 INFO - 'tests': ['a11yr', 14:53:22 INFO - 'ts_paint', 14:53:22 INFO - 'tpaint', 14:53:22 INFO - 'sessionrestore', 14:53:22 INFO - 'sessionrestore_no_auto_restore', 14:53:22 INFO - 'tabpaint']}, 14:53:22 INFO - 'other-e10s': {'tests': ['a11yr', 14:53:22 INFO - 'ts_paint', 14:53:22 INFO - 'tpaint', 14:53:22 INFO - 'sessionrestore', 14:53:22 INFO - 'sessionrestore_no_auto_restore', 14:53:22 INFO - 'tabpaint']}, 14:53:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:53:22 INFO - 'ts_paint', 14:53:22 INFO - 'tpaint', 14:53:22 INFO - 'sessionrestore', 14:53:22 INFO - 'sessionrestore_no_auto_restore', 14:53:22 INFO - 'tabpaint']}, 14:53:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:53:22 INFO - 'ts_paint', 14:53:22 INFO - 'tpaint', 14:53:22 INFO - 'sessionrestore', 14:53:22 INFO - 'sessionrestore_no_auto_restore', 14:53:22 INFO - 'tabpaint']}, 14:53:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:53:22 INFO - 'tests': ['a11yr', 14:53:22 INFO - 'ts_paint', 14:53:22 INFO - 'tpaint', 14:53:22 INFO - 'sessionrestore', 14:53:22 INFO - 'sessionrestore_no_auto_restore', 14:53:22 INFO - 'tabpaint']}, 14:53:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:53:22 INFO - 'tests': ['a11yr', 14:53:22 INFO - 'ts_paint', 14:53:22 INFO - 'tpaint', 14:53:22 INFO - 'sessionrestore', 14:53:22 INFO - 'sessionrestore_no_auto_restore', 14:53:22 INFO - 'tabpaint']}, 14:53:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:53:22 INFO - 'tests': ['tsvgx', 14:53:22 INFO - 'tsvgr_opacity', 14:53:22 INFO - 'tart', 14:53:22 INFO - 'tscrollx', 14:53:22 INFO - 'cart']}, 14:53:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:53:22 INFO - 'tsvgr_opacity', 14:53:22 INFO - 'tart', 14:53:22 INFO - 'tscrollx', 14:53:22 INFO - 'cart']}, 14:53:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:53:22 INFO - 'talos_options': ['--disable-e10s'], 14:53:22 INFO - 'tests': ['tp5o']}, 14:53:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:53:22 INFO - 'tests': ['tp5o']}, 14:53:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:53:22 INFO - 'talos_options': ['--disable-e10s', 14:53:22 INFO - '--xperf_path', 14:53:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:53:22 INFO - 'tests': ['tp5n']}, 14:53:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:53:22 INFO - 'talos_options': ['--xperf_path', 14:53:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:53:22 INFO - 'tests': ['tp5n']}}, 14:53:22 INFO - 'talos.zip': {'path': '', 14:53:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:53:22 INFO - Running post-action listener: _resource_record_post_action 14:53:22 INFO - [mozharness: 2016-10-10 21:53:22.916839Z] Finished populate-webroot step (success) 14:53:22 INFO - [mozharness: 2016-10-10 21:53:22.916927Z] Running create-virtualenv step. 14:53:22 INFO - Running pre-action listener: _resource_record_pre_action 14:53:22 INFO - Running main action method: create_virtualenv 14:53:22 INFO - Creating virtualenv /builds/slave/test/build/venv 14:53:22 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 14:53:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:53:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:53:23 INFO - Using real prefix '/usr' 14:53:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:53:23 INFO - Installing distribute.............................................................................................................................................................................................done. 14:53:25 INFO - Installing pip.................done. 14:53:25 INFO - Return code: 0 14:53:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:53: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')]} 14:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53: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')]} 14:53:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x192a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1beb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be93b0>, '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 0x1bea2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bea740>, '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': '826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767', '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 14:53:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:53:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:53:25 INFO - Using env: {'DISPLAY': ':0', 14:53:25 INFO - 'HOME': '/home/cltbld', 14:53:25 INFO - 'LANG': 'en_US.UTF-8', 14:53:25 INFO - 'LANGUAGE': 'en_US:en', 14:53:25 INFO - 'LOGNAME': 'cltbld', 14:53:25 INFO - 'MAIL': '/var/mail/cltbld', 14:53:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:25 INFO - 'MOZ_NO_REMOTE': '1', 14:53:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:25 INFO - 'NO_EM_RESTART': '1', 14:53:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:25 INFO - 'PWD': '/builds/slave/test', 14:53:25 INFO - 'SHELL': '/bin/bash', 14:53:25 INFO - 'SHLVL': '1', 14:53:25 INFO - 'TERM': 'linux', 14:53:25 INFO - 'TMOUT': '86400', 14:53:25 INFO - 'USER': 'cltbld', 14:53:25 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767', 14:53:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:25 INFO - '_': '/tools/buildbot/bin/python'} 14:53:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:25 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 14:53:25 INFO - Cleaning up... 14:53:25 INFO - Return code: 0 14:53:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:53: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')]} 14:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53: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')]} 14:53:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x192a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1beb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be93b0>, '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 0x1bea2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bea740>, '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': '826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767', '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 14:53:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:53:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:53:25 INFO - Using env: {'DISPLAY': ':0', 14:53:25 INFO - 'HOME': '/home/cltbld', 14:53:25 INFO - 'LANG': 'en_US.UTF-8', 14:53:25 INFO - 'LANGUAGE': 'en_US:en', 14:53:25 INFO - 'LOGNAME': 'cltbld', 14:53:25 INFO - 'MAIL': '/var/mail/cltbld', 14:53:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:25 INFO - 'MOZ_NO_REMOTE': '1', 14:53:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:25 INFO - 'NO_EM_RESTART': '1', 14:53:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:25 INFO - 'PWD': '/builds/slave/test', 14:53:25 INFO - 'SHELL': '/bin/bash', 14:53:25 INFO - 'SHLVL': '1', 14:53:25 INFO - 'TERM': 'linux', 14:53:25 INFO - 'TMOUT': '86400', 14:53:25 INFO - 'USER': 'cltbld', 14:53:25 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767', 14:53:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:25 INFO - '_': '/tools/buildbot/bin/python'} 14:53:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:25 INFO - Downloading/unpacking psutil>=3.1.1 14:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:53:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:53:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:53:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:53:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:53:27 INFO - Installing collected packages: psutil 14:53:27 INFO - Running setup.py install for psutil 14:53:28 INFO - building 'psutil._psutil_linux' extension 14:53:28 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 14:53:28 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 14:53:28 INFO - building 'psutil._psutil_posix' extension 14:53:28 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 14:53:28 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 14:53:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:53:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:53:28 INFO - Successfully installed psutil 14:53:28 INFO - Cleaning up... 14:53:28 INFO - Return code: 0 14:53:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:53: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')]} 14:53:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53: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')]} 14:53:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.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 0x192a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1beb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be93b0>, '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 0x1bea2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bea740>, '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': '826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767', '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 14:53:28 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 14:53:28 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 14:53:28 INFO - Using env: {'DISPLAY': ':0', 14:53:28 INFO - 'HOME': '/home/cltbld', 14:53:28 INFO - 'LANG': 'en_US.UTF-8', 14:53:28 INFO - 'LANGUAGE': 'en_US:en', 14:53:28 INFO - 'LOGNAME': 'cltbld', 14:53:28 INFO - 'MAIL': '/var/mail/cltbld', 14:53:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:28 INFO - 'MOZ_NO_REMOTE': '1', 14:53:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:28 INFO - 'NO_EM_RESTART': '1', 14:53:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:28 INFO - 'PWD': '/builds/slave/test', 14:53:28 INFO - 'SHELL': '/bin/bash', 14:53:28 INFO - 'SHLVL': '1', 14:53:28 INFO - 'TERM': 'linux', 14:53:28 INFO - 'TMOUT': '86400', 14:53:28 INFO - 'USER': 'cltbld', 14:53:28 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767', 14:53:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:28 INFO - '_': '/tools/buildbot/bin/python'} 14:53:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:53:28 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 14:53:28 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 14:53:28 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 14:53:28 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 14:53:28 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 14:53:28 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 14:53:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:53:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:53:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:53:30 INFO - Installing collected packages: mozsystemmonitor 14:53:30 INFO - Running setup.py install for mozsystemmonitor 14:53:30 INFO - Successfully installed mozsystemmonitor 14:53:30 INFO - Cleaning up... 14:53:30 INFO - Return code: 0 14:53:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:53:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:53:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:53:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:30 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 0x192a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1beb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be93b0>, '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 0x1bea2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bea740>, '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': '826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767', '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 14:53:30 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 14:53:30 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 14:53:30 INFO - Using env: {'DISPLAY': ':0', 14:53:30 INFO - 'HOME': '/home/cltbld', 14:53:30 INFO - 'LANG': 'en_US.UTF-8', 14:53:30 INFO - 'LANGUAGE': 'en_US:en', 14:53:30 INFO - 'LOGNAME': 'cltbld', 14:53:30 INFO - 'MAIL': '/var/mail/cltbld', 14:53:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:30 INFO - 'MOZ_NO_REMOTE': '1', 14:53:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:30 INFO - 'NO_EM_RESTART': '1', 14:53:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:30 INFO - 'PWD': '/builds/slave/test', 14:53:30 INFO - 'SHELL': '/bin/bash', 14:53:30 INFO - 'SHLVL': '1', 14:53:30 INFO - 'TERM': 'linux', 14:53:30 INFO - 'TMOUT': '86400', 14:53:30 INFO - 'USER': 'cltbld', 14:53:30 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767', 14:53:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:30 INFO - '_': '/tools/buildbot/bin/python'} 14:53:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:31 INFO - Downloading/unpacking jsonschema==2.5.1 14:53:31 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 14:53:31 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 14:53:31 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 14:53:31 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 14:53:31 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 14:53:31 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 14:53:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:53:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:53:32 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 14:53:32 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 14:53:32 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 14:53:32 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 14:53:32 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 14:53:32 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 14:53:33 INFO - Downloading functools32-3.2.3-2.tar.gz 14:53:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:53:33 INFO - warning: no files found matching '*.txt' 14:53:33 INFO - no previously-included directories found matching 'build' 14:53:33 INFO - no previously-included directories found matching 'dist' 14:53:33 INFO - no previously-included directories found matching '.git*' 14:53:33 INFO - Installing collected packages: jsonschema, functools32 14:53:33 INFO - Running setup.py install for functools32 14:53:33 INFO - warning: no files found matching '*.txt' 14:53:33 INFO - no previously-included directories found matching 'build' 14:53:33 INFO - no previously-included directories found matching 'dist' 14:53:33 INFO - no previously-included directories found matching '.git*' 14:53:33 INFO - Successfully installed jsonschema functools32 14:53:33 INFO - Cleaning up... 14:53:33 INFO - Return code: 0 14:53:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:53:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:53:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:53:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:33 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 0x192a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1beb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be93b0>, '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 0x1bea2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bea740>, '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': '826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767', '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 14:53:33 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 14:53:33 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 14:53:33 INFO - Using env: {'DISPLAY': ':0', 14:53:33 INFO - 'HOME': '/home/cltbld', 14:53:33 INFO - 'LANG': 'en_US.UTF-8', 14:53:33 INFO - 'LANGUAGE': 'en_US:en', 14:53:33 INFO - 'LOGNAME': 'cltbld', 14:53:33 INFO - 'MAIL': '/var/mail/cltbld', 14:53:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:33 INFO - 'MOZ_NO_REMOTE': '1', 14:53:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:33 INFO - 'NO_EM_RESTART': '1', 14:53:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:33 INFO - 'PWD': '/builds/slave/test', 14:53:33 INFO - 'SHELL': '/bin/bash', 14:53:33 INFO - 'SHLVL': '1', 14:53:33 INFO - 'TERM': 'linux', 14:53:33 INFO - 'TMOUT': '86400', 14:53:33 INFO - 'USER': 'cltbld', 14:53:33 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767', 14:53:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:33 INFO - '_': '/tools/buildbot/bin/python'} 14:53:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:53:33 INFO - Cleaning up... 14:53:33 INFO - Return code: 0 14:53:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:53:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:53:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:53:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:33 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 0x192a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1beb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be93b0>, '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 0x1bea2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bea740>, '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': '826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767', '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 14:53:33 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 14:53:33 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 14:53:33 INFO - Using env: {'DISPLAY': ':0', 14:53:33 INFO - 'HOME': '/home/cltbld', 14:53:33 INFO - 'LANG': 'en_US.UTF-8', 14:53:33 INFO - 'LANGUAGE': 'en_US:en', 14:53:33 INFO - 'LOGNAME': 'cltbld', 14:53:33 INFO - 'MAIL': '/var/mail/cltbld', 14:53:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:33 INFO - 'MOZ_NO_REMOTE': '1', 14:53:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:33 INFO - 'NO_EM_RESTART': '1', 14:53:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:33 INFO - 'PWD': '/builds/slave/test', 14:53:33 INFO - 'SHELL': '/bin/bash', 14:53:33 INFO - 'SHLVL': '1', 14:53:33 INFO - 'TERM': 'linux', 14:53:33 INFO - 'TMOUT': '86400', 14:53:33 INFO - 'USER': 'cltbld', 14:53:33 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767', 14:53:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:33 INFO - '_': '/tools/buildbot/bin/python'} 14:53:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:33 INFO - Downloading/unpacking blobuploader==1.2.4 14:53:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:53:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:53:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:35 INFO - Downloading blobuploader-1.2.4.tar.gz 14:53:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:53:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:53:35 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 14:53:35 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 14:53:35 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 14:53:35 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 14:53:35 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 14:53:35 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 14:53:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:53:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:53:36 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 14:53:36 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 14:53:36 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 14:53:36 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 14:53:36 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 14:53:36 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 14:53:36 INFO - Downloading docopt-0.6.1.tar.gz 14:53:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:53:36 INFO - Installing collected packages: blobuploader, requests, docopt 14:53:36 INFO - Running setup.py install for blobuploader 14:53:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:53:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:53:36 INFO - Running setup.py install for requests 14:53:36 INFO - Running setup.py install for docopt 14:53:37 INFO - Successfully installed blobuploader requests docopt 14:53:37 INFO - Cleaning up... 14:53:37 INFO - Return code: 0 14:53:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:53:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:53:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:53:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:37 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 0x192a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1beb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be93b0>, '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 0x1bea2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bea740>, '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': '826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767', '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 14:53:37 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 14:53:37 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 14:53:37 INFO - Using env: {'DISPLAY': ':0', 14:53:37 INFO - 'HOME': '/home/cltbld', 14:53:37 INFO - 'LANG': 'en_US.UTF-8', 14:53:37 INFO - 'LANGUAGE': 'en_US:en', 14:53:37 INFO - 'LOGNAME': 'cltbld', 14:53:37 INFO - 'MAIL': '/var/mail/cltbld', 14:53:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:37 INFO - 'MOZ_NO_REMOTE': '1', 14:53:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:37 INFO - 'NO_EM_RESTART': '1', 14:53:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:37 INFO - 'PWD': '/builds/slave/test', 14:53:37 INFO - 'SHELL': '/bin/bash', 14:53:37 INFO - 'SHLVL': '1', 14:53:37 INFO - 'TERM': 'linux', 14:53:37 INFO - 'TMOUT': '86400', 14:53:37 INFO - 'USER': 'cltbld', 14:53:37 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767', 14:53:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:37 INFO - '_': '/tools/buildbot/bin/python'} 14:53:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:53:37 INFO - Running setup.py (path:/tmp/pip-qoQ8wq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:53:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:53:37 INFO - Running setup.py (path:/tmp/pip-ukeOSj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:53:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:53:37 INFO - Running setup.py (path:/tmp/pip-JNhnWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:53:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:53:37 INFO - Running setup.py (path:/tmp/pip-t08E92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:53:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:53:37 INFO - Running setup.py (path:/tmp/pip-TbAa_B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:53:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:53:37 INFO - Running setup.py (path:/tmp/pip-CwBi7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:53:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:53:37 INFO - Running setup.py (path:/tmp/pip-uaY2UP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:53:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:53:37 INFO - Running setup.py (path:/tmp/pip-8rrhDK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:53:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:53:37 INFO - Running setup.py (path:/tmp/pip-mknAdn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:53:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:53:37 INFO - Running setup.py (path:/tmp/pip-4aHkxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:53:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:53:38 INFO - Running setup.py (path:/tmp/pip-JUtqtM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:53:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:53:38 INFO - Running setup.py (path:/tmp/pip-AkFIWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:53:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:53:38 INFO - Running setup.py (path:/tmp/pip-9hkgE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:53:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:53:38 INFO - Running setup.py (path:/tmp/pip-_fINlt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:53:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:53:38 INFO - Running setup.py (path:/tmp/pip-tUtbxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:53:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:53:38 INFO - Running setup.py (path:/tmp/pip-9akWr4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:53:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:53:38 INFO - Running setup.py (path:/tmp/pip-IYdHRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:53:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:53:38 INFO - Running setup.py install for manifestparser 14:53:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:53:38 INFO - Running setup.py install for mozcrash 14:53:38 INFO - Running setup.py install for mozdebug 14:53:38 INFO - Running setup.py install for mozdevice 14:53:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:53:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:53:38 INFO - Running setup.py install for mozfile 14:53:39 INFO - Running setup.py install for mozhttpd 14:53:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:53:39 INFO - Running setup.py install for mozinfo 14:53:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:53:39 INFO - Running setup.py install for mozInstall 14:53:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:53:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:53:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:53:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:53:39 INFO - Running setup.py install for mozleak 14:53:39 INFO - Running setup.py install for mozlog 14:53:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:53:39 INFO - Running setup.py install for moznetwork 14:53:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:53:39 INFO - Running setup.py install for mozprocess 14:53:39 INFO - Running setup.py install for mozprofile 14:53:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:53:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:53:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:53:39 INFO - Running setup.py install for mozrunner 14:53:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:53:40 INFO - Running setup.py install for mozscreenshot 14:53:40 INFO - Running setup.py install for moztest 14:53:40 INFO - Running setup.py install for mozversion 14:53:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:53:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:53:40 INFO - Cleaning up... 14:53:40 INFO - Return code: 0 14:53:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:53:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:53:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:53:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:40 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 0x192a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1beb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be93b0>, '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 0x1bea2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bea740>, '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': '826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767', '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 14:53:40 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 14:53:40 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 14:53:40 INFO - Using env: {'DISPLAY': ':0', 14:53:40 INFO - 'HOME': '/home/cltbld', 14:53:40 INFO - 'LANG': 'en_US.UTF-8', 14:53:40 INFO - 'LANGUAGE': 'en_US:en', 14:53:40 INFO - 'LOGNAME': 'cltbld', 14:53:40 INFO - 'MAIL': '/var/mail/cltbld', 14:53:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:40 INFO - 'MOZ_NO_REMOTE': '1', 14:53:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:40 INFO - 'NO_EM_RESTART': '1', 14:53:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:40 INFO - 'PWD': '/builds/slave/test', 14:53:40 INFO - 'SHELL': '/bin/bash', 14:53:40 INFO - 'SHLVL': '1', 14:53:40 INFO - 'TERM': 'linux', 14:53:40 INFO - 'TMOUT': '86400', 14:53:40 INFO - 'USER': 'cltbld', 14:53:40 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767', 14:53:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:40 INFO - '_': '/tools/buildbot/bin/python'} 14:53:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:53:40 INFO - Running setup.py (path:/tmp/pip-Y8gzYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:53:40 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)) 14:53:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:53:40 INFO - Running setup.py (path:/tmp/pip-AVFWbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:53:40 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)) 14:53:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:53:40 INFO - Running setup.py (path:/tmp/pip-OX_ikA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:53:40 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)) 14:53:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:53:40 INFO - Running setup.py (path:/tmp/pip-QbHJPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:53:40 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)) 14:53:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:53:40 INFO - Running setup.py (path:/tmp/pip-dgyon8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:53:40 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)) 14:53:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:53:40 INFO - Running setup.py (path:/tmp/pip-sTgxrr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:53:40 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)) 14:53:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:53:41 INFO - Running setup.py (path:/tmp/pip-9Wf54V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:53:41 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)) 14:53:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:53:41 INFO - Running setup.py (path:/tmp/pip-55Ky9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:53:41 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)) 14:53:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:53:41 INFO - Running setup.py (path:/tmp/pip-_ktq66-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:53:41 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)) 14:53:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:53:41 INFO - Running setup.py (path:/tmp/pip-MxfnN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:53:41 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)) 14:53:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:53:41 INFO - Running setup.py (path:/tmp/pip-1JxdHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:53:41 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)) 14:53:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:53:41 INFO - Running setup.py (path:/tmp/pip-sfWz_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:53:41 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)) 14:53:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:53:41 INFO - Running setup.py (path:/tmp/pip-KI1Fq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:53:41 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)) 14:53:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:53:41 INFO - Running setup.py (path:/tmp/pip-6lEHLx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:53:41 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)) 14:53:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:53:41 INFO - Running setup.py (path:/tmp/pip-EJ_6EY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:53:41 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)) 14:53:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:53:41 INFO - Running setup.py (path:/tmp/pip-Memv5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:53:41 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)) 14:53:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:53:41 INFO - Running setup.py (path:/tmp/pip-531j3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:53:41 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)) 14:53:41 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)) 14:53:41 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)) 14:53:41 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)) 14:53:41 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)) 14:53:41 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)) 14:53:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:53:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:53:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:53:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:53:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:53:43 INFO - Downloading blessings-1.6.tar.gz 14:53:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:53:43 INFO - Installing collected packages: blessings 14:53:43 INFO - Running setup.py install for blessings 14:53:43 INFO - Successfully installed blessings 14:53:43 INFO - Cleaning up... 14:53:43 INFO - Return code: 0 14:53:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:53:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:53:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:53:44 INFO - Reading from file tmpfile_stdout 14:53:44 INFO - Current package versions: 14:53:44 INFO - argparse == 1.2.1 14:53:44 INFO - blessings == 1.6 14:53:44 INFO - blobuploader == 1.2.4 14:53:44 INFO - docopt == 0.6.1 14:53:44 INFO - functools32 == 3.2.3-2 14:53:44 INFO - jsonschema == 2.5.1 14:53:44 INFO - manifestparser == 1.1 14:53:44 INFO - mozInstall == 1.12 14:53:44 INFO - mozcrash == 0.17 14:53:44 INFO - mozdebug == 0.1 14:53:44 INFO - mozdevice == 0.48 14:53:44 INFO - mozfile == 1.2 14:53:44 INFO - mozhttpd == 0.7 14:53:44 INFO - mozinfo == 0.9 14:53:44 INFO - mozleak == 0.1 14:53:44 INFO - mozlog == 3.3 14:53:44 INFO - moznetwork == 0.27 14:53:44 INFO - mozprocess == 0.23 14:53:44 INFO - mozprofile == 0.28 14:53:44 INFO - mozrunner == 6.12 14:53:44 INFO - mozscreenshot == 0.1 14:53:44 INFO - mozsystemmonitor == 0.3 14:53:44 INFO - moztest == 0.7 14:53:44 INFO - mozversion == 1.4 14:53:44 INFO - psutil == 3.1.1 14:53:44 INFO - requests == 1.2.3 14:53:44 INFO - wsgiref == 0.1.2 14:53:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:53: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')]} 14:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53: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')]} 14:53:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:44 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 0x192a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1beb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be93b0>, '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 0x1bea2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bea740>, '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': '826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767', '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 14:53:44 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 14:53:44 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 14:53:44 INFO - Using env: {'DISPLAY': ':0', 14:53:44 INFO - 'HOME': '/home/cltbld', 14:53:44 INFO - 'LANG': 'en_US.UTF-8', 14:53:44 INFO - 'LANGUAGE': 'en_US:en', 14:53:44 INFO - 'LOGNAME': 'cltbld', 14:53:44 INFO - 'MAIL': '/var/mail/cltbld', 14:53:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:44 INFO - 'MOZ_NO_REMOTE': '1', 14:53:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:44 INFO - 'NO_EM_RESTART': '1', 14:53:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:44 INFO - 'PWD': '/builds/slave/test', 14:53:44 INFO - 'SHELL': '/bin/bash', 14:53:44 INFO - 'SHLVL': '1', 14:53:44 INFO - 'TERM': 'linux', 14:53:44 INFO - 'TMOUT': '86400', 14:53:44 INFO - 'USER': 'cltbld', 14:53:44 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767', 14:53:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:44 INFO - '_': '/tools/buildbot/bin/python'} 14:53:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:44 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)) 14:53:44 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)) 14:53:44 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)) 14:53:44 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)) 14:53:44 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)) 14:53:44 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)) 14:53:44 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)) 14:53:44 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)) 14:53:44 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)) 14:53:44 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)) 14:53:44 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)) 14:53:44 INFO - Cleaning up... 14:53:44 INFO - Return code: 0 14:53:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:53: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')]} 14:53:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:53:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53: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')]} 14:53:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:53:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:53:44 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 0x192a030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8d8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1beb180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1be93b0>, '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 0x1bea2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bea740>, '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': '826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767', '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 14:53:44 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 14:53:44 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 14:53:44 INFO - Using env: {'DISPLAY': ':0', 14:53:44 INFO - 'HOME': '/home/cltbld', 14:53:44 INFO - 'LANG': 'en_US.UTF-8', 14:53:44 INFO - 'LANGUAGE': 'en_US:en', 14:53:44 INFO - 'LOGNAME': 'cltbld', 14:53:44 INFO - 'MAIL': '/var/mail/cltbld', 14:53:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:44 INFO - 'MOZ_NO_REMOTE': '1', 14:53:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:44 INFO - 'NO_EM_RESTART': '1', 14:53:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:44 INFO - 'PWD': '/builds/slave/test', 14:53:44 INFO - 'SHELL': '/bin/bash', 14:53:44 INFO - 'SHLVL': '1', 14:53:44 INFO - 'TERM': 'linux', 14:53:44 INFO - 'TMOUT': '86400', 14:53:44 INFO - 'USER': 'cltbld', 14:53:44 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767', 14:53:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:44 INFO - '_': '/tools/buildbot/bin/python'} 14:53:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:53:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:53:44 INFO - Cleaning up... 14:53:44 INFO - Return code: 0 14:53:44 INFO - Running post-action listener: _resource_record_post_action 14:53:44 INFO - Running post-action listener: _start_resource_monitoring 14:53:44 INFO - Starting resource monitoring. 14:53:44 INFO - [mozharness: 2016-10-10 21:53:44.664560Z] Finished create-virtualenv step (success) 14:53:44 INFO - [mozharness: 2016-10-10 21:53:44.664996Z] Running install step. 14:53:44 INFO - Running pre-action listener: _resource_record_pre_action 14:53:44 INFO - Running main action method: install 14:53:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:53:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:53:44 INFO - Reading from file tmpfile_stdout 14:53:44 INFO - Detecting whether we're running mozinstall >=1.0... 14:53:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:53:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:53:44 INFO - Reading from file tmpfile_stdout 14:53:44 INFO - Output received: 14:53:44 INFO - Usage: mozinstall [options] installer 14:53:44 INFO - Options: 14:53:44 INFO - -h, --help show this help message and exit 14:53:44 INFO - -d DEST, --destination=DEST 14:53:44 INFO - Directory to install application into. [default: 14:53:44 INFO - "/builds/slave/test"] 14:53:44 INFO - --app=APP Application being installed. [default: firefox] 14:53:44 INFO - mkdir: /builds/slave/test/build/application 14:53:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:53:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 14:53:58 INFO - Reading from file tmpfile_stdout 14:53:58 INFO - Output received: 14:53:58 INFO - /builds/slave/test/build/application/firefox/firefox 14:53:58 INFO - Running post-action listener: _resource_record_post_action 14:53:58 INFO - [mozharness: 2016-10-10 21:53:58.911913Z] Finished install step (success) 14:53:58 INFO - [mozharness: 2016-10-10 21:53:58.912255Z] Running run-tests step. 14:53:58 INFO - Running pre-action listener: _resource_record_pre_action 14:53:58 INFO - Running main action method: run_tests 14:53:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:53:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:53:58 INFO - Python 2.7.3 14:53:58 INFO - Return code: 0 14:53:58 INFO - grabbing minidump binary from tooltool 14:53:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:53:58 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 0x1be93b0>, '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 0x1bea2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1bea740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:53:58 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 14:53:58 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 14:53:58 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 14:53:58 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:53:58 INFO - Return code: 0 14:53:58 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:53:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:53:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:53:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:53:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:53:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 14:53:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-052 --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 14:53:58 INFO - Using env: {'DISPLAY': ':0', 14:53:58 INFO - 'HOME': '/home/cltbld', 14:53:58 INFO - 'LANG': 'en_US.UTF-8', 14:53:58 INFO - 'LANGUAGE': 'en_US:en', 14:53:58 INFO - 'LOGNAME': 'cltbld', 14:53:58 INFO - 'MAIL': '/var/mail/cltbld', 14:53:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:53:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:53:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:53:58 INFO - 'MOZ_NO_REMOTE': '1', 14:53:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:53:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:53:58 INFO - 'NO_EM_RESTART': '1', 14:53:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:53:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:53:58 INFO - 'PWD': '/builds/slave/test', 14:53:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:53:58 INFO - 'SHELL': '/bin/bash', 14:53:58 INFO - 'SHLVL': '1', 14:53:58 INFO - 'TERM': 'linux', 14:53:58 INFO - 'TMOUT': '86400', 14:53:58 INFO - 'USER': 'cltbld', 14:53:58 INFO - 'XDG_SESSION_COOKIE': '826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767', 14:53:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:53:58 INFO - '_': '/tools/buildbot/bin/python'} 14:53:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-052', '--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 14:53:59 INFO - mozversion application_buildid: 20161010125124 14:53:59 INFO - mozversion application_changeset: 109ee76d7cdec816e0f93411678b034eec8116a2 14:53:59 INFO - mozversion application_display_name: Firefox 14:53:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:53:59 INFO - mozversion application_name: Firefox 14:53:59 INFO - mozversion application_remotingname: firefox 14:53:59 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:53:59 INFO - mozversion application_vendor: Mozilla 14:53:59 INFO - mozversion application_version: 50.0 14:53:59 INFO - mozversion platform_buildid: 20161010125124 14:53:59 INFO - mozversion platform_changeset: 109ee76d7cdec816e0f93411678b034eec8116a2 14:53:59 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:53:59 INFO - mozversion platform_version: 50.0 14:53:59 INFO - using testdate: 1476136439 14:53:59 INFO - actual date: 1476136439 14:53:59 INFO - starting webserver on 'localhost:44801' 14:53:59 INFO - SUITE-START | Running 2 tests 14:53:59 INFO - TEST-START | dromaeo_css 14:53:59 INFO - operating with platform_type : linux_ 14:53:59 INFO - Initialising browser for dromaeo_css test... 14:53:59 INFO - TEST-INFO | started process 20741 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplyuPg7/profile http://localhost:44801/getInfo.html) 14:54:02 INFO - PROCESS | 20741 | __metrics Screen width/height:1600/1200 14:54:02 INFO - PROCESS | 20741 | colorDepth:24 14:54:02 INFO - PROCESS | 20741 | Browser inner width/height: 1024/697 14:54:02 INFO - PROCESS | 20741 | __metrics 14:54:03 INFO - PROCESS | 20741 | 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" 14:54:03 INFO - PROCESS | 20741 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:54:04 INFO - TEST-INFO | 20741: exit 0 14:54:04 INFO - Browser initialized. 14:54:04 INFO - Running cycle 1/1 for dromaeo_css test... 14:54:04 INFO - TEST-INFO | started process 20829 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmplyuPg7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:54:16 INFO - PROCESS | 20829 | RSS: Main: 143724544 14:54:16 INFO - PROCESS | 20829 | 14:55:07 INFO - PROCESS | 20829 | 1476136507922 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] 14:57:15 INFO - PROCESS | 20829 | Cycle 1(1): loaded http://localhost:44801/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44801/tests/dromaeo/cssquery-ext.html) 14:57:16 INFO - PROCESS | 20829 | RSS: Main: 259391488 14:57:16 INFO - PROCESS | 20829 | 15:00:19 INFO - PROCESS | 20829 | Cycle 1(1): loaded http://localhost:44801/tests/dromaeo/cssquery-ext.html (next: http://localhost:44801/tests/dromaeo/cssquery-jquery.html) 15:00:19 INFO - PROCESS | 20829 | RSS: Main: 235839488 15:00:19 INFO - PROCESS | 20829 | 15:03:21 INFO - PROCESS | 20829 | Cycle 1(1): loaded http://localhost:44801/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44801/tests/dromaeo/cssquery-mootools.html) 15:03:22 INFO - PROCESS | 20829 | RSS: Main: 177750016 15:03:22 INFO - PROCESS | 20829 | 15:06:24 INFO - PROCESS | 20829 | Cycle 1(1): loaded http://localhost:44801/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44801/tests/dromaeo/cssquery-prototype.html) 15:06:24 INFO - PROCESS | 20829 | RSS: Main: 217636864 15:06:24 INFO - PROCESS | 20829 | 15:09:27 INFO - PROCESS | 20829 | Cycle 1(1): loaded http://localhost:44801/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44801/tests/dromaeo/cssquery-yui.html) 15:09:27 INFO - PROCESS | 20829 | RSS: Main: 178782208 15:09:27 INFO - PROCESS | 20829 | 15:12:30 INFO - PROCESS | 20829 | Cycle 1(1): loaded http://localhost:44801/tests/dromaeo/cssquery-yui.html (next: http://localhost:44801/tests/dromaeo/cssquery-dojo.html) 15:12:30 INFO - PROCESS | 20829 | RSS: Main: 258772992 15:12:30 INFO - PROCESS | 20829 | 15:12:30 INFO - PROCESS | 20829 | __start_tp_report 15:12:30 INFO - PROCESS | 20829 | _x_x_mozilla_page_load 15:12:30 INFO - PROCESS | 20829 | _x_x_mozilla_page_load_details 15:12:30 INFO - PROCESS | 20829 | |i|pagename|runs| 15:12:30 INFO - PROCESS | 20829 | |0;dojo.html;3161.6522182559916;3163.035274241356;3184.824834634097;3198.6001749781276;3224.0166749141504;5533.889322213558;5571.13647384655;5603.719814009299;5622.943770562293;5623.156526521018;5560.916586251199;5654.575906806986;5665.8583535411635;5668.914966275509;5695.45893140152;5120.078385890548;5144.819931302405;5188.948110518884;5202.245674377232;5214.634975551713;5063.41770696371;5206.609504287175;5230.947690523103;5236.450172731866;5240.397354304266;5010.749462526877;5030.647854650176;5058.0895438821;5082.390113186404;5107.591392688576;5037;5062.443131255559;5088.567471764904;5172.560332371753;5202.661826981234;5000.324956130912;5085.872853178663;5136.306598609177;5150.793968241266;5160.6129540284255;5349.839504814861;5425;5454.181872719084;5478.342598888159;5496.285482887239;5877.118432235156;5895.498882594985;5932.940670593325;5964.701764911781;5976.73104710289;5763.9135412968835;5986.5210783137245;5994.250718660168;6010.549208809357;6023.427774361428;5495.505404513573;5548.722563871831;5554.305711786027;5568.582356323211;5628.943710562883;6083.6349819011;6087.391260873966;6089.695515224221;6095.603785753912;6125.540584456184;5669.659820410789;5715.828525144252;5880.529557635379;5911.024680927685;5913.113033045078;5861.472467477947;5898.262717160355;5930.347661757203;5932;5933.02105152645;5912.379200184005;5913.05391137416;5915.467607915309;5916.704164791743;5919.792807251725;5752.25220721312;5861.589688721834;5919.585629005928;5924.052151655715;5925.318588362394;4505.887352816206;4540.977295113569;4585.70192937458;4595.448546174481;4650.5349465053905;4104.753714777123;4111.321631930758;4113;4115.9176816464105;4119.505659320901;5769.59612827098;5777.451142141574;5782.826515204551;5783.971080144573;5799.3040835100055;5766.394528574523;5908.379620139824;5941.494972927263;5976.5218782497295;6051.122587224916;5734.16854556087;5744.310682718017;5744.569157313218;5746.856328591819;5748.137779333133;5202.03762303975;5204.271402003723;5204.323437953118;5214.504622060898;5239.764210610531;5893.528517718572;6069.484093851984;6120.510359171256;6135;6185.041318595715;5917.526597872246;5959;5963.671998040149;5966.433188847052;5982.641041537521;6131.417515336036;6162.784302549389;6184.81445556634;6185.876282474415;6203.410675985774;5362.249285100011;5641.097424412074;5761.942380576141;5809.448102430262;5895.616784908967;5906.379830117948;5915.852103697442;5958.314793798769;5962.791302304398;5976.372480889444;5815.79644712433;5822.883542329045;5826.155207494805;5827.038538641077;5827.8834423310445;5545.916811247916;5748.6838223898085;5799.3040835100055;5803.274590676166;5895.67573783446;5737.770489180553;5784.363719990713;5795;5796.565257605612;5814.73833677494;5814.186013957965;5843.211166492469;5848.53211743068;5863.912041319469;5877.559183061202;5519.806806761744;5620.241267428844;5638.154276858504;5653.943460565341;5773.711314434177 15:12:30 INFO - PROCESS | 20829 | |1;ext.html;594.7977687586219;654.7217432591149;664.0736173038612;682.3449488491051;684.096991970599;35245;47809.521904780944;48255.27617085746;48324;48335.033299334034;48755.51244487554;49273.260901086425;49284;49301;49319.50680493203;8690.695825646104;9407.059294070572;9502.619895204183;9540.093691099335;9569.473678947656;8881.95559022204;8946.86579701305;8958.417702849327;11041.668749937515;11311.773764524705;11494.770104597903;11567.479463424166;11573.826392604118;11574.6527604172;11579.421028948518;11365.03397211238;11431.085513158969;11436.313821170756;11436.428178591037;11442.084633229322;11337.546498140064;11426.08591312693;11432.142589305753;11432.828507572392;11440.19918605698;45993;46222.5377746225;46254.306185407244;46316;46455.767721161516;3690.612485688991;3956.7823769692654;3976.980115099406;4336.956630433687;4667.994026879044;35119.6488035119;35262.29475410503;35346.293074138375;35465.468017979496;35734.64265357339;4595.448546174447;4680.531946805327;4699.835505757316;4710.434747830248;4736.976315118437;368772;373265;373699;373838;374345;65400.67299663471;65758.34241657618;65791;65871;65879;40081;40447.19105617871;40771.796141019106;42833.57166428358;68368;17073.829261707473;17226.138693065295;17242;17243.310267589157;17245.913770431067;12221.572244971561;13005.609831705064;13109.934450327879;13111.14777539476;13112.541061063008;20169.193232270838;20796.1681532737;20892.268770592957;21558.245461408776;21798.128074877146;7421.703131874665;7424.925750742424;7479.252074792586;7489.325960663567;7650.617469126632;1017.4404077757204;1080.308602494404;1106.1980064453207;1143.1806443923438;1166.3060479014973;33587.496187557204;35299.29401411957;35404.64595354065;35511.15821587384;35761.64238357583;1126.442411006557;1139.3164101539176;1172.2907640877318;1177.4289469607234;1179.5163982767024;18489.90755046216;18590.44228673142;18706.895271290505;18755.90622046881;18939.242430302907;556224;560286;562043;564164;564803;66113;66368;66388.6680566594;66825;66874.33125668779;1346.811446397505;1415.6390120519407;1428.7142571485745;1458.985410145885;1474.6313421644588;34146.658533414346;35246.64753352434;36427.81786091052;36789.44815827818;36940.81529592335;57602.4239757597;62382.37617623766;63596.364036359046;63672;63766.36233637604;548054;549022;550290;552662;553248;40492.797536012135;47202.527974719815;47489.52510474851;47822;49029;53254.201186983;53286;53749.46250537601;54029.729851352065;54100;44521.642465086785;45081.549184507734;45899.77050114728;46500;47722.28416573824;44933.55066449424;45315.77342113268;46938;49988;50542;45639.31541026821;46597.767011164724;46823.29765053595;46949.76525117489;47103.293450597586;39564.40653390144;41953.580464194965;42207.788961055;42294;42441;30991.845040774653;31307.217319567193;31539.52690709687;31580.526292105176;31775.364492709556 15:12:30 INFO - PROCESS | 20829 | |2;jquery.html;375.9868404605838;396.6806720589924;422.84988828965714;433.1380552700128;449.4090271293249;18908.054597270133;20091.79449233047;20492.18031278747;20501.38495845123;20653.793462065376;18972.430827075208;19269.325573604925;19459.902700486513;19710.802891971078;19724.211031558756;5050.191969284915;5240.528352448279;5265.9473405266035;5334.093204155303;5335;5268.1834315681135;5304.761285742151;5657.264555607765;5739.311282646067;5766.481016708495;5737.082066869301;5813.09896965971;5944.197533333009;5951.940480595204;5956.553258505608;5503.229547863302;5727.312722473309;5765.048766953448;5815.563832712522;5819.127130930352;5461.4811592899005;5572.164175373686;5599.356074051455;5604.887902241973;5655.575831812589;13236;13283.402246898913;13441.865581344158;13796.379162937692;14038.508652197226;2284.851484653492;2293.9197128100436;2310.7573704761094;2397.44858682504;2534.619807028942;10563.154947604173;10573.365598064142;10607.469626518643;10681.572737090508;13051.934740326333;1963.1656545968049;1965.4496740912568;2005.9880239520958;2034.08466190215;2106.7682554918947;67821.66089169522;68742.3125768746;76484.61757691232;77983.6100819498;87535.12464875398;10837.83743243852;10859.511321990529;11051;11406;11509.597164099216;14497.492587759378;14501.854981450262;14540.854591454161;14557;14568.562943111723;7982.521048736979;8098.35213182944;8175.836483270301;8210.958945205235;8264.669413223404;7997.600119993977;8026.7591972240925;8201.548914809624;8235.21765432283;8238.50568965864;4451.243288640874;4483;4535.115652447741;4567.634589232854;4752.120857641347;1834.3488061738158;1840.7975122736489;1870.2145099058428;1871.6724573199635;1894.1949671389605;901.1979338388708;1021.7905329407603;1099.5656715597295;1114.8662160540625;1130.6608017594688;20155.59688806245;20570;20749.89625051865;20873.47816304574;20912.16351345929;1028.9691309260734;1035.891231420705;1037.6368271105052;1054.8997845204694;1058.402002868377;10673.57305707763;11569.305841649528;12325.631268421888;12466.252024878537;12493;527267;528263;531931;535228;535773;96664;97180.51409743048;98004.50997744966;98258.508707456;99592.50203748935;1078.455380033094;1086.6359769477315;1119.1941801902617;1125.926814757038;1128.28353995212;15335.38658453694;15810.893237473267;18564.44306670802;18797.530061748566;19067.523311917314;42437.78781106075;42518;45735.085298294514;47207.763961179975;48690;61527;62040.379596203464;62170;63505;63944;8367.519245095604;8369.790755230924;8403.663853446038;8421.5789210538;8543.316534677335;9195.632174713204;12759.48962041508;13251.933740331237;13618.38717257706;13860.306984650928;11850.400195973432;11871.703207419883;11942.283462992249;12100.75798484043;12637.17858339205;11436.256643318158;11521.815183167775;11641.6507504775;11900.345480998629;12177.57378491784;11850.940745296219;12246.75506489883;12308.323042232763;12350.814737779121;12414.875851241371;7159.240086196138;7215.783526494213;7476.476646634684;7895.486793358414;8662.220400164015;10579.206559507913;11857.229280096766;11991.100667449802;12119.454624542093;12653.051021173265 15:12:30 INFO - PROCESS | 20829 | |3;mootools.html;1348.608903418009;1371.6090914089486;1450.992745036275;1559.5555266748972;1880.8965506897116;8652.437591556545;9000.684976025841;9058.139476749715;9323.067693230674;9330.253579713624;9214.907850921489;9339.626414943394;9339.832520934882;9344.672936447225;9346.112119348669;2439.3291844742703;2482.03200751707;2482.1684735613503;2482.615194644833;2486.428121532049;2402.5795485790004;2506.0727530813533;2506.1729629222405;2509.5608268553024;2519.5590771614984;2428.4778772563895;2451.8774061296954;2495.126705653025;2499.950000999979;2505.5991041433376;2325.7790509901533;2344.979933728833;2373.643953406994;2427.1262345555588;2438.5610590093775;2357.1867705641525;2358.811295096388;2364.7162340519076;2365.6451532270125;2387.271882075966;9148.313876459226;9219.723408297694;9223.492707901063;9290.02454742249;9334.766630834216;5468.6171967962255;5681.829545113653;5690.5447564194765;5696.8006119785605;5701.173329867146;3386.779859309137;3404.114930118162;3429.5198672185916;3436.3127374525193;3509.175343794206;1099.5491848342222;1108.8115020446544;1113.2986218682258;1115.3530952047793;1131.1911982932243;12531.373431328488;12589.685257868536;12711.872881271254;12746.235225886476;12778.10553261272;4707.882302942386;4746.239225295057;4749.857504274877;4799.808007679723;4873.756312184448;10193.949030254802;10198.949005254926;10202.846957295647;10214.336068155546;10228.846567301496;5463.2078348639225;5475.09660905954;5487.067198576252;5490.972545137329;5492.642978206404;4511.70673906195;4534.36518887356;4691.752710934439;5037.168867136955;5073.467285934998;3976.483057202603;4013.3979903014783;4110.136871257069;4113.074558224375;4114.403411505375;1148.8678801937717;1155.7457359381092;1159.3391766692905;1161.924474909128;1174.9647510574696;907.3149771922157;1007.3250921882357;1007.9899201007896;1020.4693559349097;1052.084686322904;5988.670623115683;6163.044728067157;6183.165272688129;6286.465650419766;6396;962.8363178259713;1013.807376598444;1059.597353005853;1094.0645747885444;1119.6473110970019;4030.234255491447;4071.307877660805;4090.263752524574;4148.419221309019;4259.701820872571;11215.943920280346;11312.660620181408;11317.94341028306;11507.884921150682;11573.537058517564;11292.604758833402;11322.943385283186;11361;11443.485043173077;11536.76926461466;7712;7998.080220774686;8380.832383352421;8449.915500844912;8551.187637174458;6355.936440635534;6400.807975760735;6462.838429039123;6571.507136964651;6588.110605068255;10155.949220253851;10324.948375258076;10418.374897505872;10518.894811051792;10679.19906007037;10198.592056317664;10301.690949271533;10409.68770936873;10559.313644613076;10782.622608208989;7975.361971042186;8051.436399451982;8165.469244499089;8244.381671374551;8318.126596707501;9218.44689318643;9268.814623707623;9274.258059355101;9275.76810579741;9288.256939444964;7998.920010799818;8034.598270086355;8063.798405039921;8240.587970601566;8337.541435221121;7477.70089196426;7553.546787192786;7593.962030190035;8121.918780812116;8134.674613015413;6403.64779937108;6461.418472337512;6463.57986730861;6464.0627109069865;6520.3805638466165;5194.091034069098;5203.479652034917;5229.058769421541;5253.763580638957;5276.8273485258005;5191.480851914778;5199.532042116228;5206.661566998133;5248.107821670249;5361.705106219196 15:12:30 INFO - PROCESS | 20829 | |4;prototype.html;332.1811734075503;345.58184596638057;346.2918332011037;348.72450763896524;350.27318314497427;5559.166125081239;5603.943960560393;5621.971890140554;5625.874825034989;5668.858278543028;5231.5814734821215;5319.5744340452675;5399.946000539993;5437.34751829781;5577.470140336672;4254.659627229829;4296.677749168809;4320.503142138663;4339.717918335314;4389.144116897206;4341.305391137419;4347.978260108695;4356.411884395598;4356.651467882578;4393.362962370455;4097.262492751303;4259.403683484299;4306.569343065684;4331.068820203655;4339.500957389893;4261.829526818923;4280.250956082688;4283.443152390169;4319.784010799447;4319.956800431987;3796.6393192646656;3801.6124114698214;3805.1438426354125;3806.7715937043868;3819.2743378758223;5286;5338.679679219221;5357.812476563302;5462.754176062087;5501.669899805985;4884.7069175849565;4885.6335774817035;4893.314935908976;4906.779194936236;4910.312556242093;5735;5735.483806457397;5755.482006619382;5759;5795.710214489259;4030.1536677297804;4083.673306135502;4111.177764447093;4127.752334859918;4202.85290014851;5956;5961.970190149065;5973.761049558012;5977.3723759005115;5979.1629171915965;4222.261104306734;4224.197402493516;4226.5418430641375;4247.426597409372;4252.29837076885;4064.918701625951;4278.507971583245;4286.678499112579;4292.463442069741;4304.096139810612;4879.048585525859;4881.51184881509;4883.267509873476;4886.633502487257;4920.75396230187;5869.882602347929;5884.941150588525;5889.793857214977;5893.941060589425;5925.022371308772;1455.7961885336063;1461.8465061168638;1475.5278310940396;1480.7260656778315;1495.7681559358316;923.2337160157053;932.8693982842408;950.1496160935924;955.3885513271512;957.6313119449062;5057.508035129646;5067.974660126676;5070.340855688811;5072.315237442972;5090.465501122403;5258.343621759133;5278.9208161877605;5291.042314343717;5296.629235953446;5314.681119132864;4679.438467383936;4991.775370108354;5016.673916195436;5020.673656212334;5024.824131155392;2788.288986308519;2807.4385122975077;2812.549992001311;2818.5208514552573;2847.6298081249306;5336.46635336471;5357.517823395913;5405.675659460445;5410.91883621746;5426.565874730012;5155.922661160085;5183.740812959412;5233.869153271123;5342.839714808562;5345.545628621533;5333.333333333333;5342.637627405064;5355.277037599874;5375.838724838183;5436.592255580768;5252.317198764289;5252.711100889488;5269.4981930149725;5278.841634750964;5322.574194064388;4993.775280112331;5156.381234251764;5156.9573951368875;5190.221466779863;5198.814953962097;5356.892862142813;5366.892662146814;5400.7569659364635;5410.242566040679;5435.945640543544;3820.503334566489;3855.51562648388;3893.591172926802;3918.333883239799;4059.2287465381482;5388.811391601275;5424.783008679608;5861.384554621874;5928.584999050197;5930.52555795544;5731;5731.5701322400155;5733.509287585174;5746;5756.913646295393;5663.697349609695;5699.401562836008;5757.971210143923;5774.412036689944;5778.971105144447;5733.770649173986;5736.799212027559;5788.668606220509;5825.359210486928;5833.212516310414;5867.295924488918;5883.882322353444;5895.793647222326;5908.113782932594;5914.260717410324;5499.367572729027;5648.745806438802;5672.858178545569;5682.3465301489205;5715.685637289989 15:12:30 INFO - PROCESS | 20829 | |5;yui.html;765.2959277464732;794.2653045932512;805.8912046873673;828.3704384667651;941.2046820436738;16193.514194574167;16473;16486.835131648666;16510.339586416543;16517.587060323498;15726.449574264903;15881.761773573406;16051.919740401281;16054.1170235637;16063.357465701358;734.6290123487657;819.4755356571799;861.5993562993207;870.3733312015371;873.9781505462382;750.7034721285089;757.6817736550635;759.3735168485994;846.6063280574532;866.3502373220085;913.0367462327242;921.2307723051226;922.9861552076724;923.2152670230317;923.3351986569659;916.2532535983219;919.6321471411422;919.8068405634825;920.1764420843375;921.0007142250666;670.6713710281975;674.4570620650375;675.8749631318173;675.9121314229119;676.3777324861139;15409.614759630987;15461.845381546265;15462.690746185126;15467.226638668022;15507.534773956799;14098.929505352407;14732.55802325932;14798.70402591953;14943.701125977528;16283.430079947146;14083.788743168861;14246.786298205432;14296.285185740775;14585.416583336653;14729.852701472955;4754.405699287589;4767.237242041257;4810.422749270075;4812.061647978646;4882.048000639877;88281.55859220662;89867.55066224693;90813;91227.54386228093;92535.53732231363;10868.72828179286;10902.836457453144;11069.833952490719;11930.715245632338;12033.879661203451;21650;21710.131594736034;21802.89098554497;21804.345869623936;21897.671534926987;8702.34732395073;8703.6518539259;8724;9085.591148398338;9300.906990930003;4827.348307978448;5061.519155680205;5067.822626208065;5120.18077107661;5178.482151784791;3761.4733937248807;3823.235352929425;3841.8847434577006;3845.307844587945;3873.65137137659;641.1568787045021;642.4154019842015;644.136856612142;645.2063961327593;666.0142988377229;700.8388070743757;703.3669697272496;707.3881092854747;723.5260904107818;739.7299985505252;9416.529173541296;9504.90495095054;9715.271354648288;10348.741281468023;10501.579936802595;713.2760248347931;716.3159182980346;720.6144712578745;721.2066726600802;767.244264399566;4638.628909687217;4919.803207871717;4988.650794444354;5008.6744361616375;5037.546620804145;76177.6191119052;77485.6125719379;77556.2244377549;94326;127351;110522;112346;112525.43737281261;115106;115192;94636.52681736548;97464;98048;101893;102068.48965755124;9155.86266205994;9414.858777118212;9545.796787817486;9569.501816552807;9603.37578057424;48762;57390;57990.3904324298;58192.4180758187;58436;67006;75796.62101689457;78101.60949195217;79466;84797.57601211955;14323.78514322307;14328.49850255236;14573.489927852474;14690.192039437934;15019.324130414376;16500.17499125015;16789.41237056697;16846.66306673884;17442.389516366868;17873.910630446764;13908.791368129283;14525.782113268098;14677.41290348374;14726.926365368534;15089.622759431102;14231.928840355731;15211.239438028275;15223.467178648694;15369.538913832603;15661.295241713922;13037.47850085986;14034.929825351217;14304.713905722036;15048.6990260192;15349;12723.172993755747;12778.68053298675;12780.48878044905;12888.935555322538;12976.935115324362;11789.528418863149;11866.40667966581;11975.580854670045;12004.039676826007;12517.374131293582 15:12:30 INFO - PROCESS | 20829 | __end_tp_report 15:12:30 INFO - PROCESS | 20829 | __start_cc_report 15:12:30 INFO - PROCESS | 20829 | _x_x_mozilla_cycle_collect,996 15:12:30 INFO - PROCESS | 20829 | __end_cc_report 15:12:30 INFO - PROCESS | 20829 | __startTimestamp1476137550799__endTimestamp 15:12:30 INFO - PROCESS | 20829 | 15:12:30 INFO - PROCESS | 20829 | ------- Summary: start ------- 15:12:30 INFO - PROCESS | 20829 | Number of tests: 6 15:12:30 INFO - PROCESS | 20829 | 15:12:30 INFO - PROCESS | 20829 | [#0] dojo.html Cycles:165 Average:5534.60 Median:5761.94 stddev:613.01 (10.6%) stddev-sans-first:585.95 15:12:30 INFO - PROCESS | 20829 | Values: 3161.7 3163.0 3184.8 3198.6 3224.0 5533.9 5571.1 5603.7 5622.9 5623.2 5560.9 5654.6 5665.9 5668.9 5695.5 5120.1 5144.8 5188.9 5202.2 5214.6 5063.4 5206.6 5230.9 5236.5 5240.4 5010.7 5030.6 5058.1 5082.4 5107.6 5037.0 5062.4 5088.6 5172.6 5202.7 5000.3 5085.9 5136.3 5150.8 5160.6 5349.8 5425.0 5454.2 5478.3 5496.3 5877.1 5895.5 5932.9 5964.7 5976.7 5763.9 5986.5 5994.3 6010.5 6023.4 5495.5 5548.7 5554.3 5568.6 5628.9 6083.6 6087.4 6089.7 6095.6 6125.5 5669.7 5715.8 5880.5 5911.0 5913.1 5861.5 5898.3 5930.3 5932.0 5933.0 5912.4 5913.1 5915.5 5916.7 5919.8 5752.3 5861.6 5919.6 5924.1 5925.3 4505.9 4541.0 4585.7 4595.4 4650.5 4104.8 4111.3 4113.0 4115.9 4119.5 5769.6 5777.5 5782.8 5784.0 5799.3 5766.4 5908.4 5941.5 5976.5 6051.1 5734.2 5744.3 5744.6 5746.9 5748.1 5202.0 5204.3 5204.3 5214.5 5239.8 5893.5 6069.5 6120.5 6135.0 6185.0 5917.5 5959.0 5963.7 5966.4 5982.6 6131.4 6162.8 6184.8 6185.9 6203.4 5362.2 5641.1 5761.9 5809.4 5895.6 5906.4 5915.9 5958.3 5962.8 5976.4 5815.8 5822.9 5826.2 5827.0 5827.9 5545.9 5748.7 5799.3 5803.3 5895.7 5737.8 5784.4 5795.0 5796.6 5814.7 5814.2 5843.2 5848.5 5863.9 5877.6 5519.8 5620.2 5638.2 5653.9 5773.7 15:12:30 INFO - PROCESS | 20829 | 15:12:30 INFO - PROCESS | 20829 | [#1] ext.html Cycles:180 Average:67520.55 Median:23585.26 stddev:133179.05 (564.7%) stddev-sans-first:133457.86 15:12:30 INFO - PROCESS | 20829 | Values: 594.8 654.7 664.1 682.3 684.1 35245.0 47809.5 48255.3 48324.0 48335.0 48755.5 49273.3 49284.0 49301.0 49319.5 8690.7 9407.1 9502.6 9540.1 9569.5 8882.0 8946.9 8958.4 11041.7 11311.8 11494.8 11567.5 11573.8 11574.7 11579.4 11365.0 11431.1 11436.3 11436.4 11442.1 11337.5 11426.1 11432.1 11432.8 11440.2 45993.0 46222.5 46254.3 46316.0 46455.8 3690.6 3956.8 3977.0 4337.0 4668.0 35119.6 35262.3 35346.3 35465.5 35734.6 4595.4 4680.5 4699.8 4710.4 4737.0 368772.0 373265.0 373699.0 373838.0 374345.0 65400.7 65758.3 65791.0 65871.0 65879.0 40081.0 40447.2 40771.8 42833.6 68368.0 17073.8 17226.1 17242.0 17243.3 17245.9 12221.6 13005.6 13109.9 13111.1 13112.5 20169.2 20796.2 20892.3 21558.2 21798.1 7421.7 7424.9 7479.3 7489.3 7650.6 1017.4 1080.3 1106.2 1143.2 1166.3 33587.5 35299.3 35404.6 35511.2 35761.6 1126.4 1139.3 1172.3 1177.4 1179.5 18489.9 18590.4 18706.9 18755.9 18939.2 556224.0 560286.0 562043.0 564164.0 564803.0 66113.0 66368.0 66388.7 66825.0 66874.3 1346.8 1415.6 1428.7 1459.0 1474.6 34146.7 35246.6 36427.8 36789.4 36940.8 57602.4 62382.4 63596.4 63672.0 63766.4 548054.0 549022.0 550290.0 552662.0 553248.0 40492.8 47202.5 47489.5 47822.0 49029.0 53254.2 53286.0 53749.5 54029.7 54100.0 44521.6 45081.5 45899.8 46500.0 47722.3 44933.6 45315.8 46938.0 49988.0 50542.0 45639.3 46597.8 46823.3 46949.8 47103.3 39564.4 41953.6 42207.8 42294.0 42441.0 30991.8 31307.2 31539.5 31580.5 31775.4 15:12:30 INFO - PROCESS | 20829 | 15:12:30 INFO - PROCESS | 20829 | [#2] jquery.html Cycles:180 Average:30235.15 Median:20892.82 stddev:87589.76 (419.2%) stddev-sans-first:87806.78 15:12:30 INFO - PROCESS | 20829 | Values: 376.0 396.7 422.8 433.1 449.4 18908.1 20091.8 20492.2 20501.4 20653.8 18972.4 19269.3 19459.9 19710.8 19724.2 5050.2 5240.5 5265.9 5334.1 5335.0 5268.2 5304.8 5657.3 5739.3 5766.5 5737.1 5813.1 5944.2 5951.9 5956.6 5503.2 5727.3 5765.0 5815.6 5819.1 5461.5 5572.2 5599.4 5604.9 5655.6 13236.0 13283.4 13441.9 13796.4 14038.5 2284.9 2293.9 2310.8 2397.4 2534.6 10563.2 10573.4 10607.5 10681.6 13051.9 1963.2 1965.4 2006.0 2034.1 2106.8 67821.7 68742.3 76484.6 77983.6 87535.1 10837.8 10859.5 11051.0 11406.0 11509.6 14497.5 14501.9 14540.9 14557.0 14568.6 7982.5 8098.4 8175.8 8211.0 8264.7 7997.6 8026.8 8201.5 8235.2 8238.5 4451.2 4483.0 4535.1 4567.6 4752.1 1834.3 1840.8 1870.2 1871.7 1894.2 901.2 1021.8 1099.6 1114.9 1130.7 20155.6 20570.0 20749.9 20873.5 20912.2 1029.0 1035.9 1037.6 1054.9 1058.4 10673.6 11569.3 12325.6 12466.3 12493.0 527267.0 528263.0 531931.0 535228.0 535773.0 96664.0 97180.5 98004.5 98258.5 99592.5 1078.5 1086.6 1119.2 1125.9 1128.3 15335.4 15810.9 18564.4 18797.5 19067.5 42437.8 42518.0 45735.1 47207.8 48690.0 61527.0 62040.4 62170.0 63505.0 63944.0 8367.5 8369.8 8403.7 8421.6 8543.3 9195.6 12759.5 13251.9 13618.4 13860.3 11850.4 11871.7 11942.3 12100.8 12637.2 11436.3 11521.8 11641.7 11900.3 12177.6 11850.9 12246.8 12308.3 12350.8 12414.9 7159.2 7215.8 7476.5 7895.5 8662.2 10579.2 11857.2 11991.1 12119.5 12653.1 15:12:30 INFO - PROCESS | 20829 | 15:12:30 INFO - PROCESS | 20829 | [#3] mootools.html Cycles:180 Average:5990.19 Median:4131.41 stddev:3418.28 (82.7%) stddev-sans-first:3410.06 15:12:30 INFO - PROCESS | 20829 | Values: 1348.6 1371.6 1451.0 1559.6 1880.9 8652.4 9000.7 9058.1 9323.1 9330.3 9214.9 9339.6 9339.8 9344.7 9346.1 2439.3 2482.0 2482.2 2482.6 2486.4 2402.6 2506.1 2506.2 2509.6 2519.6 2428.5 2451.9 2495.1 2500.0 2505.6 2325.8 2345.0 2373.6 2427.1 2438.6 2357.2 2358.8 2364.7 2365.6 2387.3 9148.3 9219.7 9223.5 9290.0 9334.8 5468.6 5681.8 5690.5 5696.8 5701.2 3386.8 3404.1 3429.5 3436.3 3509.2 1099.5 1108.8 1113.3 1115.4 1131.2 12531.4 12589.7 12711.9 12746.2 12778.1 4707.9 4746.2 4749.9 4799.8 4873.8 10193.9 10198.9 10202.8 10214.3 10228.8 5463.2 5475.1 5487.1 5491.0 5492.6 4511.7 4534.4 4691.8 5037.2 5073.5 3976.5 4013.4 4110.1 4113.1 4114.4 1148.9 1155.7 1159.3 1161.9 1175.0 907.3 1007.3 1008.0 1020.5 1052.1 5988.7 6163.0 6183.2 6286.5 6396.0 962.8 1013.8 1059.6 1094.1 1119.6 4030.2 4071.3 4090.3 4148.4 4259.7 11215.9 11312.7 11317.9 11507.9 11573.5 11292.6 11322.9 11361.0 11443.5 11536.8 7712.0 7998.1 8380.8 8449.9 8551.2 6355.9 6400.8 6462.8 6571.5 6588.1 10155.9 10324.9 10418.4 10518.9 10679.2 10198.6 10301.7 10409.7 10559.3 10782.6 7975.4 8051.4 8165.5 8244.4 8318.1 9218.4 9268.8 9274.3 9275.8 9288.3 7998.9 8034.6 8063.8 8240.6 8337.5 7477.7 7553.5 7594.0 8121.9 8134.7 6403.6 6461.4 6463.6 6464.1 6520.4 5194.1 5203.5 5229.1 5253.8 5276.8 5191.5 5199.5 5206.7 5248.1 5361.7 15:12:30 INFO - PROCESS | 20829 | 15:12:30 INFO - PROCESS | 20829 | [#4] prototype.html Cycles:180 Average:4682.01 Median:5255.53 stddev:1352.05 (25.7%) stddev-sans-first:1315.84 15:12:30 INFO - PROCESS | 20829 | Values: 332.2 345.6 346.3 348.7 350.3 5559.2 5603.9 5622.0 5625.9 5668.9 5231.6 5319.6 5399.9 5437.3 5577.5 4254.7 4296.7 4320.5 4339.7 4389.1 4341.3 4348.0 4356.4 4356.7 4393.4 4097.3 4259.4 4306.6 4331.1 4339.5 4261.8 4280.3 4283.4 4319.8 4320.0 3796.6 3801.6 3805.1 3806.8 3819.3 5286.0 5338.7 5357.8 5462.8 5501.7 4884.7 4885.6 4893.3 4906.8 4910.3 5735.0 5735.5 5755.5 5759.0 5795.7 4030.2 4083.7 4111.2 4127.8 4202.9 5956.0 5962.0 5973.8 5977.4 5979.2 4222.3 4224.2 4226.5 4247.4 4252.3 4064.9 4278.5 4286.7 4292.5 4304.1 4879.0 4881.5 4883.3 4886.6 4920.8 5869.9 5884.9 5889.8 5893.9 5925.0 1455.8 1461.8 1475.5 1480.7 1495.8 923.2 932.9 950.1 955.4 957.6 5057.5 5068.0 5070.3 5072.3 5090.5 5258.3 5278.9 5291.0 5296.6 5314.7 4679.4 4991.8 5016.7 5020.7 5024.8 2788.3 2807.4 2812.5 2818.5 2847.6 5336.5 5357.5 5405.7 5410.9 5426.6 5155.9 5183.7 5233.9 5342.8 5345.5 5333.3 5342.6 5355.3 5375.8 5436.6 5252.3 5252.7 5269.5 5278.8 5322.6 4993.8 5156.4 5157.0 5190.2 5198.8 5356.9 5366.9 5400.8 5410.2 5435.9 3820.5 3855.5 3893.6 3918.3 4059.2 5388.8 5424.8 5861.4 5928.6 5930.5 5731.0 5731.6 5733.5 5746.0 5756.9 5663.7 5699.4 5758.0 5774.4 5779.0 5733.8 5736.8 5788.7 5825.4 5833.2 5867.3 5883.9 5895.8 5908.1 5914.3 5499.4 5648.7 5672.9 5682.3 5715.7 15:12:30 INFO - PROCESS | 20829 | 15:12:30 INFO - PROCESS | 20829 | [#5] yui.html Cycles:180 Average:21976.70 Median:26794.67 stddev:31149.27 (116.3%) stddev-sans-first:31195.94 15:12:30 INFO - PROCESS | 20829 | Values: 765.3 794.3 805.9 828.4 941.2 16193.5 16473.0 16486.8 16510.3 16517.6 15726.4 15881.8 16051.9 16054.1 16063.4 734.6 819.5 861.6 870.4 874.0 750.7 757.7 759.4 846.6 866.4 913.0 921.2 923.0 923.2 923.3 916.3 919.6 919.8 920.2 921.0 670.7 674.5 675.9 675.9 676.4 15409.6 15461.8 15462.7 15467.2 15507.5 14098.9 14732.6 14798.7 14943.7 16283.4 14083.8 14246.8 14296.3 14585.4 14729.9 4754.4 4767.2 4810.4 4812.1 4882.0 88281.6 89867.6 90813.0 91227.5 92535.5 10868.7 10902.8 11069.8 11930.7 12033.9 21650.0 21710.1 21802.9 21804.3 21897.7 8702.3 8703.7 8724.0 9085.6 9300.9 4827.3 5061.5 5067.8 5120.2 5178.5 3761.5 3823.2 3841.9 3845.3 3873.7 641.2 642.4 644.1 645.2 666.0 700.8 703.4 707.4 723.5 739.7 9416.5 9504.9 9715.3 10348.7 10501.6 713.3 716.3 720.6 721.2 767.2 4638.6 4919.8 4988.7 5008.7 5037.5 76177.6 77485.6 77556.2 94326.0 127351.0 110522.0 112346.0 112525.4 115106.0 115192.0 94636.5 97464.0 98048.0 101893.0 102068.5 9155.9 9414.9 9545.8 9569.5 9603.4 48762.0 57390.0 57990.4 58192.4 58436.0 67006.0 75796.6 78101.6 79466.0 84797.6 14323.8 14328.5 14573.5 14690.2 15019.3 16500.2 16789.4 16846.7 17442.4 17873.9 13908.8 14525.8 14677.4 14726.9 15089.6 14231.9 15211.2 15223.5 15369.5 15661.3 13037.5 14034.9 14304.7 15048.7 15349.0 12723.2 12778.7 12780.5 12888.9 12976.9 11789.5 11866.4 11975.6 12004.0 12517.4 15:12:30 INFO - PROCESS | 20829 | -------- Summary: end -------- 15:12:30 INFO - PROCESS | 20829 | 15:12:31 INFO - TEST-INFO | 20829: exit 0 15:12:31 INFO - TEST-OK | dromaeo_css | took 1112369ms 15:12:31 INFO - TEST-START | kraken 15:12:31 INFO - operating with platform_type : linux_ 15:12:31 INFO - Initialising browser for kraken test... 15:12:31 INFO - TEST-INFO | started process 21038 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNlgIoL/profile http://localhost:44801/getInfo.html) 15:12:35 INFO - PROCESS | 21038 | __metrics Screen width/height:1600/1200 15:12:35 INFO - PROCESS | 21038 | colorDepth:24 15:12:35 INFO - PROCESS | 21038 | Browser inner width/height: 1024/697 15:12:35 INFO - PROCESS | 21038 | __metrics 15:12:35 INFO - PROCESS | 21038 | 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" 15:12:35 INFO - PROCESS | 21038 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:12:36 INFO - PROCESS | 21038 | console.error: PushService: 15:12:36 INFO - PROCESS | 21038 | stateChangeProcessEnqueue: Error transitioning state 15:12:36 INFO - PROCESS | 21038 | UnknownError 15:12:36 INFO - TEST-INFO | 21038: exit 0 15:12:36 INFO - Browser initialized. 15:12:36 INFO - Running cycle 1/1 for kraken test... 15:12:36 INFO - TEST-INFO | started process 21124 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpNlgIoL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:12:48 INFO - PROCESS | 21124 | RSS: Main: 144257024 15:12:48 INFO - PROCESS | 21124 | 15:12:50 INFO - PROCESS | 21124 | Cycle 1(1): loaded http://localhost:44801/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44801/tests/kraken/driver.html?testName=audio-beat-detection) 15:12:51 INFO - PROCESS | 21124 | RSS: Main: 199090176 15:12:51 INFO - PROCESS | 21124 | 15:12:54 INFO - PROCESS | 21124 | Cycle 1(1): loaded http://localhost:44801/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44801/tests/kraken/driver.html?testName=audio-dft) 15:12:54 INFO - PROCESS | 21124 | RSS: Main: 183398400 15:12:55 INFO - PROCESS | 21124 | 15:12:57 INFO - PROCESS | 21124 | Cycle 1(1): loaded http://localhost:44801/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44801/tests/kraken/driver.html?testName=audio-fft) 15:12:58 INFO - PROCESS | 21124 | RSS: Main: 275226624 15:12:58 INFO - PROCESS | 21124 | 15:13:00 INFO - PROCESS | 21124 | Cycle 1(1): loaded http://localhost:44801/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44801/tests/kraken/driver.html?testName=audio-oscillator) 15:13:01 INFO - PROCESS | 21124 | RSS: Main: 179929088 15:13:01 INFO - PROCESS | 21124 | 15:13:03 INFO - PROCESS | 21124 | Cycle 1(1): loaded http://localhost:44801/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44801/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:13:03 INFO - PROCESS | 21124 | RSS: Main: 179490816 15:13:03 INFO - PROCESS | 21124 | 15:13:06 INFO - PROCESS | 21124 | Cycle 1(1): loaded http://localhost:44801/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44801/tests/kraken/driver.html?testName=imaging-darkroom) 15:13:07 INFO - PROCESS | 21124 | RSS: Main: 214798336 15:13:07 INFO - PROCESS | 21124 | 15:13:10 INFO - PROCESS | 21124 | Cycle 1(1): loaded http://localhost:44801/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44801/tests/kraken/driver.html?testName=imaging-desaturate) 15:13:11 INFO - PROCESS | 21124 | RSS: Main: 213913600 15:13:11 INFO - PROCESS | 21124 | 15:13:14 INFO - PROCESS | 21124 | Cycle 1(1): loaded http://localhost:44801/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44801/tests/kraken/driver.html?testName=json-parse-financial) 15:13:14 INFO - PROCESS | 21124 | RSS: Main: 213872640 15:13:14 INFO - PROCESS | 21124 | 15:13:16 INFO - PROCESS | 21124 | Cycle 1(1): loaded http://localhost:44801/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44801/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:13:16 INFO - PROCESS | 21124 | RSS: Main: 175931392 15:13:16 INFO - PROCESS | 21124 | 15:13:18 INFO - PROCESS | 21124 | Cycle 1(1): loaded http://localhost:44801/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44801/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:13:19 INFO - PROCESS | 21124 | RSS: Main: 183156736 15:13:19 INFO - PROCESS | 21124 | 15:13:21 INFO - PROCESS | 21124 | Cycle 1(1): loaded http://localhost:44801/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44801/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:13:21 INFO - PROCESS | 21124 | RSS: Main: 189657088 15:13:21 INFO - PROCESS | 21124 | 15:13:24 INFO - PROCESS | 21124 | Cycle 1(1): loaded http://localhost:44801/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44801/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:13:24 INFO - PROCESS | 21124 | RSS: Main: 197496832 15:13:24 INFO - PROCESS | 21124 | 15:13:27 INFO - PROCESS | 21124 | Cycle 1(1): loaded http://localhost:44801/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44801/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:13:28 INFO - PROCESS | 21124 | RSS: Main: 194306048 15:13:28 INFO - PROCESS | 21124 | 15:13:29 INFO - PROCESS | 21124 | Cycle 1(1): loaded http://localhost:44801/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44801/tests/kraken/driver.html?testName=ai-astar) 15:13:29 INFO - PROCESS | 21124 | RSS: Main: 243658752 15:13:29 INFO - PROCESS | 21124 | 15:13:29 INFO - PROCESS | 21124 | __start_tp_report 15:13:29 INFO - PROCESS | 21124 | _x_x_mozilla_page_load 15:13:29 INFO - PROCESS | 21124 | _x_x_mozilla_page_load_details 15:13:29 INFO - PROCESS | 21124 | |i|pagename|runs| 15:13:29 INFO - PROCESS | 21124 | |0;ai-astar;110;110;120;110;110;110;108;110;111;109 15:13:29 INFO - PROCESS | 21124 | |1;audio-beat-detection;143;117;155;169;121;150;139;116;189;118 15:13:29 INFO - PROCESS | 21124 | |2;audio-dft;161;160;159;160;161;160;158;160;159;160 15:13:29 INFO - PROCESS | 21124 | |3;audio-fft;75;74;76;171;76;74;74;74;162;75 15:13:29 INFO - PROCESS | 21124 | |4;audio-oscillator;83;83;154;84;84;85;150;84;84;148 15:13:29 INFO - PROCESS | 21124 | |5;imaging-gaussian-blur;117;117;116;117;117;117;116;116;117;116 15:13:29 INFO - PROCESS | 21124 | |6;imaging-darkroom;145;146;146;146;146;147;147;147;146;146 15:13:29 INFO - PROCESS | 21124 | |7;imaging-desaturate;94;95;95;94;95;95;95;94;95;94 15:13:29 INFO - PROCESS | 21124 | |8;json-parse-financial;52;50;50;49;49;52;50;49;50;50 15:13:29 INFO - PROCESS | 21124 | |9;json-stringify-tinderbox;60;62;61;61;61;60;60;61;61;61 15:13:29 INFO - PROCESS | 21124 | |10;stanford-crypto-aes;72;72;72;73;76;81;73;75;72;71 15:13:29 INFO - PROCESS | 21124 | |11;stanford-crypto-ccm;128;120;118;120;126;119;115;116;158;122 15:13:29 INFO - PROCESS | 21124 | |12;stanford-crypto-pbkdf2;154;154;157;157;153;154;151;154;153;152 15:13:29 INFO - PROCESS | 21124 | |13;stanford-crypto-sha256-iterative;64;62;64;62;61;62;61;62;62;60 15:13:29 INFO - PROCESS | 21124 | __end_tp_report 15:13:29 INFO - PROCESS | 21124 | __start_cc_report 15:13:29 INFO - PROCESS | 21124 | _x_x_mozilla_cycle_collect,1679 15:13:29 INFO - PROCESS | 21124 | __end_cc_report 15:13:29 INFO - PROCESS | 21124 | __startTimestamp1476137609883__endTimestamp 15:13:29 INFO - PROCESS | 21124 | 15:13:29 INFO - PROCESS | 21124 | ------- Summary: start ------- 15:13:29 INFO - PROCESS | 21124 | Number of tests: 14 15:13:29 INFO - PROCESS | 21124 | 15:13:29 INFO - PROCESS | 21124 | [#0] ai-astar Cycles:10 Average:110.80 Median:110.00 stddev:3.33 (3.0%) stddev-sans-first:3.52 15:13:29 INFO - PROCESS | 21124 | Values: 110.0 110.0 120.0 110.0 110.0 110.0 108.0 110.0 111.0 109.0 15:13:29 INFO - PROCESS | 21124 | 15:13:29 INFO - PROCESS | 21124 | [#1] audio-beat-detection Cycles:10 Average:141.70 Median:146.50 stddev:24.72 (16.9%) stddev-sans-first:26.21 15:13:29 INFO - PROCESS | 21124 | Values: 143.0 117.0 155.0 169.0 121.0 150.0 139.0 116.0 189.0 118.0 15:13:29 INFO - PROCESS | 21124 | 15:13:29 INFO - PROCESS | 21124 | [#2] audio-dft Cycles:10 Average:159.80 Median:160.00 stddev:0.92 (0.6%) stddev-sans-first:0.87 15:13:29 INFO - PROCESS | 21124 | Values: 161.0 160.0 159.0 160.0 161.0 160.0 158.0 160.0 159.0 160.0 15:13:29 INFO - PROCESS | 21124 | 15:13:29 INFO - PROCESS | 21124 | [#3] audio-fft Cycles:10 Average:93.10 Median:74.50 stddev:38.75 (52.0%) stddev-sans-first:40.54 15:13:29 INFO - PROCESS | 21124 | Values: 75.0 74.0 76.0 171.0 76.0 74.0 74.0 74.0 162.0 75.0 15:13:29 INFO - PROCESS | 21124 | 15:13:29 INFO - PROCESS | 21124 | [#4] audio-oscillator Cycles:10 Average:103.90 Median:84.00 stddev:32.31 (38.5%) stddev-sans-first:33.37 15:13:29 INFO - PROCESS | 21124 | Values: 83.0 83.0 154.0 84.0 84.0 85.0 150.0 84.0 84.0 148.0 15:13:29 INFO - PROCESS | 21124 | 15:13:29 INFO - PROCESS | 21124 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 15:13:29 INFO - PROCESS | 21124 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 116.0 116.0 117.0 116.0 15:13:29 INFO - PROCESS | 21124 | 15:13:29 INFO - PROCESS | 21124 | [#6] imaging-darkroom Cycles:10 Average:146.20 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.50 15:13:29 INFO - PROCESS | 21124 | Values: 145.0 146.0 146.0 146.0 146.0 147.0 147.0 147.0 146.0 146.0 15:13:29 INFO - PROCESS | 21124 | 15:13:29 INFO - PROCESS | 21124 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 15:13:29 INFO - PROCESS | 21124 | Values: 94.0 95.0 95.0 94.0 95.0 95.0 95.0 94.0 95.0 94.0 15:13:29 INFO - PROCESS | 21124 | 15:13:29 INFO - PROCESS | 21124 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.00 stddev:1.10 (2.2%) stddev-sans-first:0.93 15:13:29 INFO - PROCESS | 21124 | Values: 52.0 50.0 50.0 49.0 49.0 52.0 50.0 49.0 50.0 50.0 15:13:29 INFO - PROCESS | 21124 | 15:13:29 INFO - PROCESS | 21124 | [#9] json-stringify-tinderbox Cycles:10 Average:60.80 Median:61.00 stddev:0.63 (1.0%) stddev-sans-first:0.60 15:13:29 INFO - PROCESS | 21124 | Values: 60.0 62.0 61.0 61.0 61.0 60.0 60.0 61.0 61.0 61.0 15:13:29 INFO - PROCESS | 21124 | 15:13:29 INFO - PROCESS | 21124 | [#10] stanford-crypto-aes Cycles:10 Average:73.70 Median:73.00 stddev:2.98 (4.1%) stddev-sans-first:3.10 15:13:29 INFO - PROCESS | 21124 | Values: 72.0 72.0 72.0 73.0 76.0 81.0 73.0 75.0 72.0 71.0 15:13:29 INFO - PROCESS | 21124 | 15:13:29 INFO - PROCESS | 21124 | [#11] stanford-crypto-ccm Cycles:10 Average:124.20 Median:121.00 stddev:12.55 (10.4%) stddev-sans-first:13.24 15:13:29 INFO - PROCESS | 21124 | Values: 128.0 120.0 118.0 120.0 126.0 119.0 115.0 116.0 158.0 122.0 15:13:29 INFO - PROCESS | 21124 | 15:13:29 INFO - PROCESS | 21124 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.90 Median:154.00 stddev:1.91 (1.2%) stddev-sans-first:2.03 15:13:29 INFO - PROCESS | 21124 | Values: 154.0 154.0 157.0 157.0 153.0 154.0 151.0 154.0 153.0 152.0 15:13:29 INFO - PROCESS | 21124 | 15:13:29 INFO - PROCESS | 21124 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.00 Median:62.00 stddev:1.25 (2.0%) stddev-sans-first:1.09 15:13:29 INFO - PROCESS | 21124 | Values: 64.0 62.0 64.0 62.0 61.0 62.0 61.0 62.0 62.0 60.0 15:13:29 INFO - PROCESS | 21124 | -------- Summary: end -------- 15:13:29 INFO - PROCESS | 21124 | 15:13:30 INFO - TEST-INFO | 21124: exit 0 15:13:30 INFO - TEST-OK | kraken | took 59289ms 15:13:30 INFO - SUITE-END | took 1171s 15:13:31 INFO - Completed test suite (00:19:31) 15:13:31 INFO - javascript benchmark 15:13:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3161.6522182559916, 3163.035274241356, 3184.824834634097, 3198.6001749781276, 3224.0166749141504, 5533.889322213558, 5571.13647384655, 5603.719814009299, 5622.943770562293, 5623.156526521018, 5560.916586251199, 5654.575906806986, 5665.8583535411635, 5668.914966275509, 5695.45893140152, 5120.078385890548, 5144.819931302405, 5188.948110518884, 5202.245674377232, 5214.634975551713, 5063.41770696371, 5206.609504287175, 5230.947690523103, 5236.450172731866, 5240.397354304266, 5010.749462526877, 5030.647854650176, 5058.0895438821, 5082.390113186404, 5107.591392688576, 5037.0, 5062.443131255559, 5088.567471764904, 5172.560332371753, 5202.661826981234, 5000.324956130912, 5085.872853178663, 5136.306598609177, 5150.793968241266, 5160.6129540284255, 5349.839504814861, 5425.0, 5454.181872719084, 5478.342598888159, 5496.285482887239, 5877.118432235156, 5895.498882594985, 5932.940670593325, 5964.701764911781, 5976.73104710289, 5763.9135412968835, 5986.5210783137245, 5994.250718660168, 6010.549208809357, 6023.427774361428, 5495.505404513573, 5548.722563871831, 5554.305711786027, 5568.582356323211, 5628.943710562883, 6083.6349819011, 6087.391260873966, 6089.695515224221, 6095.603785753912, 6125.540584456184, 5669.659820410789, 5715.828525144252, 5880.529557635379, 5911.024680927685, 5913.113033045078, 5861.472467477947, 5898.262717160355, 5930.347661757203, 5932.0, 5933.02105152645, 5912.379200184005, 5913.05391137416, 5915.467607915309, 5916.704164791743, 5919.792807251725, 5752.25220721312, 5861.589688721834, 5919.585629005928, 5924.052151655715, 5925.318588362394, 4505.887352816206, 4540.977295113569, 4585.70192937458, 4595.448546174481, 4650.5349465053905, 4104.753714777123, 4111.321631930758, 4113.0, 4115.9176816464105, 4119.505659320901, 5769.59612827098, 5777.451142141574, 5782.826515204551, 5783.971080144573, 5799.3040835100055, 5766.394528574523, 5908.379620139824, 5941.494972927263, 5976.5218782497295, 6051.122587224916, 5734.16854556087, 5744.310682718017, 5744.569157313218, 5746.856328591819, 5748.137779333133, 5202.03762303975, 5204.271402003723, 5204.323437953118, 5214.504622060898, 5239.764210610531, 5893.528517718572, 6069.484093851984, 6120.510359171256, 6135.0, 6185.041318595715, 5917.526597872246, 5959.0, 5963.671998040149, 5966.433188847052, 5982.641041537521, 6131.417515336036, 6162.784302549389, 6184.81445556634, 6185.876282474415, 6203.410675985774, 5362.249285100011, 5641.097424412074, 5761.942380576141, 5809.448102430262, 5895.616784908967, 5906.379830117948, 5915.852103697442, 5958.314793798769, 5962.791302304398, 5976.372480889444, 5815.79644712433, 5822.883542329045, 5826.155207494805, 5827.038538641077, 5827.8834423310445, 5545.916811247916, 5748.6838223898085, 5799.3040835100055, 5803.274590676166, 5895.67573783446, 5737.770489180553, 5784.363719990713, 5795.0, 5796.565257605612, 5814.73833677494, 5814.186013957965, 5843.211166492469, 5848.53211743068, 5863.912041319469, 5877.559183061202, 5519.806806761744, 5620.241267428844, 5638.154276858504, 5653.943460565341, 5773.711314434177], "value": 5493.7596275757705, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [594.7977687586219, 654.7217432591149, 664.0736173038612, 682.3449488491051, 684.096991970599, 35245.0, 47809.521904780944, 48255.27617085746, 48324.0, 48335.033299334034, 48755.51244487554, 49273.260901086425, 49284.0, 49301.0, 49319.50680493203, 8690.695825646104, 9407.059294070572, 9502.619895204183, 9540.093691099335, 9569.473678947656, 8881.95559022204, 8946.86579701305, 8958.417702849327, 11041.668749937515, 11311.773764524705, 11494.770104597903, 11567.479463424166, 11573.826392604118, 11574.6527604172, 11579.421028948518, 11365.03397211238, 11431.085513158969, 11436.313821170756, 11436.428178591037, 11442.084633229322, 11337.546498140064, 11426.08591312693, 11432.142589305753, 11432.828507572392, 11440.19918605698, 45993.0, 46222.5377746225, 46254.306185407244, 46316.0, 46455.767721161516, 3690.612485688991, 3956.7823769692654, 3976.980115099406, 4336.956630433687, 4667.994026879044, 35119.6488035119, 35262.29475410503, 35346.293074138375, 35465.468017979496, 35734.64265357339, 4595.448546174447, 4680.531946805327, 4699.835505757316, 4710.434747830248, 4736.976315118437, 368772.0, 373265.0, 373699.0, 373838.0, 374345.0, 65400.67299663471, 65758.34241657618, 65791.0, 65871.0, 65879.0, 40081.0, 40447.19105617871, 40771.796141019106, 42833.57166428358, 68368.0, 17073.829261707473, 17226.138693065295, 17242.0, 17243.310267589157, 17245.913770431067, 12221.572244971561, 13005.609831705064, 13109.934450327879, 13111.14777539476, 13112.541061063008, 20169.193232270838, 20796.1681532737, 20892.268770592957, 21558.245461408776, 21798.128074877146, 7421.703131874665, 7424.925750742424, 7479.252074792586, 7489.325960663567, 7650.617469126632, 1017.4404077757204, 1080.308602494404, 1106.1980064453207, 1143.1806443923438, 1166.3060479014973, 33587.496187557204, 35299.29401411957, 35404.64595354065, 35511.15821587384, 35761.64238357583, 1126.442411006557, 1139.3164101539176, 1172.2907640877318, 1177.4289469607234, 1179.5163982767024, 18489.90755046216, 18590.44228673142, 18706.895271290505, 18755.90622046881, 18939.242430302907, 556224.0, 560286.0, 562043.0, 564164.0, 564803.0, 66113.0, 66368.0, 66388.6680566594, 66825.0, 66874.33125668779, 1346.811446397505, 1415.6390120519407, 1428.7142571485745, 1458.985410145885, 1474.6313421644588, 34146.658533414346, 35246.64753352434, 36427.81786091052, 36789.44815827818, 36940.81529592335, 57602.4239757597, 62382.37617623766, 63596.364036359046, 63672.0, 63766.36233637604, 548054.0, 549022.0, 550290.0, 552662.0, 553248.0, 40492.797536012135, 47202.527974719815, 47489.52510474851, 47822.0, 49029.0, 53254.201186983, 53286.0, 53749.46250537601, 54029.729851352065, 54100.0, 44521.642465086785, 45081.549184507734, 45899.77050114728, 46500.0, 47722.28416573824, 44933.55066449424, 45315.77342113268, 46938.0, 49988.0, 50542.0, 45639.31541026821, 46597.767011164724, 46823.29765053595, 46949.76525117489, 47103.293450597586, 39564.40653390144, 41953.580464194965, 42207.788961055, 42294.0, 42441.0, 30991.845040774653, 31307.217319567193, 31539.52690709687, 31580.526292105176, 31775.364492709556], "value": 22492.465868188345, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.9868404605838, 396.6806720589924, 422.84988828965714, 433.1380552700128, 449.4090271293249, 18908.054597270133, 20091.79449233047, 20492.18031278747, 20501.38495845123, 20653.793462065376, 18972.430827075208, 19269.325573604925, 19459.902700486513, 19710.802891971078, 19724.211031558756, 5050.191969284915, 5240.528352448279, 5265.9473405266035, 5334.093204155303, 5335.0, 5268.1834315681135, 5304.761285742151, 5657.264555607765, 5739.311282646067, 5766.481016708495, 5737.082066869301, 5813.09896965971, 5944.197533333009, 5951.940480595204, 5956.553258505608, 5503.229547863302, 5727.312722473309, 5765.048766953448, 5815.563832712522, 5819.127130930352, 5461.4811592899005, 5572.164175373686, 5599.356074051455, 5604.887902241973, 5655.575831812589, 13236.0, 13283.402246898913, 13441.865581344158, 13796.379162937692, 14038.508652197226, 2284.851484653492, 2293.9197128100436, 2310.7573704761094, 2397.44858682504, 2534.619807028942, 10563.154947604173, 10573.365598064142, 10607.469626518643, 10681.572737090508, 13051.934740326333, 1963.1656545968049, 1965.4496740912568, 2005.9880239520958, 2034.08466190215, 2106.7682554918947, 67821.66089169522, 68742.3125768746, 76484.61757691232, 77983.6100819498, 87535.12464875398, 10837.83743243852, 10859.511321990529, 11051.0, 11406.0, 11509.597164099216, 14497.492587759378, 14501.854981450262, 14540.854591454161, 14557.0, 14568.562943111723, 7982.521048736979, 8098.35213182944, 8175.836483270301, 8210.958945205235, 8264.669413223404, 7997.600119993977, 8026.7591972240925, 8201.548914809624, 8235.21765432283, 8238.50568965864, 4451.243288640874, 4483.0, 4535.115652447741, 4567.634589232854, 4752.120857641347, 1834.3488061738158, 1840.7975122736489, 1870.2145099058428, 1871.6724573199635, 1894.1949671389605, 901.1979338388708, 1021.7905329407603, 1099.5656715597295, 1114.8662160540625, 1130.6608017594688, 20155.59688806245, 20570.0, 20749.89625051865, 20873.47816304574, 20912.16351345929, 1028.9691309260734, 1035.891231420705, 1037.6368271105052, 1054.8997845204694, 1058.402002868377, 10673.57305707763, 11569.305841649528, 12325.631268421888, 12466.252024878537, 12493.0, 527267.0, 528263.0, 531931.0, 535228.0, 535773.0, 96664.0, 97180.51409743048, 98004.50997744966, 98258.508707456, 99592.50203748935, 1078.455380033094, 1086.6359769477315, 1119.1941801902617, 1125.926814757038, 1128.28353995212, 15335.38658453694, 15810.893237473267, 18564.44306670802, 18797.530061748566, 19067.523311917314, 42437.78781106075, 42518.0, 45735.085298294514, 47207.763961179975, 48690.0, 61527.0, 62040.379596203464, 62170.0, 63505.0, 63944.0, 8367.519245095604, 8369.790755230924, 8403.663853446038, 8421.5789210538, 8543.316534677335, 9195.632174713204, 12759.48962041508, 13251.933740331237, 13618.38717257706, 13860.306984650928, 11850.400195973432, 11871.703207419883, 11942.283462992249, 12100.75798484043, 12637.17858339205, 11436.256643318158, 11521.815183167775, 11641.6507504775, 11900.345480998629, 12177.57378491784, 11850.940745296219, 12246.75506489883, 12308.323042232763, 12350.814737779121, 12414.875851241371, 7159.240086196138, 7215.783526494213, 7476.476646634684, 7895.486793358414, 8662.220400164015, 10579.206559507913, 11857.229280096766, 11991.100667449802, 12119.454624542093, 12653.051021173265], "value": 9234.992577228208, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1348.608903418009, 1371.6090914089486, 1450.992745036275, 1559.5555266748972, 1880.8965506897116, 8652.437591556545, 9000.684976025841, 9058.139476749715, 9323.067693230674, 9330.253579713624, 9214.907850921489, 9339.626414943394, 9339.832520934882, 9344.672936447225, 9346.112119348669, 2439.3291844742703, 2482.03200751707, 2482.1684735613503, 2482.615194644833, 2486.428121532049, 2402.5795485790004, 2506.0727530813533, 2506.1729629222405, 2509.5608268553024, 2519.5590771614984, 2428.4778772563895, 2451.8774061296954, 2495.126705653025, 2499.950000999979, 2505.5991041433376, 2325.7790509901533, 2344.979933728833, 2373.643953406994, 2427.1262345555588, 2438.5610590093775, 2357.1867705641525, 2358.811295096388, 2364.7162340519076, 2365.6451532270125, 2387.271882075966, 9148.313876459226, 9219.723408297694, 9223.492707901063, 9290.02454742249, 9334.766630834216, 5468.6171967962255, 5681.829545113653, 5690.5447564194765, 5696.8006119785605, 5701.173329867146, 3386.779859309137, 3404.114930118162, 3429.5198672185916, 3436.3127374525193, 3509.175343794206, 1099.5491848342222, 1108.8115020446544, 1113.2986218682258, 1115.3530952047793, 1131.1911982932243, 12531.373431328488, 12589.685257868536, 12711.872881271254, 12746.235225886476, 12778.10553261272, 4707.882302942386, 4746.239225295057, 4749.857504274877, 4799.808007679723, 4873.756312184448, 10193.949030254802, 10198.949005254926, 10202.846957295647, 10214.336068155546, 10228.846567301496, 5463.2078348639225, 5475.09660905954, 5487.067198576252, 5490.972545137329, 5492.642978206404, 4511.70673906195, 4534.36518887356, 4691.752710934439, 5037.168867136955, 5073.467285934998, 3976.483057202603, 4013.3979903014783, 4110.136871257069, 4113.074558224375, 4114.403411505375, 1148.8678801937717, 1155.7457359381092, 1159.3391766692905, 1161.924474909128, 1174.9647510574696, 907.3149771922157, 1007.3250921882357, 1007.9899201007896, 1020.4693559349097, 1052.084686322904, 5988.670623115683, 6163.044728067157, 6183.165272688129, 6286.465650419766, 6396.0, 962.8363178259713, 1013.807376598444, 1059.597353005853, 1094.0645747885444, 1119.6473110970019, 4030.234255491447, 4071.307877660805, 4090.263752524574, 4148.419221309019, 4259.701820872571, 11215.943920280346, 11312.660620181408, 11317.94341028306, 11507.884921150682, 11573.537058517564, 11292.604758833402, 11322.943385283186, 11361.0, 11443.485043173077, 11536.76926461466, 7712.0, 7998.080220774686, 8380.832383352421, 8449.915500844912, 8551.187637174458, 6355.936440635534, 6400.807975760735, 6462.838429039123, 6571.507136964651, 6588.110605068255, 10155.949220253851, 10324.948375258076, 10418.374897505872, 10518.894811051792, 10679.19906007037, 10198.592056317664, 10301.690949271533, 10409.68770936873, 10559.313644613076, 10782.622608208989, 7975.361971042186, 8051.436399451982, 8165.469244499089, 8244.381671374551, 8318.126596707501, 9218.44689318643, 9268.814623707623, 9274.258059355101, 9275.76810579741, 9288.256939444964, 7998.920010799818, 8034.598270086355, 8063.798405039921, 8240.587970601566, 8337.541435221121, 7477.70089196426, 7553.546787192786, 7593.962030190035, 8121.918780812116, 8134.674613015413, 6403.64779937108, 6461.418472337512, 6463.57986730861, 6464.0627109069865, 6520.3805638466165, 5194.091034069098, 5203.479652034917, 5229.058769421541, 5253.763580638957, 5276.8273485258005, 5191.480851914778, 5199.532042116228, 5206.661566998133, 5248.107821670249, 5361.705106219196], "value": 4779.889032150039, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.1811734075503, 345.58184596638057, 346.2918332011037, 348.72450763896524, 350.27318314497427, 5559.166125081239, 5603.943960560393, 5621.971890140554, 5625.874825034989, 5668.858278543028, 5231.5814734821215, 5319.5744340452675, 5399.946000539993, 5437.34751829781, 5577.470140336672, 4254.659627229829, 4296.677749168809, 4320.503142138663, 4339.717918335314, 4389.144116897206, 4341.305391137419, 4347.978260108695, 4356.411884395598, 4356.651467882578, 4393.362962370455, 4097.262492751303, 4259.403683484299, 4306.569343065684, 4331.068820203655, 4339.500957389893, 4261.829526818923, 4280.250956082688, 4283.443152390169, 4319.784010799447, 4319.956800431987, 3796.6393192646656, 3801.6124114698214, 3805.1438426354125, 3806.7715937043868, 3819.2743378758223, 5286.0, 5338.679679219221, 5357.812476563302, 5462.754176062087, 5501.669899805985, 4884.7069175849565, 4885.6335774817035, 4893.314935908976, 4906.779194936236, 4910.312556242093, 5735.0, 5735.483806457397, 5755.482006619382, 5759.0, 5795.710214489259, 4030.1536677297804, 4083.673306135502, 4111.177764447093, 4127.752334859918, 4202.85290014851, 5956.0, 5961.970190149065, 5973.761049558012, 5977.3723759005115, 5979.1629171915965, 4222.261104306734, 4224.197402493516, 4226.5418430641375, 4247.426597409372, 4252.29837076885, 4064.918701625951, 4278.507971583245, 4286.678499112579, 4292.463442069741, 4304.096139810612, 4879.048585525859, 4881.51184881509, 4883.267509873476, 4886.633502487257, 4920.75396230187, 5869.882602347929, 5884.941150588525, 5889.793857214977, 5893.941060589425, 5925.022371308772, 1455.7961885336063, 1461.8465061168638, 1475.5278310940396, 1480.7260656778315, 1495.7681559358316, 923.2337160157053, 932.8693982842408, 950.1496160935924, 955.3885513271512, 957.6313119449062, 5057.508035129646, 5067.974660126676, 5070.340855688811, 5072.315237442972, 5090.465501122403, 5258.343621759133, 5278.9208161877605, 5291.042314343717, 5296.629235953446, 5314.681119132864, 4679.438467383936, 4991.775370108354, 5016.673916195436, 5020.673656212334, 5024.824131155392, 2788.288986308519, 2807.4385122975077, 2812.549992001311, 2818.5208514552573, 2847.6298081249306, 5336.46635336471, 5357.517823395913, 5405.675659460445, 5410.91883621746, 5426.565874730012, 5155.922661160085, 5183.740812959412, 5233.869153271123, 5342.839714808562, 5345.545628621533, 5333.333333333333, 5342.637627405064, 5355.277037599874, 5375.838724838183, 5436.592255580768, 5252.317198764289, 5252.711100889488, 5269.4981930149725, 5278.841634750964, 5322.574194064388, 4993.775280112331, 5156.381234251764, 5156.9573951368875, 5190.221466779863, 5198.814953962097, 5356.892862142813, 5366.892662146814, 5400.7569659364635, 5410.242566040679, 5435.945640543544, 3820.503334566489, 3855.51562648388, 3893.591172926802, 3918.333883239799, 4059.2287465381482, 5388.811391601275, 5424.783008679608, 5861.384554621874, 5928.584999050197, 5930.52555795544, 5731.0, 5731.5701322400155, 5733.509287585174, 5746.0, 5756.913646295393, 5663.697349609695, 5699.401562836008, 5757.971210143923, 5774.412036689944, 5778.971105144447, 5733.770649173986, 5736.799212027559, 5788.668606220509, 5825.359210486928, 5833.212516310414, 5867.295924488918, 5883.882322353444, 5895.793647222326, 5908.113782932594, 5914.260717410324, 5499.367572729027, 5648.745806438802, 5672.858178545569, 5682.3465301489205, 5715.685637289989], "value": 4253.874631227384, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [765.2959277464732, 794.2653045932512, 805.8912046873673, 828.3704384667651, 941.2046820436738, 16193.514194574167, 16473.0, 16486.835131648666, 16510.339586416543, 16517.587060323498, 15726.449574264903, 15881.761773573406, 16051.919740401281, 16054.1170235637, 16063.357465701358, 734.6290123487657, 819.4755356571799, 861.5993562993207, 870.3733312015371, 873.9781505462382, 750.7034721285089, 757.6817736550635, 759.3735168485994, 846.6063280574532, 866.3502373220085, 913.0367462327242, 921.2307723051226, 922.9861552076724, 923.2152670230317, 923.3351986569659, 916.2532535983219, 919.6321471411422, 919.8068405634825, 920.1764420843375, 921.0007142250666, 670.6713710281975, 674.4570620650375, 675.8749631318173, 675.9121314229119, 676.3777324861139, 15409.614759630987, 15461.845381546265, 15462.690746185126, 15467.226638668022, 15507.534773956799, 14098.929505352407, 14732.55802325932, 14798.70402591953, 14943.701125977528, 16283.430079947146, 14083.788743168861, 14246.786298205432, 14296.285185740775, 14585.416583336653, 14729.852701472955, 4754.405699287589, 4767.237242041257, 4810.422749270075, 4812.061647978646, 4882.048000639877, 88281.55859220662, 89867.55066224693, 90813.0, 91227.54386228093, 92535.53732231363, 10868.72828179286, 10902.836457453144, 11069.833952490719, 11930.715245632338, 12033.879661203451, 21650.0, 21710.131594736034, 21802.89098554497, 21804.345869623936, 21897.671534926987, 8702.34732395073, 8703.6518539259, 8724.0, 9085.591148398338, 9300.906990930003, 4827.348307978448, 5061.519155680205, 5067.822626208065, 5120.18077107661, 5178.482151784791, 3761.4733937248807, 3823.235352929425, 3841.8847434577006, 3845.307844587945, 3873.65137137659, 641.1568787045021, 642.4154019842015, 644.136856612142, 645.2063961327593, 666.0142988377229, 700.8388070743757, 703.3669697272496, 707.3881092854747, 723.5260904107818, 739.7299985505252, 9416.529173541296, 9504.90495095054, 9715.271354648288, 10348.741281468023, 10501.579936802595, 713.2760248347931, 716.3159182980346, 720.6144712578745, 721.2066726600802, 767.244264399566, 4638.628909687217, 4919.803207871717, 4988.650794444354, 5008.6744361616375, 5037.546620804145, 76177.6191119052, 77485.6125719379, 77556.2244377549, 94326.0, 127351.0, 110522.0, 112346.0, 112525.43737281261, 115106.0, 115192.0, 94636.52681736548, 97464.0, 98048.0, 101893.0, 102068.48965755124, 9155.86266205994, 9414.858777118212, 9545.796787817486, 9569.501816552807, 9603.37578057424, 48762.0, 57390.0, 57990.3904324298, 58192.4180758187, 58436.0, 67006.0, 75796.62101689457, 78101.60949195217, 79466.0, 84797.57601211955, 14323.78514322307, 14328.49850255236, 14573.489927852474, 14690.192039437934, 15019.324130414376, 16500.17499125015, 16789.41237056697, 16846.66306673884, 17442.389516366868, 17873.910630446764, 13908.791368129283, 14525.782113268098, 14677.41290348374, 14726.926365368534, 15089.622759431102, 14231.928840355731, 15211.239438028275, 15223.467178648694, 15369.538913832603, 15661.295241713922, 13037.47850085986, 14034.929825351217, 14304.713905722036, 15048.6990260192, 15349.0, 12723.172993755747, 12778.68053298675, 12780.48878044905, 12888.935555322538, 12976.935115324362, 11789.528418863149, 11866.40667966581, 11975.580854670045, 12004.039676826007, 12517.374131293582], "value": 8059.608080450518, "unit": "score"}], "name": "dromaeo_css", "value": 7562.253346613784, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 120.0, 110.0, 110.0, 110.0, 108.0, 110.0, 111.0, 109.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 117.0, 155.0, 169.0, 121.0, 150.0, 139.0, 116.0, 189.0, 118.0], "value": 141.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 159.0, 160.0, 161.0, 160.0, 158.0, 160.0, 159.0, 160.0], "value": 159.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 76.0, 171.0, 76.0, 74.0, 74.0, 74.0, 162.0, 75.0], "value": 93.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 154.0, 84.0, 84.0, 85.0, 150.0, 84.0, 84.0, 148.0], "value": 103.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 116.0, 117.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 146.0, 147.0, 147.0, 147.0, 146.0, 146.0], "value": 146.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 50.0, 49.0, 49.0, 52.0, 50.0, 49.0, 50.0, 50.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 61.0, 61.0, 61.0, 60.0, 60.0, 61.0, 61.0, 61.0], "value": 60.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 72.0, 73.0, 76.0, 81.0, 73.0, 75.0, 72.0, 71.0], "value": 73.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 120.0, 118.0, 120.0, 126.0, 119.0, 115.0, 116.0, 158.0, 122.0], "value": 124.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 154.0, 157.0, 157.0, 153.0, 154.0, 151.0, 154.0, 153.0, 152.0], "value": 153.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 64.0, 62.0, 61.0, 62.0, 61.0, 62.0, 62.0, 60.0], "value": 62.0, "unit": "score"}], "name": "kraken", "value": 1491.4, "alertThreshold": 2.0}]} 15:13:31 INFO - Return code: 0 15:13:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:13:31 INFO - # TBPL SUCCESS # 15:13:31 INFO - Running post-action listener: _resource_record_post_action 15:13:31 INFO - [mozharness: 2016-10-10 22:13:31.187527Z] Finished run-tests step (success) 15:13:31 INFO - Running post-run listener: _resource_record_post_run 15:13:31 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 291565568; Read time: 12; Write time: 172728 15:13:31 INFO - TinderboxPrint: CPU usage
13.0% 15:13:31 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 15:13:31 INFO - TinderboxPrint: I/O write bytes / time
291,565,568 / 172,728 15:13:31 INFO - TinderboxPrint: CPU idle
8,237.0 (87.0%) 15:13:31 INFO - TinderboxPrint: CPU user
1,186.9 (12.5%) 15:13:31 INFO - TinderboxPrint: Swap in / out
0 / 0 15:13:31 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 71262208; Read time: 0; Write time: 32692 15:13:31 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 0; Write bytes: 219742208; Read time: 0; Write time: 140004 15:13:31 INFO - Running post-run listener: _upload_blobber_files 15:13:31 INFO - Blob upload gear active. 15:13:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:13:31 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 15:13:31 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:13:31 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:13:32 INFO - (blobuploader) - INFO - Open directory for files ... 15:13:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:13:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:13:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:13:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:13:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:13:32 INFO - (blobuploader) - INFO - Done attempting. 15:13:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:13:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:13:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:13:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:13:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:13:36 INFO - (blobuploader) - INFO - Done attempting. 15:13:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:13:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:13:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:13:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:13:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:13:36 INFO - (blobuploader) - INFO - Done attempting. 15:13:36 INFO - (blobuploader) - INFO - Iteration through files over. 15:13:37 INFO - Return code: 0 15:13:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:13:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:13:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f277be4806be161d9519c6921b0f260f56ba751bd6d18640c48f606ce4cb79bccd3f2bb524d4f2957f8db3aa15efd60e85b5e8c8609737784f2cfc7d977269b8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5363bc5dea34b734ff46b0c790626c837828e351a86e6a972c1ebdc08752699c89e93c1c251a9b3e136ef4c6e1436d770a52fc8bcb23381d5ca536f2ddf2dd0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0e198fb8040440f865ca3cb256762ba9ef261b1eb253711f99d866c40bcf00bdf48a7ed906cb950f66744d063b3036a25079211821773d48ce789ce4b22fc401"} 15:13:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:13:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:13:37 INFO - Contents: 15:13:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f277be4806be161d9519c6921b0f260f56ba751bd6d18640c48f606ce4cb79bccd3f2bb524d4f2957f8db3aa15efd60e85b5e8c8609737784f2cfc7d977269b8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5363bc5dea34b734ff46b0c790626c837828e351a86e6a972c1ebdc08752699c89e93c1c251a9b3e136ef4c6e1436d770a52fc8bcb23381d5ca536f2ddf2dd0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0e198fb8040440f865ca3cb256762ba9ef261b1eb253711f99d866c40bcf00bdf48a7ed906cb950f66744d063b3036a25079211821773d48ce789ce4b22fc401"} 15:13:37 INFO - Running post-run listener: copy_logs_to_upload_dir 15:13:37 INFO - Copying logs to upload dir... 15:13:37 INFO - mkdir: /builds/slave/test/build/upload/logs 15:13:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1673.388828 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 53 secs) (at 2016-10-10 15:13:34.788741) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-10 15:13:34.792102) ========= 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=826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767 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-Beta/sha512/f277be4806be161d9519c6921b0f260f56ba751bd6d18640c48f606ce4cb79bccd3f2bb524d4f2957f8db3aa15efd60e85b5e8c8609737784f2cfc7d977269b8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5363bc5dea34b734ff46b0c790626c837828e351a86e6a972c1ebdc08752699c89e93c1c251a9b3e136ef4c6e1436d770a52fc8bcb23381d5ca536f2ddf2dd0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0e198fb8040440f865ca3cb256762ba9ef261b1eb253711f99d866c40bcf00bdf48a7ed906cb950f66744d063b3036a25079211821773d48ce789ce4b22fc401"} build_url:https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011485 build_url: 'https://queue.taskcluster.net/v1/task/P0kGmOoPR1i2FK2KxQf4iQ/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f277be4806be161d9519c6921b0f260f56ba751bd6d18640c48f606ce4cb79bccd3f2bb524d4f2957f8db3aa15efd60e85b5e8c8609737784f2cfc7d977269b8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5363bc5dea34b734ff46b0c790626c837828e351a86e6a972c1ebdc08752699c89e93c1c251a9b3e136ef4c6e1436d770a52fc8bcb23381d5ca536f2ddf2dd0c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0e198fb8040440f865ca3cb256762ba9ef261b1eb253711f99d866c40bcf00bdf48a7ed906cb950f66744d063b3036a25079211821773d48ce789ce4b22fc401"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-10 15:13:34.817610) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 15:13:34.817991) ========= 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=826874ccc97bd7bf541b4875000001d5-1476135942.114048-632181767 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004417 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-10 15:13:34.905122) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-10 15:13:34.905731) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-10 15:13:34.906101) ========= ========= Total master_lag: 0.16 =========