builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-032 starttime: 1471986587.99 results: success (0) buildid: 20160823121617 builduid: 224537630c104762a06d8ac80ff02f26 revision: a72bfbdf5c9b36e68ca806a25944180f2652f0c9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 14:09:47.993452) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 14:09:47.993892) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 14:09:47.994205) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 14:09:48.010119) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 14:09:48.010388) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1471986350.400089-1953456045 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-23 14:09:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 70.9M=0s 2016-08-23 14:09:48 (70.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.134226 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 14:09:48.157973) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 14:09:48.158315) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1471986350.400089-1953456045 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.016814 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 14:09:48.207771) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 14:09:48.208146) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a72bfbdf5c9b36e68ca806a25944180f2652f0c9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a72bfbdf5c9b36e68ca806a25944180f2652f0c9 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1471986350.400089-1953456045 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-23 14:09:48,761 truncating revision to first 12 chars 2016-08-23 14:09:48,761 Setting DEBUG logging. 2016-08-23 14:09:48,761 attempt 1/10 2016-08-23 14:09:48,761 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/a72bfbdf5c9b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-23 14:09:49,021 unpacking tar archive at: mozilla-aurora-a72bfbdf5c9b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.541992 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 14:09:48.763402) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 14:09:48.763687) ========= script_repo_revision: a72bfbdf5c9b36e68ca806a25944180f2652f0c9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 14:09:48.764092) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-23 14:09:48.764371) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-23 14:09:48.772493) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 1 secs) (at 2016-08-23 14:09:48.772787) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1471986350.400089-1953456045 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:09:49 INFO - MultiFileLogger online at 20160823 14:09:49 in /builds/slave/test 14:09:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 14:09:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:09:49 INFO - {'append_to_log': False, 14:09:49 INFO - 'base_work_dir': '/builds/slave/test', 14:09:49 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:09:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:09:49 INFO - 'branch': 'Mozilla-Aurora', 14:09:49 INFO - 'buildbot_json_path': 'buildprops.json', 14:09:49 INFO - 'config_files': ('talos/linux_config.py',), 14:09:49 INFO - 'default_actions': ('clobber', 14:09:49 INFO - 'read-buildbot-config', 14:09:49 INFO - 'download-and-extract', 14:09:49 INFO - 'populate-webroot', 14:09:49 INFO - 'create-virtualenv', 14:09:49 INFO - 'install', 14:09:49 INFO - 'run-tests'), 14:09:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:09:49 INFO - 'download_minidump_stackwalk': True, 14:09:49 INFO - 'download_symbols': 'ondemand', 14:09:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:09:49 INFO - 'tooltool.py': '/tools/tooltool.py', 14:09:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:09:49 INFO - '/tools/misc-python/virtualenv.py')}, 14:09:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:09:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:09:49 INFO - 'installer_path': 'installer.exe', 14:09:49 INFO - 'log_level': 'info', 14:09:49 INFO - 'log_name': 'talos', 14:09:49 INFO - 'log_to_console': True, 14:09:49 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:09:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:09:49 INFO - 'opt_config_files': (), 14:09:49 INFO - 'pip_index': False, 14:09:49 INFO - 'sps_profile': False, 14:09:49 INFO - 'sps_profile_interval': 0, 14:09:49 INFO - 'suite': 'dromaeojs', 14:09:49 INFO - 'system_bits': '32', 14:09:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:09:49 INFO - 'title': 'talos-linux64-ix-032', 14:09:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:09:49 INFO - 'use_talos_json': True, 14:09:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:09:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:09:49 INFO - 'work_dir': 'build'} 14:09:49 INFO - [mozharness: 2016-08-23 21:09:49.420798Z] Running clobber step. 14:09:49 INFO - Running pre-action listener: _resource_record_pre_action 14:09:49 INFO - Running main action method: clobber 14:09:49 INFO - rmtree: /builds/slave/test/build 14:09:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:09:49 INFO - Running post-action listener: _resource_record_post_action 14:09:49 INFO - [mozharness: 2016-08-23 21:09:49.881692Z] Finished clobber step (success) 14:09:49 INFO - [mozharness: 2016-08-23 21:09:49.881795Z] Running read-buildbot-config step. 14:09:49 INFO - Running pre-action listener: _resource_record_pre_action 14:09:49 INFO - Running main action method: read_buildbot_config 14:09:49 INFO - Using buildbot properties: 14:09:49 INFO - { 14:09:49 INFO - "project": "", 14:09:49 INFO - "product": "firefox", 14:09:49 INFO - "script_repo_revision": "production", 14:09:49 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 14:09:49 INFO - "repository": "", 14:09:49 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 14:09:49 INFO - "buildid": "20160823121617", 14:09:49 INFO - "pgo_build": "True", 14:09:49 INFO - "basedir": "/builds/slave/test", 14:09:49 INFO - "buildnumber": 424, 14:09:49 INFO - "slavename": "talos-linux64-ix-032", 14:09:49 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:09:49 INFO - "platform": "ubuntu64_hw", 14:09:49 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 14:09:49 INFO - "branch": "mozilla-aurora", 14:09:49 INFO - "repo_path": "releases/mozilla-aurora", 14:09:49 INFO - "revision": "a72bfbdf5c9b36e68ca806a25944180f2652f0c9", 14:09:49 INFO - "stage_platform": "linux64-pgo", 14:09:49 INFO - "builduid": "224537630c104762a06d8ac80ff02f26", 14:09:49 INFO - "slavebuilddir": "test" 14:09:49 INFO - } 14:09:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 14:09:49 INFO - Running post-action listener: _resource_record_post_action 14:09:49 INFO - [mozharness: 2016-08-23 21:09:49.884248Z] Finished read-buildbot-config step (success) 14:09:49 INFO - [mozharness: 2016-08-23 21:09:49.884338Z] Running download-and-extract step. 14:09:49 INFO - Running pre-action listener: _resource_record_pre_action 14:09:49 INFO - Running main action method: download_and_extract 14:09:49 INFO - mkdir: /builds/slave/test/build/tests 14:09:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:49 INFO - https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 14:09:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:09:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:09:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:09:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:09:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:09:49 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:09:49 INFO - retry: Failed, sleeping 30 seconds before retrying 14:10:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 14:10:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:10:19 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:10:19 INFO - retry: Failed, sleeping 60 seconds before retrying 14:11:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 14:11:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:11:19 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:11:19 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 14:11:19 INFO - Caught exception: HTTP Error 404: Not Found 14:11:19 INFO - Caught exception: HTTP Error 404: Not Found 14:11:19 INFO - Caught exception: HTTP Error 404: Not Found 14:11:19 INFO - trying https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:11:19 INFO - Downloading https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:11:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:11:20 INFO - Downloaded 1361 bytes. 14:11:20 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 14:11:20 INFO - Using the following test package requirements: 14:11:20 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 14:11:20 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:11:20 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 14:11:20 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:11:20 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 14:11:20 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:11:20 INFO - u'jsshell-linux-x86_64.zip'], 14:11:20 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:11:20 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 14:11:20 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 14:11:20 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:11:20 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 14:11:20 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:11:20 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 14:11:20 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:11:20 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 14:11:20 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 14:11:20 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 14:11:20 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:11:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:11:20 INFO - https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:11:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:11:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:11:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:11:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:11:20 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:11:20 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:11:20 INFO - retry: Failed, sleeping 30 seconds before retrying 14:11:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 14:11:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:11:50 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:11:50 INFO - retry: Failed, sleeping 60 seconds before retrying 14:12:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 14:12:50 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:12:50 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:12:50 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 14:12:50 INFO - Caught exception: HTTP Error 404: Not Found 14:12:50 INFO - Caught exception: HTTP Error 404: Not Found 14:12:50 INFO - Caught exception: HTTP Error 404: Not Found 14:12:50 INFO - trying https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:12:50 INFO - Downloading https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:12:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:12:51 INFO - Downloaded 21767590 bytes. 14:12:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:12:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:12:52 INFO - Return code: 0 14:12:52 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:12: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')]} 14:12:52 INFO - https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 14:12:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:12:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:12:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:12:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:12:52 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:12:52 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:12:52 INFO - retry: Failed, sleeping 30 seconds before retrying 14:13:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 14:13:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:13:22 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:13:22 INFO - retry: Failed, sleeping 60 seconds before retrying 14:14:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 14:14:22 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:14:22 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:14:22 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 14:14:22 INFO - Caught exception: HTTP Error 404: Not Found 14:14:22 INFO - Caught exception: HTTP Error 404: Not Found 14:14:22 INFO - Caught exception: HTTP Error 404: Not Found 14:14:22 INFO - trying https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:14:22 INFO - Downloading https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 14:14:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:14:23 INFO - Downloaded 21767590 bytes. 14:14:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:14:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 14:14:25 INFO - Return code: 0 14:14:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:14:25 INFO - https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 14:14:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:14:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:14:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:14:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:14:25 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:14:25 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:14:25 INFO - retry: Failed, sleeping 30 seconds before retrying 14:14:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 14:14:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:14:55 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:14:55 INFO - retry: Failed, sleeping 60 seconds before retrying 14:15:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 14:15:55 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:15:55 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:15:55 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 14:15:55 INFO - Caught exception: HTTP Error 404: Not Found 14:15:55 INFO - Caught exception: HTTP Error 404: Not Found 14:15:55 INFO - Caught exception: HTTP Error 404: Not Found 14:15:55 INFO - trying https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:15:55 INFO - Downloading https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 14:15:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:15:56 INFO - Downloaded 14413723 bytes. 14:15:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:15:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 14:15:56 INFO - Return code: 0 14:15:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:15:56 INFO - https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 14:15:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:15:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:15:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:15:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:15:56 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:15:56 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 14:15:56 INFO - retry: Failed, sleeping 30 seconds before retrying 14:16:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 14:16:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:16:26 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 14:16:26 INFO - retry: Failed, sleeping 60 seconds before retrying 14:17:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 14:17:26 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:17:26 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 14:17:26 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 14:17:26 INFO - Caught exception: HTTP Error 404: Not Found 14:17:26 INFO - Caught exception: HTTP Error 404: Not Found 14:17:26 INFO - Caught exception: HTTP Error 404: Not Found 14:17:26 INFO - trying https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:17:26 INFO - Downloading https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:17:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:17:28 INFO - Downloaded 63744205 bytes. 14:17:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:17:28 INFO - mkdir: /builds/slave/test/properties 14:17:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:17:28 INFO - Writing to file /builds/slave/test/properties/build_url 14:17:28 INFO - Contents: 14:17:28 INFO - build_url:https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 14:17:28 INFO - Running post-action listener: _resource_record_post_action 14:17:28 INFO - Running post-action listener: set_extra_try_arguments 14:17:28 INFO - [mozharness: 2016-08-23 21:17:28.771124Z] Finished download-and-extract step (success) 14:17:28 INFO - [mozharness: 2016-08-23 21:17:28.771362Z] Running populate-webroot step. 14:17:28 INFO - Running pre-action listener: _resource_record_pre_action 14:17:28 INFO - Running main action method: populate_webroot 14:17:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:17:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:17:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:17:28 INFO - '--tppagecycles', 14:17:28 INFO - '7'], 14:17:28 INFO - 'tests': ['tsvgm']}}, 14:17:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:17:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:17:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:17:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:17:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:17:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:17:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:28 INFO - 'talos_options': ['--disable-e10s'], 14:17:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:17:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:17:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:28 INFO - 'talos_options': ['--disable-e10s'], 14:17:28 INFO - 'tests': ['damp', 'tps']}, 14:17:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:28 INFO - 'tests': ['damp', 'tps']}, 14:17:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:17:28 INFO - 'tests': ['dromaeo_dom']}, 14:17:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:17:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:17:28 INFO - 'tests': ['basic_compositor_video']}, 14:17:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:17:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:17:28 INFO - 'tests': ['a11yr', 14:17:28 INFO - 'ts_paint', 14:17:28 INFO - 'tpaint', 14:17:28 INFO - 'sessionrestore', 14:17:28 INFO - 'sessionrestore_no_auto_restore', 14:17:28 INFO - 'tabpaint']}, 14:17:28 INFO - 'other-e10s': {'tests': ['a11yr', 14:17:28 INFO - 'ts_paint', 14:17:28 INFO - 'tpaint', 14:17:28 INFO - 'sessionrestore', 14:17:28 INFO - 'sessionrestore_no_auto_restore', 14:17:28 INFO - 'tabpaint']}, 14:17:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:17:28 INFO - 'ts_paint', 14:17:28 INFO - 'tpaint', 14:17:28 INFO - 'sessionrestore', 14:17:28 INFO - 'sessionrestore_no_auto_restore', 14:17:28 INFO - 'tabpaint']}, 14:17:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:17:28 INFO - 'ts_paint', 14:17:28 INFO - 'tpaint', 14:17:28 INFO - 'sessionrestore', 14:17:28 INFO - 'sessionrestore_no_auto_restore', 14:17:28 INFO - 'tabpaint']}, 14:17:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:17:28 INFO - 'tests': ['a11yr', 14:17:28 INFO - 'ts_paint', 14:17:28 INFO - 'tpaint', 14:17:28 INFO - 'sessionrestore', 14:17:28 INFO - 'sessionrestore_no_auto_restore', 14:17:28 INFO - 'tabpaint']}, 14:17:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:17:28 INFO - 'tests': ['a11yr', 14:17:28 INFO - 'ts_paint', 14:17:28 INFO - 'tpaint', 14:17:28 INFO - 'sessionrestore', 14:17:28 INFO - 'sessionrestore_no_auto_restore', 14:17:28 INFO - 'tabpaint']}, 14:17:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:17:28 INFO - 'tests': ['tsvgx', 14:17:28 INFO - 'tsvgr_opacity', 14:17:28 INFO - 'tart', 14:17:28 INFO - 'tscrollx', 14:17:28 INFO - 'cart']}, 14:17:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:17:28 INFO - 'tsvgr_opacity', 14:17:28 INFO - 'tart', 14:17:28 INFO - 'tscrollx', 14:17:28 INFO - 'cart']}, 14:17:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:28 INFO - 'talos_options': ['--disable-e10s'], 14:17:28 INFO - 'tests': ['tp5o']}, 14:17:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:28 INFO - 'tests': ['tp5o']}, 14:17:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:28 INFO - 'talos_options': ['--disable-e10s', 14:17:28 INFO - '--xperf_path', 14:17:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:17:28 INFO - 'tests': ['tp5n']}, 14:17:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:17:28 INFO - 'talos_options': ['--xperf_path', 14:17:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:17:28 INFO - 'tests': ['tp5n']}}, 14:17:28 INFO - 'talos.zip': {'path': '', 14:17:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:17:28 INFO - Running post-action listener: _resource_record_post_action 14:17:28 INFO - [mozharness: 2016-08-23 21:17:28.789947Z] Finished populate-webroot step (success) 14:17:28 INFO - [mozharness: 2016-08-23 21:17:28.790036Z] Running create-virtualenv step. 14:17:28 INFO - Running pre-action listener: _resource_record_pre_action 14:17:28 INFO - Running main action method: create_virtualenv 14:17:28 INFO - Creating virtualenv /builds/slave/test/build/venv 14:17:28 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:17:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:17:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:17:28 INFO - Using real prefix '/usr' 14:17:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:17:29 INFO - Installing distribute.............................................................................................................................................................................................done. 14:17:31 INFO - Installing pip.................done. 14:17:31 INFO - Return code: 0 14:17:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:17:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:31 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf31180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2f3b0>, '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 0xf302b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf30740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471986350.400089-1953456045', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:17:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:17:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:17:31 INFO - Using env: {'DISPLAY': ':0', 14:17:31 INFO - 'HOME': '/home/cltbld', 14:17:31 INFO - 'LANG': 'en_US.UTF-8', 14:17:31 INFO - 'LANGUAGE': 'en_US:en', 14:17:31 INFO - 'LOGNAME': 'cltbld', 14:17:31 INFO - 'MAIL': '/var/mail/cltbld', 14:17:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:31 INFO - 'MOZ_NO_REMOTE': '1', 14:17:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:31 INFO - 'NO_EM_RESTART': '1', 14:17:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:31 INFO - 'PWD': '/builds/slave/test', 14:17:31 INFO - 'SHELL': '/bin/bash', 14:17:31 INFO - 'SHLVL': '1', 14:17:31 INFO - 'TERM': 'linux', 14:17:31 INFO - 'TMOUT': '86400', 14:17:31 INFO - 'USER': 'cltbld', 14:17:31 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471986350.400089-1953456045', 14:17:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:31 INFO - '_': '/tools/buildbot/bin/python'} 14:17:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:31 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:17:31 INFO - Cleaning up... 14:17:31 INFO - Return code: 0 14:17:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:17:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:31 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf31180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2f3b0>, '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 0xf302b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf30740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471986350.400089-1953456045', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:17:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:17:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:17:31 INFO - Using env: {'DISPLAY': ':0', 14:17:31 INFO - 'HOME': '/home/cltbld', 14:17:31 INFO - 'LANG': 'en_US.UTF-8', 14:17:31 INFO - 'LANGUAGE': 'en_US:en', 14:17:31 INFO - 'LOGNAME': 'cltbld', 14:17:31 INFO - 'MAIL': '/var/mail/cltbld', 14:17:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:31 INFO - 'MOZ_NO_REMOTE': '1', 14:17:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:31 INFO - 'NO_EM_RESTART': '1', 14:17:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:31 INFO - 'PWD': '/builds/slave/test', 14:17:31 INFO - 'SHELL': '/bin/bash', 14:17:31 INFO - 'SHLVL': '1', 14:17:31 INFO - 'TERM': 'linux', 14:17:31 INFO - 'TMOUT': '86400', 14:17:31 INFO - 'USER': 'cltbld', 14:17:31 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471986350.400089-1953456045', 14:17:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:31 INFO - '_': '/tools/buildbot/bin/python'} 14:17:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:31 INFO - Downloading/unpacking psutil>=3.1.1 14:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:17:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:17:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:17:33 INFO - Installing collected packages: psutil 14:17:33 INFO - Running setup.py install for psutil 14:17:33 INFO - building 'psutil._psutil_linux' extension 14:17:33 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 14:17:34 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 14:17:34 INFO - building 'psutil._psutil_posix' extension 14:17:34 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 14:17:34 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 14:17:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:17:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:17:34 INFO - Successfully installed psutil 14:17:34 INFO - Cleaning up... 14:17:34 INFO - Return code: 0 14:17:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:34 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf31180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2f3b0>, '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 0xf302b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf30740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471986350.400089-1953456045', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:17:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:17:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:17:34 INFO - Using env: {'DISPLAY': ':0', 14:17:34 INFO - 'HOME': '/home/cltbld', 14:17:34 INFO - 'LANG': 'en_US.UTF-8', 14:17:34 INFO - 'LANGUAGE': 'en_US:en', 14:17:34 INFO - 'LOGNAME': 'cltbld', 14:17:34 INFO - 'MAIL': '/var/mail/cltbld', 14:17:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:34 INFO - 'MOZ_NO_REMOTE': '1', 14:17:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:34 INFO - 'NO_EM_RESTART': '1', 14:17:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:34 INFO - 'PWD': '/builds/slave/test', 14:17:34 INFO - 'SHELL': '/bin/bash', 14:17:34 INFO - 'SHLVL': '1', 14:17:34 INFO - 'TERM': 'linux', 14:17:34 INFO - 'TMOUT': '86400', 14:17:34 INFO - 'USER': 'cltbld', 14:17:34 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471986350.400089-1953456045', 14:17:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:34 INFO - '_': '/tools/buildbot/bin/python'} 14:17:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:36 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:17:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:17:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:17:36 INFO - Installing collected packages: mozsystemmonitor 14:17:36 INFO - Running setup.py install for mozsystemmonitor 14:17:36 INFO - Successfully installed mozsystemmonitor 14:17:36 INFO - Cleaning up... 14:17:36 INFO - Return code: 0 14:17:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:17:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:36 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf31180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2f3b0>, '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 0xf302b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf30740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471986350.400089-1953456045', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:17:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:17:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:17:36 INFO - Using env: {'DISPLAY': ':0', 14:17:36 INFO - 'HOME': '/home/cltbld', 14:17:36 INFO - 'LANG': 'en_US.UTF-8', 14:17:36 INFO - 'LANGUAGE': 'en_US:en', 14:17:36 INFO - 'LOGNAME': 'cltbld', 14:17:36 INFO - 'MAIL': '/var/mail/cltbld', 14:17:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:36 INFO - 'MOZ_NO_REMOTE': '1', 14:17:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:36 INFO - 'NO_EM_RESTART': '1', 14:17:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:36 INFO - 'PWD': '/builds/slave/test', 14:17:36 INFO - 'SHELL': '/bin/bash', 14:17:36 INFO - 'SHLVL': '1', 14:17:36 INFO - 'TERM': 'linux', 14:17:36 INFO - 'TMOUT': '86400', 14:17:36 INFO - 'USER': 'cltbld', 14:17:36 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471986350.400089-1953456045', 14:17:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:36 INFO - '_': '/tools/buildbot/bin/python'} 14:17:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:36 INFO - Downloading/unpacking jsonschema==2.5.1 14:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:17:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:17:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:38 INFO - Downloading functools32-3.2.3-2.tar.gz 14:17:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:17:39 INFO - warning: no files found matching '*.txt' 14:17:39 INFO - no previously-included directories found matching 'build' 14:17:39 INFO - no previously-included directories found matching 'dist' 14:17:39 INFO - no previously-included directories found matching '.git*' 14:17:39 INFO - Installing collected packages: jsonschema, functools32 14:17:39 INFO - Running setup.py install for functools32 14:17:39 INFO - warning: no files found matching '*.txt' 14:17:39 INFO - no previously-included directories found matching 'build' 14:17:39 INFO - no previously-included directories found matching 'dist' 14:17:39 INFO - no previously-included directories found matching '.git*' 14:17:39 INFO - Successfully installed jsonschema functools32 14:17:39 INFO - Cleaning up... 14:17:39 INFO - Return code: 0 14:17:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:17:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:39 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf31180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2f3b0>, '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 0xf302b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf30740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471986350.400089-1953456045', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:17:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:17:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:17:39 INFO - Using env: {'DISPLAY': ':0', 14:17:39 INFO - 'HOME': '/home/cltbld', 14:17:39 INFO - 'LANG': 'en_US.UTF-8', 14:17:39 INFO - 'LANGUAGE': 'en_US:en', 14:17:39 INFO - 'LOGNAME': 'cltbld', 14:17:39 INFO - 'MAIL': '/var/mail/cltbld', 14:17:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:39 INFO - 'MOZ_NO_REMOTE': '1', 14:17:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:39 INFO - 'NO_EM_RESTART': '1', 14:17:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:39 INFO - 'PWD': '/builds/slave/test', 14:17:39 INFO - 'SHELL': '/bin/bash', 14:17:39 INFO - 'SHLVL': '1', 14:17:39 INFO - 'TERM': 'linux', 14:17:39 INFO - 'TMOUT': '86400', 14:17:39 INFO - 'USER': 'cltbld', 14:17:39 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471986350.400089-1953456045', 14:17:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:39 INFO - '_': '/tools/buildbot/bin/python'} 14:17:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:17:39 INFO - Cleaning up... 14:17:39 INFO - Return code: 0 14:17:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:17:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:39 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf31180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2f3b0>, '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 0xf302b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf30740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471986350.400089-1953456045', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:17:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:17:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:17:39 INFO - Using env: {'DISPLAY': ':0', 14:17:39 INFO - 'HOME': '/home/cltbld', 14:17:39 INFO - 'LANG': 'en_US.UTF-8', 14:17:39 INFO - 'LANGUAGE': 'en_US:en', 14:17:39 INFO - 'LOGNAME': 'cltbld', 14:17:39 INFO - 'MAIL': '/var/mail/cltbld', 14:17:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:39 INFO - 'MOZ_NO_REMOTE': '1', 14:17:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:39 INFO - 'NO_EM_RESTART': '1', 14:17:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:39 INFO - 'PWD': '/builds/slave/test', 14:17:39 INFO - 'SHELL': '/bin/bash', 14:17:39 INFO - 'SHLVL': '1', 14:17:39 INFO - 'TERM': 'linux', 14:17:39 INFO - 'TMOUT': '86400', 14:17:39 INFO - 'USER': 'cltbld', 14:17:39 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471986350.400089-1953456045', 14:17:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:39 INFO - '_': '/tools/buildbot/bin/python'} 14:17:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:39 INFO - Downloading/unpacking blobuploader==1.2.4 14:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:41 INFO - Downloading blobuploader-1.2.4.tar.gz 14:17:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:17:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:17:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:42 INFO - Downloading docopt-0.6.1.tar.gz 14:17:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:17:42 INFO - Installing collected packages: blobuploader, requests, docopt 14:17:42 INFO - Running setup.py install for blobuploader 14:17:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:17:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:17:42 INFO - Running setup.py install for requests 14:17:42 INFO - Running setup.py install for docopt 14:17:42 INFO - Successfully installed blobuploader requests docopt 14:17:42 INFO - Cleaning up... 14:17:43 INFO - Return code: 0 14:17:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:17:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:43 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf31180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2f3b0>, '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 0xf302b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf30740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471986350.400089-1953456045', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:17:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:17:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:17:43 INFO - Using env: {'DISPLAY': ':0', 14:17:43 INFO - 'HOME': '/home/cltbld', 14:17:43 INFO - 'LANG': 'en_US.UTF-8', 14:17:43 INFO - 'LANGUAGE': 'en_US:en', 14:17:43 INFO - 'LOGNAME': 'cltbld', 14:17:43 INFO - 'MAIL': '/var/mail/cltbld', 14:17:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:43 INFO - 'MOZ_NO_REMOTE': '1', 14:17:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:43 INFO - 'NO_EM_RESTART': '1', 14:17:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:43 INFO - 'PWD': '/builds/slave/test', 14:17:43 INFO - 'SHELL': '/bin/bash', 14:17:43 INFO - 'SHLVL': '1', 14:17:43 INFO - 'TERM': 'linux', 14:17:43 INFO - 'TMOUT': '86400', 14:17:43 INFO - 'USER': 'cltbld', 14:17:43 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471986350.400089-1953456045', 14:17:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:43 INFO - '_': '/tools/buildbot/bin/python'} 14:17:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:17:43 INFO - Running setup.py (path:/tmp/pip-8EZ34u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:17:43 INFO - Running setup.py (path:/tmp/pip-DTCFXK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:17:43 INFO - Running setup.py (path:/tmp/pip-668IRl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:17:43 INFO - Running setup.py (path:/tmp/pip-gRUeDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:17:43 INFO - Running setup.py (path:/tmp/pip-eLpaTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:17:43 INFO - Running setup.py (path:/tmp/pip-2OW_IJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:17:43 INFO - Running setup.py (path:/tmp/pip-aQiMfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:17:43 INFO - Running setup.py (path:/tmp/pip-IIaKC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:17:43 INFO - Running setup.py (path:/tmp/pip-oc_GZ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:17:43 INFO - Running setup.py (path:/tmp/pip-yfXrLW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:17:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:17:43 INFO - Running setup.py (path:/tmp/pip-PXCoZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:17:44 INFO - Running setup.py (path:/tmp/pip-J4jitu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:17:44 INFO - Running setup.py (path:/tmp/pip-W9676Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:17:44 INFO - Running setup.py (path:/tmp/pip-sfCRSC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:17:44 INFO - Running setup.py (path:/tmp/pip-WAJX_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:17:44 INFO - Running setup.py (path:/tmp/pip-DzZp3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:17:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:17:44 INFO - Running setup.py (path:/tmp/pip-nrblUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:17:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:17:44 INFO - Running setup.py install for manifestparser 14:17:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:17:44 INFO - Running setup.py install for mozcrash 14:17:44 INFO - Running setup.py install for mozdebug 14:17:44 INFO - Running setup.py install for mozdevice 14:17:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:17:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:17:44 INFO - Running setup.py install for mozfile 14:17:44 INFO - Running setup.py install for mozhttpd 14:17:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:17:45 INFO - Running setup.py install for mozinfo 14:17:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:17:45 INFO - Running setup.py install for mozInstall 14:17:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:17:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:17:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:17:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:17:45 INFO - Running setup.py install for mozleak 14:17:45 INFO - Running setup.py install for mozlog 14:17:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:17:45 INFO - Running setup.py install for moznetwork 14:17:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:17:45 INFO - Running setup.py install for mozprocess 14:17:45 INFO - Running setup.py install for mozprofile 14:17:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:17:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:17:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:17:45 INFO - Running setup.py install for mozrunner 14:17:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:17:45 INFO - Running setup.py install for mozscreenshot 14:17:46 INFO - Running setup.py install for moztest 14:17:46 INFO - Running setup.py install for mozversion 14:17:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:17:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:17:46 INFO - Cleaning up... 14:17:46 INFO - Return code: 0 14:17:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:17:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:46 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf31180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2f3b0>, '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 0xf302b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf30740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471986350.400089-1953456045', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:17:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:17:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:17:46 INFO - Using env: {'DISPLAY': ':0', 14:17:46 INFO - 'HOME': '/home/cltbld', 14:17:46 INFO - 'LANG': 'en_US.UTF-8', 14:17:46 INFO - 'LANGUAGE': 'en_US:en', 14:17:46 INFO - 'LOGNAME': 'cltbld', 14:17:46 INFO - 'MAIL': '/var/mail/cltbld', 14:17:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:46 INFO - 'MOZ_NO_REMOTE': '1', 14:17:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:46 INFO - 'NO_EM_RESTART': '1', 14:17:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:46 INFO - 'PWD': '/builds/slave/test', 14:17:46 INFO - 'SHELL': '/bin/bash', 14:17:46 INFO - 'SHLVL': '1', 14:17:46 INFO - 'TERM': 'linux', 14:17:46 INFO - 'TMOUT': '86400', 14:17:46 INFO - 'USER': 'cltbld', 14:17:46 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471986350.400089-1953456045', 14:17:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:46 INFO - '_': '/tools/buildbot/bin/python'} 14:17:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:17:46 INFO - Running setup.py (path:/tmp/pip-eQ57Oz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:17:46 INFO - Running setup.py (path:/tmp/pip-R8YmwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:17:46 INFO - Running setup.py (path:/tmp/pip-wqmmo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:17:46 INFO - Running setup.py (path:/tmp/pip-SjogzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:17:46 INFO - Running setup.py (path:/tmp/pip-_5Cuby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:17:46 INFO - Running setup.py (path:/tmp/pip-vbrZsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:17:46 INFO - Running setup.py (path:/tmp/pip-ilzL7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:17:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:17:46 INFO - Running setup.py (path:/tmp/pip-6j95TJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:17:47 INFO - Running setup.py (path:/tmp/pip-dVqbKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:17:47 INFO - Running setup.py (path:/tmp/pip-KaBj7u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:17:47 INFO - Running setup.py (path:/tmp/pip-lTp_hW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:17:47 INFO - Running setup.py (path:/tmp/pip-JaE2I2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:17:47 INFO - Running setup.py (path:/tmp/pip-Q63GBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:17:47 INFO - Running setup.py (path:/tmp/pip-Sf6USI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:17:47 INFO - Running setup.py (path:/tmp/pip-AqJ6T0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:17:47 INFO - Running setup.py (path:/tmp/pip-GrGWpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:17:47 INFO - Running setup.py (path:/tmp/pip-efylSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:17:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:17:47 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:17:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:17:49 INFO - Downloading blessings-1.6.tar.gz 14:17:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:17:49 INFO - Installing collected packages: blessings 14:17:49 INFO - Running setup.py install for blessings 14:17:49 INFO - Successfully installed blessings 14:17:49 INFO - Cleaning up... 14:17:49 INFO - Return code: 0 14:17:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:17:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:17:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:17:50 INFO - Reading from file tmpfile_stdout 14:17:50 INFO - Current package versions: 14:17:50 INFO - argparse == 1.2.1 14:17:50 INFO - blessings == 1.6 14:17:50 INFO - blobuploader == 1.2.4 14:17:50 INFO - docopt == 0.6.1 14:17:50 INFO - functools32 == 3.2.3-2 14:17:50 INFO - jsonschema == 2.5.1 14:17:50 INFO - manifestparser == 1.1 14:17:50 INFO - mozInstall == 1.12 14:17:50 INFO - mozcrash == 0.17 14:17:50 INFO - mozdebug == 0.1 14:17:50 INFO - mozdevice == 0.48 14:17:50 INFO - mozfile == 1.2 14:17:50 INFO - mozhttpd == 0.7 14:17:50 INFO - mozinfo == 0.9 14:17:50 INFO - mozleak == 0.1 14:17:50 INFO - mozlog == 3.3 14:17:50 INFO - moznetwork == 0.27 14:17:50 INFO - mozprocess == 0.23 14:17:50 INFO - mozprofile == 0.28 14:17:50 INFO - mozrunner == 6.12 14:17:50 INFO - mozscreenshot == 0.1 14:17:50 INFO - mozsystemmonitor == 0.3 14:17:50 INFO - moztest == 0.7 14:17:50 INFO - mozversion == 1.4 14:17:50 INFO - psutil == 3.1.1 14:17:50 INFO - requests == 1.2.3 14:17:50 INFO - wsgiref == 0.1.2 14:17:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:50 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf31180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2f3b0>, '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 0xf302b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf30740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471986350.400089-1953456045', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:17:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:17:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:17:50 INFO - Using env: {'DISPLAY': ':0', 14:17:50 INFO - 'HOME': '/home/cltbld', 14:17:50 INFO - 'LANG': 'en_US.UTF-8', 14:17:50 INFO - 'LANGUAGE': 'en_US:en', 14:17:50 INFO - 'LOGNAME': 'cltbld', 14:17:50 INFO - 'MAIL': '/var/mail/cltbld', 14:17:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:50 INFO - 'MOZ_NO_REMOTE': '1', 14:17:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:50 INFO - 'NO_EM_RESTART': '1', 14:17:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:50 INFO - 'PWD': '/builds/slave/test', 14:17:50 INFO - 'SHELL': '/bin/bash', 14:17:50 INFO - 'SHLVL': '1', 14:17:50 INFO - 'TERM': 'linux', 14:17:50 INFO - 'TMOUT': '86400', 14:17:50 INFO - 'USER': 'cltbld', 14:17:50 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471986350.400089-1953456045', 14:17:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:50 INFO - '_': '/tools/buildbot/bin/python'} 14:17:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:17:50 INFO - Cleaning up... 14:17:50 INFO - Return code: 0 14:17:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:17:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:17:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:17:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:17:50 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 0xc70030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xdd38a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xf31180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xf2f3b0>, '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 0xf302b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf30740>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471986350.400089-1953456045', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 14:17:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:17:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:17:50 INFO - Using env: {'DISPLAY': ':0', 14:17:50 INFO - 'HOME': '/home/cltbld', 14:17:50 INFO - 'LANG': 'en_US.UTF-8', 14:17:50 INFO - 'LANGUAGE': 'en_US:en', 14:17:50 INFO - 'LOGNAME': 'cltbld', 14:17:50 INFO - 'MAIL': '/var/mail/cltbld', 14:17:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:17:50 INFO - 'MOZ_NO_REMOTE': '1', 14:17:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:17:50 INFO - 'NO_EM_RESTART': '1', 14:17:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:17:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:17:50 INFO - 'PWD': '/builds/slave/test', 14:17:50 INFO - 'SHELL': '/bin/bash', 14:17:50 INFO - 'SHLVL': '1', 14:17:50 INFO - 'TERM': 'linux', 14:17:50 INFO - 'TMOUT': '86400', 14:17:50 INFO - 'USER': 'cltbld', 14:17:50 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471986350.400089-1953456045', 14:17:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:17:50 INFO - '_': '/tools/buildbot/bin/python'} 14:17:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:17:50 INFO - Cleaning up... 14:17:50 INFO - Return code: 0 14:17:50 INFO - Running post-action listener: _resource_record_post_action 14:17:50 INFO - Running post-action listener: _start_resource_monitoring 14:17:50 INFO - Starting resource monitoring. 14:17:50 INFO - [mozharness: 2016-08-23 21:17:50.505186Z] Finished create-virtualenv step (success) 14:17:50 INFO - [mozharness: 2016-08-23 21:17:50.505618Z] Running install step. 14:17:50 INFO - Running pre-action listener: _resource_record_pre_action 14:17:50 INFO - Running main action method: install 14:17:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:17:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:17:50 INFO - Reading from file tmpfile_stdout 14:17:50 INFO - Detecting whether we're running mozinstall >=1.0... 14:17:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:17:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:17:50 INFO - Reading from file tmpfile_stdout 14:17:50 INFO - Output received: 14:17:50 INFO - Usage: mozinstall [options] installer 14:17:50 INFO - Options: 14:17:50 INFO - -h, --help show this help message and exit 14:17:50 INFO - -d DEST, --destination=DEST 14:17:50 INFO - Directory to install application into. [default: 14:17:50 INFO - "/builds/slave/test"] 14:17:50 INFO - --app=APP Application being installed. [default: firefox] 14:17:50 INFO - mkdir: /builds/slave/test/build/application 14:17:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:17:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 14:18:06 INFO - Reading from file tmpfile_stdout 14:18:06 INFO - Output received: 14:18:06 INFO - /builds/slave/test/build/application/firefox/firefox 14:18:06 INFO - Running post-action listener: _resource_record_post_action 14:18:06 INFO - [mozharness: 2016-08-23 21:18:06.800504Z] Finished install step (success) 14:18:06 INFO - [mozharness: 2016-08-23 21:18:06.800785Z] Running run-tests step. 14:18:06 INFO - Running pre-action listener: _resource_record_pre_action 14:18:06 INFO - Running main action method: run_tests 14:18:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:18:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:18:06 INFO - Python 2.7.3 14:18:06 INFO - Return code: 0 14:18:06 INFO - grabbing minidump binary from tooltool 14:18:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:06 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 0xf2f3b0>, '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 0xf302b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xf30740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:18:06 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:18:06 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 14:18:06 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:18:06 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:18:06 INFO - Return code: 0 14:18:06 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:18:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:18:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:18:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:18:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:18:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:18:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-032 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:18:06 INFO - Using env: {'DISPLAY': ':0', 14:18:06 INFO - 'HOME': '/home/cltbld', 14:18:06 INFO - 'LANG': 'en_US.UTF-8', 14:18:06 INFO - 'LANGUAGE': 'en_US:en', 14:18:06 INFO - 'LOGNAME': 'cltbld', 14:18:06 INFO - 'MAIL': '/var/mail/cltbld', 14:18:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:18:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:18:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:06 INFO - 'MOZ_NO_REMOTE': '1', 14:18:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:18:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:18:06 INFO - 'NO_EM_RESTART': '1', 14:18:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:18:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:06 INFO - 'PWD': '/builds/slave/test', 14:18:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:18:06 INFO - 'SHELL': '/bin/bash', 14:18:06 INFO - 'SHLVL': '1', 14:18:06 INFO - 'TERM': 'linux', 14:18:06 INFO - 'TMOUT': '86400', 14:18:06 INFO - 'USER': 'cltbld', 14:18:06 INFO - 'XDG_SESSION_COOKIE': '745a4373a2a16df560f216e000000264-1471986350.400089-1953456045', 14:18:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:06 INFO - '_': '/tools/buildbot/bin/python'} 14:18:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-032', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:18:07 INFO - mozversion application_buildid: 20160823121617 14:18:07 INFO - mozversion application_changeset: a72bfbdf5c9b36e68ca806a25944180f2652f0c9 14:18:07 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:18:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:18:07 INFO - mozversion application_name: Firefox 14:18:07 INFO - mozversion application_remotingname: firefox-dev 14:18:07 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:18:07 INFO - mozversion application_vendor: Mozilla 14:18:07 INFO - mozversion application_version: 50.0a2 14:18:07 INFO - mozversion platform_buildid: 20160823121617 14:18:07 INFO - mozversion platform_changeset: a72bfbdf5c9b36e68ca806a25944180f2652f0c9 14:18:07 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:18:07 INFO - mozversion platform_version: 50.0a2 14:18:07 INFO - using testdate: 1471987087 14:18:07 INFO - actual date: 1471987087 14:18:07 INFO - starting webserver on 'localhost:53470' 14:18:07 INFO - SUITE-START | Running 2 tests 14:18:07 INFO - TEST-START | dromaeo_css 14:18:07 INFO - operating with platform_type : linux_ 14:18:07 INFO - Initialising browser for dromaeo_css test... 14:18:07 INFO - TEST-INFO | started process 13057 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphkABKK/profile http://localhost:53470/getInfo.html) 14:18:11 INFO - PROCESS | 13057 | __metrics Screen width/height:1600/1200 14:18:11 INFO - PROCESS | 13057 | colorDepth:24 14:18:11 INFO - PROCESS | 13057 | Browser inner width/height: 1024/697 14:18:11 INFO - PROCESS | 13057 | __metrics 14:18:12 INFO - PROCESS | 13057 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:18:12 INFO - PROCESS | 13057 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:18:13 INFO - TEST-INFO | 13057: exit 0 14:18:13 INFO - Browser initialized. 14:18:13 INFO - Running cycle 1/1 for dromaeo_css test... 14:18:13 INFO - TEST-INFO | started process 13145 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphkABKK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:18:25 INFO - PROCESS | 13145 | RSS: Main: 144871424 14:18:25 INFO - PROCESS | 13145 | 14:19:16 INFO - PROCESS | 13145 | 1471987156093 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 14:21:25 INFO - PROCESS | 13145 | Cycle 1(1): loaded http://localhost:53470/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53470/tests/dromaeo/cssquery-ext.html) 14:21:26 INFO - PROCESS | 13145 | RSS: Main: 275927040 14:21:26 INFO - PROCESS | 13145 | 14:24:29 INFO - PROCESS | 13145 | Cycle 1(1): loaded http://localhost:53470/tests/dromaeo/cssquery-ext.html (next: http://localhost:53470/tests/dromaeo/cssquery-jquery.html) 14:24:29 INFO - PROCESS | 13145 | RSS: Main: 235945984 14:24:29 INFO - PROCESS | 13145 | 14:27:31 INFO - PROCESS | 13145 | Cycle 1(1): loaded http://localhost:53470/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53470/tests/dromaeo/cssquery-mootools.html) 14:27:32 INFO - PROCESS | 13145 | RSS: Main: 176230400 14:27:32 INFO - PROCESS | 13145 | 14:30:34 INFO - PROCESS | 13145 | Cycle 1(1): loaded http://localhost:53470/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53470/tests/dromaeo/cssquery-prototype.html) 14:30:34 INFO - PROCESS | 13145 | RSS: Main: 220958720 14:30:34 INFO - PROCESS | 13145 | 14:33:37 INFO - PROCESS | 13145 | Cycle 1(1): loaded http://localhost:53470/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53470/tests/dromaeo/cssquery-yui.html) 14:33:37 INFO - PROCESS | 13145 | RSS: Main: 175656960 14:33:37 INFO - PROCESS | 13145 | 14:36:40 INFO - PROCESS | 13145 | Cycle 1(1): loaded http://localhost:53470/tests/dromaeo/cssquery-yui.html (next: http://localhost:53470/tests/dromaeo/cssquery-dojo.html) 14:36:40 INFO - PROCESS | 13145 | RSS: Main: 267386880 14:36:40 INFO - PROCESS | 13145 | 14:36:40 INFO - PROCESS | 13145 | __start_tp_report 14:36:40 INFO - PROCESS | 13145 | _x_x_mozilla_page_load 14:36:40 INFO - PROCESS | 13145 | _x_x_mozilla_page_load_details 14:36:40 INFO - PROCESS | 13145 | |i|pagename|runs| 14:36:40 INFO - PROCESS | 13145 | |0;dojo.html;3275.9017229483125;3285.4414749492585;3289.934201315973;3292.670732926709;3319.7178239849613;5669.319681638204;5779.3642699303045;5784.971075144618;5815.970920145393;5845.2985641723;5746.195532625426;5798.9130163047585;5806.651600903949;5812.883742325151;5853.473187413132;5117.257997590347;5193.896122077556;5232.947670523294;5299.5230429261355;5340.439253878345;5245.86885327866;5307.548858347026;5308.840734777963;5310.495502927216;5318.627696061278;5163.1480805667015;5215.530602245797;5228.08508511009;5232.3459567554055;5270.920936185941;5323.520883120537;5326.280952071479;5329.227262046981;5340.172273297645;5358.169483730008;5058;5084.313617661605;5085.974570127126;5244.082285600005;5246.501582349709;5656.802011929604;5708.1437784332265;5717.399673034354;5718.399568045337;5727.3699893011735;6227.844303892393;6230.750769969241;6232.158658581079;6239.625622462667;6240.500759939239;6010.669413182273;6013.909791353133;6043.969780151115;6047.667378294192;6248.968755156195;5700.7149642518125;5739.282589676291;5757.596968212227;5758.309002919635;5762.394948530344;6133.846653833601;6174.475169610543;6176.845578860564;6180;6194.721237544321;5893.174955506232;5981.431763982502;6072.423119803611;6076.422739839708;6141.915626108677;6243.250809902841;6249.96875015622;6251.562390632703;6258.843528911722;6270.874582508324;6144.324124346318;6183.567150299526;6186.505079593621;6207.96896015517;6216.844578885564;6030.065339872327;6070.878582428327;6079.270487541523;6118.558266176196;6144.446999770043;4725.716457012591;4769.213079841857;4774.856754297377;4797.664163508521;4805.951940480551;4238.788060596958;4248.129133527619;4252.957470425318;4258.169656916872;4279.7218180818145;5764.74058667361;5845.824625261249;5859.062549991981;5884.2350494436305;5920.31916329619;6272.3100458949475;6282.685865706696;6283.560150789493;6289.811305660837;6291.339409362043;5946.226990491295;5951.018082016419;5953.493953014043;5967.492763115097;5988.940110598838;5204.297419848348;5261.342332208474;5343.198520221998;5355.196720491957;5365.1147560652835;6437.613743175424;6474.6762661866715;6496;6496.382843629847;6498.902516462257;6182.783602573888;6223.688815559294;6237.282712488122;6238.469730072995;6239.313675495782;6248.281447633581;6384.361563843579;6432.35676432353;6501;6517.967410162919;6138.878900475427;6147.477464415396;6154.353792851686;6157.938420615916;6158.04550294705;6241.21984751906;6265.4361107500545;6267.059941008885;6267.467265282502;6281.057841323838;5915.08316210988;6003.669798161143;6013.609115407485;6162.938370616236;6171.228596425447;6033.939660603513;6080.2399700037495;6083.422074902877;6107.206063211754;6120.296165940796;5964.254468191476;6011.188489553854;6026.969865150646;6031.396860313934;6037.78867739627;5939.198208241832;6012.819615411545;6093.9390606093375;6102.786402475892;6112.938870611237;5977.701114944322;5978.252718410199;5981.1327357532045;6001.849953751192;6018.849528761816 14:36:40 INFO - PROCESS | 13145 | |1;ext.html;608.3672980100696;633.7971849008318;671.5366397185942;674.8785218660643;685.8902575587907;35392.823035884816;39371.60628393715;39442.60557394425;46220.768896155554;47188.52811471893;50072.74963625177;50404.74797626016;50550.49449505504;50567.49432505674;50596.241056384184;9346.392484488504;9485.525723713821;9503.619855205783;9504.57229424674;9507.619695212183;8526.57367131644;8741.30069594431;8750.431221970583;8973.147550982647;9347.766305842375;11160.441977901113;11528.308301501937;11538.480768365444;11998.820017699742;12004;11412.71468213293;11433.256838305484;11457.14071444645;11506.539738410453;11519.424028798569;8444.324454043723;10969.890301096966;11192.38441885696;11259.943700281528;11273.37996410197;42031.1593768126;46439.30341044887;46584.5341546587;46584.76707616474;46606.300905486445;4024.9597504024878;4094.0992981544014;4465.531119232466;4501.617362524189;4637.234856248715;35352;35496.822515887514;35793.28413431743;35898;36132.45801312982;4683.531646835323;4788.090262850047;4855.562999330042;4993.775280112403;5041.546260836506;380826;381402;383241;383514.9999999972;383813;63003.6849815748;63422.365776342565;63437.68281158565;63445;63456.682716586125;65600.70397536064;65692.34307656862;65797.6710116456;65966.67016664887;66053;17396.130193490528;17615.82384176142;17626;17626.735598966025;17642.911785440992;13659.931700341434;13668.316584170752;13718.725625487434;13719.314034298246;13755;15163.545093647208;16085.437009704605;16350.75473867893;16859.747103793452;16875.409360672318;6673.1992160941;6783.762568310184;6802.965985170142;6803.387695107464;6836.547543490506;1012.6658202793061;1099.2470157941837;1134.8581427321585;1189.0606420927543;1192.9105317101253;34771.478427823604;34909;35353;35695.6216622455;35971.82014089913;1153.9826902596467;1239.5227837282712;1255.265669583304;1261.9053570982212;1272.1730874931368;17642.70492332626;18182.81817181811;18223.817761822476;18246.908765456352;18435.354762583243;596033;596198;598547;599344;600704;55811.44188558062;55872;56416.43583564112;62411;63742.6812865942;1408.2465880753748;1435.174774504664;1441.1641248076091;1476.9852301477063;1482.0662982321069;34068;36254.274914502086;36270.4559431614;36337;36383.63616363909;55635.44364556464;60142.39857601543;61774.38225617686;62071.6896415515;67193;427711;430414;431399;432417;589483;28821;30440.69559304467;30472.238194045327;30690.84654576802;30796.692033079384;52380;52591;52782.7273855317;52984.735076324374;53874.730626346616;44140.77929610331;44470.777646111565;45179.322310164716;46060.769696152645;48287.5171248283;44120.33819492646;45587;46422;46844;47473;44202;44377;45495.31757023581;45781.31328030016;46118.7694061541;37039.629603703615;39811.40282895817;40175.59824401719;40772.184556308115;40907.795461022506;29585.408291833613;30028.849855751454;30209.848950755986;30453.695463045973;30585.388292233587 14:36:40 INFO - PROCESS | 13145 | |2;jquery.html;399.1025157357583;415.5989470161294;424.1284161049045;426.27533193570935;438.3731264292063;15621.921890390546;20655.793442065577;21698.674519882195;21723.13107475701;22051.22820701276;20878.582428351423;20914.686279705817;20920.790792092073;20937;21021.26425575105;5733.856653583652;5755.39568345324;6057.666828324441;6099.603525770811;6189.226346706661;5965.254343207099;6096.512279017668;6113.32753397126;6128.662923539204;6135.263768347804;6124.2650881894015;6159.784407545737;6163.63018218906;6165.969170154143;6166.660833654169;6067.643057541949;6133.539984501158;6152.015677491602;6625.900611490832;6744.393004629558;5970.761169553212;6011.969940150271;6019.548533859935;6026.276846778371;6028.577999540034;14886;15208.08751474915;15489.225538723018;15580.688386232212;19001.8598884066;2421.370443684637;2435.3059378077164;2688.1397952655157;2787.5818627205877;3029.303260250155;10864.293820901696;11082.501287442085;11245.662630121109;11333.093352531778;11381.487833047533;2012.23535056679;2022.7572691276994;2047.2118234479683;2108;2108.3674897530677;61684;77473.61263193704;86183;91178.5441072797;93870.53064734701;11409.543618255177;11626.546681664791;11681.065514758799;11903.285802851857;12256.08079394049;16834.831651683326;16853.662926741395;16857.662846743242;16866.578335541708;16886.240119194666;8658.134186581417;8661.090585488433;8696.260817830429;8831.91168088311;8887.600057997406;8344.499330040217;8427.241548260687;8441.451305665112;8458.915410845935;8625.309975201968;5081.110805609003;5131.640785145079;5141.845744627667;5191.195364718475;5281.55106815925;2084.1871670048695;2109.230131002204;2136.6474531702406;2152.8815915124505;2178.9782102178774;955.2405837359316;1005.4922264256504;1017.8829434615114;1037.2376303416982;1040.958361665525;20725.58548829015;21461.67807482889;21689.240876569245;21702.348929532138;21715.13139474435;1018.022698209727;1050.1231471721046;1052.905238528536;1067.2209287220373;1067.9733006674896;9798.559064842098;11895.167338286237;11967.521299148111;12029;12053.457594408274;552143;552159;553936;563734;567240;91830;92320.53839730758;92378.53810730904;92975.53512232531;94515;1012.5443550402201;1030.7114008077751;1048.6120135549895;1058.6030238660503;1059.9364038157735;17829.643407132044;17938.91030544839;18351.724724129417;19057.523561911064;19391.2243510258;42963.35554966616;42987.35518967156;43224.56775432331;43387.56612433962;45137.32294015527;55455.168172478254;63672.36327636665;63975;64421;65613;8668.30653547702;8673.436226645248;8698.304135669005;8713.477191368538;9050.411723237969;13477;14314.212718300178;14383.28083595795;14448.422063117358;14940.178290194144;11113.944430277797;11156.888431115585;11352.829707554558;11449.942750286194;11849.407529623657;12006.159568830099;12238;12458.937705311415;12550.246985180553;12722.300273485047;11321.320720756781;11388;11401.258918170292;11542.249753765978;11677.941610291895;8852.955735221283;8925.687600934196;9055.094490550891;9384.108509691569;9568.282378821477;9881;10622.25644204929;10690.19823513224;10758;11048.613298534512 14:36:40 INFO - PROCESS | 13145 | |3;mootools.html;1363.495506662535;1522.7563589825627;1580.1467207707838;1735.4012865561385;1864.2915692037016;9024;9478.336516443851;9523.42859428434;9800.264980126483;9832.557534910928;9691.079347461997;9786.804263914735;9797.804043919117;9819.509024548779;9820.754481137976;2473.406382468208;2519.533886231044;2524.924252272435;2526.2800101970943;2526.519961207374;2443.3402981195068;2538.733432989537;2539.771420572148;2542.224621490445;2542.250036239305;2486.042873493709;2501.949961000779;2526.2042456626195;2529.215943057649;2530.392705750625;2457.3610861176135;2468.456939473322;2476.578981573137;2480.913168039128;2503.6119401492797;2422.115927686382;2431.003288651662;2437.390652336916;2447.559439300925;2476.2695004973575;9567.712968610953;9682.612695492171;9710.805783884354;9803.460809655466;9803.705888823346;5538.6122971392015;5772.480476757112;5784.045632470637;5789.102689083199;5791.189233507313;3528.259065596228;3559.75081744278;3586.1751797086818;3605.6118394418336;3612.187257866985;1247.8502579690405;1248.931308778017;1249.5938819883575;1286.2604002698486;1301.9739605207938;13841.961852861075;13980.161190328512;13981;13981.370838312301;13995.860041399557;4806.351142595775;4832.081904438216;4846.61227101831;4958.628102892298;4997.90004199914;10653.094486968694;10753.731156721145;10770.030697237285;10783.406912619725;10787.83818242727;5588.860278492989;5610.607257492018;5628.352739435018;5629.043062679354;5629.099344105005;4541;4627.745473998963;5170.560502357342;5243.68537887728;5271.446498117719;4167.354060120686;4173.384810338799;4245.3631955206965;4245.575442455792;4274.572542745701;1232.3468561662332;1242.2794779028143;1247.7629250442387;1253.486070711004;1268.2707443220186;951.871497347863;1000.3697670467559;1003.4130033930087;1016.9949150254201;1031.56674196836;6141.785037523665;6254.718537665816;6255.280642726054;6395.360463953567;6446.7421303148285;490.15693008026136;493.91603427417425;503.94708555601136;505.2320472881273;508.54231191926823;4272;4525.253333200051;4546.977265113654;4560.17916774977;4597.655175861823;12100.33448160342;12311.50753969849;12319.199252048587;12341.876581234252;12414.441350139265;12279.693007674701;12380.876191238152;12391.442385092692;12399.628011159679;12402.689932751573;8590.699325523577;8952.373333866697;8970.820583588422;9093.363464557417;9171.128742769559;6199.380061993765;6411.6794160290865;6451.225852897683;6490.48076153916;6513.534454747644;10567.365958042543;10674.62638807638;10718.24972251935;10722.19583531254;11023.44882755875;11090;11459.255148415326;11469.598564050219;11516.42417879086;11580.131490138105;7819.452638315263;8343.833123337621;8644.265237454636;8706.303495720455;8973.37186396946;9562.235021198427;9630.759231019281;9645;9652.2278217741;9694.854577181206;8523.659053637784;8581.95709021451;8602.483850968962;8641.308695304486;8831.205191532792;8239.093699693149;8241.629126689193;8401.579921004048;8490.193431623986;8494.57527123629;7707.576083315247;7774.572398518136;7836.568988705448;7840.686372545034;7876.291133797986;5479.835604931858;5562.11006239016;5606.159076138449;5705.286839145107;5741.5406767459335;5266.394364647961;5436;5640.266765320482;5655.4910058094965;5705.714714264187 14:36:40 INFO - PROCESS | 13145 | |4;prototype.html;340.16150189782195;346.5512161750533;354.55680399500625;358.10652422206596;359.91721903962105;5541.113421852499;5569.331680198382;5573.8327850164505;5573.860653483665;5589.105743081102;5218.495122341278;5303.443138470453;5406.351237851463;5459.099248623972;5498.752556134974;4357.847475338363;4357.978210108953;4358.629516491102;4367.606915377615;4389.517153113155;4336.176126535964;4359.237133501624;4369.016971181474;4389.648828093761;4408.757518336491;4135.896602584944;4294.291441912081;4312.202242585116;4317.222899878021;4341.522432532434;4285.57144285572;4288.935665965013;4296.677749168824;4302.41917341158;4307.741535507879;3822.48396466479;3836.251930873481;3839.3298914972074;3839.8864329344456;3844.154286057063;5235.607329450307;5295.973520132413;5371.59713021521;5389.8113566025;5390.434004429518;4936.728479933602;4955.776990035457;4967.130752118364;4975.502449755032;4978.377702787152;5754.424557544254;5765.682887441189;5774.595778295521;5774.7112644368035;5810.796622118206;4115.218108559394;4146.647534959532;4219.472565929259;4221.535631080578;4236.216299984513;5961.344252132262;5972.343042265347;5984.97007514964;5990.281166260033;5996.280446346423;4286.164197981426;4293.570642935744;4301.2257793597455;4307.353896915425;4314.158739045856;4245.341971994346;4291.8841101313255;4323;4326.329418940069;4347.152305300436;4892.657513974059;4906.803727850917;4918.754062296871;4921.655484116078;4923.212286034289;5744.684042377709;5773.16289138081;5777.335606405233;5784.4504772046585;5792.391798861143;1398.797174409705;1451.7894905238682;1453.5566652170978;1497.951433996207;1499.422722251941;995.5320999130397;1004.5684899018869;1011.0344620887035;1021.2544842265039;1028;5619.7471113799975;5638.464345887134;5641.5204707600315;5643.097104463266;5652.756393593403;5844.970775146097;5871.882562348729;5884.852878678067;5899.498542623839;5918.940810591925;5445.863853403696;5558.833235002957;5569.972150139223;5603.131514615241;5652.7738890443925;2731.811661927028;2758.7930905182193;2766.5573508238585;2766.9169924902285;2804;5384.119496069662;5394.298741163702;5411.5129638331955;5414.675119492765;5418.864528386822;5298.337707786527;5654.547636189095;6054;6088.330283668793;6091.172648205521;6194.287656919512;6209.720562574695;6229.595076320115;6254.655993920287;6264.9373506265265;5718.285214348207;5951.821445356646;6004.939950600438;6019.939800601938;6030.7889223876955;5912.216181643666;5914.526837852875;5924.7926322578505;5951.434613711691;5959.195508606283;6126.754929802758;6240.656763878031;6253.906191407224;6254.3745625437095;6257.53068519872;4291.249031419551;4298.892527686833;4300.247456695129;4307.310830267143;4325.783710814384;5935.2877654681715;5964.612300200473;5996.830618029617;6034.728437220423;6035.637861728311;5669.971650141888;5767.192593036894;5815.883682326415;5864.354920958776;5879.20630714848;5828.329742079715;5832.358440571618;5838.927180850907;5840.503557197686;5842.1820945068575;5598.272224610774;5728.828135155952;5774.220480235114;5805.94194058054;5807.477327040418;5833.270841144857;5837.737301821513;5838.211841401527;5848.210491583582;5852.313042783019;5574.299838549281;5694.4590263924865;5710.314762228559;5714.714264286852;5762.625429347079 14:36:40 INFO - PROCESS | 13145 | |5;yui.html;806.0247101007781;848.262012049517;874.1258741258737;893.4282059481932;917.2625229191409;16247.431339903105;16878.662426751474;16887.493375198734;16937.91531042346;17084.743728844078;16244.269007894643;16488.34046638133;16527.83472165281;16542.751858722128;16550.586235344123;744.292921724364;869.3088994249587;882.6601758323028;891.7815135291862;897.712731925784;901.833160865242;902.6569903436684;904.3804993579387;904.4708845325492;904.7421484876811;948.1182500274776;956.5456408206117;956.6699488676395;958.0706714486937;959.0122174160625;774.1639029847751;912.1243606138115;932.8274269260174;943.6791490893129;946.9195118414943;691.4744793956629;696.4324075878143;696.6969368324795;696.7491702986921;696.8675951569186;15750.291236894367;15799.605009874846;15822.446214382442;15829.28768205422;15830;15735.92132039344;16155.676886462204;16168.514944551682;16341.918290408472;16811;14385.280735963264;14697.118172909659;14719.632009199855;14776.261186940717;14781.187034713086;4583.289590113538;4600.263957766741;4604.79278432468;4717.033008233304;4755.239161734141;89296.55351723265;89619.55190224072;89872;91590.54204729;94492;12159.148859579744;12180.390980450942;12348;12937.935310323577;13367.532136375181;22324.776752232596;22497.88751056267;22559.661605075937;22579.548409032057;22623;8837.204651581389;8957.641694332284;8983.640654373883;9013.18881300686;9257.583408746623;5106.361704786902;5121.513456221653;5134.486551344836;5172.948270517247;5348.037353276448;3887.2031233597045;4014.1971605678395;4016.6786657067364;4039.2527382434346;4049.4330793688905;710.7903168565285;714.118064190724;714.8427345983876;736.7716008037419;756.765402725157;707.7628994286972;775.9030121234846;788.6529926832176;792.1523969352852;802.3220378779922;10420.166386689047;10485.790284194425;10570.989195342478;11155.442227888572;11273.54905803775;746.2462912458456;757.257887270478;766.6971546239316;798.2017982017982;829.3903980574273;4859.222524396079;5222.3472065991755;5247.816326428633;5263.36839579253;5273.947260527346;130255;137324;139176;142245.28877355548;142699;96112;120296;120602.76383416848;121586;123867.3806630961;81731.18268817355;83810.58094709487;96957.51521242349;98638;99275.50362248142;10012.84980725275;10039.80561673714;10542.367457952547;11106.888931110585;11111.277766945122;51999.48000519946;58810;59948;60858.39141608704;63665;54201.728991354794;80393.59803201181;82007.58996204981;82943;82987;15919.681606367576;15927.761083583524;16208.270627822012;16221.188940553162;16554.172291385625;17104.57238569046;17248.396306129227;18091.36680216186;18557.9072104644;18668.439946801616;15051.172185529464;15946.202689865693;15970.02332621994;16125.919370403073;16411.343546258016;15035.097894126387;15092.698146037237;15157.924210378878;16187.514374568782;16204.594885127495;15416.383344666536;15539;15809.525714228592;16625.50123496297;16663.750043749595;11867.05063594893;11894.524219031488;12039.939800300943;12528.74942501163;12639.115261931944;11896.881031189578;12700.491980320683;12802.487900483877;12927.095103342675;12996.220226786423 14:36:40 INFO - PROCESS | 13145 | __end_tp_report 14:36:40 INFO - PROCESS | 13145 | __start_cc_report 14:36:40 INFO - PROCESS | 13145 | _x_x_mozilla_cycle_collect,1130 14:36:40 INFO - PROCESS | 13145 | __end_cc_report 14:36:40 INFO - PROCESS | 13145 | __startTimestamp1471988200940__endTimestamp 14:36:40 INFO - PROCESS | 13145 | 14:36:40 INFO - PROCESS | 13145 | ------- Summary: start ------- 14:36:40 INFO - PROCESS | 13145 | Number of tests: 6 14:36:40 INFO - PROCESS | 13145 | 14:36:40 INFO - PROCESS | 13145 | [#0] dojo.html Cycles:165 Average:5750.76 Median:5988.94 stddev:661.15 (11.0%) stddev-sans-first:634.03 14:36:40 INFO - PROCESS | 13145 | Values: 3275.9 3285.4 3289.9 3292.7 3319.7 5669.3 5779.4 5785.0 5816.0 5845.3 5746.2 5798.9 5806.7 5812.9 5853.5 5117.3 5193.9 5232.9 5299.5 5340.4 5245.9 5307.5 5308.8 5310.5 5318.6 5163.1 5215.5 5228.1 5232.3 5270.9 5323.5 5326.3 5329.2 5340.2 5358.2 5058.0 5084.3 5086.0 5244.1 5246.5 5656.8 5708.1 5717.4 5718.4 5727.4 6227.8 6230.8 6232.2 6239.6 6240.5 6010.7 6013.9 6044.0 6047.7 6249.0 5700.7 5739.3 5757.6 5758.3 5762.4 6133.8 6174.5 6176.8 6180.0 6194.7 5893.2 5981.4 6072.4 6076.4 6141.9 6243.3 6250.0 6251.6 6258.8 6270.9 6144.3 6183.6 6186.5 6208.0 6216.8 6030.1 6070.9 6079.3 6118.6 6144.4 4725.7 4769.2 4774.9 4797.7 4806.0 4238.8 4248.1 4253.0 4258.2 4279.7 5764.7 5845.8 5859.1 5884.2 5920.3 6272.3 6282.7 6283.6 6289.8 6291.3 5946.2 5951.0 5953.5 5967.5 5988.9 5204.3 5261.3 5343.2 5355.2 5365.1 6437.6 6474.7 6496.0 6496.4 6498.9 6182.8 6223.7 6237.3 6238.5 6239.3 6248.3 6384.4 6432.4 6501.0 6518.0 6138.9 6147.5 6154.4 6157.9 6158.0 6241.2 6265.4 6267.1 6267.5 6281.1 5915.1 6003.7 6013.6 6162.9 6171.2 6033.9 6080.2 6083.4 6107.2 6120.3 5964.3 6011.2 6027.0 6031.4 6037.8 5939.2 6012.8 6093.9 6102.8 6112.9 5977.7 5978.3 5981.1 6001.8 6018.8 14:36:40 INFO - PROCESS | 13145 | 14:36:40 INFO - PROCESS | 13145 | [#1] ext.html Cycles:180 Average:65699.42 Median:429062.50 stddev:130159.70 (30.3%) stddev-sans-first:130433.08 14:36:40 INFO - PROCESS | 13145 | Values: 608.4 633.8 671.5 674.9 685.9 35392.8 39371.6 39442.6 46220.8 47188.5 50072.7 50404.7 50550.5 50567.5 50596.2 9346.4 9485.5 9503.6 9504.6 9507.6 8526.6 8741.3 8750.4 8973.1 9347.8 11160.4 11528.3 11538.5 11998.8 12004.0 11412.7 11433.3 11457.1 11506.5 11519.4 8444.3 10969.9 11192.4 11259.9 11273.4 42031.2 46439.3 46584.5 46584.8 46606.3 4025.0 4094.1 4465.5 4501.6 4637.2 35352.0 35496.8 35793.3 35898.0 36132.5 4683.5 4788.1 4855.6 4993.8 5041.5 380826.0 381402.0 383241.0 383515.0 383813.0 63003.7 63422.4 63437.7 63445.0 63456.7 65600.7 65692.3 65797.7 65966.7 66053.0 17396.1 17615.8 17626.0 17626.7 17642.9 13659.9 13668.3 13718.7 13719.3 13755.0 15163.5 16085.4 16350.8 16859.7 16875.4 6673.2 6783.8 6803.0 6803.4 6836.5 1012.7 1099.2 1134.9 1189.1 1192.9 34771.5 34909.0 35353.0 35695.6 35971.8 1154.0 1239.5 1255.3 1261.9 1272.2 17642.7 18182.8 18223.8 18246.9 18435.4 596033.0 596198.0 598547.0 599344.0 600704.0 55811.4 55872.0 56416.4 62411.0 63742.7 1408.2 1435.2 1441.2 1477.0 1482.1 34068.0 36254.3 36270.5 36337.0 36383.6 55635.4 60142.4 61774.4 62071.7 67193.0 427711.0 430414.0 431399.0 432417.0 589483.0 28821.0 30440.7 30472.2 30690.8 30796.7 52380.0 52591.0 52782.7 52984.7 53874.7 44140.8 44470.8 45179.3 46060.8 48287.5 44120.3 45587.0 46422.0 46844.0 47473.0 44202.0 44377.0 45495.3 45781.3 46118.8 37039.6 39811.4 40175.6 40772.2 40907.8 29585.4 30028.8 30209.8 30453.7 30585.4 14:36:40 INFO - PROCESS | 13145 | 14:36:40 INFO - PROCESS | 13145 | [#2] jquery.html Cycles:180 Average:31359.80 Median:12115.10 stddev:91697.24 (756.9%) stddev-sans-first:91925.01 14:36:40 INFO - PROCESS | 13145 | Values: 399.1 415.6 424.1 426.3 438.4 15621.9 20655.8 21698.7 21723.1 22051.2 20878.6 20914.7 20920.8 20937.0 21021.3 5733.9 5755.4 6057.7 6099.6 6189.2 5965.3 6096.5 6113.3 6128.7 6135.3 6124.3 6159.8 6163.6 6166.0 6166.7 6067.6 6133.5 6152.0 6625.9 6744.4 5970.8 6012.0 6019.5 6026.3 6028.6 14886.0 15208.1 15489.2 15580.7 19001.9 2421.4 2435.3 2688.1 2787.6 3029.3 10864.3 11082.5 11245.7 11333.1 11381.5 2012.2 2022.8 2047.2 2108.0 2108.4 61684.0 77473.6 86183.0 91178.5 93870.5 11409.5 11626.5 11681.1 11903.3 12256.1 16834.8 16853.7 16857.7 16866.6 16886.2 8658.1 8661.1 8696.3 8831.9 8887.6 8344.5 8427.2 8441.5 8458.9 8625.3 5081.1 5131.6 5141.8 5191.2 5281.6 2084.2 2109.2 2136.6 2152.9 2179.0 955.2 1005.5 1017.9 1037.2 1041.0 20725.6 21461.7 21689.2 21702.3 21715.1 1018.0 1050.1 1052.9 1067.2 1068.0 9798.6 11895.2 11967.5 12029.0 12053.5 552143.0 552159.0 553936.0 563734.0 567240.0 91830.0 92320.5 92378.5 92975.5 94515.0 1012.5 1030.7 1048.6 1058.6 1059.9 17829.6 17938.9 18351.7 19057.5 19391.2 42963.4 42987.4 43224.6 43387.6 45137.3 55455.2 63672.4 63975.0 64421.0 65613.0 8668.3 8673.4 8698.3 8713.5 9050.4 13477.0 14314.2 14383.3 14448.4 14940.2 11113.9 11156.9 11352.8 11449.9 11849.4 12006.2 12238.0 12458.9 12550.2 12722.3 11321.3 11388.0 11401.3 11542.2 11677.9 8853.0 8925.7 9055.1 9384.1 9568.3 9881.0 10622.3 10690.2 10758.0 11048.6 14:36:40 INFO - PROCESS | 13145 | 14:36:40 INFO - PROCESS | 13145 | [#3] mootools.html Cycles:180 Average:6332.16 Median:4717.05 stddev:3716.90 (78.8%) stddev-sans-first:3708.57 14:36:40 INFO - PROCESS | 13145 | Values: 1363.5 1522.8 1580.1 1735.4 1864.3 9024.0 9478.3 9523.4 9800.3 9832.6 9691.1 9786.8 9797.8 9819.5 9820.8 2473.4 2519.5 2524.9 2526.3 2526.5 2443.3 2538.7 2539.8 2542.2 2542.3 2486.0 2501.9 2526.2 2529.2 2530.4 2457.4 2468.5 2476.6 2480.9 2503.6 2422.1 2431.0 2437.4 2447.6 2476.3 9567.7 9682.6 9710.8 9803.5 9803.7 5538.6 5772.5 5784.0 5789.1 5791.2 3528.3 3559.8 3586.2 3605.6 3612.2 1247.9 1248.9 1249.6 1286.3 1302.0 13842.0 13980.2 13981.0 13981.4 13995.9 4806.4 4832.1 4846.6 4958.6 4997.9 10653.1 10753.7 10770.0 10783.4 10787.8 5588.9 5610.6 5628.4 5629.0 5629.1 4541.0 4627.7 5170.6 5243.7 5271.4 4167.4 4173.4 4245.4 4245.6 4274.6 1232.3 1242.3 1247.8 1253.5 1268.3 951.9 1000.4 1003.4 1017.0 1031.6 6141.8 6254.7 6255.3 6395.4 6446.7 490.2 493.9 503.9 505.2 508.5 4272.0 4525.3 4547.0 4560.2 4597.7 12100.3 12311.5 12319.2 12341.9 12414.4 12279.7 12380.9 12391.4 12399.6 12402.7 8590.7 8952.4 8970.8 9093.4 9171.1 6199.4 6411.7 6451.2 6490.5 6513.5 10567.4 10674.6 10718.2 10722.2 11023.4 11090.0 11459.3 11469.6 11516.4 11580.1 7819.5 8343.8 8644.3 8706.3 8973.4 9562.2 9630.8 9645.0 9652.2 9694.9 8523.7 8582.0 8602.5 8641.3 8831.2 8239.1 8241.6 8401.6 8490.2 8494.6 7707.6 7774.6 7836.6 7840.7 7876.3 5479.8 5562.1 5606.2 5705.3 5741.5 5266.4 5436.0 5640.3 5655.5 5705.7 14:36:40 INFO - PROCESS | 13145 | 14:36:40 INFO - PROCESS | 13145 | [#4] prototype.html Cycles:180 Average:4863.97 Median:5519.93 stddev:1435.61 (26.0%) stddev-sans-first:1398.90 14:36:40 INFO - PROCESS | 13145 | Values: 340.2 346.6 354.6 358.1 359.9 5541.1 5569.3 5573.8 5573.9 5589.1 5218.5 5303.4 5406.4 5459.1 5498.8 4357.8 4358.0 4358.6 4367.6 4389.5 4336.2 4359.2 4369.0 4389.6 4408.8 4135.9 4294.3 4312.2 4317.2 4341.5 4285.6 4288.9 4296.7 4302.4 4307.7 3822.5 3836.3 3839.3 3839.9 3844.2 5235.6 5296.0 5371.6 5389.8 5390.4 4936.7 4955.8 4967.1 4975.5 4978.4 5754.4 5765.7 5774.6 5774.7 5810.8 4115.2 4146.6 4219.5 4221.5 4236.2 5961.3 5972.3 5985.0 5990.3 5996.3 4286.2 4293.6 4301.2 4307.4 4314.2 4245.3 4291.9 4323.0 4326.3 4347.2 4892.7 4906.8 4918.8 4921.7 4923.2 5744.7 5773.2 5777.3 5784.5 5792.4 1398.8 1451.8 1453.6 1498.0 1499.4 995.5 1004.6 1011.0 1021.3 1028.0 5619.7 5638.5 5641.5 5643.1 5652.8 5845.0 5871.9 5884.9 5899.5 5918.9 5445.9 5558.8 5570.0 5603.1 5652.8 2731.8 2758.8 2766.6 2766.9 2804.0 5384.1 5394.3 5411.5 5414.7 5418.9 5298.3 5654.5 6054.0 6088.3 6091.2 6194.3 6209.7 6229.6 6254.7 6264.9 5718.3 5951.8 6004.9 6019.9 6030.8 5912.2 5914.5 5924.8 5951.4 5959.2 6126.8 6240.7 6253.9 6254.4 6257.5 4291.2 4298.9 4300.2 4307.3 4325.8 5935.3 5964.6 5996.8 6034.7 6035.6 5670.0 5767.2 5815.9 5864.4 5879.2 5828.3 5832.4 5838.9 5840.5 5842.2 5598.3 5728.8 5774.2 5805.9 5807.5 5833.3 5837.7 5838.2 5848.2 5852.3 5574.3 5694.5 5710.3 5714.7 5762.6 14:36:40 INFO - PROCESS | 13145 | 14:36:40 INFO - PROCESS | 13145 | [#5] yui.html Cycles:180 Average:23660.87 Median:4015.44 stddev:34648.75 (862.9%) stddev-sans-first:34703.45 14:36:40 INFO - PROCESS | 13145 | Values: 806.0 848.3 874.1 893.4 917.3 16247.4 16878.7 16887.5 16937.9 17084.7 16244.3 16488.3 16527.8 16542.8 16550.6 744.3 869.3 882.7 891.8 897.7 901.8 902.7 904.4 904.5 904.7 948.1 956.5 956.7 958.1 959.0 774.2 912.1 932.8 943.7 946.9 691.5 696.4 696.7 696.7 696.9 15750.3 15799.6 15822.4 15829.3 15830.0 15735.9 16155.7 16168.5 16341.9 16811.0 14385.3 14697.1 14719.6 14776.3 14781.2 4583.3 4600.3 4604.8 4717.0 4755.2 89296.6 89619.6 89872.0 91590.5 94492.0 12159.1 12180.4 12348.0 12937.9 13367.5 22324.8 22497.9 22559.7 22579.5 22623.0 8837.2 8957.6 8983.6 9013.2 9257.6 5106.4 5121.5 5134.5 5172.9 5348.0 3887.2 4014.2 4016.7 4039.3 4049.4 710.8 714.1 714.8 736.8 756.8 707.8 775.9 788.7 792.2 802.3 10420.2 10485.8 10571.0 11155.4 11273.5 746.2 757.3 766.7 798.2 829.4 4859.2 5222.3 5247.8 5263.4 5273.9 130255.0 137324.0 139176.0 142245.3 142699.0 96112.0 120296.0 120602.8 121586.0 123867.4 81731.2 83810.6 96957.5 98638.0 99275.5 10012.8 10039.8 10542.4 11106.9 11111.3 51999.5 58810.0 59948.0 60858.4 63665.0 54201.7 80393.6 82007.6 82943.0 82987.0 15919.7 15927.8 16208.3 16221.2 16554.2 17104.6 17248.4 18091.4 18557.9 18668.4 15051.2 15946.2 15970.0 16125.9 16411.3 15035.1 15092.7 15157.9 16187.5 16204.6 15416.4 15539.0 15809.5 16625.5 16663.8 11867.1 11894.5 12039.9 12528.7 12639.1 11896.9 12700.5 12802.5 12927.1 12996.2 14:36:40 INFO - PROCESS | 13145 | -------- Summary: end -------- 14:36:40 INFO - PROCESS | 13145 | 14:36:41 INFO - TEST-INFO | 13145: exit 0 14:36:41 INFO - TEST-OK | dromaeo_css | took 1114621ms 14:36:41 INFO - TEST-START | kraken 14:36:41 INFO - operating with platform_type : linux_ 14:36:41 INFO - Initialising browser for kraken test... 14:36:41 INFO - TEST-INFO | started process 13351 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYZFcIT/profile http://localhost:53470/getInfo.html) 14:36:46 INFO - PROCESS | 13351 | __metrics Screen width/height:1600/1200 14:36:46 INFO - PROCESS | 13351 | colorDepth:24 14:36:46 INFO - PROCESS | 13351 | Browser inner width/height: 1024/697 14:36:46 INFO - PROCESS | 13351 | __metrics 14:36:47 INFO - PROCESS | 13351 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:36:47 INFO - PROCESS | 13351 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:36:48 INFO - TEST-INFO | 13351: exit 0 14:36:48 INFO - Browser initialized. 14:36:48 INFO - Running cycle 1/1 for kraken test... 14:36:48 INFO - TEST-INFO | started process 13438 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYZFcIT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:37:00 INFO - PROCESS | 13438 | RSS: Main: 145330176 14:37:00 INFO - PROCESS | 13438 | 14:37:02 INFO - PROCESS | 13438 | Cycle 1(1): loaded http://localhost:53470/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53470/tests/kraken/driver.html?testName=audio-beat-detection) 14:37:03 INFO - PROCESS | 13438 | RSS: Main: 190976000 14:37:03 INFO - PROCESS | 13438 | 14:37:06 INFO - PROCESS | 13438 | Cycle 1(1): loaded http://localhost:53470/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53470/tests/kraken/driver.html?testName=audio-dft) 14:37:06 INFO - PROCESS | 13438 | RSS: Main: 181293056 14:37:07 INFO - PROCESS | 13438 | 14:37:09 INFO - PROCESS | 13438 | Cycle 1(1): loaded http://localhost:53470/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53470/tests/kraken/driver.html?testName=audio-fft) 14:37:10 INFO - PROCESS | 13438 | RSS: Main: 270540800 14:37:10 INFO - PROCESS | 13438 | 14:37:12 INFO - PROCESS | 13438 | Cycle 1(1): loaded http://localhost:53470/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53470/tests/kraken/driver.html?testName=audio-oscillator) 14:37:13 INFO - PROCESS | 13438 | RSS: Main: 175054848 14:37:13 INFO - PROCESS | 13438 | 14:37:15 INFO - PROCESS | 13438 | Cycle 1(1): loaded http://localhost:53470/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53470/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:37:15 INFO - PROCESS | 13438 | RSS: Main: 176590848 14:37:15 INFO - PROCESS | 13438 | 14:37:18 INFO - PROCESS | 13438 | Cycle 1(1): loaded http://localhost:53470/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53470/tests/kraken/driver.html?testName=imaging-darkroom) 14:37:19 INFO - PROCESS | 13438 | RSS: Main: 213409792 14:37:19 INFO - PROCESS | 13438 | 14:37:22 INFO - PROCESS | 13438 | Cycle 1(1): loaded http://localhost:53470/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53470/tests/kraken/driver.html?testName=imaging-desaturate) 14:37:23 INFO - PROCESS | 13438 | RSS: Main: 211656704 14:37:23 INFO - PROCESS | 13438 | 14:37:26 INFO - PROCESS | 13438 | Cycle 1(1): loaded http://localhost:53470/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53470/tests/kraken/driver.html?testName=json-parse-financial) 14:37:26 INFO - PROCESS | 13438 | RSS: Main: 211177472 14:37:26 INFO - PROCESS | 13438 | 14:37:28 INFO - PROCESS | 13438 | Cycle 1(1): loaded http://localhost:53470/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53470/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:37:28 INFO - PROCESS | 13438 | RSS: Main: 176193536 14:37:28 INFO - PROCESS | 13438 | 14:37:30 INFO - PROCESS | 13438 | Cycle 1(1): loaded http://localhost:53470/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53470/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:37:31 INFO - PROCESS | 13438 | RSS: Main: 181604352 14:37:31 INFO - PROCESS | 13438 | 14:37:33 INFO - PROCESS | 13438 | Cycle 1(1): loaded http://localhost:53470/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53470/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:37:33 INFO - PROCESS | 13438 | RSS: Main: 186019840 14:37:33 INFO - PROCESS | 13438 | 14:37:36 INFO - PROCESS | 13438 | Cycle 1(1): loaded http://localhost:53470/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53470/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:37:36 INFO - PROCESS | 13438 | RSS: Main: 180289536 14:37:36 INFO - PROCESS | 13438 | 14:37:39 INFO - PROCESS | 13438 | Cycle 1(1): loaded http://localhost:53470/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53470/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:37:40 INFO - PROCESS | 13438 | RSS: Main: 184369152 14:37:40 INFO - PROCESS | 13438 | 14:37:41 INFO - PROCESS | 13438 | Cycle 1(1): loaded http://localhost:53470/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53470/tests/kraken/driver.html?testName=ai-astar) 14:37:41 INFO - PROCESS | 13438 | RSS: Main: 235720704 14:37:41 INFO - PROCESS | 13438 | 14:37:41 INFO - PROCESS | 13438 | __start_tp_report 14:37:41 INFO - PROCESS | 13438 | _x_x_mozilla_page_load 14:37:41 INFO - PROCESS | 13438 | _x_x_mozilla_page_load_details 14:37:41 INFO - PROCESS | 13438 | |i|pagename|runs| 14:37:41 INFO - PROCESS | 13438 | |0;ai-astar;99;110;107;111;109;112;109;110;109;109 14:37:41 INFO - PROCESS | 13438 | |1;audio-beat-detection;154;116;117;177;116;119;150;114;156;114 14:37:41 INFO - PROCESS | 13438 | |2;audio-dft;162;159;160;160;159;160;160;159;170;161 14:37:41 INFO - PROCESS | 13438 | |3;audio-fft;75;75;75;113;77;76;74;75;111;75 14:37:41 INFO - PROCESS | 13438 | |4;audio-oscillator;83;84;124;84;83;84;121;83;84;119 14:37:41 INFO - PROCESS | 13438 | |5;imaging-gaussian-blur;116;117;117;116;117;118;117;118;117;117 14:37:41 INFO - PROCESS | 13438 | |6;imaging-darkroom;146;145;146;147;146;145;146;145;145;145 14:37:41 INFO - PROCESS | 13438 | |7;imaging-desaturate;94;94;95;94;95;95;95;94;94;95 14:37:41 INFO - PROCESS | 13438 | |8;json-parse-financial;52;50;49;50;50;51;51;51;49;50 14:37:41 INFO - PROCESS | 13438 | |9;json-stringify-tinderbox;59;59;59;58;59;58;58;59;58;59 14:37:41 INFO - PROCESS | 13438 | |10;stanford-crypto-aes;73;71;71;72;73;71;72;72;71;71 14:37:41 INFO - PROCESS | 13438 | |11;stanford-crypto-ccm;117;131;116;132;116;114;117;125;162;116 14:37:41 INFO - PROCESS | 13438 | |12;stanford-crypto-pbkdf2;154;152;151;153;149;151;158;149;153;154 14:37:41 INFO - PROCESS | 13438 | |13;stanford-crypto-sha256-iterative;65;61;63;61;62;60;63;61;60;62 14:37:41 INFO - PROCESS | 13438 | __end_tp_report 14:37:41 INFO - PROCESS | 13438 | __start_cc_report 14:37:41 INFO - PROCESS | 13438 | _x_x_mozilla_cycle_collect,1639 14:37:41 INFO - PROCESS | 13438 | __end_cc_report 14:37:41 INFO - PROCESS | 13438 | __startTimestamp1471988261886__endTimestamp 14:37:41 INFO - PROCESS | 13438 | 14:37:41 INFO - PROCESS | 13438 | ------- Summary: start ------- 14:37:41 INFO - PROCESS | 13438 | Number of tests: 14 14:37:41 INFO - PROCESS | 13438 | 14:37:41 INFO - PROCESS | 13438 | [#0] ai-astar Cycles:10 Average:108.50 Median:110.00 stddev:3.60 (3.3%) stddev-sans-first:1.42 14:37:41 INFO - PROCESS | 13438 | Values: 99.0 110.0 107.0 111.0 109.0 112.0 109.0 110.0 109.0 109.0 14:37:41 INFO - PROCESS | 13438 | 14:37:41 INFO - PROCESS | 13438 | [#1] audio-beat-detection Cycles:10 Average:133.30 Median:134.50 stddev:23.44 (17.4%) stddev-sans-first:23.64 14:37:41 INFO - PROCESS | 13438 | Values: 154.0 116.0 117.0 177.0 116.0 119.0 150.0 114.0 156.0 114.0 14:37:41 INFO - PROCESS | 13438 | 14:37:41 INFO - PROCESS | 13438 | [#2] audio-dft Cycles:10 Average:161.00 Median:160.00 stddev:3.30 (2.1%) stddev-sans-first:3.48 14:37:41 INFO - PROCESS | 13438 | Values: 162.0 159.0 160.0 160.0 159.0 160.0 160.0 159.0 170.0 161.0 14:37:41 INFO - PROCESS | 13438 | 14:37:41 INFO - PROCESS | 13438 | [#3] audio-fft Cycles:10 Average:82.60 Median:75.00 stddev:15.52 (20.7%) stddev-sans-first:16.22 14:37:41 INFO - PROCESS | 13438 | Values: 75.0 75.0 75.0 113.0 77.0 76.0 74.0 75.0 111.0 75.0 14:37:41 INFO - PROCESS | 13438 | 14:37:41 INFO - PROCESS | 13438 | [#4] audio-oscillator Cycles:10 Average:94.90 Median:83.50 stddev:18.28 (21.9%) stddev-sans-first:18.88 14:37:41 INFO - PROCESS | 13438 | Values: 83.0 84.0 124.0 84.0 83.0 84.0 121.0 83.0 84.0 119.0 14:37:41 INFO - PROCESS | 13438 | 14:37:41 INFO - PROCESS | 13438 | [#5] imaging-gaussian-blur Cycles:10 Average:117.00 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.60 14:37:41 INFO - PROCESS | 13438 | Values: 116.0 117.0 117.0 116.0 117.0 118.0 117.0 118.0 117.0 117.0 14:37:41 INFO - PROCESS | 13438 | 14:37:41 INFO - PROCESS | 13438 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 14:37:41 INFO - PROCESS | 13438 | Values: 146.0 145.0 146.0 147.0 146.0 145.0 146.0 145.0 145.0 145.0 14:37:41 INFO - PROCESS | 13438 | 14:37:41 INFO - PROCESS | 13438 | [#7] imaging-desaturate Cycles:10 Average:94.50 Median:95.00 stddev:0.53 (0.6%) stddev-sans-first:0.53 14:37:41 INFO - PROCESS | 13438 | Values: 94.0 94.0 95.0 94.0 95.0 95.0 95.0 94.0 94.0 95.0 14:37:41 INFO - PROCESS | 13438 | 14:37:41 INFO - PROCESS | 13438 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:50.50 stddev:0.95 (1.9%) stddev-sans-first:0.78 14:37:41 INFO - PROCESS | 13438 | Values: 52.0 50.0 49.0 50.0 50.0 51.0 51.0 51.0 49.0 50.0 14:37:41 INFO - PROCESS | 13438 | 14:37:41 INFO - PROCESS | 13438 | [#9] json-stringify-tinderbox Cycles:10 Average:58.60 Median:59.00 stddev:0.52 (0.9%) stddev-sans-first:0.53 14:37:41 INFO - PROCESS | 13438 | Values: 59.0 59.0 59.0 58.0 59.0 58.0 58.0 59.0 58.0 59.0 14:37:41 INFO - PROCESS | 13438 | 14:37:41 INFO - PROCESS | 13438 | [#10] stanford-crypto-aes Cycles:10 Average:71.70 Median:72.00 stddev:0.82 (1.1%) stddev-sans-first:0.73 14:37:41 INFO - PROCESS | 13438 | Values: 73.0 71.0 71.0 72.0 73.0 71.0 72.0 72.0 71.0 71.0 14:37:41 INFO - PROCESS | 13438 | 14:37:41 INFO - PROCESS | 13438 | [#11] stanford-crypto-ccm Cycles:10 Average:124.60 Median:121.00 stddev:14.70 (12.1%) stddev-sans-first:15.33 14:37:41 INFO - PROCESS | 13438 | Values: 117.0 131.0 116.0 132.0 116.0 114.0 117.0 125.0 162.0 116.0 14:37:41 INFO - PROCESS | 13438 | 14:37:41 INFO - PROCESS | 13438 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.40 Median:153.00 stddev:2.67 (1.7%) stddev-sans-first:2.77 14:37:41 INFO - PROCESS | 13438 | Values: 154.0 152.0 151.0 153.0 149.0 151.0 158.0 149.0 153.0 154.0 14:37:41 INFO - PROCESS | 13438 | 14:37:41 INFO - PROCESS | 13438 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.80 Median:62.00 stddev:1.55 (2.5%) stddev-sans-first:1.13 14:37:41 INFO - PROCESS | 13438 | Values: 65.0 61.0 63.0 61.0 62.0 60.0 63.0 61.0 60.0 62.0 14:37:41 INFO - PROCESS | 13438 | -------- Summary: end -------- 14:37:41 INFO - PROCESS | 13438 | 14:37:42 INFO - TEST-INFO | 13438: exit 0 14:37:42 INFO - TEST-OK | kraken | took 61282ms 14:37:42 INFO - SUITE-END | took 1175s 14:37:43 INFO - Completed test suite (00:19:35) 14:37:43 INFO - javascript benchmark 14:37:43 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3275.9017229483125, 3285.4414749492585, 3289.934201315973, 3292.670732926709, 3319.7178239849613, 5669.319681638204, 5779.3642699303045, 5784.971075144618, 5815.970920145393, 5845.2985641723, 5746.195532625426, 5798.9130163047585, 5806.651600903949, 5812.883742325151, 5853.473187413132, 5117.257997590347, 5193.896122077556, 5232.947670523294, 5299.5230429261355, 5340.439253878345, 5245.86885327866, 5307.548858347026, 5308.840734777963, 5310.495502927216, 5318.627696061278, 5163.1480805667015, 5215.530602245797, 5228.08508511009, 5232.3459567554055, 5270.920936185941, 5323.520883120537, 5326.280952071479, 5329.227262046981, 5340.172273297645, 5358.169483730008, 5058.0, 5084.313617661605, 5085.974570127126, 5244.082285600005, 5246.501582349709, 5656.802011929604, 5708.1437784332265, 5717.399673034354, 5718.399568045337, 5727.3699893011735, 6227.844303892393, 6230.750769969241, 6232.158658581079, 6239.625622462667, 6240.500759939239, 6010.669413182273, 6013.909791353133, 6043.969780151115, 6047.667378294192, 6248.968755156195, 5700.7149642518125, 5739.282589676291, 5757.596968212227, 5758.309002919635, 5762.394948530344, 6133.846653833601, 6174.475169610543, 6176.845578860564, 6180.0, 6194.721237544321, 5893.174955506232, 5981.431763982502, 6072.423119803611, 6076.422739839708, 6141.915626108677, 6243.250809902841, 6249.96875015622, 6251.562390632703, 6258.843528911722, 6270.874582508324, 6144.324124346318, 6183.567150299526, 6186.505079593621, 6207.96896015517, 6216.844578885564, 6030.065339872327, 6070.878582428327, 6079.270487541523, 6118.558266176196, 6144.446999770043, 4725.716457012591, 4769.213079841857, 4774.856754297377, 4797.664163508521, 4805.951940480551, 4238.788060596958, 4248.129133527619, 4252.957470425318, 4258.169656916872, 4279.7218180818145, 5764.74058667361, 5845.824625261249, 5859.062549991981, 5884.2350494436305, 5920.31916329619, 6272.3100458949475, 6282.685865706696, 6283.560150789493, 6289.811305660837, 6291.339409362043, 5946.226990491295, 5951.018082016419, 5953.493953014043, 5967.492763115097, 5988.940110598838, 5204.297419848348, 5261.342332208474, 5343.198520221998, 5355.196720491957, 5365.1147560652835, 6437.613743175424, 6474.6762661866715, 6496.0, 6496.382843629847, 6498.902516462257, 6182.783602573888, 6223.688815559294, 6237.282712488122, 6238.469730072995, 6239.313675495782, 6248.281447633581, 6384.361563843579, 6432.35676432353, 6501.0, 6517.967410162919, 6138.878900475427, 6147.477464415396, 6154.353792851686, 6157.938420615916, 6158.04550294705, 6241.21984751906, 6265.4361107500545, 6267.059941008885, 6267.467265282502, 6281.057841323838, 5915.08316210988, 6003.669798161143, 6013.609115407485, 6162.938370616236, 6171.228596425447, 6033.939660603513, 6080.2399700037495, 6083.422074902877, 6107.206063211754, 6120.296165940796, 5964.254468191476, 6011.188489553854, 6026.969865150646, 6031.396860313934, 6037.78867739627, 5939.198208241832, 6012.819615411545, 6093.9390606093375, 6102.786402475892, 6112.938870611237, 5977.701114944322, 5978.252718410199, 5981.1327357532045, 6001.849953751192, 6018.849528761816], "value": 5705.258549767822, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [608.3672980100696, 633.7971849008318, 671.5366397185942, 674.8785218660643, 685.8902575587907, 35392.823035884816, 39371.60628393715, 39442.60557394425, 46220.768896155554, 47188.52811471893, 50072.74963625177, 50404.74797626016, 50550.49449505504, 50567.49432505674, 50596.241056384184, 9346.392484488504, 9485.525723713821, 9503.619855205783, 9504.57229424674, 9507.619695212183, 8526.57367131644, 8741.30069594431, 8750.431221970583, 8973.147550982647, 9347.766305842375, 11160.441977901113, 11528.308301501937, 11538.480768365444, 11998.820017699742, 12004.0, 11412.71468213293, 11433.256838305484, 11457.14071444645, 11506.539738410453, 11519.424028798569, 8444.324454043723, 10969.890301096966, 11192.38441885696, 11259.943700281528, 11273.37996410197, 42031.1593768126, 46439.30341044887, 46584.5341546587, 46584.76707616474, 46606.300905486445, 4024.9597504024878, 4094.0992981544014, 4465.531119232466, 4501.617362524189, 4637.234856248715, 35352.0, 35496.822515887514, 35793.28413431743, 35898.0, 36132.45801312982, 4683.531646835323, 4788.090262850047, 4855.562999330042, 4993.775280112403, 5041.546260836506, 380826.0, 381402.0, 383241.0, 383514.9999999972, 383813.0, 63003.6849815748, 63422.365776342565, 63437.68281158565, 63445.0, 63456.682716586125, 65600.70397536064, 65692.34307656862, 65797.6710116456, 65966.67016664887, 66053.0, 17396.130193490528, 17615.82384176142, 17626.0, 17626.735598966025, 17642.911785440992, 13659.931700341434, 13668.316584170752, 13718.725625487434, 13719.314034298246, 13755.0, 15163.545093647208, 16085.437009704605, 16350.75473867893, 16859.747103793452, 16875.409360672318, 6673.1992160941, 6783.762568310184, 6802.965985170142, 6803.387695107464, 6836.547543490506, 1012.6658202793061, 1099.2470157941837, 1134.8581427321585, 1189.0606420927543, 1192.9105317101253, 34771.478427823604, 34909.0, 35353.0, 35695.6216622455, 35971.82014089913, 1153.9826902596467, 1239.5227837282712, 1255.265669583304, 1261.9053570982212, 1272.1730874931368, 17642.70492332626, 18182.81817181811, 18223.817761822476, 18246.908765456352, 18435.354762583243, 596033.0, 596198.0, 598547.0, 599344.0, 600704.0, 55811.44188558062, 55872.0, 56416.43583564112, 62411.0, 63742.6812865942, 1408.2465880753748, 1435.174774504664, 1441.1641248076091, 1476.9852301477063, 1482.0662982321069, 34068.0, 36254.274914502086, 36270.4559431614, 36337.0, 36383.63616363909, 55635.44364556464, 60142.39857601543, 61774.38225617686, 62071.6896415515, 67193.0, 427711.0, 430414.0, 431399.0, 432417.0, 589483.0, 28821.0, 30440.69559304467, 30472.238194045327, 30690.84654576802, 30796.692033079384, 52380.0, 52591.0, 52782.7273855317, 52984.735076324374, 53874.730626346616, 44140.77929610331, 44470.777646111565, 45179.322310164716, 46060.769696152645, 48287.5171248283, 44120.33819492646, 45587.0, 46422.0, 46844.0, 47473.0, 44202.0, 44377.0, 45495.31757023581, 45781.31328030016, 46118.7694061541, 37039.629603703615, 39811.40282895817, 40175.59824401719, 40772.184556308115, 40907.795461022506, 29585.408291833613, 30028.849855751454, 30209.848950755986, 30453.695463045973, 30585.388292233587], "value": 21972.202033665082, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.1025157357583, 415.5989470161294, 424.1284161049045, 426.27533193570935, 438.3731264292063, 15621.921890390546, 20655.793442065577, 21698.674519882195, 21723.13107475701, 22051.22820701276, 20878.582428351423, 20914.686279705817, 20920.790792092073, 20937.0, 21021.26425575105, 5733.856653583652, 5755.39568345324, 6057.666828324441, 6099.603525770811, 6189.226346706661, 5965.254343207099, 6096.512279017668, 6113.32753397126, 6128.662923539204, 6135.263768347804, 6124.2650881894015, 6159.784407545737, 6163.63018218906, 6165.969170154143, 6166.660833654169, 6067.643057541949, 6133.539984501158, 6152.015677491602, 6625.900611490832, 6744.393004629558, 5970.761169553212, 6011.969940150271, 6019.548533859935, 6026.276846778371, 6028.577999540034, 14886.0, 15208.08751474915, 15489.225538723018, 15580.688386232212, 19001.8598884066, 2421.370443684637, 2435.3059378077164, 2688.1397952655157, 2787.5818627205877, 3029.303260250155, 10864.293820901696, 11082.501287442085, 11245.662630121109, 11333.093352531778, 11381.487833047533, 2012.23535056679, 2022.7572691276994, 2047.2118234479683, 2108.0, 2108.3674897530677, 61684.0, 77473.61263193704, 86183.0, 91178.5441072797, 93870.53064734701, 11409.543618255177, 11626.546681664791, 11681.065514758799, 11903.285802851857, 12256.08079394049, 16834.831651683326, 16853.662926741395, 16857.662846743242, 16866.578335541708, 16886.240119194666, 8658.134186581417, 8661.090585488433, 8696.260817830429, 8831.91168088311, 8887.600057997406, 8344.499330040217, 8427.241548260687, 8441.451305665112, 8458.915410845935, 8625.309975201968, 5081.110805609003, 5131.640785145079, 5141.845744627667, 5191.195364718475, 5281.55106815925, 2084.1871670048695, 2109.230131002204, 2136.6474531702406, 2152.8815915124505, 2178.9782102178774, 955.2405837359316, 1005.4922264256504, 1017.8829434615114, 1037.2376303416982, 1040.958361665525, 20725.58548829015, 21461.67807482889, 21689.240876569245, 21702.348929532138, 21715.13139474435, 1018.022698209727, 1050.1231471721046, 1052.905238528536, 1067.2209287220373, 1067.9733006674896, 9798.559064842098, 11895.167338286237, 11967.521299148111, 12029.0, 12053.457594408274, 552143.0, 552159.0, 553936.0, 563734.0, 567240.0, 91830.0, 92320.53839730758, 92378.53810730904, 92975.53512232531, 94515.0, 1012.5443550402201, 1030.7114008077751, 1048.6120135549895, 1058.6030238660503, 1059.9364038157735, 17829.643407132044, 17938.91030544839, 18351.724724129417, 19057.523561911064, 19391.2243510258, 42963.35554966616, 42987.35518967156, 43224.56775432331, 43387.56612433962, 45137.32294015527, 55455.168172478254, 63672.36327636665, 63975.0, 64421.0, 65613.0, 8668.30653547702, 8673.436226645248, 8698.304135669005, 8713.477191368538, 9050.411723237969, 13477.0, 14314.212718300178, 14383.28083595795, 14448.422063117358, 14940.178290194144, 11113.944430277797, 11156.888431115585, 11352.829707554558, 11449.942750286194, 11849.407529623657, 12006.159568830099, 12238.0, 12458.937705311415, 12550.246985180553, 12722.300273485047, 11321.320720756781, 11388.0, 11401.258918170292, 11542.249753765978, 11677.941610291895, 8852.955735221283, 8925.687600934196, 9055.094490550891, 9384.108509691569, 9568.282378821477, 9881.0, 10622.25644204929, 10690.19823513224, 10758.0, 11048.613298534512], "value": 9656.991797975808, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1363.495506662535, 1522.7563589825627, 1580.1467207707838, 1735.4012865561385, 1864.2915692037016, 9024.0, 9478.336516443851, 9523.42859428434, 9800.264980126483, 9832.557534910928, 9691.079347461997, 9786.804263914735, 9797.804043919117, 9819.509024548779, 9820.754481137976, 2473.406382468208, 2519.533886231044, 2524.924252272435, 2526.2800101970943, 2526.519961207374, 2443.3402981195068, 2538.733432989537, 2539.771420572148, 2542.224621490445, 2542.250036239305, 2486.042873493709, 2501.949961000779, 2526.2042456626195, 2529.215943057649, 2530.392705750625, 2457.3610861176135, 2468.456939473322, 2476.578981573137, 2480.913168039128, 2503.6119401492797, 2422.115927686382, 2431.003288651662, 2437.390652336916, 2447.559439300925, 2476.2695004973575, 9567.712968610953, 9682.612695492171, 9710.805783884354, 9803.460809655466, 9803.705888823346, 5538.6122971392015, 5772.480476757112, 5784.045632470637, 5789.102689083199, 5791.189233507313, 3528.259065596228, 3559.75081744278, 3586.1751797086818, 3605.6118394418336, 3612.187257866985, 1247.8502579690405, 1248.931308778017, 1249.5938819883575, 1286.2604002698486, 1301.9739605207938, 13841.961852861075, 13980.161190328512, 13981.0, 13981.370838312301, 13995.860041399557, 4806.351142595775, 4832.081904438216, 4846.61227101831, 4958.628102892298, 4997.90004199914, 10653.094486968694, 10753.731156721145, 10770.030697237285, 10783.406912619725, 10787.83818242727, 5588.860278492989, 5610.607257492018, 5628.352739435018, 5629.043062679354, 5629.099344105005, 4541.0, 4627.745473998963, 5170.560502357342, 5243.68537887728, 5271.446498117719, 4167.354060120686, 4173.384810338799, 4245.3631955206965, 4245.575442455792, 4274.572542745701, 1232.3468561662332, 1242.2794779028143, 1247.7629250442387, 1253.486070711004, 1268.2707443220186, 951.871497347863, 1000.3697670467559, 1003.4130033930087, 1016.9949150254201, 1031.56674196836, 6141.785037523665, 6254.718537665816, 6255.280642726054, 6395.360463953567, 6446.7421303148285, 490.15693008026136, 493.91603427417425, 503.94708555601136, 505.2320472881273, 508.54231191926823, 4272.0, 4525.253333200051, 4546.977265113654, 4560.17916774977, 4597.655175861823, 12100.33448160342, 12311.50753969849, 12319.199252048587, 12341.876581234252, 12414.441350139265, 12279.693007674701, 12380.876191238152, 12391.442385092692, 12399.628011159679, 12402.689932751573, 8590.699325523577, 8952.373333866697, 8970.820583588422, 9093.363464557417, 9171.128742769559, 6199.380061993765, 6411.6794160290865, 6451.225852897683, 6490.48076153916, 6513.534454747644, 10567.365958042543, 10674.62638807638, 10718.24972251935, 10722.19583531254, 11023.44882755875, 11090.0, 11459.255148415326, 11469.598564050219, 11516.42417879086, 11580.131490138105, 7819.452638315263, 8343.833123337621, 8644.265237454636, 8706.303495720455, 8973.37186396946, 9562.235021198427, 9630.759231019281, 9645.0, 9652.2278217741, 9694.854577181206, 8523.659053637784, 8581.95709021451, 8602.483850968962, 8641.308695304486, 8831.205191532792, 8239.093699693149, 8241.629126689193, 8401.579921004048, 8490.193431623986, 8494.57527123629, 7707.576083315247, 7774.572398518136, 7836.568988705448, 7840.686372545034, 7876.291133797986, 5479.835604931858, 5562.11006239016, 5606.159076138449, 5705.286839145107, 5741.5406767459335, 5266.394364647961, 5436.0, 5640.266765320482, 5655.4910058094965, 5705.714714264187], "value": 4929.036000198169, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.16150189782195, 346.5512161750533, 354.55680399500625, 358.10652422206596, 359.91721903962105, 5541.113421852499, 5569.331680198382, 5573.8327850164505, 5573.860653483665, 5589.105743081102, 5218.495122341278, 5303.443138470453, 5406.351237851463, 5459.099248623972, 5498.752556134974, 4357.847475338363, 4357.978210108953, 4358.629516491102, 4367.606915377615, 4389.517153113155, 4336.176126535964, 4359.237133501624, 4369.016971181474, 4389.648828093761, 4408.757518336491, 4135.896602584944, 4294.291441912081, 4312.202242585116, 4317.222899878021, 4341.522432532434, 4285.57144285572, 4288.935665965013, 4296.677749168824, 4302.41917341158, 4307.741535507879, 3822.48396466479, 3836.251930873481, 3839.3298914972074, 3839.8864329344456, 3844.154286057063, 5235.607329450307, 5295.973520132413, 5371.59713021521, 5389.8113566025, 5390.434004429518, 4936.728479933602, 4955.776990035457, 4967.130752118364, 4975.502449755032, 4978.377702787152, 5754.424557544254, 5765.682887441189, 5774.595778295521, 5774.7112644368035, 5810.796622118206, 4115.218108559394, 4146.647534959532, 4219.472565929259, 4221.535631080578, 4236.216299984513, 5961.344252132262, 5972.343042265347, 5984.97007514964, 5990.281166260033, 5996.280446346423, 4286.164197981426, 4293.570642935744, 4301.2257793597455, 4307.353896915425, 4314.158739045856, 4245.341971994346, 4291.8841101313255, 4323.0, 4326.329418940069, 4347.152305300436, 4892.657513974059, 4906.803727850917, 4918.754062296871, 4921.655484116078, 4923.212286034289, 5744.684042377709, 5773.16289138081, 5777.335606405233, 5784.4504772046585, 5792.391798861143, 1398.797174409705, 1451.7894905238682, 1453.5566652170978, 1497.951433996207, 1499.422722251941, 995.5320999130397, 1004.5684899018869, 1011.0344620887035, 1021.2544842265039, 1028.0, 5619.7471113799975, 5638.464345887134, 5641.5204707600315, 5643.097104463266, 5652.756393593403, 5844.970775146097, 5871.882562348729, 5884.852878678067, 5899.498542623839, 5918.940810591925, 5445.863853403696, 5558.833235002957, 5569.972150139223, 5603.131514615241, 5652.7738890443925, 2731.811661927028, 2758.7930905182193, 2766.5573508238585, 2766.9169924902285, 2804.0, 5384.119496069662, 5394.298741163702, 5411.5129638331955, 5414.675119492765, 5418.864528386822, 5298.337707786527, 5654.547636189095, 6054.0, 6088.330283668793, 6091.172648205521, 6194.287656919512, 6209.720562574695, 6229.595076320115, 6254.655993920287, 6264.9373506265265, 5718.285214348207, 5951.821445356646, 6004.939950600438, 6019.939800601938, 6030.7889223876955, 5912.216181643666, 5914.526837852875, 5924.7926322578505, 5951.434613711691, 5959.195508606283, 6126.754929802758, 6240.656763878031, 6253.906191407224, 6254.3745625437095, 6257.53068519872, 4291.249031419551, 4298.892527686833, 4300.247456695129, 4307.310830267143, 4325.783710814384, 5935.2877654681715, 5964.612300200473, 5996.830618029617, 6034.728437220423, 6035.637861728311, 5669.971650141888, 5767.192593036894, 5815.883682326415, 5864.354920958776, 5879.20630714848, 5828.329742079715, 5832.358440571618, 5838.927180850907, 5840.503557197686, 5842.1820945068575, 5598.272224610774, 5728.828135155952, 5774.220480235114, 5805.94194058054, 5807.477327040418, 5833.270841144857, 5837.737301821513, 5838.211841401527, 5848.210491583582, 5852.313042783019, 5574.299838549281, 5694.4590263924865, 5710.314762228559, 5714.714264286852, 5762.625429347079], "value": 4407.885716622294, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [806.0247101007781, 848.262012049517, 874.1258741258737, 893.4282059481932, 917.2625229191409, 16247.431339903105, 16878.662426751474, 16887.493375198734, 16937.91531042346, 17084.743728844078, 16244.269007894643, 16488.34046638133, 16527.83472165281, 16542.751858722128, 16550.586235344123, 744.292921724364, 869.3088994249587, 882.6601758323028, 891.7815135291862, 897.712731925784, 901.833160865242, 902.6569903436684, 904.3804993579387, 904.4708845325492, 904.7421484876811, 948.1182500274776, 956.5456408206117, 956.6699488676395, 958.0706714486937, 959.0122174160625, 774.1639029847751, 912.1243606138115, 932.8274269260174, 943.6791490893129, 946.9195118414943, 691.4744793956629, 696.4324075878143, 696.6969368324795, 696.7491702986921, 696.8675951569186, 15750.291236894367, 15799.605009874846, 15822.446214382442, 15829.28768205422, 15830.0, 15735.92132039344, 16155.676886462204, 16168.514944551682, 16341.918290408472, 16811.0, 14385.280735963264, 14697.118172909659, 14719.632009199855, 14776.261186940717, 14781.187034713086, 4583.289590113538, 4600.263957766741, 4604.79278432468, 4717.033008233304, 4755.239161734141, 89296.55351723265, 89619.55190224072, 89872.0, 91590.54204729, 94492.0, 12159.148859579744, 12180.390980450942, 12348.0, 12937.935310323577, 13367.532136375181, 22324.776752232596, 22497.88751056267, 22559.661605075937, 22579.548409032057, 22623.0, 8837.204651581389, 8957.641694332284, 8983.640654373883, 9013.18881300686, 9257.583408746623, 5106.361704786902, 5121.513456221653, 5134.486551344836, 5172.948270517247, 5348.037353276448, 3887.2031233597045, 4014.1971605678395, 4016.6786657067364, 4039.2527382434346, 4049.4330793688905, 710.7903168565285, 714.118064190724, 714.8427345983876, 736.7716008037419, 756.765402725157, 707.7628994286972, 775.9030121234846, 788.6529926832176, 792.1523969352852, 802.3220378779922, 10420.166386689047, 10485.790284194425, 10570.989195342478, 11155.442227888572, 11273.54905803775, 746.2462912458456, 757.257887270478, 766.6971546239316, 798.2017982017982, 829.3903980574273, 4859.222524396079, 5222.3472065991755, 5247.816326428633, 5263.36839579253, 5273.947260527346, 130255.0, 137324.0, 139176.0, 142245.28877355548, 142699.0, 96112.0, 120296.0, 120602.76383416848, 121586.0, 123867.3806630961, 81731.18268817355, 83810.58094709487, 96957.51521242349, 98638.0, 99275.50362248142, 10012.84980725275, 10039.80561673714, 10542.367457952547, 11106.888931110585, 11111.277766945122, 51999.48000519946, 58810.0, 59948.0, 60858.39141608704, 63665.0, 54201.728991354794, 80393.59803201181, 82007.58996204981, 82943.0, 82987.0, 15919.681606367576, 15927.761083583524, 16208.270627822012, 16221.188940553162, 16554.172291385625, 17104.57238569046, 17248.396306129227, 18091.36680216186, 18557.9072104644, 18668.439946801616, 15051.172185529464, 15946.202689865693, 15970.02332621994, 16125.919370403073, 16411.343546258016, 15035.097894126387, 15092.698146037237, 15157.924210378878, 16187.514374568782, 16204.594885127495, 15416.383344666536, 15539.0, 15809.525714228592, 16625.50123496297, 16663.750043749595, 11867.05063594893, 11894.524219031488, 12039.939800300943, 12528.74942501163, 12639.115261931944, 11896.881031189578, 12700.491980320683, 12802.487900483877, 12927.095103342675, 12996.220226786423], "value": 8502.729184856853, "unit": "score"}], "name": "dromaeo_css", "value": 7791.078694561192, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 110.0, 107.0, 111.0, 109.0, 112.0, 109.0, 110.0, 109.0, 109.0], "value": 108.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 116.0, 117.0, 177.0, 116.0, 119.0, 150.0, 114.0, 156.0, 114.0], "value": 133.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 159.0, 160.0, 160.0, 159.0, 160.0, 160.0, 159.0, 170.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 113.0, 77.0, 76.0, 74.0, 75.0, 111.0, 75.0], "value": 82.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 124.0, 84.0, 83.0, 84.0, 121.0, 83.0, 84.0, 119.0], "value": 94.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 117.0, 116.0, 117.0, 118.0, 117.0, 118.0, 117.0, 117.0], "value": 117.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 147.0, 146.0, 145.0, 146.0, 145.0, 145.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 94.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 49.0, 50.0, 50.0, 51.0, 51.0, 51.0, 49.0, 50.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 59.0, 58.0, 59.0, 58.0, 58.0, 59.0, 58.0, 59.0], "value": 58.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 71.0, 71.0, 72.0, 73.0, 71.0, 72.0, 72.0, 71.0, 71.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 131.0, 116.0, 132.0, 116.0, 114.0, 117.0, 125.0, 162.0, 116.0], "value": 124.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 152.0, 151.0, 153.0, 149.0, 151.0, 158.0, 149.0, 153.0, 154.0], "value": 152.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 61.0, 63.0, 61.0, 62.0, 60.0, 63.0, 61.0, 60.0, 62.0], "value": 61.8, "unit": "score"}], "name": "kraken", "value": 1456.8, "alertThreshold": 2.0}]} 14:37:43 INFO - Return code: 0 14:37:43 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:37:43 INFO - # TBPL SUCCESS # 14:37:43 INFO - Running post-action listener: _resource_record_post_action 14:37:43 INFO - [mozharness: 2016-08-23 21:37:43.182303Z] Finished run-tests step (success) 14:37:43 INFO - Running post-run listener: _resource_record_post_run 14:37:43 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 324333568; Read time: 12; Write time: 573340 14:37:43 INFO - TinderboxPrint: CPU usage
13.0% 14:37:43 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 14:37:43 INFO - TinderboxPrint: I/O write bytes / time
324,333,568 / 573,340 14:37:43 INFO - TinderboxPrint: CPU idle
8,280.6 (87.0%) 14:37:43 INFO - TinderboxPrint: CPU user
1,183.4 (12.4%) 14:37:43 INFO - TinderboxPrint: Swap in / out
0 / 0 14:37:43 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 79151104; Read time: 0; Write time: 175448 14:37:43 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 0; Write bytes: 245182464; Read time: 0; Write time: 397892 14:37:43 INFO - Running post-run listener: _upload_blobber_files 14:37:43 INFO - Blob upload gear active. 14:37:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:37:43 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 14:37:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:37:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:37:44 INFO - (blobuploader) - INFO - Open directory for files ... 14:37:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:37:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:37:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:37:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:37:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:37:48 INFO - (blobuploader) - INFO - Done attempting. 14:37:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:37:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:37:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:37:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:37:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:37:49 INFO - (blobuploader) - INFO - Done attempting. 14:37:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:37:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:37:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:37:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:37:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:37:50 INFO - (blobuploader) - INFO - Done attempting. 14:37:50 INFO - (blobuploader) - INFO - Iteration through files over. 14:37:50 INFO - Return code: 0 14:37:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:37:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:37:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/212c51556cddf9a32c01f1b6b26cd51560f2fc0203f66fbf87a9890f298c637377b47f4f4184725b6d9c1dc58d75bb2bbf003f53cf9b14fd85208475ce40312e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba6ec650e0b6112a1b4050ecc27f7216e7c997ec0247cf35314cd8600ea845d34449d88ebc9a67f59ce28279222bc37a7ec9f39c2cb68dd4b8c802d7dd01e215", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/278bc28d9ab379fe26b604c1533888b7a0c96154f8e6ad55229664f19efeb651927aa1444f984f6b3a6745f3cc95fdc846a10c66307a0798a919c80b2f1aed05"} 14:37:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:37:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:37:50 INFO - Contents: 14:37:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/212c51556cddf9a32c01f1b6b26cd51560f2fc0203f66fbf87a9890f298c637377b47f4f4184725b6d9c1dc58d75bb2bbf003f53cf9b14fd85208475ce40312e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba6ec650e0b6112a1b4050ecc27f7216e7c997ec0247cf35314cd8600ea845d34449d88ebc9a67f59ce28279222bc37a7ec9f39c2cb68dd4b8c802d7dd01e215", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/278bc28d9ab379fe26b604c1533888b7a0c96154f8e6ad55229664f19efeb651927aa1444f984f6b3a6745f3cc95fdc846a10c66307a0798a919c80b2f1aed05"} 14:37:50 INFO - Running post-run listener: copy_logs_to_upload_dir 14:37:50 INFO - Copying logs to upload dir... 14:37:50 INFO - mkdir: /builds/slave/test/build/upload/logs 14:37:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1680.996842 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 1 secs) (at 2016-08-23 14:37:49.780076) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-23 14:37:49.783509) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1471986350.400089-1953456045 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/212c51556cddf9a32c01f1b6b26cd51560f2fc0203f66fbf87a9890f298c637377b47f4f4184725b6d9c1dc58d75bb2bbf003f53cf9b14fd85208475ce40312e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba6ec650e0b6112a1b4050ecc27f7216e7c997ec0247cf35314cd8600ea845d34449d88ebc9a67f59ce28279222bc37a7ec9f39c2cb68dd4b8c802d7dd01e215", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/278bc28d9ab379fe26b604c1533888b7a0c96154f8e6ad55229664f19efeb651927aa1444f984f6b3a6745f3cc95fdc846a10c66307a0798a919c80b2f1aed05"} build_url:https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011333 build_url: 'https://queue.taskcluster.net/v1/task/VpHdSUSORsyv12rwXfivJg/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/212c51556cddf9a32c01f1b6b26cd51560f2fc0203f66fbf87a9890f298c637377b47f4f4184725b6d9c1dc58d75bb2bbf003f53cf9b14fd85208475ce40312e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ba6ec650e0b6112a1b4050ecc27f7216e7c997ec0247cf35314cd8600ea845d34449d88ebc9a67f59ce28279222bc37a7ec9f39c2cb68dd4b8c802d7dd01e215", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/278bc28d9ab379fe26b604c1533888b7a0c96154f8e6ad55229664f19efeb651927aa1444f984f6b3a6745f3cc95fdc846a10c66307a0798a919c80b2f1aed05"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-23 14:37:49.809557) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 14:37:49.809929) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=745a4373a2a16df560f216e000000264-1471986350.400089-1953456045 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004390 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 14:37:49.892212) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 14:37:49.892653) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 14:37:49.892981) ========= ========= Total master_lag: 0.16 =========