builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-047 starttime: 1474324267.64 results: success (0) buildid: 20160919064031 builduid: ae1839bfaf4840dab36e395afce28275 revision: 01d67bfe6c8198416ec190cbc216d953c1625b94 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 15:31:07.636789) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-19 15:31:07.637232) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 15:31:07.637510) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-19 15:31:07.652499) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 15:31:07.652902) ========= 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=03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-19 15:31:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2016-09-19 15:31:07 (224 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.077921 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 15:31:07.744791) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 15:31:07.745103) ========= 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=03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017466 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 15:31:07.794562) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-19 15:31:07.794923) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 01d67bfe6c8198416ec190cbc216d953c1625b94 --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 01d67bfe6c8198416ec190cbc216d953c1625b94 --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=03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-19 15:31:08,037 truncating revision to first 12 chars 2016-09-19 15:31:08,037 Setting DEBUG logging. 2016-09-19 15:31:08,037 attempt 1/10 2016-09-19 15:31:08,037 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/01d67bfe6c81?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-19 15:31:09,223 unpacking tar archive at: mozilla-beta-01d67bfe6c81/testing/mozharness/ program finished with exit code 0 elapsedTime=1.509091 ========= master_lag: 1.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-09-19 15:31:10.336931) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 15:31:10.337285) ========= script_repo_revision: 01d67bfe6c8198416ec190cbc216d953c1625b94 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-19 15:31:10.337762) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 15:31:10.338047) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-19 15:31:10.919318) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-09-19 15:31:10.922815) ========= /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=03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:31:11 INFO - MultiFileLogger online at 20160919 15:31:11 in /builds/slave/test 15:31:11 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 15:31:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:31:11 INFO - {'append_to_log': False, 15:31:11 INFO - 'base_work_dir': '/builds/slave/test', 15:31:11 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:31:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:31:11 INFO - 'branch': 'Mozilla-Beta', 15:31:11 INFO - 'buildbot_json_path': 'buildprops.json', 15:31:11 INFO - 'config_files': ('talos/linux_config.py',), 15:31:11 INFO - 'default_actions': ('clobber', 15:31:11 INFO - 'read-buildbot-config', 15:31:11 INFO - 'download-and-extract', 15:31:11 INFO - 'populate-webroot', 15:31:11 INFO - 'create-virtualenv', 15:31:11 INFO - 'install', 15:31:11 INFO - 'run-tests'), 15:31:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:31:11 INFO - 'download_minidump_stackwalk': True, 15:31:11 INFO - 'download_symbols': 'ondemand', 15:31:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:31:11 INFO - 'tooltool.py': '/tools/tooltool.py', 15:31:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:31:11 INFO - '/tools/misc-python/virtualenv.py')}, 15:31:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:31:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:31:11 INFO - 'installer_path': 'installer.exe', 15:31:11 INFO - 'log_level': 'info', 15:31:11 INFO - 'log_name': 'talos', 15:31:11 INFO - 'log_to_console': True, 15:31:11 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:31:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:31:11 INFO - 'opt_config_files': (), 15:31:11 INFO - 'pip_index': False, 15:31:11 INFO - 'sps_profile': False, 15:31:11 INFO - 'sps_profile_interval': 0, 15:31:11 INFO - 'suite': 'dromaeojs', 15:31:11 INFO - 'system_bits': '32', 15:31:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:31:11 INFO - 'title': 'talos-linux64-ix-047', 15:31:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:31:11 INFO - 'use_talos_json': True, 15:31:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:31:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:31:11 INFO - 'work_dir': 'build'} 15:31:11 INFO - [mozharness: 2016-09-19 22:31:11.259764Z] Running clobber step. 15:31:11 INFO - Running pre-action listener: _resource_record_pre_action 15:31:11 INFO - Running main action method: clobber 15:31:11 INFO - rmtree: /builds/slave/test/build 15:31:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:31:11 INFO - Running post-action listener: _resource_record_post_action 15:31:11 INFO - [mozharness: 2016-09-19 22:31:11.718592Z] Finished clobber step (success) 15:31:11 INFO - [mozharness: 2016-09-19 22:31:11.718695Z] Running read-buildbot-config step. 15:31:11 INFO - Running pre-action listener: _resource_record_pre_action 15:31:11 INFO - Running main action method: read_buildbot_config 15:31:11 INFO - Using buildbot properties: 15:31:11 INFO - { 15:31:11 INFO - "project": "", 15:31:11 INFO - "product": "firefox", 15:31:11 INFO - "script_repo_revision": "production", 15:31:11 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 15:31:11 INFO - "repository": "", 15:31:11 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 15:31:11 INFO - "buildid": "20160919064031", 15:31:11 INFO - "pgo_build": "True", 15:31:11 INFO - "basedir": "/builds/slave/test", 15:31:11 INFO - "buildnumber": 386, 15:31:11 INFO - "slavename": "talos-linux64-ix-047", 15:31:11 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 15:31:11 INFO - "platform": "ubuntu64_hw", 15:31:11 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 15:31:11 INFO - "branch": "mozilla-beta", 15:31:11 INFO - "repo_path": "releases/mozilla-beta", 15:31:11 INFO - "revision": "01d67bfe6c8198416ec190cbc216d953c1625b94", 15:31:11 INFO - "stage_platform": "linux64-pgo", 15:31:11 INFO - "builduid": "ae1839bfaf4840dab36e395afce28275", 15:31:11 INFO - "slavebuilddir": "test" 15:31:11 INFO - } 15:31:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 15:31:11 INFO - Running post-action listener: _resource_record_post_action 15:31:11 INFO - [mozharness: 2016-09-19 22:31:11.721160Z] Finished read-buildbot-config step (success) 15:31:11 INFO - [mozharness: 2016-09-19 22:31:11.721250Z] Running download-and-extract step. 15:31:11 INFO - Running pre-action listener: _resource_record_pre_action 15:31:11 INFO - Running main action method: download_and_extract 15:31:11 INFO - mkdir: /builds/slave/test/build/tests 15:31:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:31:11 INFO - https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 15:31:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:31:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:31:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:31:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:31:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:31:11 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:31:11 INFO - retry: Failed, sleeping 30 seconds before retrying 15:31:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:31:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:31:41 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:31:41 INFO - retry: Failed, sleeping 60 seconds before retrying 15:32:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:32:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:32:41 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:32:41 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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! 15:32:41 INFO - Caught exception: HTTP Error 404: Not Found 15:32:41 INFO - Caught exception: HTTP Error 404: Not Found 15:32:41 INFO - Caught exception: HTTP Error 404: Not Found 15:32:41 INFO - trying https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:32:41 INFO - Downloading https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:32:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:32:42 INFO - Downloaded 1327 bytes. 15:32:42 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 15:32:42 INFO - Using the following test package requirements: 15:32:42 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 15:32:42 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:32:42 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 15:32:42 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:32:42 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 15:32:42 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:32:42 INFO - u'jsshell-linux-x86_64.zip'], 15:32:42 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:32:42 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 15:32:42 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 15:32:42 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:32:42 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 15:32:42 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:32:42 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 15:32:42 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:32:42 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 15:32:42 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 15:32:42 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 15:32:42 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:32:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:32:42 INFO - https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:32:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:32:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:32:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:32:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:32:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:32:42 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:32:42 INFO - retry: Failed, sleeping 30 seconds before retrying 15:33:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:33:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:33:12 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:33:12 INFO - retry: Failed, sleeping 60 seconds before retrying 15:34:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:34:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:34:12 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:34:12 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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! 15:34:12 INFO - Caught exception: HTTP Error 404: Not Found 15:34:12 INFO - Caught exception: HTTP Error 404: Not Found 15:34:12 INFO - Caught exception: HTTP Error 404: Not Found 15:34:12 INFO - trying https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:34:12 INFO - Downloading https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:34:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:34:13 INFO - Downloaded 21772072 bytes. 15:34:13 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'] 15:34:13 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 15:34:14 INFO - Return code: 0 15:34:14 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 15:34:14 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')]} 15:34:14 INFO - https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 15:34:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:34:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:34:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:34:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:34:14 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:34:14 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:34:14 INFO - retry: Failed, sleeping 30 seconds before retrying 15:34:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:34:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:34:44 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:34:44 INFO - retry: Failed, sleeping 60 seconds before retrying 15:35:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:35:44 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:35:44 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:35:44 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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! 15:35:44 INFO - Caught exception: HTTP Error 404: Not Found 15:35:44 INFO - Caught exception: HTTP Error 404: Not Found 15:35:44 INFO - Caught exception: HTTP Error 404: Not Found 15:35:44 INFO - trying https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 15:35:44 INFO - Downloading https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:35:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:35:45 INFO - Downloaded 21772072 bytes. 15:35:45 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'] 15:35:45 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 15:35:47 INFO - Return code: 0 15:35: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')]} 15:35:47 INFO - https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 15:35:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:35:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:35:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:35:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:35:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:35:47 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:35:47 INFO - retry: Failed, sleeping 30 seconds before retrying 15:36:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:36:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:36:17 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:36:17 INFO - retry: Failed, sleeping 60 seconds before retrying 15:37:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:37:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:37:17 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:37:17 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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! 15:37:17 INFO - Caught exception: HTTP Error 404: Not Found 15:37:17 INFO - Caught exception: HTTP Error 404: Not Found 15:37:17 INFO - Caught exception: HTTP Error 404: Not Found 15:37:17 INFO - trying https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 15:37:17 INFO - Downloading https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:37:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:37:17 INFO - Downloaded 14413740 bytes. 15:37:17 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'] 15:37:17 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 15:37:18 INFO - Return code: 0 15:37:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:18 INFO - https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 15:37:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:37:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:37:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:37:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:37:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:37:18 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 15:37:18 INFO - retry: Failed, sleeping 30 seconds before retrying 15:37:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:37:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:37:48 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 15:37:48 INFO - retry: Failed, sleeping 60 seconds before retrying 15:38:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:38:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:38:48 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 15:38:48 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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! 15:38:48 INFO - Caught exception: HTTP Error 404: Not Found 15:38:48 INFO - Caught exception: HTTP Error 404: Not Found 15:38:48 INFO - Caught exception: HTTP Error 404: Not Found 15:38:48 INFO - trying https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:38:48 INFO - Downloading https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:38:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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 15:38:49 INFO - Downloaded 56767399 bytes. 15:38:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:38:49 INFO - mkdir: /builds/slave/test/properties 15:38:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:38:49 INFO - Writing to file /builds/slave/test/properties/build_url 15:38:49 INFO - Contents: 15:38:49 INFO - build_url:https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 15:38:50 INFO - Running post-action listener: _resource_record_post_action 15:38:50 INFO - Running post-action listener: set_extra_try_arguments 15:38:50 INFO - [mozharness: 2016-09-19 22:38:50.152212Z] Finished download-and-extract step (success) 15:38:50 INFO - [mozharness: 2016-09-19 22:38:50.152449Z] Running populate-webroot step. 15:38:50 INFO - Running pre-action listener: _resource_record_pre_action 15:38:50 INFO - Running main action method: populate_webroot 15:38:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:38:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:38:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:38:50 INFO - '--tppagecycles', 15:38:50 INFO - '7'], 15:38:50 INFO - 'tests': ['tsvgm']}}, 15:38:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:38:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:38:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:38:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:38:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:38:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:38:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:38:50 INFO - 'talos_options': ['--disable-e10s'], 15:38:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:38:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:38:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:38:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:38:50 INFO - 'talos_options': ['--disable-e10s'], 15:38:50 INFO - 'tests': ['damp', 'tps']}, 15:38:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:38:50 INFO - 'tests': ['damp', 'tps']}, 15:38:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:38:50 INFO - 'tests': ['dromaeo_dom']}, 15:38:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:38:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:38:50 INFO - 'tests': ['basic_compositor_video']}, 15:38:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:38:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:38:50 INFO - 'tests': ['a11yr', 15:38:50 INFO - 'ts_paint', 15:38:50 INFO - 'tpaint', 15:38:50 INFO - 'sessionrestore', 15:38:50 INFO - 'sessionrestore_no_auto_restore', 15:38:50 INFO - 'tabpaint']}, 15:38:50 INFO - 'other-e10s': {'tests': ['a11yr', 15:38:50 INFO - 'ts_paint', 15:38:50 INFO - 'tpaint', 15:38:50 INFO - 'sessionrestore', 15:38:50 INFO - 'sessionrestore_no_auto_restore', 15:38:50 INFO - 'tabpaint']}, 15:38:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:38:50 INFO - 'ts_paint', 15:38:50 INFO - 'tpaint', 15:38:50 INFO - 'sessionrestore', 15:38:50 INFO - 'sessionrestore_no_auto_restore', 15:38:50 INFO - 'tabpaint']}, 15:38:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:38:50 INFO - 'ts_paint', 15:38:50 INFO - 'tpaint', 15:38:50 INFO - 'sessionrestore', 15:38:50 INFO - 'sessionrestore_no_auto_restore', 15:38:50 INFO - 'tabpaint']}, 15:38:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:38:50 INFO - 'tests': ['a11yr', 15:38:50 INFO - 'ts_paint', 15:38:50 INFO - 'tpaint', 15:38:50 INFO - 'sessionrestore', 15:38:50 INFO - 'sessionrestore_no_auto_restore', 15:38:50 INFO - 'tabpaint']}, 15:38:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:38:50 INFO - 'tests': ['a11yr', 15:38:50 INFO - 'ts_paint', 15:38:50 INFO - 'tpaint', 15:38:50 INFO - 'sessionrestore', 15:38:50 INFO - 'sessionrestore_no_auto_restore', 15:38:50 INFO - 'tabpaint']}, 15:38:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:38:50 INFO - 'tests': ['tsvgx', 15:38:50 INFO - 'tsvgr_opacity', 15:38:50 INFO - 'tart', 15:38:50 INFO - 'tscrollx', 15:38:50 INFO - 'cart']}, 15:38:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:38:50 INFO - 'tsvgr_opacity', 15:38:50 INFO - 'tart', 15:38:50 INFO - 'tscrollx', 15:38:50 INFO - 'cart']}, 15:38:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:38:50 INFO - 'talos_options': ['--disable-e10s'], 15:38:50 INFO - 'tests': ['tp5o']}, 15:38:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:38:50 INFO - 'tests': ['tp5o']}, 15:38:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:38:50 INFO - 'talos_options': ['--disable-e10s', 15:38:50 INFO - '--xperf_path', 15:38:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:38:50 INFO - 'tests': ['tp5n']}, 15:38:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:38:50 INFO - 'talos_options': ['--xperf_path', 15:38:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:38:50 INFO - 'tests': ['tp5n']}}, 15:38:50 INFO - 'talos.zip': {'path': '', 15:38:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:38:50 INFO - Running post-action listener: _resource_record_post_action 15:38:50 INFO - [mozharness: 2016-09-19 22:38:50.168985Z] Finished populate-webroot step (success) 15:38:50 INFO - [mozharness: 2016-09-19 22:38:50.169076Z] Running create-virtualenv step. 15:38:50 INFO - Running pre-action listener: _resource_record_pre_action 15:38:50 INFO - Running main action method: create_virtualenv 15:38:50 INFO - Creating virtualenv /builds/slave/test/build/venv 15:38:50 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 15:38:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:38:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:38:50 INFO - Using real prefix '/usr' 15:38:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:38:50 INFO - Installing distribute.............................................................................................................................................................................................done. 15:38:52 INFO - Installing pip.................done. 15:38:52 INFO - Return code: 0 15:38:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:38:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:52 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 0x254f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26b28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2810180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280e3b0>, '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 0x280f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280f740>, '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': '03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155', '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 15:38:52 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 15:38:52 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 15:38:52 INFO - Using env: {'DISPLAY': ':0', 15:38:52 INFO - 'HOME': '/home/cltbld', 15:38:52 INFO - 'LANG': 'en_US.UTF-8', 15:38:52 INFO - 'LANGUAGE': 'en_US:en', 15:38:52 INFO - 'LOGNAME': 'cltbld', 15:38:52 INFO - 'MAIL': '/var/mail/cltbld', 15:38:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:52 INFO - 'MOZ_NO_REMOTE': '1', 15:38:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:52 INFO - 'NO_EM_RESTART': '1', 15:38:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:52 INFO - 'PWD': '/builds/slave/test', 15:38:52 INFO - 'SHELL': '/bin/bash', 15:38:52 INFO - 'SHLVL': '1', 15:38:52 INFO - 'TERM': 'linux', 15:38:52 INFO - 'TMOUT': '86400', 15:38:52 INFO - 'USER': 'cltbld', 15:38:52 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155', 15:38:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:52 INFO - '_': '/tools/buildbot/bin/python'} 15:38:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:52 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 15:38:52 INFO - Cleaning up... 15:38:52 INFO - Return code: 0 15:38:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:38:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:52 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 0x254f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26b28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2810180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280e3b0>, '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 0x280f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280f740>, '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': '03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155', '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 15:38:52 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 15:38:52 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 15:38:52 INFO - Using env: {'DISPLAY': ':0', 15:38:52 INFO - 'HOME': '/home/cltbld', 15:38:52 INFO - 'LANG': 'en_US.UTF-8', 15:38:52 INFO - 'LANGUAGE': 'en_US:en', 15:38:52 INFO - 'LOGNAME': 'cltbld', 15:38:52 INFO - 'MAIL': '/var/mail/cltbld', 15:38:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:52 INFO - 'MOZ_NO_REMOTE': '1', 15:38:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:52 INFO - 'NO_EM_RESTART': '1', 15:38:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:52 INFO - 'PWD': '/builds/slave/test', 15:38:52 INFO - 'SHELL': '/bin/bash', 15:38:52 INFO - 'SHLVL': '1', 15:38:52 INFO - 'TERM': 'linux', 15:38:52 INFO - 'TMOUT': '86400', 15:38:52 INFO - 'USER': 'cltbld', 15:38:52 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155', 15:38:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:52 INFO - '_': '/tools/buildbot/bin/python'} 15:38:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:53 INFO - Downloading/unpacking psutil>=3.1.1 15:38:53 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 15:38:53 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 15:38:53 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 15:38:53 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 15:38:53 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 15:38:53 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 15:38:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:38:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:38:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:38:55 INFO - Installing collected packages: psutil 15:38:55 INFO - Running setup.py install for psutil 15:38:55 INFO - building 'psutil._psutil_linux' extension 15:38:55 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 15:38:55 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 15:38:55 INFO - building 'psutil._psutil_posix' extension 15:38:55 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 15:38:55 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 15:38:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:38:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:38:55 INFO - Successfully installed psutil 15:38:55 INFO - Cleaning up... 15:38:55 INFO - Return code: 0 15:38:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:38:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:38:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:55 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 0x254f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26b28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2810180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280e3b0>, '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 0x280f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280f740>, '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': '03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155', '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 15:38:55 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 15:38:55 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 15:38:55 INFO - Using env: {'DISPLAY': ':0', 15:38:55 INFO - 'HOME': '/home/cltbld', 15:38:55 INFO - 'LANG': 'en_US.UTF-8', 15:38:55 INFO - 'LANGUAGE': 'en_US:en', 15:38:55 INFO - 'LOGNAME': 'cltbld', 15:38:55 INFO - 'MAIL': '/var/mail/cltbld', 15:38:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:55 INFO - 'MOZ_NO_REMOTE': '1', 15:38:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:55 INFO - 'NO_EM_RESTART': '1', 15:38:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:55 INFO - 'PWD': '/builds/slave/test', 15:38:55 INFO - 'SHELL': '/bin/bash', 15:38:55 INFO - 'SHLVL': '1', 15:38:55 INFO - 'TERM': 'linux', 15:38:55 INFO - 'TMOUT': '86400', 15:38:55 INFO - 'USER': 'cltbld', 15:38:55 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155', 15:38:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:55 INFO - '_': '/tools/buildbot/bin/python'} 15:38:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:38:55 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 15:38:55 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 15:38:55 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 15:38:55 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 15:38:55 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 15:38:55 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 15:38:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:38:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:38:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:38:57 INFO - Installing collected packages: mozsystemmonitor 15:38:57 INFO - Running setup.py install for mozsystemmonitor 15:38:57 INFO - Successfully installed mozsystemmonitor 15:38:57 INFO - Cleaning up... 15:38:58 INFO - Return code: 0 15:38:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:38: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')]} 15:38:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38: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')]} 15:38:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38:58 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 0x254f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26b28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2810180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280e3b0>, '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 0x280f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280f740>, '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': '03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155', '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 15:38:58 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 15:38:58 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 15:38:58 INFO - Using env: {'DISPLAY': ':0', 15:38:58 INFO - 'HOME': '/home/cltbld', 15:38:58 INFO - 'LANG': 'en_US.UTF-8', 15:38:58 INFO - 'LANGUAGE': 'en_US:en', 15:38:58 INFO - 'LOGNAME': 'cltbld', 15:38:58 INFO - 'MAIL': '/var/mail/cltbld', 15:38:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:58 INFO - 'MOZ_NO_REMOTE': '1', 15:38:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:38:58 INFO - 'NO_EM_RESTART': '1', 15:38:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:38:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:58 INFO - 'PWD': '/builds/slave/test', 15:38:58 INFO - 'SHELL': '/bin/bash', 15:38:58 INFO - 'SHLVL': '1', 15:38:58 INFO - 'TERM': 'linux', 15:38:58 INFO - 'TMOUT': '86400', 15:38:58 INFO - 'USER': 'cltbld', 15:38:58 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155', 15:38:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:58 INFO - '_': '/tools/buildbot/bin/python'} 15:38:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:58 INFO - Downloading/unpacking jsonschema==2.5.1 15:38:58 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 15:38:58 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 15:38:58 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 15:38:58 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 15:38:58 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 15:38:58 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 15:39:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:39:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:39:00 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 15:39:00 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 15:39:00 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 15:39:00 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 15:39:00 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 15:39:00 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 15:39:00 INFO - Downloading functools32-3.2.3-2.tar.gz 15:39:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:39:00 INFO - warning: no files found matching '*.txt' 15:39:00 INFO - no previously-included directories found matching 'build' 15:39:00 INFO - no previously-included directories found matching 'dist' 15:39:00 INFO - no previously-included directories found matching '.git*' 15:39:00 INFO - Installing collected packages: jsonschema, functools32 15:39:00 INFO - Running setup.py install for functools32 15:39:00 INFO - warning: no files found matching '*.txt' 15:39:00 INFO - no previously-included directories found matching 'build' 15:39:00 INFO - no previously-included directories found matching 'dist' 15:39:00 INFO - no previously-included directories found matching '.git*' 15:39:00 INFO - Successfully installed jsonschema functools32 15:39:00 INFO - Cleaning up... 15:39:00 INFO - Return code: 0 15:39:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:39:00 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')]} 15:39:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:00 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')]} 15:39:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:00 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 0x254f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26b28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2810180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280e3b0>, '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 0x280f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280f740>, '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': '03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155', '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 15:39:00 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 15:39:00 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 15:39:00 INFO - Using env: {'DISPLAY': ':0', 15:39:00 INFO - 'HOME': '/home/cltbld', 15:39:00 INFO - 'LANG': 'en_US.UTF-8', 15:39:00 INFO - 'LANGUAGE': 'en_US:en', 15:39:00 INFO - 'LOGNAME': 'cltbld', 15:39:00 INFO - 'MAIL': '/var/mail/cltbld', 15:39:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:00 INFO - 'MOZ_NO_REMOTE': '1', 15:39:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:00 INFO - 'NO_EM_RESTART': '1', 15:39:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:00 INFO - 'PWD': '/builds/slave/test', 15:39:00 INFO - 'SHELL': '/bin/bash', 15:39:00 INFO - 'SHLVL': '1', 15:39:00 INFO - 'TERM': 'linux', 15:39:00 INFO - 'TMOUT': '86400', 15:39:00 INFO - 'USER': 'cltbld', 15:39:00 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155', 15:39:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:00 INFO - '_': '/tools/buildbot/bin/python'} 15:39:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:39:00 INFO - Cleaning up... 15:39:00 INFO - Return code: 0 15:39:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:39:00 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')]} 15:39:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:00 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')]} 15:39:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:00 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 0x254f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26b28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2810180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280e3b0>, '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 0x280f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280f740>, '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': '03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155', '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 15:39:00 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 15:39:00 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 15:39:00 INFO - Using env: {'DISPLAY': ':0', 15:39:00 INFO - 'HOME': '/home/cltbld', 15:39:00 INFO - 'LANG': 'en_US.UTF-8', 15:39:00 INFO - 'LANGUAGE': 'en_US:en', 15:39:00 INFO - 'LOGNAME': 'cltbld', 15:39:00 INFO - 'MAIL': '/var/mail/cltbld', 15:39:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:00 INFO - 'MOZ_NO_REMOTE': '1', 15:39:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:00 INFO - 'NO_EM_RESTART': '1', 15:39:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:00 INFO - 'PWD': '/builds/slave/test', 15:39:00 INFO - 'SHELL': '/bin/bash', 15:39:00 INFO - 'SHLVL': '1', 15:39:00 INFO - 'TERM': 'linux', 15:39:00 INFO - 'TMOUT': '86400', 15:39:00 INFO - 'USER': 'cltbld', 15:39:00 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155', 15:39:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:00 INFO - '_': '/tools/buildbot/bin/python'} 15:39:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:01 INFO - Downloading/unpacking blobuploader==1.2.4 15:39:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:39:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:39:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:39:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:39:02 INFO - Downloading blobuploader-1.2.4.tar.gz 15:39:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:39:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:39:03 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 15:39:03 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 15:39:03 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 15:39:03 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 15:39:03 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 15:39:03 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 15:39:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:39:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:39:03 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 15:39:03 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 15:39:03 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 15:39:03 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 15:39:03 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 15:39:03 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 15:39:03 INFO - Downloading docopt-0.6.1.tar.gz 15:39:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:39:03 INFO - Installing collected packages: blobuploader, requests, docopt 15:39:03 INFO - Running setup.py install for blobuploader 15:39:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:39:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:39:03 INFO - Running setup.py install for requests 15:39:04 INFO - Running setup.py install for docopt 15:39:04 INFO - Successfully installed blobuploader requests docopt 15:39:04 INFO - Cleaning up... 15:39:04 INFO - Return code: 0 15:39:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:39:04 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')]} 15:39:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:04 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')]} 15:39:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:04 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 0x254f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26b28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2810180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280e3b0>, '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 0x280f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280f740>, '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': '03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155', '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 15:39:04 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 15:39:04 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 15:39:04 INFO - Using env: {'DISPLAY': ':0', 15:39:04 INFO - 'HOME': '/home/cltbld', 15:39:04 INFO - 'LANG': 'en_US.UTF-8', 15:39:04 INFO - 'LANGUAGE': 'en_US:en', 15:39:04 INFO - 'LOGNAME': 'cltbld', 15:39:04 INFO - 'MAIL': '/var/mail/cltbld', 15:39:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:04 INFO - 'MOZ_NO_REMOTE': '1', 15:39:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:04 INFO - 'NO_EM_RESTART': '1', 15:39:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:04 INFO - 'PWD': '/builds/slave/test', 15:39:04 INFO - 'SHELL': '/bin/bash', 15:39:04 INFO - 'SHLVL': '1', 15:39:04 INFO - 'TERM': 'linux', 15:39:04 INFO - 'TMOUT': '86400', 15:39:04 INFO - 'USER': 'cltbld', 15:39:04 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155', 15:39:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:04 INFO - '_': '/tools/buildbot/bin/python'} 15:39:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:39:04 INFO - Running setup.py (path:/tmp/pip-Nk0ATx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:39:04 INFO - Running setup.py (path:/tmp/pip-iwhSer-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:39:04 INFO - Running setup.py (path:/tmp/pip-lfhJfp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:39:04 INFO - Running setup.py (path:/tmp/pip-pHljyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:39:04 INFO - Running setup.py (path:/tmp/pip-G3It5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:39:04 INFO - Running setup.py (path:/tmp/pip-50IRxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:39:05 INFO - Running setup.py (path:/tmp/pip-OsCHUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:39:05 INFO - Running setup.py (path:/tmp/pip-oRIx3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:39:05 INFO - Running setup.py (path:/tmp/pip-hbezXj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:39:05 INFO - Running setup.py (path:/tmp/pip-Q1_15s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:39:05 INFO - Running setup.py (path:/tmp/pip-FrM8q9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:39:05 INFO - Running setup.py (path:/tmp/pip-hA9PBi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:39:05 INFO - Running setup.py (path:/tmp/pip-Lt3kLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:39:05 INFO - Running setup.py (path:/tmp/pip-ZxnLzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:05 INFO - Running setup.py (path:/tmp/pip-XiZfJs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:39:05 INFO - Running setup.py (path:/tmp/pip-ZrLhol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:39:05 INFO - Running setup.py (path:/tmp/pip-PDXAjc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:39:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:39:05 INFO - Running setup.py install for manifestparser 15:39:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:39:05 INFO - Running setup.py install for mozcrash 15:39:06 INFO - Running setup.py install for mozdebug 15:39:06 INFO - Running setup.py install for mozdevice 15:39:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:39:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:39:06 INFO - Running setup.py install for mozfile 15:39:06 INFO - Running setup.py install for mozhttpd 15:39:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:39:06 INFO - Running setup.py install for mozinfo 15:39:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:39:06 INFO - Running setup.py install for mozInstall 15:39:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:39:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:39:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:39:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:39:06 INFO - Running setup.py install for mozleak 15:39:06 INFO - Running setup.py install for mozlog 15:39:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:39:06 INFO - Running setup.py install for moznetwork 15:39:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:39:06 INFO - Running setup.py install for mozprocess 15:39:07 INFO - Running setup.py install for mozprofile 15:39:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:39:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:39:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:39:07 INFO - Running setup.py install for mozrunner 15:39:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:39:07 INFO - Running setup.py install for mozscreenshot 15:39:07 INFO - Running setup.py install for moztest 15:39:07 INFO - Running setup.py install for mozversion 15:39:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:39:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:39:07 INFO - Cleaning up... 15:39:07 INFO - Return code: 0 15:39:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:39:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:07 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 0x254f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26b28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2810180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280e3b0>, '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 0x280f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280f740>, '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': '03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155', '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 15:39:07 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 15:39:07 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 15:39:07 INFO - Using env: {'DISPLAY': ':0', 15:39:07 INFO - 'HOME': '/home/cltbld', 15:39:07 INFO - 'LANG': 'en_US.UTF-8', 15:39:07 INFO - 'LANGUAGE': 'en_US:en', 15:39:07 INFO - 'LOGNAME': 'cltbld', 15:39:07 INFO - 'MAIL': '/var/mail/cltbld', 15:39:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:07 INFO - 'MOZ_NO_REMOTE': '1', 15:39:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:07 INFO - 'NO_EM_RESTART': '1', 15:39:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:07 INFO - 'PWD': '/builds/slave/test', 15:39:07 INFO - 'SHELL': '/bin/bash', 15:39:07 INFO - 'SHLVL': '1', 15:39:07 INFO - 'TERM': 'linux', 15:39:07 INFO - 'TMOUT': '86400', 15:39:07 INFO - 'USER': 'cltbld', 15:39:07 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155', 15:39:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:07 INFO - '_': '/tools/buildbot/bin/python'} 15:39:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:39:07 INFO - Running setup.py (path:/tmp/pip-lt98v0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:39:07 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)) 15:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:39:07 INFO - Running setup.py (path:/tmp/pip-y4vsZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:39:07 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)) 15:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:39:07 INFO - Running setup.py (path:/tmp/pip-AMWCj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:39:08 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)) 15:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:39:08 INFO - Running setup.py (path:/tmp/pip-SPxDcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:39:08 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)) 15:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:39:08 INFO - Running setup.py (path:/tmp/pip-iqNbEQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:39:08 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)) 15:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:39:08 INFO - Running setup.py (path:/tmp/pip-my3cIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:39:08 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)) 15:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:39:08 INFO - Running setup.py (path:/tmp/pip-6RPWTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:39:08 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)) 15:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:39:08 INFO - Running setup.py (path:/tmp/pip-UkIV50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:39:08 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)) 15:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:39:08 INFO - Running setup.py (path:/tmp/pip-4_I1bP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:39:08 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)) 15:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:39:08 INFO - Running setup.py (path:/tmp/pip-zyA1Sv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:39:08 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)) 15:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:39:08 INFO - Running setup.py (path:/tmp/pip-49St48-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:39:08 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)) 15:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:39:08 INFO - Running setup.py (path:/tmp/pip-YDiIQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:39:08 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)) 15:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:39:08 INFO - Running setup.py (path:/tmp/pip-VDOnEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:39:08 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)) 15:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:39:08 INFO - Running setup.py (path:/tmp/pip-oZz2kh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:39:08 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)) 15:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:08 INFO - Running setup.py (path:/tmp/pip-ZvjH4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:39:08 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)) 15:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:39:08 INFO - Running setup.py (path:/tmp/pip-jI8RGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:39:08 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)) 15:39:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:39:08 INFO - Running setup.py (path:/tmp/pip-mo93CV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:39:09 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)) 15:39:09 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)) 15:39:09 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)) 15:39:09 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)) 15:39:09 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)) 15:39:09 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)) 15:39:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:39:09 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 15:39:09 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 15:39:09 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 15:39:09 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 15:39:09 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 15:39:09 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 15:39:10 INFO - Downloading blessings-1.6.tar.gz 15:39:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:39:11 INFO - Installing collected packages: blessings 15:39:11 INFO - Running setup.py install for blessings 15:39:11 INFO - Successfully installed blessings 15:39:11 INFO - Cleaning up... 15:39:11 INFO - Return code: 0 15:39:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:39:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:39:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:39:11 INFO - Reading from file tmpfile_stdout 15:39:11 INFO - Current package versions: 15:39:11 INFO - argparse == 1.2.1 15:39:11 INFO - blessings == 1.6 15:39:11 INFO - blobuploader == 1.2.4 15:39:11 INFO - docopt == 0.6.1 15:39:11 INFO - functools32 == 3.2.3-2 15:39:11 INFO - jsonschema == 2.5.1 15:39:11 INFO - manifestparser == 1.1 15:39:11 INFO - mozInstall == 1.12 15:39:11 INFO - mozcrash == 0.17 15:39:11 INFO - mozdebug == 0.1 15:39:11 INFO - mozdevice == 0.48 15:39:11 INFO - mozfile == 1.2 15:39:11 INFO - mozhttpd == 0.7 15:39:11 INFO - mozinfo == 0.9 15:39:11 INFO - mozleak == 0.1 15:39:11 INFO - mozlog == 3.3 15:39:11 INFO - moznetwork == 0.27 15:39:11 INFO - mozprocess == 0.23 15:39:11 INFO - mozprofile == 0.28 15:39:11 INFO - mozrunner == 6.12 15:39:11 INFO - mozscreenshot == 0.1 15:39:11 INFO - mozsystemmonitor == 0.3 15:39:11 INFO - moztest == 0.7 15:39:11 INFO - mozversion == 1.4 15:39:11 INFO - psutil == 3.1.1 15:39:11 INFO - requests == 1.2.3 15:39:11 INFO - wsgiref == 0.1.2 15:39:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:39:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:11 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 0x254f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26b28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2810180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280e3b0>, '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 0x280f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280f740>, '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': '03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155', '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 15:39:11 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 15:39:11 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 15:39:11 INFO - Using env: {'DISPLAY': ':0', 15:39:11 INFO - 'HOME': '/home/cltbld', 15:39:11 INFO - 'LANG': 'en_US.UTF-8', 15:39:11 INFO - 'LANGUAGE': 'en_US:en', 15:39:11 INFO - 'LOGNAME': 'cltbld', 15:39:11 INFO - 'MAIL': '/var/mail/cltbld', 15:39:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:11 INFO - 'MOZ_NO_REMOTE': '1', 15:39:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:11 INFO - 'NO_EM_RESTART': '1', 15:39:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:11 INFO - 'PWD': '/builds/slave/test', 15:39:11 INFO - 'SHELL': '/bin/bash', 15:39:11 INFO - 'SHLVL': '1', 15:39:11 INFO - 'TERM': 'linux', 15:39:11 INFO - 'TMOUT': '86400', 15:39:11 INFO - 'USER': 'cltbld', 15:39:11 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155', 15:39:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:11 INFO - '_': '/tools/buildbot/bin/python'} 15:39:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:11 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)) 15:39:11 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)) 15:39:11 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)) 15:39:11 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)) 15:39:11 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)) 15:39:11 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)) 15:39:11 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)) 15:39:11 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)) 15:39:11 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)) 15:39:11 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)) 15:39:11 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)) 15:39:11 INFO - Cleaning up... 15:39:11 INFO - Return code: 0 15:39:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:39:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:39:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:39:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:39:11 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 0x254f030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26b28a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2810180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280e3b0>, '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 0x280f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280f740>, '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': '03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155', '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 15:39:11 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 15:39:11 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 15:39:11 INFO - Using env: {'DISPLAY': ':0', 15:39:11 INFO - 'HOME': '/home/cltbld', 15:39:11 INFO - 'LANG': 'en_US.UTF-8', 15:39:11 INFO - 'LANGUAGE': 'en_US:en', 15:39:11 INFO - 'LOGNAME': 'cltbld', 15:39:11 INFO - 'MAIL': '/var/mail/cltbld', 15:39:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:11 INFO - 'MOZ_NO_REMOTE': '1', 15:39:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:11 INFO - 'NO_EM_RESTART': '1', 15:39:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:11 INFO - 'PWD': '/builds/slave/test', 15:39:11 INFO - 'SHELL': '/bin/bash', 15:39:11 INFO - 'SHLVL': '1', 15:39:11 INFO - 'TERM': 'linux', 15:39:11 INFO - 'TMOUT': '86400', 15:39:11 INFO - 'USER': 'cltbld', 15:39:11 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155', 15:39:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:11 INFO - '_': '/tools/buildbot/bin/python'} 15:39:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:39:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:39:11 INFO - Cleaning up... 15:39:11 INFO - Return code: 0 15:39:11 INFO - Running post-action listener: _resource_record_post_action 15:39:11 INFO - Running post-action listener: _start_resource_monitoring 15:39:11 INFO - Starting resource monitoring. 15:39:11 INFO - [mozharness: 2016-09-19 22:39:11.885667Z] Finished create-virtualenv step (success) 15:39:11 INFO - [mozharness: 2016-09-19 22:39:11.886101Z] Running install step. 15:39:11 INFO - Running pre-action listener: _resource_record_pre_action 15:39:11 INFO - Running main action method: install 15:39:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:39:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:39:12 INFO - Reading from file tmpfile_stdout 15:39:12 INFO - Detecting whether we're running mozinstall >=1.0... 15:39:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:39:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:39:12 INFO - Reading from file tmpfile_stdout 15:39:12 INFO - Output received: 15:39:12 INFO - Usage: mozinstall [options] installer 15:39:12 INFO - Options: 15:39:12 INFO - -h, --help show this help message and exit 15:39:12 INFO - -d DEST, --destination=DEST 15:39:12 INFO - Directory to install application into. [default: 15:39:12 INFO - "/builds/slave/test"] 15:39:12 INFO - --app=APP Application being installed. [default: firefox] 15:39:12 INFO - mkdir: /builds/slave/test/build/application 15:39:12 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'] 15:39:12 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 15:39:26 INFO - Reading from file tmpfile_stdout 15:39:26 INFO - Output received: 15:39:26 INFO - /builds/slave/test/build/application/firefox/firefox 15:39:26 INFO - Running post-action listener: _resource_record_post_action 15:39:26 INFO - [mozharness: 2016-09-19 22:39:26.268114Z] Finished install step (success) 15:39:26 INFO - [mozharness: 2016-09-19 22:39:26.268397Z] Running run-tests step. 15:39:26 INFO - Running pre-action listener: _resource_record_pre_action 15:39:26 INFO - Running main action method: run_tests 15:39:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:39:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:39:26 INFO - Python 2.7.3 15:39:26 INFO - Return code: 0 15:39:26 INFO - grabbing minidump binary from tooltool 15:39:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:39:26 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 0x280e3b0>, '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 0x280f2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280f740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:39:26 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 15:39:26 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 15:39:26 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 15:39:26 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:39:26 INFO - Return code: 0 15:39:26 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:39:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:39:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:39:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:39:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:39:26 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/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 15:39:26 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/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-047 --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 15:39:26 INFO - Using env: {'DISPLAY': ':0', 15:39:26 INFO - 'HOME': '/home/cltbld', 15:39:26 INFO - 'LANG': 'en_US.UTF-8', 15:39:26 INFO - 'LANGUAGE': 'en_US:en', 15:39:26 INFO - 'LOGNAME': 'cltbld', 15:39:26 INFO - 'MAIL': '/var/mail/cltbld', 15:39:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:39:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:39:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:39:26 INFO - 'MOZ_NO_REMOTE': '1', 15:39:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:39:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:39:26 INFO - 'NO_EM_RESTART': '1', 15:39:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:39:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:39:26 INFO - 'PWD': '/builds/slave/test', 15:39:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:39:26 INFO - 'SHELL': '/bin/bash', 15:39:26 INFO - 'SHLVL': '1', 15:39:26 INFO - 'TERM': 'linux', 15:39:26 INFO - 'TMOUT': '86400', 15:39:26 INFO - 'USER': 'cltbld', 15:39:26 INFO - 'XDG_SESSION_COOKIE': '03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155', 15:39:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:39:26 INFO - '_': '/tools/buildbot/bin/python'} 15:39:26 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/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-047', '--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 15:39:26 INFO - mozversion application_buildid: 20160919064031 15:39:26 INFO - mozversion application_changeset: 01d67bfe6c8198416ec190cbc216d953c1625b94 15:39:26 INFO - mozversion application_display_name: Firefox 15:39:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:39:26 INFO - mozversion application_name: Firefox 15:39:26 INFO - mozversion application_remotingname: firefox 15:39:26 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:39:26 INFO - mozversion application_vendor: Mozilla 15:39:26 INFO - mozversion application_version: 50.0 15:39:26 INFO - mozversion platform_buildid: 20160919064031 15:39:26 INFO - mozversion platform_changeset: 01d67bfe6c8198416ec190cbc216d953c1625b94 15:39:26 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:39:26 INFO - mozversion platform_version: 50.0 15:39:26 INFO - using testdate: 1474324766 15:39:26 INFO - actual date: 1474324766 15:39:26 INFO - starting webserver on 'localhost:46599' 15:39:26 INFO - SUITE-START | Running 2 tests 15:39:26 INFO - TEST-START | dromaeo_css 15:39:26 INFO - operating with platform_type : linux_ 15:39:26 INFO - Initialising browser for dromaeo_css test... 15:39:26 INFO - TEST-INFO | started process 11701 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjHtM80/profile http://localhost:46599/getInfo.html) 15:39:30 INFO - PROCESS | 11701 | __metrics Screen width/height:1600/1200 15:39:30 INFO - PROCESS | 11701 | colorDepth:24 15:39:30 INFO - PROCESS | 11701 | Browser inner width/height: 1024/697 15:39:30 INFO - PROCESS | 11701 | __metrics 15:39:31 INFO - PROCESS | 11701 | 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:39:31 INFO - PROCESS | 11701 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:39:32 INFO - TEST-INFO | 11701: exit 0 15:39:32 INFO - Browser initialized. 15:39:32 INFO - Running cycle 1/1 for dromaeo_css test... 15:39:32 INFO - TEST-INFO | started process 11790 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpjHtM80/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:39:44 INFO - PROCESS | 11790 | RSS: Main: 143048704 15:39:44 INFO - PROCESS | 11790 | 15:40:34 INFO - PROCESS | 11790 | 1474324834869 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] 15:42:43 INFO - PROCESS | 11790 | Cycle 1(1): loaded http://localhost:46599/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46599/tests/dromaeo/cssquery-ext.html) 15:42:44 INFO - PROCESS | 11790 | RSS: Main: 223944704 15:42:44 INFO - PROCESS | 11790 | 15:45:47 INFO - PROCESS | 11790 | Cycle 1(1): loaded http://localhost:46599/tests/dromaeo/cssquery-ext.html (next: http://localhost:46599/tests/dromaeo/cssquery-jquery.html) 15:45:47 INFO - PROCESS | 11790 | RSS: Main: 232513536 15:45:47 INFO - PROCESS | 11790 | 15:48:49 INFO - PROCESS | 11790 | Cycle 1(1): loaded http://localhost:46599/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46599/tests/dromaeo/cssquery-mootools.html) 15:48:50 INFO - PROCESS | 11790 | RSS: Main: 177225728 15:48:50 INFO - PROCESS | 11790 | 15:51:52 INFO - PROCESS | 11790 | Cycle 1(1): loaded http://localhost:46599/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46599/tests/dromaeo/cssquery-prototype.html) 15:51:52 INFO - PROCESS | 11790 | RSS: Main: 175865856 15:51:52 INFO - PROCESS | 11790 | 15:54:55 INFO - PROCESS | 11790 | Cycle 1(1): loaded http://localhost:46599/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46599/tests/dromaeo/cssquery-yui.html) 15:54:55 INFO - PROCESS | 11790 | RSS: Main: 176058368 15:54:55 INFO - PROCESS | 11790 | 15:57:58 INFO - PROCESS | 11790 | Cycle 1(1): loaded http://localhost:46599/tests/dromaeo/cssquery-yui.html (next: http://localhost:46599/tests/dromaeo/cssquery-dojo.html) 15:57:58 INFO - PROCESS | 11790 | RSS: Main: 264007680 15:57:58 INFO - PROCESS | 11790 | 15:57:58 INFO - PROCESS | 11790 | __start_tp_report 15:57:58 INFO - PROCESS | 11790 | _x_x_mozilla_page_load 15:57:58 INFO - PROCESS | 11790 | _x_x_mozilla_page_load_details 15:57:58 INFO - PROCESS | 11790 | |i|pagename|runs| 15:57:58 INFO - PROCESS | 11790 | |0;dojo.html;3245.6267529234137;3268.0522648431956;3273.754468414868;3294.2587917718533;3326.1352048467393;5560.499555040045;5612.326520817497;5640.774369025245;5670.404607516208;5672.205891175238;5592.4407559244055;5717.514011309044;5721.513671337942;5724.570657200717;5736.45503677151;5044.949550504503;5201.349831271091;5204.557612602934;5250.133727934886;5283.577313814905;5097;5190.325257716491;5232.084385232588;5243.895122097556;5252.6323157379;5088.669236499634;5095.286659867622;5122.743862806863;5129.2562578426105;5163.122269214224;5210.921836172461;5223.555997740159;5224.92162617561;5232.084385232569;5261.8947621047555;5008.198688209869;5070.08738427083;5113.923291150636;5119.436861945183;5136.897262054776;5542.528885044776;5572.944270557283;5585.944140558624;5591.412901645309;5611.8877622447735;6055.909161362583;6060.393960603904;6069.817905462844;6071.757129714806;6073.089036644495;6022.367651396584;6085.2393450818645;6086.9391306087255;6098.481629061535;6118.663473508956;5705.343885453184;5723.055695810144;5725.456081672234;5748.080307150836;5763.596548241625;6038.033914573735;6228.4705800007505;6259.0924315973925;6264.59280146789;6269.467095296951;5856.385079566669;5891.351951285355;5910.261217347832;5951.166836642873;5985.610935289193;6023.247094113236;6030.03519436888;6031.035034394564;6031.185789918305;6032.125341825411;5882.617629854046;6005.579609427298;6009.429104235091;6012.609180403259;6014.18808460861;5924.081767325985;6001.789937352171;6012.699365031731;6045;6047.395260473917;4638.420197475316;4641.721496710208;4644.256918892961;4645.094206629742;4675.976620116878;4054.2094291613444;4169.061961058737;4180.352045432902;4194.475690538683;4197.349410841324;5743.425657434307;5773.826785196451;5778.855528611818;5819.854503637358;5863.736131874076;6064.727087281083;6074;6095.969520152371;6098.939010609837;6100.176476175749;5659.490645841894;5754.251947246831;5759.539236861041;5761.337446193657;5762.135679648002;5220.399654039834;5244.554212891947;5268.736563171826;5269.815556455505;5315.04329220744;6247.687615619201;6258.561900666909;6263.498920086473;6263.843403914848;6264.968675156595;5939.554533410098;6035;6072.14989901423;6072.8785424292155;6077.4226448487325;6128.080787881853;6191.040388739752;6192.164057852313;6251.968740156452;6304.527160463075;5956.136360227656;6011.819645410645;6015.157877897185;6019.247594050744;6050.2437195350585;6001.5798894077;6126.908096378469;6130.417610327012;6131.110988906673;6131.478824299985;5905.173275741314;5959.4934430573885;5963.821085367446;6000.399960003964;6032.517398608188;5985.820425387245;5986.4013598639785;5988.5508586855285;5989.221401217814;5992.820215393545;5755.107958266475;5893.6463812171405;5900.911486327795;5930.05119180929;5962.046072628532;5951.613145145551;6003.579749417673;6004.489618382487;6042.788502402394;6080.331163572092;5825.184474173534;5858.384869588802;5866.941330586639;5876.941230587639;5898 15:57:58 INFO - PROCESS | 11790 | |1;ext.html;606.3239487970914;640.2924768131215;664.5281849886582;672.6098862659654;677.8881484555048;35812;47271.527284727184;47748.04503909924;47833.28250076243;47957.520424795744;49099;49538.50461495384;49550;49576.256356154685;49646;8471.279941205004;9177.632894684204;9299.674511392102;9352.438853668802;10920.945395273013;10734.194935379877;11162.888371116307;11172.720681982975;11177.273477223995;11197;11276.548938042468;11308.604198853041;11313.490892909838;11317.83023254652;11321.603743868965;11197.608083717072;11230.438478076063;11248.325100493956;11254.49354779037;11260.605878794202;11096.500657470433;11134.220604557684;11160.107191424666;11168.66494005181;11173.608923687712;46145.76927115377;46588.53411465876;46663.533364666255;46673;46693.06613867738;3631.981840090809;3768.4535742317485;3840.6543411093135;4127.545969943304;4444.910996805803;34204.82897585521;34374.18968784268;34420;35212.64787352119;35450;4600.378948841897;4628.544352726974;4636.5131661175765;4665.626749860037;4738.95261047392;390757;390844;390871;391730;392718;60400;60524.39475605276;60526.39473605296;60555.88054418162;60781.08828367578;39532.407013894815;40223.597764021986;40230.798846005586;42324.57675423268;65553;18800.717989230172;19118.52203694891;19167.808321916604;19169.137388817537;19177;12946.223226606433;13801.309934503435;14656.193909335088;14960.775588366183;14973.625659358604;20191.69712454285;20493.180272788923;20550.38348849507;20714.378568642664;20732.79267207339;7149.678264478111;7177.497575169792;7202.747903823341;7388.926110738824;7454.813629659194;1039.9948000259951;1073.2058276875057;1123.4214379594516;1124.1208479651307;1173.6420391780418;33666.663333366356;34294.792235515764;34391.312173756385;34470.31059378798;34605.30789384249;1153.7346410325672;1175.5364571109023;1191.6782468733568;1198.5385626533673;1215.0583297944163;17812.376566820098;18192.81807181911;18212.180451879696;18219.36232231892;18250.452486425427;627704;629958;634760;634817;638051;63621;63840.36159638437;63918;63932.360676392644;64247.678761605894;1347.036868638928;1425.7504936636255;1453.6147920800997;1467.743144949651;1486.2642991719167;32851.34297313992;35282.29435411226;35438.29123417569;35607.28785424329;35730.464043039894;54739.178912315554;55203.72398137984;55272.44727552673;55307;55375;463161;465329;465768;469070;470029;27290;29925;30771;30844.38311233718;32400.35199296048;51598;51987.22019169791;52121;52503;52541.211881822564;43542;44438.11579431262;44827.775861120484;45370.546294537955;46039.53960460486;43000;43945.78027109972;44638.77680611576;45306;45511;42688.14623707571;43900.341494876964;44503;44743.776281118386;44812.551874480836;36362.45456318105;38993.41509877411;39266;39462.80268598639;39695;29056;29070.546472676706;29171.41657166802;29189.41621167607;29386.265343366587 15:57:58 INFO - PROCESS | 11790 | |2;jquery.html;415.8833247877856;420.52690722936694;432.6927881204345;432.96536277097823;439.11956527163034;18975.71536426953;20133.99330033498;20600.690989635168;21199.894000530014;21485.67771483425;20068.59862802743;20655.586888262264;20807.583848323025;20841.895790521063;20955.37133885982;5647.237622920894;5943.583949123563;5956.136360227764;5959.374265702104;5962.821115366524;5678.914816277759;5892.351841297454;5899.498542623882;5920.052791553352;6006.189164462785;5968.940310596903;5970.074638431029;5975.193348897887;5977.88044239115;5982.730777115018;5717.228174196472;5924.0521516557355;5967.761289548413;6063.242094738158;6074.665893375863;5236.319278493809;5483.890322193533;5896.292444906596;5896.3219229788265;5909;14620.853791462056;14684.926575367163;14696.779548306784;14840.703185936221;14844.480443184437;2641.3660721426913;2857.8142420742583;2978.1065680295824;2994.5807586937844;3051.801632893877;10320.174386049099;10552.947235263851;10795.892041079645;10873.184511161695;10963.177761667906;1920.3470819921154;1954.8631595788158;1967.567135230247;1976.9901150494156;2012.6276638821864;84194;90003;91027;91610.54194728984;91858.54070729604;11930;12017.699557511132;12217.69455763613;12310.569130080583;12414.441350139265;17179.31282748701;17200.483985480456;17213.31146754116;17216.655666886843;17226.483205503853;8001.479903806234;8229.300509456763;8649;8695.347848911357;8923.910760892439;9220.262379009662;9231.95384023089;9238.353315268001;9442.763930901783;9454;4955.695601545299;4961.677490963076;5158.303629010111;5303.151495760737;5310.893782124336;2063.13348393675;2092.6337890869036;2099.1451625788914;2168.436206586299;2193.7257842769654;962.932594718377;965.7971825916636;1024.7694268789612;1068.8735448812192;1081.118888106191;19256.614867702847;20713.067911944;20905.89547052255;21064.368068957956;21115.26096586612;1033.6973370082665;1054.752133248686;1072.9356238625708;1079.2391364088298;1111.1610733895857;10569.947150264354;11595.76808463826;11640.592579259684;11663.35851528157;11676.299422034535;562031;573506;585898;597769;603153;100090.4995475018;104449;104988.47505762575;105583.47208264063;106320;1004.6333088422673;1032.8347464405808;1055.1927775251938;1055.2771351624162;1056.0759335581367;17736.733948990517;17788.3774067907;18083;18547.536311592317;18760.718589220898;46747.29879051885;47275.290870637655;47381.76309118432;49726.502734973634;49886.750566246934;61277;62149;62566;62824.057639136365;64127.679361602895;8294.257897873984;8701.956490217508;8921.06328835463;8923.73228803137;8939.329550283624;11790.646280611596;12899.806502902651;13332.40004199794;13505.459781609019;13652.180869147884;10320.43237621938;11619.767604648028;11815.291082535075;11940.880591194324;12303.44634491432;11336.829947550958;11923.701907452383;12142.635720928387;12205.328706921204;12388.256704597752;11707.121965852766;12008.579699710468;12020.158588898694;12376.319302438453;12378.62864114078;8656.134386561294;8708.17272359125;8782.912170878464;8841.425307355;8905.599248033725;11239.325640461599;11292.322460652387;12078.698032549257;12432.75134497323;12507 15:57:58 INFO - PROCESS | 11790 | |3;mootools.html;1386.6810633554276;1531.8008658874344;1575.306864979409;1734.2889415339705;1825.1513046433415;8320.750377488677;9183;9467.2899532535;9473.90526094739;9768.804623907517;9454.33819632626;9455.1017653323;9466.100720431565;9482.288828337885;9528.618855245782;2496.2885577610386;2544.122277814151;2546.936326591841;2549.069589599792;2550.2221822344272;2446.6819313489223;2525.8863351149243;2531.721510633829;2532.7593878581597;2535.290118766748;2521.34445044288;2538.0862889359814;2549.413634863982;2550.349660836484;2557.7186509484036;2436.281297017384;2440.8413453125577;2447.0578827151585;2479.47930934504;2484.9875750621313;2407.915722949688;2413.3122060212754;2422.140140250221;2423.2245681381964;2460.0405841721745;9313.860292095624;9537.904620953841;9538.90461095387;9607.519624018842;9622.422654640744;5558.555315574785;5718.285214348207;5727.856803579903;5733.770649174028;5734.311882574076;3582.8925132246072;3623.655752703489;3645.927081458356;3654.8538058477893;3656.4149736042373;1259.615817175761;1268.8413948256468;1269.092598791859;1290.0324756432676;1291.819145319656;13161.210327380388;13176.60470185896;13255;13348.666283342995;13369.33153342329;4773.689710168828;4831.202851529459;4843.903121937541;4891.951080489221;4962.15643340633;10484.63303784364;10486.423246721504;10500.73748156287;10515.631952881766;10523.789524209473;5584.46947539983;5588.524975377139;5592.02139625572;5594.496495315441;5598.1322894951345;4695.530446955346;5033.169527028;5141;5191.117510023229;5236.738163091906;4156;4185.372194170899;4256.297710877732;4294.26997410441;4317.481902171759;1219.841420615332;1221.5663439479035;1237.1154624443566;1238.6346027921784;1252.136026141959;875.7766769473743;980.5244456438764;1032.3135115148343;1060.506864308085;1063.3301020357126;6461.127747754087;6504.577202481823;6561.835954101186;6592.373724496165;6819.7272109116075;1008.1833714691123;1020.4591566469783;1036.259074761549;1037.0821822686826;1062.984055239172;4353.629941455012;4399.274119770202;4428.579284967923;4507.797149128297;4515.119551687455;11805.586804461802;12098.455569499394;12145.14983951115;12147.574834880914;12298.692532686755;11194.328340299446;12036.518539258506;12064;12075.335856527976;12124.090693198046;8864;9091;9111.179993800457;9111.817763644822;9117.133872282122;6246.219222597176;6268.937310626835;6404.615723056631;6511.120998665119;6631.801045968628;11035.83446248323;11049.558017679525;11051.72370690707;11182.776344473228;11191.832122518332;10183.236257280585;10409.84385234207;10444.947775261075;10479.68560943173;10506.212034097625;7742.496737712031;8295.58522073906;8340.207680270607;8484.363672724447;8561.785955351166;9394.906050939404;9574.186194173573;9583.952080239555;9591.328606997444;9602.519874006131;8477.872831907403;8576.356773242156;8578.699745508877;8646.697365592174;8660.826783464421;7737.11023232313;8082.4746391486715;8087.716929907425;8229.629666665134;8336.458130221516;7328.706851725871;7476.962615186889;7545.434092443198;7592.316691497792;7699.615019248903;5406.324209473816;5414.377346605191;5461.317335333083;5541.113421852484;5626.718664066862;5449.918251226314;5563.944360556343;5571.66570005801;5593.454977985089;5622.353429355677 15:57:58 INFO - PROCESS | 11790 | |4;prototype.html;337.76862848948457;340.553874424504;342.7241070937894;342.7686311739576;347.15467835819766;5504.724763761816;5595.692236926968;5598.417013111602;5616.5787565932615;5641.435856414357;5442.782288708447;5477.643953143043;5478.58910581707;5547.389787123413;5559.221708960748;4153.190127925056;4263.850765223217;4374.934375984347;4406.625436837857;4420.911581768362;4345.174416860802;4345.8478953236645;4353.717008394444;4381.058072514409;4428.424304840382;4319.740815551061;4331.7400955942585;4334.761588112652;4340.804663790122;4387.3857659927635;4189.706720529564;4315.762633055181;4320.243957307459;4349.195398851191;4356.956430435686;3867.2265546890735;3892.08535994044;3895.454636350913;3907.6873850091924;3916.8824935251705;5150.459201783796;5348.8127915523155;5390.6226564140525;5392.514673679388;5455.672659640394;4880.974995251002;4910.7299098549565;4921.507849215086;4923.212286034253;4926.842192084857;5706.229658996024;5709.714514274311;5724.940885936116;5730.512906402961;5739.827805165852;4077.5922407759285;4139.627433530996;4149.751014939083;4167.020750123718;4170.541240463546;5891.643342662969;5961.403859614047;5969.164316995624;5982.700864956735;6053.545984051214;4228.386883901817;4239.788010599457;4240.724352917051;4242.342436922282;4292.141571685675;4216.725912815718;4265.658747300208;4326.762028088422;4332.588404101605;4333.761643109596;4875.317455556225;4876.878078048077;4878.07316609843;4896.36347274852;4927.211646136672;5785.50823180026;5806.970965145148;5829.242198514166;5830.154627578977;5841.06542953134;1426.3367534096694;1462.3638717158071;1480.3856399594047;1486.851314868526;1491.6494623763406;951.039450155348;956.679512363366;957.5116690487902;959.3092973059386;1000.1448761308976;5592.217089607459;5620.381758006616;5630.324361076697;5633.774649014075;5636.2954630671165;5867.237259156283;5894.734736936848;5895.469407753237;5898.731772668897;5928.258967629046;5487.204355368529;5525.585581081435;5552.889901837357;5578.163275508706;5661.745221465044;3084.4293805646025;3107.145534977854;3116.626004879384;3139.246580820632;3149.9527507087414;5862.560307976919;5889.793857214977;5991.760329586855;6087.173847845628;6113.327533971348;5934.6142500737315;5940.287165540163;5941.821745347646;5965.343812180657;5967.403259673998;6023.307319658208;6024.789132380433;6026.367231440723;6040.073951931192;6053.606515576474;5552.52803511706;5872.412758724094;5909.881802364014;5957.3744756799915;5962.582619216613;5875.764969401176;5878.529717622666;5885.911711324248;5887.411258874078;5892;5823.505002074871;6062.909056364247;6082.60463069899;6163.691815409301;6190.876182476415;4244.427002354642;4258.403823464655;4269.4449721536;4276.422682937888;4298.312270036779;6062.6665533394325;6063.848403789941;6070.150178974859;6072.514198863992;6072.597366908955;5688.431156884444;5788.84223155379;5808.767649293981;5818.679972601548;5934.643921364732;5748.913766293593;5768.192453056491;5778.682172480554;5800.941990580209;5843.824685259449;5408.45915408456;5700.857478563069;5745;5798.217240672455;5802.303723553201;5686.2323586315315;5780.248567686174;5798.884022319615;5800.187973683604;5808.796692115756;5653.660780353028;5653.971730141323;5663.377028526941;5692.31692196939;5692.914606280826 15:57:58 INFO - PROCESS | 11790 | |5;yui.html;806.073016031564;818.2022528035166;824.5588610093597;889.4574309671101;908.0964440381821;14599.854001459982;15551.222438878054;15555.922220388882;15593.06441613504;15626.76559851603;14962.775558366633;15089.698206035873;15127.243637818092;15140;15155.77266341003;740.4150720930472;866.1035827918106;867.154524338771;889.9199072083511;894.1550235027901;891.2112780189547;892.9642814287453;897.9685711000117;900.4462255712755;900.7342833863993;937.8874535055803;940.7412961435591;941.7833898203418;944.5418971798672;945.4327403557845;940.920021798148;941.7786820097272;943.4433684126365;943.5848226780263;943.7168849345169;691.8235849858304;693.4902846407887;693.9895901561481;694.7672529702555;695.8156088636515;15219.239038048054;15242.542723718307;15273;15290;15292.770608440882;15396.307166177548;15812;15995.780632706077;16037.438689645924;16037.438689645924;13799.793003104862;13920.30398480072;13950.720985580232;14040.648983775385;14220.217888016155;3962.363041904827;3985.20295940813;4059.939100913489;4062.979685101556;4073.06319546506;83422;86129;86260;86286.56856715676;87294.12705872924;13026.869731302755;13039.348032598333;13239.139455935334;13286.534971276153;13324.467021319233;25018.749812502007;25104.246872593852;25118.623220651705;25133.24600261995;25163.370915727253;8270.503769773833;8468.745937621758;8505.277051450696;8523.275521580612;8626.611802468904;4883.975580122076;4915.5330243626795;4987.800487980513;4998.075356059068;5026.698398096126;3804.581496035489;3919.627635374635;3923.843046278117;3924.372100463969;3930.1746633206776;658.7793089315134;673.3468535520537;674.1101745695732;678.1624693503519;683.2620769568854;647.0649910878735;657.7369052378991;714.2821464428216;736.5580651608992;747.7345542332503;9796.216302695768;10012.749681258027;10043.598256069676;10133.304108715567;10171.796564068678;738.4867517075688;745.4260219630962;756.9924300757032;762.8741257692419;779.1429427629562;4791.928121078186;4845.927311090337;4917.901641967141;5071.39143302806;5087.745612719349;82257;83416.58291708502;104234;104864.47567762113;108283;99041.50479247702;100371.49814250882;105759;108835.45582272197;111068.4446577762;91182.54408728046;91648.54175729211;91990.54004729934;93260.53369733243;94091;9612.231021518122;9693.6122555097;9750.85373719409;9813.9018609813;10008.349457285252;38907.610923890395;47805.521944781496;49479.25781113358;51457;52912;68950.3104968944;81759.591202046;82859;83063;85376.57311713402;12153.635390938285;13319.134256273312;13537.932310338385;13684;14125.364358603681;12642.2414655121;13450;14148.363323650667;14636;14759.631009224855;11066.225364224427;11841.940790295994;11957.940210298893;11999.280043197436;12089.395530223277;12921.418536166084;13421.194728316332;13531.526396576073;14396;14432.134071955716;13085.869141308465;13191.076624636184;13555.457781688621;13598.524051658145;13721.794173087612;10869.130469562575;11436.94281528587;11530.654080377602;11589.24669896454;11633.709157271136;11454.312741235553;11475.254108482923;11895.464704088165;11988.280703157838;11997.64007079789 15:57:58 INFO - PROCESS | 11790 | __end_tp_report 15:57:58 INFO - PROCESS | 11790 | __start_cc_report 15:57:58 INFO - PROCESS | 11790 | _x_x_mozilla_cycle_collect,1096 15:57:58 INFO - PROCESS | 11790 | __end_cc_report 15:57:58 INFO - PROCESS | 11790 | __startTimestamp1474325878435__endTimestamp 15:57:58 INFO - PROCESS | 11790 | 15:57:58 INFO - PROCESS | 11790 | ------- Summary: start ------- 15:57:58 INFO - PROCESS | 11790 | Number of tests: 6 15:57:58 INFO - PROCESS | 11790 | 15:57:58 INFO - PROCESS | 11790 | [#0] dojo.html Cycles:165 Average:5639.94 Median:5891.35 stddev:634.78 (10.8%) stddev-sans-first:608.30 15:57:58 INFO - PROCESS | 11790 | Values: 3245.6 3268.1 3273.8 3294.3 3326.1 5560.5 5612.3 5640.8 5670.4 5672.2 5592.4 5717.5 5721.5 5724.6 5736.5 5044.9 5201.3 5204.6 5250.1 5283.6 5097.0 5190.3 5232.1 5243.9 5252.6 5088.7 5095.3 5122.7 5129.3 5163.1 5210.9 5223.6 5224.9 5232.1 5261.9 5008.2 5070.1 5113.9 5119.4 5136.9 5542.5 5572.9 5585.9 5591.4 5611.9 6055.9 6060.4 6069.8 6071.8 6073.1 6022.4 6085.2 6086.9 6098.5 6118.7 5705.3 5723.1 5725.5 5748.1 5763.6 6038.0 6228.5 6259.1 6264.6 6269.5 5856.4 5891.4 5910.3 5951.2 5985.6 6023.2 6030.0 6031.0 6031.2 6032.1 5882.6 6005.6 6009.4 6012.6 6014.2 5924.1 6001.8 6012.7 6045.0 6047.4 4638.4 4641.7 4644.3 4645.1 4676.0 4054.2 4169.1 4180.4 4194.5 4197.3 5743.4 5773.8 5778.9 5819.9 5863.7 6064.7 6074.0 6096.0 6098.9 6100.2 5659.5 5754.3 5759.5 5761.3 5762.1 5220.4 5244.6 5268.7 5269.8 5315.0 6247.7 6258.6 6263.5 6263.8 6265.0 5939.6 6035.0 6072.1 6072.9 6077.4 6128.1 6191.0 6192.2 6252.0 6304.5 5956.1 6011.8 6015.2 6019.2 6050.2 6001.6 6126.9 6130.4 6131.1 6131.5 5905.2 5959.5 5963.8 6000.4 6032.5 5985.8 5986.4 5988.6 5989.2 5992.8 5755.1 5893.6 5900.9 5930.1 5962.0 5951.6 6003.6 6004.5 6042.8 6080.3 5825.2 5858.4 5866.9 5876.9 5898.0 15:57:58 INFO - PROCESS | 11790 | 15:57:58 INFO - PROCESS | 11790 | [#1] ext.html Cycles:180 Average:66444.51 Median:215498.00 stddev:134748.14 (62.5%) stddev-sans-first:135035.48 15:57:58 INFO - PROCESS | 11790 | Values: 606.3 640.3 664.5 672.6 677.9 35812.0 47271.5 47748.0 47833.3 47957.5 49099.0 49538.5 49550.0 49576.3 49646.0 8471.3 9177.6 9299.7 9352.4 10920.9 10734.2 11162.9 11172.7 11177.3 11197.0 11276.5 11308.6 11313.5 11317.8 11321.6 11197.6 11230.4 11248.3 11254.5 11260.6 11096.5 11134.2 11160.1 11168.7 11173.6 46145.8 46588.5 46663.5 46673.0 46693.1 3632.0 3768.5 3840.7 4127.5 4444.9 34204.8 34374.2 34420.0 35212.6 35450.0 4600.4 4628.5 4636.5 4665.6 4739.0 390757.0 390844.0 390871.0 391730.0 392718.0 60400.0 60524.4 60526.4 60555.9 60781.1 39532.4 40223.6 40230.8 42324.6 65553.0 18800.7 19118.5 19167.8 19169.1 19177.0 12946.2 13801.3 14656.2 14960.8 14973.6 20191.7 20493.2 20550.4 20714.4 20732.8 7149.7 7177.5 7202.7 7388.9 7454.8 1040.0 1073.2 1123.4 1124.1 1173.6 33666.7 34294.8 34391.3 34470.3 34605.3 1153.7 1175.5 1191.7 1198.5 1215.1 17812.4 18192.8 18212.2 18219.4 18250.5 627704.0 629958.0 634760.0 634817.0 638051.0 63621.0 63840.4 63918.0 63932.4 64247.7 1347.0 1425.8 1453.6 1467.7 1486.3 32851.3 35282.3 35438.3 35607.3 35730.5 54739.2 55203.7 55272.4 55307.0 55375.0 463161.0 465329.0 465768.0 469070.0 470029.0 27290.0 29925.0 30771.0 30844.4 32400.4 51598.0 51987.2 52121.0 52503.0 52541.2 43542.0 44438.1 44827.8 45370.5 46039.5 43000.0 43945.8 44638.8 45306.0 45511.0 42688.1 43900.3 44503.0 44743.8 44812.6 36362.5 38993.4 39266.0 39462.8 39695.0 29056.0 29070.5 29171.4 29189.4 29386.3 15:57:58 INFO - PROCESS | 11790 | 15:57:58 INFO - PROCESS | 11790 | [#2] jquery.html Cycles:180 Average:32681.98 Median:11527.26 stddev:96351.21 (835.9%) stddev-sans-first:96591.04 15:57:58 INFO - PROCESS | 11790 | Values: 415.9 420.5 432.7 433.0 439.1 18975.7 20134.0 20600.7 21199.9 21485.7 20068.6 20655.6 20807.6 20841.9 20955.4 5647.2 5943.6 5956.1 5959.4 5962.8 5678.9 5892.4 5899.5 5920.1 6006.2 5968.9 5970.1 5975.2 5977.9 5982.7 5717.2 5924.1 5967.8 6063.2 6074.7 5236.3 5483.9 5896.3 5896.3 5909.0 14620.9 14684.9 14696.8 14840.7 14844.5 2641.4 2857.8 2978.1 2994.6 3051.8 10320.2 10552.9 10795.9 10873.2 10963.2 1920.3 1954.9 1967.6 1977.0 2012.6 84194.0 90003.0 91027.0 91610.5 91858.5 11930.0 12017.7 12217.7 12310.6 12414.4 17179.3 17200.5 17213.3 17216.7 17226.5 8001.5 8229.3 8649.0 8695.3 8923.9 9220.3 9232.0 9238.4 9442.8 9454.0 4955.7 4961.7 5158.3 5303.2 5310.9 2063.1 2092.6 2099.1 2168.4 2193.7 962.9 965.8 1024.8 1068.9 1081.1 19256.6 20713.1 20905.9 21064.4 21115.3 1033.7 1054.8 1072.9 1079.2 1111.2 10569.9 11595.8 11640.6 11663.4 11676.3 562031.0 573506.0 585898.0 597769.0 603153.0 100090.5 104449.0 104988.5 105583.5 106320.0 1004.6 1032.8 1055.2 1055.3 1056.1 17736.7 17788.4 18083.0 18547.5 18760.7 46747.3 47275.3 47381.8 49726.5 49886.8 61277.0 62149.0 62566.0 62824.1 64127.7 8294.3 8702.0 8921.1 8923.7 8939.3 11790.6 12899.8 13332.4 13505.5 13652.2 10320.4 11619.8 11815.3 11940.9 12303.4 11336.8 11923.7 12142.6 12205.3 12388.3 11707.1 12008.6 12020.2 12376.3 12378.6 8656.1 8708.2 8782.9 8841.4 8905.6 11239.3 11292.3 12078.7 12432.8 12507.0 15:57:58 INFO - PROCESS | 11790 | 15:57:58 INFO - PROCESS | 11790 | [#3] mootools.html Cycles:180 Average:6245.13 Median:4468.19 stddev:3563.19 (79.7%) stddev-sans-first:3554.48 15:57:58 INFO - PROCESS | 11790 | Values: 1386.7 1531.8 1575.3 1734.3 1825.2 8320.8 9183.0 9467.3 9473.9 9768.8 9454.3 9455.1 9466.1 9482.3 9528.6 2496.3 2544.1 2546.9 2549.1 2550.2 2446.7 2525.9 2531.7 2532.8 2535.3 2521.3 2538.1 2549.4 2550.3 2557.7 2436.3 2440.8 2447.1 2479.5 2485.0 2407.9 2413.3 2422.1 2423.2 2460.0 9313.9 9537.9 9538.9 9607.5 9622.4 5558.6 5718.3 5727.9 5733.8 5734.3 3582.9 3623.7 3645.9 3654.9 3656.4 1259.6 1268.8 1269.1 1290.0 1291.8 13161.2 13176.6 13255.0 13348.7 13369.3 4773.7 4831.2 4843.9 4892.0 4962.2 10484.6 10486.4 10500.7 10515.6 10523.8 5584.5 5588.5 5592.0 5594.5 5598.1 4695.5 5033.2 5141.0 5191.1 5236.7 4156.0 4185.4 4256.3 4294.3 4317.5 1219.8 1221.6 1237.1 1238.6 1252.1 875.8 980.5 1032.3 1060.5 1063.3 6461.1 6504.6 6561.8 6592.4 6819.7 1008.2 1020.5 1036.3 1037.1 1063.0 4353.6 4399.3 4428.6 4507.8 4515.1 11805.6 12098.5 12145.1 12147.6 12298.7 11194.3 12036.5 12064.0 12075.3 12124.1 8864.0 9091.0 9111.2 9111.8 9117.1 6246.2 6268.9 6404.6 6511.1 6631.8 11035.8 11049.6 11051.7 11182.8 11191.8 10183.2 10409.8 10444.9 10479.7 10506.2 7742.5 8295.6 8340.2 8484.4 8561.8 9394.9 9574.2 9584.0 9591.3 9602.5 8477.9 8576.4 8578.7 8646.7 8660.8 7737.1 8082.5 8087.7 8229.6 8336.5 7328.7 7477.0 7545.4 7592.3 7699.6 5406.3 5414.4 5461.3 5541.1 5626.7 5449.9 5563.9 5571.7 5593.5 5622.4 15:57:58 INFO - PROCESS | 11790 | 15:57:58 INFO - PROCESS | 11790 | [#4] prototype.html Cycles:180 Average:4875.01 Median:5607.50 stddev:1425.77 (25.4%) stddev-sans-first:1388.50 15:57:58 INFO - PROCESS | 11790 | Values: 337.8 340.6 342.7 342.8 347.2 5504.7 5595.7 5598.4 5616.6 5641.4 5442.8 5477.6 5478.6 5547.4 5559.2 4153.2 4263.9 4374.9 4406.6 4420.9 4345.2 4345.8 4353.7 4381.1 4428.4 4319.7 4331.7 4334.8 4340.8 4387.4 4189.7 4315.8 4320.2 4349.2 4357.0 3867.2 3892.1 3895.5 3907.7 3916.9 5150.5 5348.8 5390.6 5392.5 5455.7 4881.0 4910.7 4921.5 4923.2 4926.8 5706.2 5709.7 5724.9 5730.5 5739.8 4077.6 4139.6 4149.8 4167.0 4170.5 5891.6 5961.4 5969.2 5982.7 6053.5 4228.4 4239.8 4240.7 4242.3 4292.1 4216.7 4265.7 4326.8 4332.6 4333.8 4875.3 4876.9 4878.1 4896.4 4927.2 5785.5 5807.0 5829.2 5830.2 5841.1 1426.3 1462.4 1480.4 1486.9 1491.6 951.0 956.7 957.5 959.3 1000.1 5592.2 5620.4 5630.3 5633.8 5636.3 5867.2 5894.7 5895.5 5898.7 5928.3 5487.2 5525.6 5552.9 5578.2 5661.7 3084.4 3107.1 3116.6 3139.2 3150.0 5862.6 5889.8 5991.8 6087.2 6113.3 5934.6 5940.3 5941.8 5965.3 5967.4 6023.3 6024.8 6026.4 6040.1 6053.6 5552.5 5872.4 5909.9 5957.4 5962.6 5875.8 5878.5 5885.9 5887.4 5892.0 5823.5 6062.9 6082.6 6163.7 6190.9 4244.4 4258.4 4269.4 4276.4 4298.3 6062.7 6063.8 6070.2 6072.5 6072.6 5688.4 5788.8 5808.8 5818.7 5934.6 5748.9 5768.2 5778.7 5800.9 5843.8 5408.5 5700.9 5745.0 5798.2 5802.3 5686.2 5780.2 5798.9 5800.2 5808.8 5653.7 5654.0 5663.4 5692.3 5692.9 15:57:58 INFO - PROCESS | 11790 | 15:57:58 INFO - PROCESS | 11790 | [#5] yui.html Cycles:180 Average:21255.15 Median:4061.46 stddev:30112.71 (741.4%) stddev-sans-first:30158.04 15:57:58 INFO - PROCESS | 11790 | Values: 806.1 818.2 824.6 889.5 908.1 14599.9 15551.2 15555.9 15593.1 15626.8 14962.8 15089.7 15127.2 15140.0 15155.8 740.4 866.1 867.2 889.9 894.2 891.2 893.0 898.0 900.4 900.7 937.9 940.7 941.8 944.5 945.4 940.9 941.8 943.4 943.6 943.7 691.8 693.5 694.0 694.8 695.8 15219.2 15242.5 15273.0 15290.0 15292.8 15396.3 15812.0 15995.8 16037.4 16037.4 13799.8 13920.3 13950.7 14040.6 14220.2 3962.4 3985.2 4059.9 4063.0 4073.1 83422.0 86129.0 86260.0 86286.6 87294.1 13026.9 13039.3 13239.1 13286.5 13324.5 25018.7 25104.2 25118.6 25133.2 25163.4 8270.5 8468.7 8505.3 8523.3 8626.6 4884.0 4915.5 4987.8 4998.1 5026.7 3804.6 3919.6 3923.8 3924.4 3930.2 658.8 673.3 674.1 678.2 683.3 647.1 657.7 714.3 736.6 747.7 9796.2 10012.7 10043.6 10133.3 10171.8 738.5 745.4 757.0 762.9 779.1 4791.9 4845.9 4917.9 5071.4 5087.7 82257.0 83416.6 104234.0 104864.5 108283.0 99041.5 100371.5 105759.0 108835.5 111068.4 91182.5 91648.5 91990.5 93260.5 94091.0 9612.2 9693.6 9750.9 9813.9 10008.3 38907.6 47805.5 49479.3 51457.0 52912.0 68950.3 81759.6 82859.0 83063.0 85376.6 12153.6 13319.1 13537.9 13684.0 14125.4 12642.2 13450.0 14148.4 14636.0 14759.6 11066.2 11841.9 11957.9 11999.3 12089.4 12921.4 13421.2 13531.5 14396.0 14432.1 13085.9 13191.1 13555.5 13598.5 13721.8 10869.1 11436.9 11530.7 11589.2 11633.7 11454.3 11475.3 11895.5 11988.3 11997.6 15:57:58 INFO - PROCESS | 11790 | -------- Summary: end -------- 15:57:58 INFO - PROCESS | 11790 | 15:57:59 INFO - TEST-INFO | 11790: exit 0 15:57:59 INFO - TEST-OK | dromaeo_css | took 1112612ms 15:57:59 INFO - TEST-START | kraken 15:57:59 INFO - operating with platform_type : linux_ 15:57:59 INFO - Initialising browser for kraken test... 15:57:59 INFO - TEST-INFO | started process 11980 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpw0Tpzk/profile http://localhost:46599/getInfo.html) 15:58:02 INFO - PROCESS | 11980 | __metrics Screen width/height:1600/1200 15:58:02 INFO - PROCESS | 11980 | colorDepth:24 15:58:02 INFO - PROCESS | 11980 | Browser inner width/height: 1024/697 15:58:02 INFO - PROCESS | 11980 | __metrics 15:58:03 INFO - PROCESS | 11980 | 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:58:03 INFO - PROCESS | 11980 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:58:04 INFO - TEST-INFO | 11980: exit 0 15:58:04 INFO - Browser initialized. 15:58:04 INFO - Running cycle 1/1 for kraken test... 15:58:04 INFO - TEST-INFO | started process 12066 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpw0Tpzk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:58:16 INFO - PROCESS | 12066 | RSS: Main: 143302656 15:58:16 INFO - PROCESS | 12066 | 15:58:18 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:46599/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46599/tests/kraken/driver.html?testName=audio-beat-detection) 15:58:19 INFO - PROCESS | 12066 | RSS: Main: 198418432 15:58:19 INFO - PROCESS | 12066 | 15:58:22 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:46599/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46599/tests/kraken/driver.html?testName=audio-dft) 15:58:22 INFO - PROCESS | 12066 | RSS: Main: 183185408 15:58:22 INFO - PROCESS | 12066 | 15:58:25 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:46599/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46599/tests/kraken/driver.html?testName=audio-fft) 15:58:26 INFO - PROCESS | 12066 | RSS: Main: 273752064 15:58:26 INFO - PROCESS | 12066 | 15:58:28 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:46599/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46599/tests/kraken/driver.html?testName=audio-oscillator) 15:58:28 INFO - PROCESS | 12066 | RSS: Main: 179793920 15:58:28 INFO - PROCESS | 12066 | 15:58:31 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:46599/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46599/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:58:31 INFO - PROCESS | 12066 | RSS: Main: 178565120 15:58:31 INFO - PROCESS | 12066 | 15:58:34 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:46599/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46599/tests/kraken/driver.html?testName=imaging-darkroom) 15:58:35 INFO - PROCESS | 12066 | RSS: Main: 214597632 15:58:35 INFO - PROCESS | 12066 | 15:58:38 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:46599/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46599/tests/kraken/driver.html?testName=imaging-desaturate) 15:58:39 INFO - PROCESS | 12066 | RSS: Main: 213098496 15:58:39 INFO - PROCESS | 12066 | 15:58:41 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:46599/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46599/tests/kraken/driver.html?testName=json-parse-financial) 15:58:42 INFO - PROCESS | 12066 | RSS: Main: 213426176 15:58:42 INFO - PROCESS | 12066 | 15:58:44 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:46599/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46599/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:58:44 INFO - PROCESS | 12066 | RSS: Main: 176095232 15:58:44 INFO - PROCESS | 12066 | 15:58:46 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:46599/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46599/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:58:46 INFO - PROCESS | 12066 | RSS: Main: 183218176 15:58:46 INFO - PROCESS | 12066 | 15:58:48 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:46599/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46599/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:58:49 INFO - PROCESS | 12066 | RSS: Main: 185593856 15:58:49 INFO - PROCESS | 12066 | 15:58:52 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:46599/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46599/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:58:52 INFO - PROCESS | 12066 | RSS: Main: 185008128 15:58:52 INFO - PROCESS | 12066 | 15:58:55 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:46599/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46599/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:58:55 INFO - PROCESS | 12066 | RSS: Main: 182804480 15:58:55 INFO - PROCESS | 12066 | 15:58:57 INFO - PROCESS | 12066 | Cycle 1(1): loaded http://localhost:46599/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46599/tests/kraken/driver.html?testName=ai-astar) 15:58:57 INFO - PROCESS | 12066 | RSS: Main: 234762240 15:58:57 INFO - PROCESS | 12066 | 15:58:57 INFO - PROCESS | 12066 | __start_tp_report 15:58:57 INFO - PROCESS | 12066 | _x_x_mozilla_page_load 15:58:57 INFO - PROCESS | 12066 | _x_x_mozilla_page_load_details 15:58:57 INFO - PROCESS | 12066 | |i|pagename|runs| 15:58:57 INFO - PROCESS | 12066 | |0;ai-astar;113;112;121;113;112;112;112;112;112;112 15:58:57 INFO - PROCESS | 12066 | |1;audio-beat-detection;142;117;142;174;115;138;141;121;176;115 15:58:57 INFO - PROCESS | 12066 | |2;audio-dft;161;161;161;159;163;160;160;160;165;161 15:58:57 INFO - PROCESS | 12066 | |3;audio-fft;74;74;75;174;75;75;75;74;173;76 15:58:57 INFO - PROCESS | 12066 | |4;audio-oscillator;83;84;158;84;83;83;144;83;82;140 15:58:57 INFO - PROCESS | 12066 | |5;imaging-gaussian-blur;117;116;117;116;116;117;117;117;117;117 15:58:57 INFO - PROCESS | 12066 | |6;imaging-darkroom;146;145;145;147;146;146;146;146;146;145 15:58:57 INFO - PROCESS | 12066 | |7;imaging-desaturate;94;95;95;94;95;95;94;95;94;94 15:58:57 INFO - PROCESS | 12066 | |8;json-parse-financial;54;53;52;54;54;57;55;54;54;54 15:58:57 INFO - PROCESS | 12066 | |9;json-stringify-tinderbox;58;58;59;59;59;59;58;58;59;58 15:58:57 INFO - PROCESS | 12066 | |10;stanford-crypto-aes;73;73;73;82;76;81;71;75;73;72 15:58:57 INFO - PROCESS | 12066 | |11;stanford-crypto-ccm;121;117;117;124;119;124;124;115;149;119 15:58:57 INFO - PROCESS | 12066 | |12;stanford-crypto-pbkdf2;155;150;151;152;156;152;154;153;152;151 15:58:57 INFO - PROCESS | 12066 | |13;stanford-crypto-sha256-iterative;63;64;62;60;62;60;61;61;60;60 15:58:57 INFO - PROCESS | 12066 | __end_tp_report 15:58:57 INFO - PROCESS | 12066 | __start_cc_report 15:58:57 INFO - PROCESS | 12066 | _x_x_mozilla_cycle_collect,1648 15:58:57 INFO - PROCESS | 12066 | __end_cc_report 15:58:57 INFO - PROCESS | 12066 | __startTimestamp1474325937732__endTimestamp 15:58:57 INFO - PROCESS | 12066 | 15:58:57 INFO - PROCESS | 12066 | ------- Summary: start ------- 15:58:57 INFO - PROCESS | 12066 | Number of tests: 14 15:58:57 INFO - PROCESS | 12066 | 15:58:57 INFO - PROCESS | 12066 | [#0] ai-astar Cycles:10 Average:113.10 Median:112.00 stddev:2.81 (2.5%) stddev-sans-first:2.98 15:58:57 INFO - PROCESS | 12066 | Values: 113.0 112.0 121.0 113.0 112.0 112.0 112.0 112.0 112.0 112.0 15:58:57 INFO - PROCESS | 12066 | 15:58:57 INFO - PROCESS | 12066 | [#1] audio-beat-detection Cycles:10 Average:138.10 Median:141.50 stddev:22.53 (15.9%) stddev-sans-first:23.85 15:58:57 INFO - PROCESS | 12066 | Values: 142.0 117.0 142.0 174.0 115.0 138.0 141.0 121.0 176.0 115.0 15:58:57 INFO - PROCESS | 12066 | 15:58:57 INFO - PROCESS | 12066 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.00 stddev:1.73 (1.1%) stddev-sans-first:1.83 15:58:57 INFO - PROCESS | 12066 | Values: 161.0 161.0 161.0 159.0 163.0 160.0 160.0 160.0 165.0 161.0 15:58:57 INFO - PROCESS | 12066 | 15:58:57 INFO - PROCESS | 12066 | [#3] audio-fft Cycles:10 Average:94.50 Median:75.00 stddev:41.64 (55.5%) stddev-sans-first:43.50 15:58:57 INFO - PROCESS | 12066 | Values: 74.0 74.0 75.0 174.0 75.0 75.0 75.0 74.0 173.0 76.0 15:58:57 INFO - PROCESS | 12066 | 15:58:57 INFO - PROCESS | 12066 | [#4] audio-oscillator Cycles:10 Average:102.40 Median:83.00 stddev:31.33 (37.7%) stddev-sans-first:32.43 15:58:57 INFO - PROCESS | 12066 | Values: 83.0 84.0 158.0 84.0 83.0 83.0 144.0 83.0 82.0 140.0 15:58:57 INFO - PROCESS | 12066 | 15:58:57 INFO - PROCESS | 12066 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 15:58:57 INFO - PROCESS | 12066 | Values: 117.0 116.0 117.0 116.0 116.0 117.0 117.0 117.0 117.0 117.0 15:58:57 INFO - PROCESS | 12066 | 15:58:57 INFO - PROCESS | 12066 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.63 (0.4%) stddev-sans-first:0.67 15:58:57 INFO - PROCESS | 12066 | Values: 146.0 145.0 145.0 147.0 146.0 146.0 146.0 146.0 146.0 145.0 15:58:57 INFO - PROCESS | 12066 | 15:58:57 INFO - PROCESS | 12066 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 15:58:57 INFO - PROCESS | 12066 | Values: 94.0 95.0 95.0 94.0 95.0 95.0 94.0 95.0 94.0 94.0 15:58:57 INFO - PROCESS | 12066 | 15:58:57 INFO - PROCESS | 12066 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.00 stddev:1.29 (2.4%) stddev-sans-first:1.36 15:58:57 INFO - PROCESS | 12066 | Values: 54.0 53.0 52.0 54.0 54.0 57.0 55.0 54.0 54.0 54.0 15:58:57 INFO - PROCESS | 12066 | 15:58:57 INFO - PROCESS | 12066 | [#9] json-stringify-tinderbox Cycles:10 Average:58.50 Median:59.00 stddev:0.53 (0.9%) stddev-sans-first:0.53 15:58:57 INFO - PROCESS | 12066 | Values: 58.0 58.0 59.0 59.0 59.0 59.0 58.0 58.0 59.0 58.0 15:58:57 INFO - PROCESS | 12066 | 15:58:57 INFO - PROCESS | 12066 | [#10] stanford-crypto-aes Cycles:10 Average:74.90 Median:74.00 stddev:3.75 (5.1%) stddev-sans-first:3.92 15:58:57 INFO - PROCESS | 12066 | Values: 73.0 73.0 73.0 82.0 76.0 81.0 71.0 75.0 73.0 72.0 15:58:57 INFO - PROCESS | 12066 | 15:58:57 INFO - PROCESS | 12066 | [#11] stanford-crypto-ccm Cycles:10 Average:122.90 Median:122.50 stddev:9.72 (7.9%) stddev-sans-first:10.29 15:58:57 INFO - PROCESS | 12066 | Values: 121.0 117.0 117.0 124.0 119.0 124.0 124.0 115.0 149.0 119.0 15:58:57 INFO - PROCESS | 12066 | 15:58:57 INFO - PROCESS | 12066 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.60 Median:152.50 stddev:1.90 (1.2%) stddev-sans-first:1.80 15:58:57 INFO - PROCESS | 12066 | Values: 155.0 150.0 151.0 152.0 156.0 152.0 154.0 153.0 152.0 151.0 15:58:57 INFO - PROCESS | 12066 | 15:58:57 INFO - PROCESS | 12066 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.30 Median:61.50 stddev:1.42 (2.3%) stddev-sans-first:1.36 15:58:57 INFO - PROCESS | 12066 | Values: 63.0 64.0 62.0 60.0 62.0 60.0 61.0 61.0 60.0 60.0 15:58:57 INFO - PROCESS | 12066 | -------- Summary: end -------- 15:58:57 INFO - PROCESS | 12066 | 15:58:58 INFO - TEST-INFO | 12066: exit 0 15:58:58 INFO - TEST-OK | kraken | took 59544ms 15:58:58 INFO - SUITE-END | took 1172s 15:58:58 INFO - Completed test suite (00:19:32) 15:58:58 INFO - javascript benchmark 15:58:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3245.6267529234137, 3268.0522648431956, 3273.754468414868, 3294.2587917718533, 3326.1352048467393, 5560.499555040045, 5612.326520817497, 5640.774369025245, 5670.404607516208, 5672.205891175238, 5592.4407559244055, 5717.514011309044, 5721.513671337942, 5724.570657200717, 5736.45503677151, 5044.949550504503, 5201.349831271091, 5204.557612602934, 5250.133727934886, 5283.577313814905, 5097.0, 5190.325257716491, 5232.084385232588, 5243.895122097556, 5252.6323157379, 5088.669236499634, 5095.286659867622, 5122.743862806863, 5129.2562578426105, 5163.122269214224, 5210.921836172461, 5223.555997740159, 5224.92162617561, 5232.084385232569, 5261.8947621047555, 5008.198688209869, 5070.08738427083, 5113.923291150636, 5119.436861945183, 5136.897262054776, 5542.528885044776, 5572.944270557283, 5585.944140558624, 5591.412901645309, 5611.8877622447735, 6055.909161362583, 6060.393960603904, 6069.817905462844, 6071.757129714806, 6073.089036644495, 6022.367651396584, 6085.2393450818645, 6086.9391306087255, 6098.481629061535, 6118.663473508956, 5705.343885453184, 5723.055695810144, 5725.456081672234, 5748.080307150836, 5763.596548241625, 6038.033914573735, 6228.4705800007505, 6259.0924315973925, 6264.59280146789, 6269.467095296951, 5856.385079566669, 5891.351951285355, 5910.261217347832, 5951.166836642873, 5985.610935289193, 6023.247094113236, 6030.03519436888, 6031.035034394564, 6031.185789918305, 6032.125341825411, 5882.617629854046, 6005.579609427298, 6009.429104235091, 6012.609180403259, 6014.18808460861, 5924.081767325985, 6001.789937352171, 6012.699365031731, 6045.0, 6047.395260473917, 4638.420197475316, 4641.721496710208, 4644.256918892961, 4645.094206629742, 4675.976620116878, 4054.2094291613444, 4169.061961058737, 4180.352045432902, 4194.475690538683, 4197.349410841324, 5743.425657434307, 5773.826785196451, 5778.855528611818, 5819.854503637358, 5863.736131874076, 6064.727087281083, 6074.0, 6095.969520152371, 6098.939010609837, 6100.176476175749, 5659.490645841894, 5754.251947246831, 5759.539236861041, 5761.337446193657, 5762.135679648002, 5220.399654039834, 5244.554212891947, 5268.736563171826, 5269.815556455505, 5315.04329220744, 6247.687615619201, 6258.561900666909, 6263.498920086473, 6263.843403914848, 6264.968675156595, 5939.554533410098, 6035.0, 6072.14989901423, 6072.8785424292155, 6077.4226448487325, 6128.080787881853, 6191.040388739752, 6192.164057852313, 6251.968740156452, 6304.527160463075, 5956.136360227656, 6011.819645410645, 6015.157877897185, 6019.247594050744, 6050.2437195350585, 6001.5798894077, 6126.908096378469, 6130.417610327012, 6131.110988906673, 6131.478824299985, 5905.173275741314, 5959.4934430573885, 5963.821085367446, 6000.399960003964, 6032.517398608188, 5985.820425387245, 5986.4013598639785, 5988.5508586855285, 5989.221401217814, 5992.820215393545, 5755.107958266475, 5893.6463812171405, 5900.911486327795, 5930.05119180929, 5962.046072628532, 5951.613145145551, 6003.579749417673, 6004.489618382487, 6042.788502402394, 6080.331163572092, 5825.184474173534, 5858.384869588802, 5866.941330586639, 5876.941230587639, 5898.0], "value": 5597.197945507776, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.3239487970914, 640.2924768131215, 664.5281849886582, 672.6098862659654, 677.8881484555048, 35812.0, 47271.527284727184, 47748.04503909924, 47833.28250076243, 47957.520424795744, 49099.0, 49538.50461495384, 49550.0, 49576.256356154685, 49646.0, 8471.279941205004, 9177.632894684204, 9299.674511392102, 9352.438853668802, 10920.945395273013, 10734.194935379877, 11162.888371116307, 11172.720681982975, 11177.273477223995, 11197.0, 11276.548938042468, 11308.604198853041, 11313.490892909838, 11317.83023254652, 11321.603743868965, 11197.608083717072, 11230.438478076063, 11248.325100493956, 11254.49354779037, 11260.605878794202, 11096.500657470433, 11134.220604557684, 11160.107191424666, 11168.66494005181, 11173.608923687712, 46145.76927115377, 46588.53411465876, 46663.533364666255, 46673.0, 46693.06613867738, 3631.981840090809, 3768.4535742317485, 3840.6543411093135, 4127.545969943304, 4444.910996805803, 34204.82897585521, 34374.18968784268, 34420.0, 35212.64787352119, 35450.0, 4600.378948841897, 4628.544352726974, 4636.5131661175765, 4665.626749860037, 4738.95261047392, 390757.0, 390844.0, 390871.0, 391730.0, 392718.0, 60400.0, 60524.39475605276, 60526.39473605296, 60555.88054418162, 60781.08828367578, 39532.407013894815, 40223.597764021986, 40230.798846005586, 42324.57675423268, 65553.0, 18800.717989230172, 19118.52203694891, 19167.808321916604, 19169.137388817537, 19177.0, 12946.223226606433, 13801.309934503435, 14656.193909335088, 14960.775588366183, 14973.625659358604, 20191.69712454285, 20493.180272788923, 20550.38348849507, 20714.378568642664, 20732.79267207339, 7149.678264478111, 7177.497575169792, 7202.747903823341, 7388.926110738824, 7454.813629659194, 1039.9948000259951, 1073.2058276875057, 1123.4214379594516, 1124.1208479651307, 1173.6420391780418, 33666.663333366356, 34294.792235515764, 34391.312173756385, 34470.31059378798, 34605.30789384249, 1153.7346410325672, 1175.5364571109023, 1191.6782468733568, 1198.5385626533673, 1215.0583297944163, 17812.376566820098, 18192.81807181911, 18212.180451879696, 18219.36232231892, 18250.452486425427, 627704.0, 629958.0, 634760.0, 634817.0, 638051.0, 63621.0, 63840.36159638437, 63918.0, 63932.360676392644, 64247.678761605894, 1347.036868638928, 1425.7504936636255, 1453.6147920800997, 1467.743144949651, 1486.2642991719167, 32851.34297313992, 35282.29435411226, 35438.29123417569, 35607.28785424329, 35730.464043039894, 54739.178912315554, 55203.72398137984, 55272.44727552673, 55307.0, 55375.0, 463161.0, 465329.0, 465768.0, 469070.0, 470029.0, 27290.0, 29925.0, 30771.0, 30844.38311233718, 32400.35199296048, 51598.0, 51987.22019169791, 52121.0, 52503.0, 52541.211881822564, 43542.0, 44438.11579431262, 44827.775861120484, 45370.546294537955, 46039.53960460486, 43000.0, 43945.78027109972, 44638.77680611576, 45306.0, 45511.0, 42688.14623707571, 43900.341494876964, 44503.0, 44743.776281118386, 44812.551874480836, 36362.45456318105, 38993.41509877411, 39266.0, 39462.80268598639, 39695.0, 29056.0, 29070.546472676706, 29171.41657166802, 29189.41621167607, 29386.265343366587], "value": 21937.284426801576, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.8833247877856, 420.52690722936694, 432.6927881204345, 432.96536277097823, 439.11956527163034, 18975.71536426953, 20133.99330033498, 20600.690989635168, 21199.894000530014, 21485.67771483425, 20068.59862802743, 20655.586888262264, 20807.583848323025, 20841.895790521063, 20955.37133885982, 5647.237622920894, 5943.583949123563, 5956.136360227764, 5959.374265702104, 5962.821115366524, 5678.914816277759, 5892.351841297454, 5899.498542623882, 5920.052791553352, 6006.189164462785, 5968.940310596903, 5970.074638431029, 5975.193348897887, 5977.88044239115, 5982.730777115018, 5717.228174196472, 5924.0521516557355, 5967.761289548413, 6063.242094738158, 6074.665893375863, 5236.319278493809, 5483.890322193533, 5896.292444906596, 5896.3219229788265, 5909.0, 14620.853791462056, 14684.926575367163, 14696.779548306784, 14840.703185936221, 14844.480443184437, 2641.3660721426913, 2857.8142420742583, 2978.1065680295824, 2994.5807586937844, 3051.801632893877, 10320.174386049099, 10552.947235263851, 10795.892041079645, 10873.184511161695, 10963.177761667906, 1920.3470819921154, 1954.8631595788158, 1967.567135230247, 1976.9901150494156, 2012.6276638821864, 84194.0, 90003.0, 91027.0, 91610.54194728984, 91858.54070729604, 11930.0, 12017.699557511132, 12217.69455763613, 12310.569130080583, 12414.441350139265, 17179.31282748701, 17200.483985480456, 17213.31146754116, 17216.655666886843, 17226.483205503853, 8001.479903806234, 8229.300509456763, 8649.0, 8695.347848911357, 8923.910760892439, 9220.262379009662, 9231.95384023089, 9238.353315268001, 9442.763930901783, 9454.0, 4955.695601545299, 4961.677490963076, 5158.303629010111, 5303.151495760737, 5310.893782124336, 2063.13348393675, 2092.6337890869036, 2099.1451625788914, 2168.436206586299, 2193.7257842769654, 962.932594718377, 965.7971825916636, 1024.7694268789612, 1068.8735448812192, 1081.118888106191, 19256.614867702847, 20713.067911944, 20905.89547052255, 21064.368068957956, 21115.26096586612, 1033.6973370082665, 1054.752133248686, 1072.9356238625708, 1079.2391364088298, 1111.1610733895857, 10569.947150264354, 11595.76808463826, 11640.592579259684, 11663.35851528157, 11676.299422034535, 562031.0, 573506.0, 585898.0, 597769.0, 603153.0, 100090.4995475018, 104449.0, 104988.47505762575, 105583.47208264063, 106320.0, 1004.6333088422673, 1032.8347464405808, 1055.1927775251938, 1055.2771351624162, 1056.0759335581367, 17736.733948990517, 17788.3774067907, 18083.0, 18547.536311592317, 18760.718589220898, 46747.29879051885, 47275.290870637655, 47381.76309118432, 49726.502734973634, 49886.750566246934, 61277.0, 62149.0, 62566.0, 62824.057639136365, 64127.679361602895, 8294.257897873984, 8701.956490217508, 8921.06328835463, 8923.73228803137, 8939.329550283624, 11790.646280611596, 12899.806502902651, 13332.40004199794, 13505.459781609019, 13652.180869147884, 10320.43237621938, 11619.767604648028, 11815.291082535075, 11940.880591194324, 12303.44634491432, 11336.829947550958, 11923.701907452383, 12142.635720928387, 12205.328706921204, 12388.256704597752, 11707.121965852766, 12008.579699710468, 12020.158588898694, 12376.319302438453, 12378.62864114078, 8656.134386561294, 8708.17272359125, 8782.912170878464, 8841.425307355, 8905.599248033725, 11239.325640461599, 11292.322460652387, 12078.698032549257, 12432.75134497323, 12507.0], "value": 9701.713493705447, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1386.6810633554276, 1531.8008658874344, 1575.306864979409, 1734.2889415339705, 1825.1513046433415, 8320.750377488677, 9183.0, 9467.2899532535, 9473.90526094739, 9768.804623907517, 9454.33819632626, 9455.1017653323, 9466.100720431565, 9482.288828337885, 9528.618855245782, 2496.2885577610386, 2544.122277814151, 2546.936326591841, 2549.069589599792, 2550.2221822344272, 2446.6819313489223, 2525.8863351149243, 2531.721510633829, 2532.7593878581597, 2535.290118766748, 2521.34445044288, 2538.0862889359814, 2549.413634863982, 2550.349660836484, 2557.7186509484036, 2436.281297017384, 2440.8413453125577, 2447.0578827151585, 2479.47930934504, 2484.9875750621313, 2407.915722949688, 2413.3122060212754, 2422.140140250221, 2423.2245681381964, 2460.0405841721745, 9313.860292095624, 9537.904620953841, 9538.90461095387, 9607.519624018842, 9622.422654640744, 5558.555315574785, 5718.285214348207, 5727.856803579903, 5733.770649174028, 5734.311882574076, 3582.8925132246072, 3623.655752703489, 3645.927081458356, 3654.8538058477893, 3656.4149736042373, 1259.615817175761, 1268.8413948256468, 1269.092598791859, 1290.0324756432676, 1291.819145319656, 13161.210327380388, 13176.60470185896, 13255.0, 13348.666283342995, 13369.33153342329, 4773.689710168828, 4831.202851529459, 4843.903121937541, 4891.951080489221, 4962.15643340633, 10484.63303784364, 10486.423246721504, 10500.73748156287, 10515.631952881766, 10523.789524209473, 5584.46947539983, 5588.524975377139, 5592.02139625572, 5594.496495315441, 5598.1322894951345, 4695.530446955346, 5033.169527028, 5141.0, 5191.117510023229, 5236.738163091906, 4156.0, 4185.372194170899, 4256.297710877732, 4294.26997410441, 4317.481902171759, 1219.841420615332, 1221.5663439479035, 1237.1154624443566, 1238.6346027921784, 1252.136026141959, 875.7766769473743, 980.5244456438764, 1032.3135115148343, 1060.506864308085, 1063.3301020357126, 6461.127747754087, 6504.577202481823, 6561.835954101186, 6592.373724496165, 6819.7272109116075, 1008.1833714691123, 1020.4591566469783, 1036.259074761549, 1037.0821822686826, 1062.984055239172, 4353.629941455012, 4399.274119770202, 4428.579284967923, 4507.797149128297, 4515.119551687455, 11805.586804461802, 12098.455569499394, 12145.14983951115, 12147.574834880914, 12298.692532686755, 11194.328340299446, 12036.518539258506, 12064.0, 12075.335856527976, 12124.090693198046, 8864.0, 9091.0, 9111.179993800457, 9111.817763644822, 9117.133872282122, 6246.219222597176, 6268.937310626835, 6404.615723056631, 6511.120998665119, 6631.801045968628, 11035.83446248323, 11049.558017679525, 11051.72370690707, 11182.776344473228, 11191.832122518332, 10183.236257280585, 10409.84385234207, 10444.947775261075, 10479.68560943173, 10506.212034097625, 7742.496737712031, 8295.58522073906, 8340.207680270607, 8484.363672724447, 8561.785955351166, 9394.906050939404, 9574.186194173573, 9583.952080239555, 9591.328606997444, 9602.519874006131, 8477.872831907403, 8576.356773242156, 8578.699745508877, 8646.697365592174, 8660.826783464421, 7737.11023232313, 8082.4746391486715, 8087.716929907425, 8229.629666665134, 8336.458130221516, 7328.706851725871, 7476.962615186889, 7545.434092443198, 7592.316691497792, 7699.615019248903, 5406.324209473816, 5414.377346605191, 5461.317335333083, 5541.113421852484, 5626.718664066862, 5449.918251226314, 5563.944360556343, 5571.66570005801, 5593.454977985089, 5622.353429355677], "value": 4981.738555976059, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.76862848948457, 340.553874424504, 342.7241070937894, 342.7686311739576, 347.15467835819766, 5504.724763761816, 5595.692236926968, 5598.417013111602, 5616.5787565932615, 5641.435856414357, 5442.782288708447, 5477.643953143043, 5478.58910581707, 5547.389787123413, 5559.221708960748, 4153.190127925056, 4263.850765223217, 4374.934375984347, 4406.625436837857, 4420.911581768362, 4345.174416860802, 4345.8478953236645, 4353.717008394444, 4381.058072514409, 4428.424304840382, 4319.740815551061, 4331.7400955942585, 4334.761588112652, 4340.804663790122, 4387.3857659927635, 4189.706720529564, 4315.762633055181, 4320.243957307459, 4349.195398851191, 4356.956430435686, 3867.2265546890735, 3892.08535994044, 3895.454636350913, 3907.6873850091924, 3916.8824935251705, 5150.459201783796, 5348.8127915523155, 5390.6226564140525, 5392.514673679388, 5455.672659640394, 4880.974995251002, 4910.7299098549565, 4921.507849215086, 4923.212286034253, 4926.842192084857, 5706.229658996024, 5709.714514274311, 5724.940885936116, 5730.512906402961, 5739.827805165852, 4077.5922407759285, 4139.627433530996, 4149.751014939083, 4167.020750123718, 4170.541240463546, 5891.643342662969, 5961.403859614047, 5969.164316995624, 5982.700864956735, 6053.545984051214, 4228.386883901817, 4239.788010599457, 4240.724352917051, 4242.342436922282, 4292.141571685675, 4216.725912815718, 4265.658747300208, 4326.762028088422, 4332.588404101605, 4333.761643109596, 4875.317455556225, 4876.878078048077, 4878.07316609843, 4896.36347274852, 4927.211646136672, 5785.50823180026, 5806.970965145148, 5829.242198514166, 5830.154627578977, 5841.06542953134, 1426.3367534096694, 1462.3638717158071, 1480.3856399594047, 1486.851314868526, 1491.6494623763406, 951.039450155348, 956.679512363366, 957.5116690487902, 959.3092973059386, 1000.1448761308976, 5592.217089607459, 5620.381758006616, 5630.324361076697, 5633.774649014075, 5636.2954630671165, 5867.237259156283, 5894.734736936848, 5895.469407753237, 5898.731772668897, 5928.258967629046, 5487.204355368529, 5525.585581081435, 5552.889901837357, 5578.163275508706, 5661.745221465044, 3084.4293805646025, 3107.145534977854, 3116.626004879384, 3139.246580820632, 3149.9527507087414, 5862.560307976919, 5889.793857214977, 5991.760329586855, 6087.173847845628, 6113.327533971348, 5934.6142500737315, 5940.287165540163, 5941.821745347646, 5965.343812180657, 5967.403259673998, 6023.307319658208, 6024.789132380433, 6026.367231440723, 6040.073951931192, 6053.606515576474, 5552.52803511706, 5872.412758724094, 5909.881802364014, 5957.3744756799915, 5962.582619216613, 5875.764969401176, 5878.529717622666, 5885.911711324248, 5887.411258874078, 5892.0, 5823.505002074871, 6062.909056364247, 6082.60463069899, 6163.691815409301, 6190.876182476415, 4244.427002354642, 4258.403823464655, 4269.4449721536, 4276.422682937888, 4298.312270036779, 6062.6665533394325, 6063.848403789941, 6070.150178974859, 6072.514198863992, 6072.597366908955, 5688.431156884444, 5788.84223155379, 5808.767649293981, 5818.679972601548, 5934.643921364732, 5748.913766293593, 5768.192453056491, 5778.682172480554, 5800.941990580209, 5843.824685259449, 5408.45915408456, 5700.857478563069, 5745.0, 5798.217240672455, 5802.303723553201, 5686.2323586315315, 5780.248567686174, 5798.884022319615, 5800.187973683604, 5808.796692115756, 5653.660780353028, 5653.971730141323, 5663.377028526941, 5692.31692196939, 5692.914606280826], "value": 4416.309027997719, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [806.073016031564, 818.2022528035166, 824.5588610093597, 889.4574309671101, 908.0964440381821, 14599.854001459982, 15551.222438878054, 15555.922220388882, 15593.06441613504, 15626.76559851603, 14962.775558366633, 15089.698206035873, 15127.243637818092, 15140.0, 15155.77266341003, 740.4150720930472, 866.1035827918106, 867.154524338771, 889.9199072083511, 894.1550235027901, 891.2112780189547, 892.9642814287453, 897.9685711000117, 900.4462255712755, 900.7342833863993, 937.8874535055803, 940.7412961435591, 941.7833898203418, 944.5418971798672, 945.4327403557845, 940.920021798148, 941.7786820097272, 943.4433684126365, 943.5848226780263, 943.7168849345169, 691.8235849858304, 693.4902846407887, 693.9895901561481, 694.7672529702555, 695.8156088636515, 15219.239038048054, 15242.542723718307, 15273.0, 15290.0, 15292.770608440882, 15396.307166177548, 15812.0, 15995.780632706077, 16037.438689645924, 16037.438689645924, 13799.793003104862, 13920.30398480072, 13950.720985580232, 14040.648983775385, 14220.217888016155, 3962.363041904827, 3985.20295940813, 4059.939100913489, 4062.979685101556, 4073.06319546506, 83422.0, 86129.0, 86260.0, 86286.56856715676, 87294.12705872924, 13026.869731302755, 13039.348032598333, 13239.139455935334, 13286.534971276153, 13324.467021319233, 25018.749812502007, 25104.246872593852, 25118.623220651705, 25133.24600261995, 25163.370915727253, 8270.503769773833, 8468.745937621758, 8505.277051450696, 8523.275521580612, 8626.611802468904, 4883.975580122076, 4915.5330243626795, 4987.800487980513, 4998.075356059068, 5026.698398096126, 3804.581496035489, 3919.627635374635, 3923.843046278117, 3924.372100463969, 3930.1746633206776, 658.7793089315134, 673.3468535520537, 674.1101745695732, 678.1624693503519, 683.2620769568854, 647.0649910878735, 657.7369052378991, 714.2821464428216, 736.5580651608992, 747.7345542332503, 9796.216302695768, 10012.749681258027, 10043.598256069676, 10133.304108715567, 10171.796564068678, 738.4867517075688, 745.4260219630962, 756.9924300757032, 762.8741257692419, 779.1429427629562, 4791.928121078186, 4845.927311090337, 4917.901641967141, 5071.39143302806, 5087.745612719349, 82257.0, 83416.58291708502, 104234.0, 104864.47567762113, 108283.0, 99041.50479247702, 100371.49814250882, 105759.0, 108835.45582272197, 111068.4446577762, 91182.54408728046, 91648.54175729211, 91990.54004729934, 93260.53369733243, 94091.0, 9612.231021518122, 9693.6122555097, 9750.85373719409, 9813.9018609813, 10008.349457285252, 38907.610923890395, 47805.521944781496, 49479.25781113358, 51457.0, 52912.0, 68950.3104968944, 81759.591202046, 82859.0, 83063.0, 85376.57311713402, 12153.635390938285, 13319.134256273312, 13537.932310338385, 13684.0, 14125.364358603681, 12642.2414655121, 13450.0, 14148.363323650667, 14636.0, 14759.631009224855, 11066.225364224427, 11841.940790295994, 11957.940210298893, 11999.280043197436, 12089.395530223277, 12921.418536166084, 13421.194728316332, 13531.526396576073, 14396.0, 14432.134071955716, 13085.869141308465, 13191.076624636184, 13555.457781688621, 13598.524051658145, 13721.794173087612, 10869.130469562575, 11436.94281528587, 11530.654080377602, 11589.24669896454, 11633.709157271136, 11454.312741235553, 11475.254108482923, 11895.464704088165, 11988.280703157838, 11997.64007079789], "value": 7896.82505093171, "unit": "score"}], "name": "dromaeo_css", "value": 7691.123567678846, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 121.0, 113.0, 112.0, 112.0, 112.0, 112.0, 112.0, 112.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 117.0, 142.0, 174.0, 115.0, 138.0, 141.0, 121.0, 176.0, 115.0], "value": 138.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 161.0, 159.0, 163.0, 160.0, 160.0, 160.0, 165.0, 161.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 75.0, 174.0, 75.0, 75.0, 75.0, 74.0, 173.0, 76.0], "value": 94.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 158.0, 84.0, 83.0, 83.0, 144.0, 83.0, 82.0, 140.0], "value": 102.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 116.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 145.0, 147.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 52.0, 54.0, 54.0, 57.0, 55.0, 54.0, 54.0, 54.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 59.0, 59.0, 59.0, 59.0, 58.0, 58.0, 59.0, 58.0], "value": 58.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 82.0, 76.0, 81.0, 71.0, 75.0, 73.0, 72.0], "value": 74.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 117.0, 117.0, 124.0, 119.0, 124.0, 124.0, 115.0, 149.0, 119.0], "value": 122.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 150.0, 151.0, 152.0, 156.0, 152.0, 154.0, 153.0, 152.0, 151.0], "value": 152.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 62.0, 60.0, 62.0, 60.0, 61.0, 61.0, 60.0, 60.0], "value": 61.3, "unit": "score"}], "name": "kraken", "value": 1490.5000000000002, "alertThreshold": 2.0}]} 15:58:58 INFO - Return code: 0 15:58:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:58:59 INFO - # TBPL SUCCESS # 15:58:59 INFO - Running post-action listener: _resource_record_post_action 15:58:59 INFO - [mozharness: 2016-09-19 22:58:59.049078Z] Finished run-tests step (success) 15:58:59 INFO - Running post-run listener: _resource_record_post_run 15:58:59 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 4096; Write bytes: 290811904; Read time: 16; Write time: 170900 15:58:59 INFO - TinderboxPrint: CPU usage
12.9% 15:58:59 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 15:58:59 INFO - TinderboxPrint: I/O write bytes / time
290,811,904 / 170,900 15:58:59 INFO - TinderboxPrint: CPU idle
8,255.8 (87.1%) 15:58:59 INFO - TinderboxPrint: CPU user
1,177.8 (12.4%) 15:58:59 INFO - TinderboxPrint: Swap in / out
0 / 0 15:58:59 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 70078464; Read time: 0; Write time: 38668 15:58:59 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 220733440; Read time: 0; Write time: 132232 15:58:59 INFO - Running post-run listener: _upload_blobber_files 15:58:59 INFO - Blob upload gear active. 15:58:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:58:59 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:58:59 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:58:59 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:59:00 INFO - (blobuploader) - INFO - Open directory for files ... 15:59:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:59:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:59:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:59:04 INFO - (blobuploader) - INFO - Done attempting. 15:59:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:59:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:59:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:59:05 INFO - (blobuploader) - INFO - Done attempting. 15:59:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:59:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:59:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:59:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:59:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:59:05 INFO - (blobuploader) - INFO - Done attempting. 15:59:05 INFO - (blobuploader) - INFO - Iteration through files over. 15:59:05 INFO - Return code: 0 15:59:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:59:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:59:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fcfa0af6fcd6254d0e13ecbfa948cc0b4ccaa69993f437bc0cc8ac3f2fb3972b1db33c29647bf4706255648f90621aa9ef630268e42818f24dc33ad746a63fb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e759c27de0dc9756b87d83dfb7eff336629eed42dffec70fe68daa858d5b11d1b80c18ce29f4857fbe4cca97c0a4787d656204810020d68e363bc04fdbccf6cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fae405146de3b33616e1c4f82d26a35917bca3f00726572d16e9c66aa72240e677350d419d8f0eb47b3a8531fb0921d4d1253d7fd4369529f08ada1606db75bd"} 15:59:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:59:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:59:05 INFO - Contents: 15:59:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fcfa0af6fcd6254d0e13ecbfa948cc0b4ccaa69993f437bc0cc8ac3f2fb3972b1db33c29647bf4706255648f90621aa9ef630268e42818f24dc33ad746a63fb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e759c27de0dc9756b87d83dfb7eff336629eed42dffec70fe68daa858d5b11d1b80c18ce29f4857fbe4cca97c0a4787d656204810020d68e363bc04fdbccf6cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fae405146de3b33616e1c4f82d26a35917bca3f00726572d16e9c66aa72240e677350d419d8f0eb47b3a8531fb0921d4d1253d7fd4369529f08ada1606db75bd"} 15:59:05 INFO - Running post-run listener: copy_logs_to_upload_dir 15:59:05 INFO - Copying logs to upload dir... 15:59:05 INFO - mkdir: /builds/slave/test/build/upload/logs 15:59:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1674.873605 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-09-19 15:59:05.817180) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 15:59:05.821069) ========= 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=03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155 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/fcfa0af6fcd6254d0e13ecbfa948cc0b4ccaa69993f437bc0cc8ac3f2fb3972b1db33c29647bf4706255648f90621aa9ef630268e42818f24dc33ad746a63fb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e759c27de0dc9756b87d83dfb7eff336629eed42dffec70fe68daa858d5b11d1b80c18ce29f4857fbe4cca97c0a4787d656204810020d68e363bc04fdbccf6cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fae405146de3b33616e1c4f82d26a35917bca3f00726572d16e9c66aa72240e677350d419d8f0eb47b3a8531fb0921d4d1253d7fd4369529f08ada1606db75bd"} build_url:https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011416 build_url: 'https://queue.taskcluster.net/v1/task/NP4rm_HuS0OWUwXOhIFq3A/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/fcfa0af6fcd6254d0e13ecbfa948cc0b4ccaa69993f437bc0cc8ac3f2fb3972b1db33c29647bf4706255648f90621aa9ef630268e42818f24dc33ad746a63fb9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e759c27de0dc9756b87d83dfb7eff336629eed42dffec70fe68daa858d5b11d1b80c18ce29f4857fbe4cca97c0a4787d656204810020d68e363bc04fdbccf6cd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fae405146de3b33616e1c4f82d26a35917bca3f00726572d16e9c66aa72240e677350d419d8f0eb47b3a8531fb0921d4d1253d7fd4369529f08ada1606db75bd"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-19 15:59:05.846027) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 15:59:05.846343) ========= 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=03627c79f6bb3d1578c4a402000001ba-1474324267.163669-437383155 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004507 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-19 15:59:05.928550) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 15:59:05.928870) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-19 15:59:05.929151) ========= ========= Total master_lag: 1.19 =========