builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-059 starttime: 1473096009.31 results: success (0) buildid: 20160905071519 builduid: 4e9a9030d151432c8c640f6671ce6c5c revision: 37c314d53f827e5fc094fcb9b1537b4101681507 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-05 10:20:09.311486) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-05 10:20:09.311946) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-05 10:20:09.312270) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-05 10:20:09.344588) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 10:20:09.345096) ========= 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=3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-05 10:20:09-- 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% 46.4M=0s 2016-09-05 10:20:09 (46.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.144931 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 10:20:09.504915) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 10:20:09.505253) ========= 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=3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017141 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 10:20:09.589135) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 10:20:09.589688) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 37c314d53f827e5fc094fcb9b1537b4101681507 --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 37c314d53f827e5fc094fcb9b1537b4101681507 --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=3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-05 10:20:09,761 truncating revision to first 12 chars 2016-09-05 10:20:09,762 Setting DEBUG logging. 2016-09-05 10:20:09,762 attempt 1/10 2016-09-05 10:20:09,762 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/37c314d53f82?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-05 10:20:10,093 unpacking tar archive at: mozilla-aurora-37c314d53f82/testing/mozharness/ program finished with exit code 0 elapsedTime=0.688388 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 10:20:10.292294) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-05 10:20:10.292611) ========= script_repo_revision: 37c314d53f827e5fc094fcb9b1537b4101681507 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-05 10:20:10.293060) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-05 10:20:10.293401) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-05 10:20:10.303941) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 6 secs) (at 2016-09-05 10:20:10.304278) ========= /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=3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 10:20:10 INFO - MultiFileLogger online at 20160905 10:20:10 in /builds/slave/test 10:20:10 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 10:20:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:20:10 INFO - {'append_to_log': False, 10:20:10 INFO - 'base_work_dir': '/builds/slave/test', 10:20:10 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:20:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:20:10 INFO - 'branch': 'Mozilla-Aurora', 10:20:10 INFO - 'buildbot_json_path': 'buildprops.json', 10:20:10 INFO - 'config_files': ('talos/linux_config.py',), 10:20:10 INFO - 'default_actions': ('clobber', 10:20:10 INFO - 'read-buildbot-config', 10:20:10 INFO - 'download-and-extract', 10:20:10 INFO - 'populate-webroot', 10:20:10 INFO - 'create-virtualenv', 10:20:10 INFO - 'install', 10:20:10 INFO - 'run-tests'), 10:20:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:20:10 INFO - 'download_minidump_stackwalk': True, 10:20:10 INFO - 'download_symbols': 'ondemand', 10:20:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:20:10 INFO - 'tooltool.py': '/tools/tooltool.py', 10:20:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:20:10 INFO - '/tools/misc-python/virtualenv.py')}, 10:20:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:20:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:20:10 INFO - 'installer_path': 'installer.exe', 10:20:10 INFO - 'log_level': 'info', 10:20:10 INFO - 'log_name': 'talos', 10:20:10 INFO - 'log_to_console': True, 10:20:10 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 10:20:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 10:20:10 INFO - 'opt_config_files': (), 10:20:10 INFO - 'pip_index': False, 10:20:10 INFO - 'sps_profile': False, 10:20:10 INFO - 'sps_profile_interval': 0, 10:20:10 INFO - 'suite': 'dromaeojs', 10:20:10 INFO - 'system_bits': '32', 10:20:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:20:10 INFO - 'title': 'talos-linux64-ix-059', 10:20:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:20:10 INFO - 'use_talos_json': True, 10:20:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:20:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:20:10 INFO - 'work_dir': 'build'} 10:20:10 INFO - [mozharness: 2016-09-05 17:20:10.563510Z] Running clobber step. 10:20:10 INFO - Running pre-action listener: _resource_record_pre_action 10:20:10 INFO - Running main action method: clobber 10:20:10 INFO - rmtree: /builds/slave/test/build 10:20:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:20:11 INFO - Running post-action listener: _resource_record_post_action 10:20:11 INFO - [mozharness: 2016-09-05 17:20:11.014419Z] Finished clobber step (success) 10:20:11 INFO - [mozharness: 2016-09-05 17:20:11.014519Z] Running read-buildbot-config step. 10:20:11 INFO - Running pre-action listener: _resource_record_pre_action 10:20:11 INFO - Running main action method: read_buildbot_config 10:20:11 INFO - Using buildbot properties: 10:20:11 INFO - { 10:20:11 INFO - "project": "", 10:20:11 INFO - "product": "firefox", 10:20:11 INFO - "script_repo_revision": "production", 10:20:11 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 10:20:11 INFO - "repository": "", 10:20:11 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 10:20:11 INFO - "buildid": "20160905071519", 10:20:11 INFO - "pgo_build": "True", 10:20:11 INFO - "basedir": "/builds/slave/test", 10:20:11 INFO - "buildnumber": 737, 10:20:11 INFO - "slavename": "talos-linux64-ix-059", 10:20:11 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 10:20:11 INFO - "platform": "ubuntu64_hw", 10:20:11 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 10:20:11 INFO - "branch": "mozilla-aurora", 10:20:11 INFO - "repo_path": "releases/mozilla-aurora", 10:20:11 INFO - "revision": "37c314d53f827e5fc094fcb9b1537b4101681507", 10:20:11 INFO - "stage_platform": "linux64-pgo", 10:20:11 INFO - "builduid": "4e9a9030d151432c8c640f6671ce6c5c", 10:20:11 INFO - "slavebuilddir": "test" 10:20:11 INFO - } 10:20:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 10:20:11 INFO - Running post-action listener: _resource_record_post_action 10:20:11 INFO - [mozharness: 2016-09-05 17:20:11.016964Z] Finished read-buildbot-config step (success) 10:20:11 INFO - [mozharness: 2016-09-05 17:20:11.017053Z] Running download-and-extract step. 10:20:11 INFO - Running pre-action listener: _resource_record_pre_action 10:20:11 INFO - Running main action method: download_and_extract 10:20:11 INFO - mkdir: /builds/slave/test/build/tests 10:20:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:20:11 INFO - https://queue.taskcluster.net/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 10:20:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 10:20:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 10:20:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:20:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:20:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 10:20:11 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:20:11 INFO - retry: Failed, sleeping 30 seconds before retrying 10:20:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:20:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 10:20:41 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:20:41 INFO - retry: Failed, sleeping 60 seconds before retrying 10:21:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:21:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 10:21:41 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:21:41 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/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! 10:21:41 INFO - Caught exception: HTTP Error 404: Not Found 10:21:41 INFO - Caught exception: HTTP Error 404: Not Found 10:21:41 INFO - Caught exception: HTTP Error 404: Not Found 10:21:41 INFO - trying https://queue.taskcluster.net/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 10:21:41 INFO - Downloading https://queue.taskcluster.net/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:21:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:21:41 INFO - Downloaded 1361 bytes. 10:21:41 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 10:21:41 INFO - Using the following test package requirements: 10:21:41 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 10:21:41 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 10:21:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 10:21:41 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 10:21:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 10:21:41 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 10:21:41 INFO - u'jsshell-linux-x86_64.zip'], 10:21:41 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 10:21:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 10:21:41 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 10:21:41 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 10:21:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 10:21:41 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 10:21:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 10:21:41 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 10:21:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 10:21:41 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 10:21:41 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 10:21:41 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 10:21:41 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')]} 10:21:41 INFO - https://queue.taskcluster.net/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:21:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:21:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:21:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:21:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:21:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:21:41 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:21:41 INFO - retry: Failed, sleeping 30 seconds before retrying 10:22:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:22:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:22:11 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:22:11 INFO - retry: Failed, sleeping 60 seconds before retrying 10:23:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:23:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:23:11 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:23:11 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/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! 10:23:11 INFO - Caught exception: HTTP Error 404: Not Found 10:23:11 INFO - Caught exception: HTTP Error 404: Not Found 10:23:11 INFO - Caught exception: HTTP Error 404: Not Found 10:23:11 INFO - trying https://queue.taskcluster.net/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:23:11 INFO - Downloading https://queue.taskcluster.net/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:23:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:23:12 INFO - Downloaded 21769279 bytes. 10:23:12 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'] 10:23:12 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 10:23:13 INFO - Return code: 0 10:23:13 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 10:23:13 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')]} 10:23:13 INFO - https://queue.taskcluster.net/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 10:23:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:23:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:23:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:23:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:23:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:23:13 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:23:13 INFO - retry: Failed, sleeping 30 seconds before retrying 10:23:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:23:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:23:43 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:23:43 INFO - retry: Failed, sleeping 60 seconds before retrying 10:24:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:24:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:24:43 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:24:43 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/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! 10:24:43 INFO - Caught exception: HTTP Error 404: Not Found 10:24:43 INFO - Caught exception: HTTP Error 404: Not Found 10:24:43 INFO - Caught exception: HTTP Error 404: Not Found 10:24:43 INFO - trying https://queue.taskcluster.net/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 10:24:43 INFO - Downloading https://queue.taskcluster.net/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:24:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:24:44 INFO - Downloaded 21769279 bytes. 10:24:44 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'] 10:24:44 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 10:24:46 INFO - Return code: 0 10:24: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')]} 10:24:46 INFO - https://queue.taskcluster.net/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 10:24:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 10:24:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 10:24:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:24:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:24:46 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 10:24:46 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:24:46 INFO - retry: Failed, sleeping 30 seconds before retrying 10:25:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:25:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 10:25:16 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:25:16 INFO - retry: Failed, sleeping 60 seconds before retrying 10:26:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:26:16 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 10:26:16 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:26:16 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/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! 10:26:16 INFO - Caught exception: HTTP Error 404: Not Found 10:26:16 INFO - Caught exception: HTTP Error 404: Not Found 10:26:16 INFO - Caught exception: HTTP Error 404: Not Found 10:26:16 INFO - trying https://queue.taskcluster.net/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 10:26:16 INFO - Downloading https://queue.taskcluster.net/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:26:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:26:17 INFO - Downloaded 14413740 bytes. 10:26:17 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'] 10:26:17 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 10:26:17 INFO - Return code: 0 10:26:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:26:17 INFO - https://queue.taskcluster.net/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 10:26:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 10:26:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 10:26:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:26:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:26:17 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 10:26:17 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 10:26:17 INFO - retry: Failed, sleeping 30 seconds before retrying 10:26:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:26:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 10:26:47 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 10:26:47 INFO - retry: Failed, sleeping 60 seconds before retrying 10:27:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:27:47 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 10:27:47 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 10:27:47 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/AgXHR6EITOSNRTnEkhfJqA/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! 10:27:47 INFO - Caught exception: HTTP Error 404: Not Found 10:27:47 INFO - Caught exception: HTTP Error 404: Not Found 10:27:47 INFO - Caught exception: HTTP Error 404: Not Found 10:27:47 INFO - trying https://queue.taskcluster.net/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 10:27:47 INFO - Downloading https://queue.taskcluster.net/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:27:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AgXHR6EITOSNRTnEkhfJqA/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 10:27:49 INFO - Downloaded 63897279 bytes. 10:27:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 10:27:49 INFO - mkdir: /builds/slave/test/properties 10:27:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:27:49 INFO - Writing to file /builds/slave/test/properties/build_url 10:27:49 INFO - Contents: 10:27:49 INFO - build_url:https://queue.taskcluster.net/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 10:27:50 INFO - Running post-action listener: _resource_record_post_action 10:27:50 INFO - Running post-action listener: set_extra_try_arguments 10:27:50 INFO - [mozharness: 2016-09-05 17:27:50.282015Z] Finished download-and-extract step (success) 10:27:50 INFO - [mozharness: 2016-09-05 17:27:50.282247Z] Running populate-webroot step. 10:27:50 INFO - Running pre-action listener: _resource_record_pre_action 10:27:50 INFO - Running main action method: populate_webroot 10:27:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:27:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:27:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:27:50 INFO - '--tppagecycles', 10:27:50 INFO - '7'], 10:27:50 INFO - 'tests': ['tsvgm']}}, 10:27:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:27:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:27:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:27:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:27:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:27:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:27:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:27:50 INFO - 'talos_options': ['--disable-e10s'], 10:27:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:27:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:27:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:27:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:27:50 INFO - 'talos_options': ['--disable-e10s'], 10:27:50 INFO - 'tests': ['damp', 'tps']}, 10:27:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:27:50 INFO - 'tests': ['damp', 'tps']}, 10:27:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:27:50 INFO - 'tests': ['dromaeo_dom']}, 10:27:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:27:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:27:50 INFO - 'tests': ['basic_compositor_video']}, 10:27:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:27:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:27:50 INFO - 'tests': ['a11yr', 10:27:50 INFO - 'ts_paint', 10:27:50 INFO - 'tpaint', 10:27:50 INFO - 'sessionrestore', 10:27:50 INFO - 'sessionrestore_no_auto_restore', 10:27:50 INFO - 'tabpaint']}, 10:27:50 INFO - 'other-e10s': {'tests': ['a11yr', 10:27:50 INFO - 'ts_paint', 10:27:50 INFO - 'tpaint', 10:27:50 INFO - 'sessionrestore', 10:27:50 INFO - 'sessionrestore_no_auto_restore', 10:27:50 INFO - 'tabpaint']}, 10:27:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:27:50 INFO - 'ts_paint', 10:27:50 INFO - 'tpaint', 10:27:50 INFO - 'sessionrestore', 10:27:50 INFO - 'sessionrestore_no_auto_restore', 10:27:50 INFO - 'tabpaint']}, 10:27:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:27:50 INFO - 'ts_paint', 10:27:50 INFO - 'tpaint', 10:27:50 INFO - 'sessionrestore', 10:27:50 INFO - 'sessionrestore_no_auto_restore', 10:27:50 INFO - 'tabpaint']}, 10:27:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:27:50 INFO - 'tests': ['a11yr', 10:27:50 INFO - 'ts_paint', 10:27:50 INFO - 'tpaint', 10:27:50 INFO - 'sessionrestore', 10:27:50 INFO - 'sessionrestore_no_auto_restore', 10:27:50 INFO - 'tabpaint']}, 10:27:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:27:50 INFO - 'tests': ['a11yr', 10:27:50 INFO - 'ts_paint', 10:27:50 INFO - 'tpaint', 10:27:50 INFO - 'sessionrestore', 10:27:50 INFO - 'sessionrestore_no_auto_restore', 10:27:50 INFO - 'tabpaint']}, 10:27:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:27:50 INFO - 'tests': ['tsvgx', 10:27:50 INFO - 'tsvgr_opacity', 10:27:50 INFO - 'tart', 10:27:50 INFO - 'tscrollx', 10:27:50 INFO - 'cart']}, 10:27:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:27:50 INFO - 'tsvgr_opacity', 10:27:50 INFO - 'tart', 10:27:50 INFO - 'tscrollx', 10:27:50 INFO - 'cart']}, 10:27:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:27:50 INFO - 'talos_options': ['--disable-e10s'], 10:27:50 INFO - 'tests': ['tp5o']}, 10:27:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:27:50 INFO - 'tests': ['tp5o']}, 10:27:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:27:50 INFO - 'talos_options': ['--disable-e10s', 10:27:50 INFO - '--xperf_path', 10:27:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:27:50 INFO - 'tests': ['tp5n']}, 10:27:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:27:50 INFO - 'talos_options': ['--xperf_path', 10:27:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:27:50 INFO - 'tests': ['tp5n']}}, 10:27:50 INFO - 'talos.zip': {'path': '', 10:27:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:27:50 INFO - Running post-action listener: _resource_record_post_action 10:27:50 INFO - [mozharness: 2016-09-05 17:27:50.303903Z] Finished populate-webroot step (success) 10:27:50 INFO - [mozharness: 2016-09-05 17:27:50.303992Z] Running create-virtualenv step. 10:27:50 INFO - Running pre-action listener: _resource_record_pre_action 10:27:50 INFO - Running main action method: create_virtualenv 10:27:50 INFO - Creating virtualenv /builds/slave/test/build/venv 10:27:50 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:27:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:27:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:27:50 INFO - Using real prefix '/usr' 10:27:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:27:50 INFO - Installing distribute.............................................................................................................................................................................................done. 10:27:52 INFO - Installing pip.................done. 10:27:52 INFO - Return code: 0 10:27:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:27: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')]} 10:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:27:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:27: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')]} 10:27:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:27:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:27:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x141c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dd180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16db3b0>, '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 0x16dc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16dc740>, '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': '3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535', '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 10:27:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:27:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:27:52 INFO - Using env: {'DISPLAY': ':0', 10:27:52 INFO - 'HOME': '/home/cltbld', 10:27:52 INFO - 'LANG': 'en_US.UTF-8', 10:27:52 INFO - 'LANGUAGE': 'en_US:en', 10:27:52 INFO - 'LOGNAME': 'cltbld', 10:27:52 INFO - 'MAIL': '/var/mail/cltbld', 10:27:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:27:52 INFO - 'MOZ_NO_REMOTE': '1', 10:27:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:27:52 INFO - 'NO_EM_RESTART': '1', 10:27:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:27:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:27:52 INFO - 'PWD': '/builds/slave/test', 10:27:52 INFO - 'SHELL': '/bin/bash', 10:27:52 INFO - 'SHLVL': '1', 10:27:52 INFO - 'TERM': 'linux', 10:27:52 INFO - 'TMOUT': '86400', 10:27:52 INFO - 'USER': 'cltbld', 10:27:52 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535', 10:27:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:27:52 INFO - '_': '/tools/buildbot/bin/python'} 10:27:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:27:53 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 10:27:53 INFO - Cleaning up... 10:27:53 INFO - Return code: 0 10:27:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:27:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:27:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:27:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:27:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:27:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:27:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:27:53 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 0x141c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dd180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16db3b0>, '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 0x16dc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16dc740>, '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': '3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535', '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 10:27:53 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 10:27:53 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 10:27:53 INFO - Using env: {'DISPLAY': ':0', 10:27:53 INFO - 'HOME': '/home/cltbld', 10:27:53 INFO - 'LANG': 'en_US.UTF-8', 10:27:53 INFO - 'LANGUAGE': 'en_US:en', 10:27:53 INFO - 'LOGNAME': 'cltbld', 10:27:53 INFO - 'MAIL': '/var/mail/cltbld', 10:27:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:27:53 INFO - 'MOZ_NO_REMOTE': '1', 10:27:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:27:53 INFO - 'NO_EM_RESTART': '1', 10:27:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:27:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:27:53 INFO - 'PWD': '/builds/slave/test', 10:27:53 INFO - 'SHELL': '/bin/bash', 10:27:53 INFO - 'SHLVL': '1', 10:27:53 INFO - 'TERM': 'linux', 10:27:53 INFO - 'TMOUT': '86400', 10:27:53 INFO - 'USER': 'cltbld', 10:27:53 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535', 10:27:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:27:53 INFO - '_': '/tools/buildbot/bin/python'} 10:27:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:27:53 INFO - Downloading/unpacking psutil>=3.1.1 10:27:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:27:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:27:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:27:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:27:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:27:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:27:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:27:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:27:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:27:55 INFO - Installing collected packages: psutil 10:27:55 INFO - Running setup.py install for psutil 10:27:55 INFO - building 'psutil._psutil_linux' extension 10:27:55 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 10:27:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 10:27:55 INFO - building 'psutil._psutil_posix' extension 10:27:55 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 10:27:55 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 10:27:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:27:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:27:55 INFO - Successfully installed psutil 10:27:55 INFO - Cleaning up... 10:27:55 INFO - Return code: 0 10:27:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:27:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:27:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:27:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:27:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x141c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dd180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16db3b0>, '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 0x16dc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16dc740>, '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': '3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535', '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 10:27:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:27:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:27:55 INFO - Using env: {'DISPLAY': ':0', 10:27:55 INFO - 'HOME': '/home/cltbld', 10:27:55 INFO - 'LANG': 'en_US.UTF-8', 10:27:55 INFO - 'LANGUAGE': 'en_US:en', 10:27:55 INFO - 'LOGNAME': 'cltbld', 10:27:55 INFO - 'MAIL': '/var/mail/cltbld', 10:27:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:27:55 INFO - 'MOZ_NO_REMOTE': '1', 10:27:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:27:55 INFO - 'NO_EM_RESTART': '1', 10:27:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:27:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:27:55 INFO - 'PWD': '/builds/slave/test', 10:27:55 INFO - 'SHELL': '/bin/bash', 10:27:55 INFO - 'SHLVL': '1', 10:27:55 INFO - 'TERM': 'linux', 10:27:55 INFO - 'TMOUT': '86400', 10:27:55 INFO - 'USER': 'cltbld', 10:27:55 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535', 10:27:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:27:55 INFO - '_': '/tools/buildbot/bin/python'} 10:27:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:27:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:27:56 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 10:27:56 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 10:27:56 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 10:27:56 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 10:27:56 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 10:27:56 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 10:27:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:27:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:27:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:27:58 INFO - Installing collected packages: mozsystemmonitor 10:27:58 INFO - Running setup.py install for mozsystemmonitor 10:27:58 INFO - Successfully installed mozsystemmonitor 10:27:58 INFO - Cleaning up... 10:27:58 INFO - Return code: 0 10:27:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:27:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:27:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:27:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:27:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x141c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dd180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16db3b0>, '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 0x16dc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16dc740>, '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': '3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535', '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 10:27:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:27:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:27:58 INFO - Using env: {'DISPLAY': ':0', 10:27:58 INFO - 'HOME': '/home/cltbld', 10:27:58 INFO - 'LANG': 'en_US.UTF-8', 10:27:58 INFO - 'LANGUAGE': 'en_US:en', 10:27:58 INFO - 'LOGNAME': 'cltbld', 10:27:58 INFO - 'MAIL': '/var/mail/cltbld', 10:27:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:27:58 INFO - 'MOZ_NO_REMOTE': '1', 10:27:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:27:58 INFO - 'NO_EM_RESTART': '1', 10:27:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:27:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:27:58 INFO - 'PWD': '/builds/slave/test', 10:27:58 INFO - 'SHELL': '/bin/bash', 10:27:58 INFO - 'SHLVL': '1', 10:27:58 INFO - 'TERM': 'linux', 10:27:58 INFO - 'TMOUT': '86400', 10:27:58 INFO - 'USER': 'cltbld', 10:27:58 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535', 10:27:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:27:58 INFO - '_': '/tools/buildbot/bin/python'} 10:27:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:27:58 INFO - Downloading/unpacking jsonschema==2.5.1 10:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:27:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:27:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:27:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:27:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:28:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:28:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:28:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:28:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:28:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:28:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:28:00 INFO - Downloading functools32-3.2.3-2.tar.gz 10:28:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:28:00 INFO - warning: no files found matching '*.txt' 10:28:00 INFO - no previously-included directories found matching 'build' 10:28:00 INFO - no previously-included directories found matching 'dist' 10:28:00 INFO - no previously-included directories found matching '.git*' 10:28:00 INFO - Installing collected packages: jsonschema, functools32 10:28:00 INFO - Running setup.py install for functools32 10:28:00 INFO - warning: no files found matching '*.txt' 10:28:00 INFO - no previously-included directories found matching 'build' 10:28:00 INFO - no previously-included directories found matching 'dist' 10:28:00 INFO - no previously-included directories found matching '.git*' 10:28:00 INFO - Successfully installed jsonschema functools32 10:28:00 INFO - Cleaning up... 10:28:00 INFO - Return code: 0 10:28:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:28:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x141c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dd180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16db3b0>, '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 0x16dc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16dc740>, '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': '3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535', '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 10:28:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:28:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:28:00 INFO - Using env: {'DISPLAY': ':0', 10:28:00 INFO - 'HOME': '/home/cltbld', 10:28:00 INFO - 'LANG': 'en_US.UTF-8', 10:28:00 INFO - 'LANGUAGE': 'en_US:en', 10:28:00 INFO - 'LOGNAME': 'cltbld', 10:28:00 INFO - 'MAIL': '/var/mail/cltbld', 10:28:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:00 INFO - 'MOZ_NO_REMOTE': '1', 10:28:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:28:00 INFO - 'NO_EM_RESTART': '1', 10:28:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:28:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:00 INFO - 'PWD': '/builds/slave/test', 10:28:00 INFO - 'SHELL': '/bin/bash', 10:28:00 INFO - 'SHLVL': '1', 10:28:00 INFO - 'TERM': 'linux', 10:28:00 INFO - 'TMOUT': '86400', 10:28:00 INFO - 'USER': 'cltbld', 10:28:00 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535', 10:28:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:00 INFO - '_': '/tools/buildbot/bin/python'} 10:28:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:28:00 INFO - Cleaning up... 10:28:00 INFO - Return code: 0 10:28:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:28:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x141c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dd180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16db3b0>, '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 0x16dc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16dc740>, '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': '3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535', '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 10:28:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:28:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:28:00 INFO - Using env: {'DISPLAY': ':0', 10:28:00 INFO - 'HOME': '/home/cltbld', 10:28:00 INFO - 'LANG': 'en_US.UTF-8', 10:28:00 INFO - 'LANGUAGE': 'en_US:en', 10:28:00 INFO - 'LOGNAME': 'cltbld', 10:28:00 INFO - 'MAIL': '/var/mail/cltbld', 10:28:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:00 INFO - 'MOZ_NO_REMOTE': '1', 10:28:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:28:00 INFO - 'NO_EM_RESTART': '1', 10:28:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:28:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:00 INFO - 'PWD': '/builds/slave/test', 10:28:00 INFO - 'SHELL': '/bin/bash', 10:28:00 INFO - 'SHLVL': '1', 10:28:00 INFO - 'TERM': 'linux', 10:28:00 INFO - 'TMOUT': '86400', 10:28:00 INFO - 'USER': 'cltbld', 10:28:00 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535', 10:28:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:00 INFO - '_': '/tools/buildbot/bin/python'} 10:28:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:01 INFO - Downloading/unpacking blobuploader==1.2.4 10:28:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:28:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:28:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:28:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:28:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:28:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:28:02 INFO - Downloading blobuploader-1.2.4.tar.gz 10:28:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:28:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:28:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:28:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:28:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:28:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:28:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:28:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:28:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:28:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:28:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:28:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:28:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:28:03 INFO - Downloading docopt-0.6.1.tar.gz 10:28:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:28:03 INFO - Installing collected packages: blobuploader, requests, docopt 10:28:03 INFO - Running setup.py install for blobuploader 10:28:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:28:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:28:03 INFO - Running setup.py install for requests 10:28:04 INFO - Running setup.py install for docopt 10:28:04 INFO - Successfully installed blobuploader requests docopt 10:28:04 INFO - Cleaning up... 10:28:04 INFO - Return code: 0 10:28:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:28:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:28:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:28:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x141c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dd180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16db3b0>, '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 0x16dc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16dc740>, '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': '3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535', '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 10:28:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:28:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:28:04 INFO - Using env: {'DISPLAY': ':0', 10:28:04 INFO - 'HOME': '/home/cltbld', 10:28:04 INFO - 'LANG': 'en_US.UTF-8', 10:28:04 INFO - 'LANGUAGE': 'en_US:en', 10:28:04 INFO - 'LOGNAME': 'cltbld', 10:28:04 INFO - 'MAIL': '/var/mail/cltbld', 10:28:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:04 INFO - 'MOZ_NO_REMOTE': '1', 10:28:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:28:04 INFO - 'NO_EM_RESTART': '1', 10:28:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:28:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:04 INFO - 'PWD': '/builds/slave/test', 10:28:04 INFO - 'SHELL': '/bin/bash', 10:28:04 INFO - 'SHLVL': '1', 10:28:04 INFO - 'TERM': 'linux', 10:28:04 INFO - 'TMOUT': '86400', 10:28:04 INFO - 'USER': 'cltbld', 10:28:04 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535', 10:28:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:04 INFO - '_': '/tools/buildbot/bin/python'} 10:28:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:28:04 INFO - Running setup.py (path:/tmp/pip-b7GtdH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:28:04 INFO - Running setup.py (path:/tmp/pip-Pgo0wP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:28:04 INFO - Running setup.py (path:/tmp/pip-EMnyjV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:28:04 INFO - Running setup.py (path:/tmp/pip-n1LOGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:28:04 INFO - Running setup.py (path:/tmp/pip-Jbg0He-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:28:04 INFO - Running setup.py (path:/tmp/pip-Vj1SN6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:28:05 INFO - Running setup.py (path:/tmp/pip-Do3a2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:28:05 INFO - Running setup.py (path:/tmp/pip-c9SeKE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:28:05 INFO - Running setup.py (path:/tmp/pip-GZHUtz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:28:05 INFO - Running setup.py (path:/tmp/pip-bQEjyv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:28:05 INFO - Running setup.py (path:/tmp/pip-GmJwnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:28:05 INFO - Running setup.py (path:/tmp/pip-fqXxCi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:28:05 INFO - Running setup.py (path:/tmp/pip-xAOIS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:28:05 INFO - Running setup.py (path:/tmp/pip-BazpQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:28:05 INFO - Running setup.py (path:/tmp/pip-7ezHLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:28:05 INFO - Running setup.py (path:/tmp/pip-GDl7kX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:28:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:28:05 INFO - Running setup.py (path:/tmp/pip-ZXDJt3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:28:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:28:05 INFO - Running setup.py install for manifestparser 10:28:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:28:05 INFO - Running setup.py install for mozcrash 10:28:06 INFO - Running setup.py install for mozdebug 10:28:06 INFO - Running setup.py install for mozdevice 10:28:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:28:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:28:06 INFO - Running setup.py install for mozfile 10:28:06 INFO - Running setup.py install for mozhttpd 10:28:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:28:06 INFO - Running setup.py install for mozinfo 10:28:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:28:06 INFO - Running setup.py install for mozInstall 10:28:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:28:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:28:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:28:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:28:06 INFO - Running setup.py install for mozleak 10:28:06 INFO - Running setup.py install for mozlog 10:28:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:28:06 INFO - Running setup.py install for moznetwork 10:28:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:28:07 INFO - Running setup.py install for mozprocess 10:28:07 INFO - Running setup.py install for mozprofile 10:28:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:28:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:28:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:28:07 INFO - Running setup.py install for mozrunner 10:28:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:28:07 INFO - Running setup.py install for mozscreenshot 10:28:07 INFO - Running setup.py install for moztest 10:28:07 INFO - Running setup.py install for mozversion 10:28:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:28:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:28:07 INFO - Cleaning up... 10:28:07 INFO - Return code: 0 10:28:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:28:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:28:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:28:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x141c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dd180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16db3b0>, '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 0x16dc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16dc740>, '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': '3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535', '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 10:28:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:28:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:28:07 INFO - Using env: {'DISPLAY': ':0', 10:28:07 INFO - 'HOME': '/home/cltbld', 10:28:07 INFO - 'LANG': 'en_US.UTF-8', 10:28:07 INFO - 'LANGUAGE': 'en_US:en', 10:28:07 INFO - 'LOGNAME': 'cltbld', 10:28:07 INFO - 'MAIL': '/var/mail/cltbld', 10:28:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:07 INFO - 'MOZ_NO_REMOTE': '1', 10:28:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:28:07 INFO - 'NO_EM_RESTART': '1', 10:28:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:28:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:07 INFO - 'PWD': '/builds/slave/test', 10:28:07 INFO - 'SHELL': '/bin/bash', 10:28:07 INFO - 'SHLVL': '1', 10:28:07 INFO - 'TERM': 'linux', 10:28:07 INFO - 'TMOUT': '86400', 10:28:07 INFO - 'USER': 'cltbld', 10:28:07 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535', 10:28:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:07 INFO - '_': '/tools/buildbot/bin/python'} 10:28:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:28:07 INFO - Running setup.py (path:/tmp/pip-lPlHbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:28:07 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:28:07 INFO - Running setup.py (path:/tmp/pip-nQmQ0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:28:08 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)) 10:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:28:08 INFO - Running setup.py (path:/tmp/pip-XAM7qk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:28:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:28:08 INFO - Running setup.py (path:/tmp/pip-AI4r6F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:28:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:28:08 INFO - Running setup.py (path:/tmp/pip-IJoJJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:28:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:28:08 INFO - Running setup.py (path:/tmp/pip-D_o3qM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:28:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:28:08 INFO - Running setup.py (path:/tmp/pip-9lJaO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:28:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:28:08 INFO - Running setup.py (path:/tmp/pip-vJydqC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:28:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:28:08 INFO - Running setup.py (path:/tmp/pip-9Nrgdv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:28:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:28:08 INFO - Running setup.py (path:/tmp/pip-YNqRVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:28:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:28:08 INFO - Running setup.py (path:/tmp/pip-LzgV70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:28:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:28:08 INFO - Running setup.py (path:/tmp/pip-vX8BEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:28:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:28:08 INFO - Running setup.py (path:/tmp/pip-B59qH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:28:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:28:08 INFO - Running setup.py (path:/tmp/pip-Yqtt8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:28:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:28:08 INFO - Running setup.py (path:/tmp/pip-a5ynGi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:28:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:28:08 INFO - Running setup.py (path:/tmp/pip-4wccH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:28:09 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)) 10:28:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:28:09 INFO - Running setup.py (path:/tmp/pip-8xNU8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:28:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:28:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:28:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:28:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:28:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:28:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:28:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:28:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:28:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:28:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:28:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:28:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:28:11 INFO - Downloading blessings-1.6.tar.gz 10:28:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:28:11 INFO - Installing collected packages: blessings 10:28:11 INFO - Running setup.py install for blessings 10:28:11 INFO - Successfully installed blessings 10:28:11 INFO - Cleaning up... 10:28:11 INFO - Return code: 0 10:28:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:28:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:28:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:28:11 INFO - Reading from file tmpfile_stdout 10:28:11 INFO - Current package versions: 10:28:11 INFO - argparse == 1.2.1 10:28:11 INFO - blessings == 1.6 10:28:11 INFO - blobuploader == 1.2.4 10:28:11 INFO - docopt == 0.6.1 10:28:11 INFO - functools32 == 3.2.3-2 10:28:11 INFO - jsonschema == 2.5.1 10:28:11 INFO - manifestparser == 1.1 10:28:11 INFO - mozInstall == 1.12 10:28:11 INFO - mozcrash == 0.17 10:28:11 INFO - mozdebug == 0.1 10:28:11 INFO - mozdevice == 0.48 10:28:11 INFO - mozfile == 1.2 10:28:11 INFO - mozhttpd == 0.7 10:28:11 INFO - mozinfo == 0.9 10:28:11 INFO - mozleak == 0.1 10:28:11 INFO - mozlog == 3.3 10:28:11 INFO - moznetwork == 0.27 10:28:11 INFO - mozprocess == 0.23 10:28:11 INFO - mozprofile == 0.28 10:28:11 INFO - mozrunner == 6.12 10:28:11 INFO - mozscreenshot == 0.1 10:28:11 INFO - mozsystemmonitor == 0.3 10:28:11 INFO - moztest == 0.7 10:28:11 INFO - mozversion == 1.4 10:28:11 INFO - psutil == 3.1.1 10:28:11 INFO - requests == 1.2.3 10:28:11 INFO - wsgiref == 0.1.2 10:28:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:28:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:28:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:28:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x141c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dd180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16db3b0>, '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 0x16dc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16dc740>, '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': '3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535', '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 10:28:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:28:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:28:11 INFO - Using env: {'DISPLAY': ':0', 10:28:11 INFO - 'HOME': '/home/cltbld', 10:28:11 INFO - 'LANG': 'en_US.UTF-8', 10:28:11 INFO - 'LANGUAGE': 'en_US:en', 10:28:11 INFO - 'LOGNAME': 'cltbld', 10:28:11 INFO - 'MAIL': '/var/mail/cltbld', 10:28:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:11 INFO - 'MOZ_NO_REMOTE': '1', 10:28:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:28:11 INFO - 'NO_EM_RESTART': '1', 10:28:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:28:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:11 INFO - 'PWD': '/builds/slave/test', 10:28:11 INFO - 'SHELL': '/bin/bash', 10:28:11 INFO - 'SHLVL': '1', 10:28:11 INFO - 'TERM': 'linux', 10:28:11 INFO - 'TMOUT': '86400', 10:28:11 INFO - 'USER': 'cltbld', 10:28:11 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535', 10:28:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:11 INFO - '_': '/tools/buildbot/bin/python'} 10:28:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:28:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:28:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:28:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:28:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:28:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:28:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:28:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:28:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:28:11 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:28:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:28:11 INFO - Cleaning up... 10:28:11 INFO - Return code: 0 10:28:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:28:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:28:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:28:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:28:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:28:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:28:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x141c030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x157f8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x16dd180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16db3b0>, '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 0x16dc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16dc740>, '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': '3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535', '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 10:28:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:28:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:28:11 INFO - Using env: {'DISPLAY': ':0', 10:28:11 INFO - 'HOME': '/home/cltbld', 10:28:11 INFO - 'LANG': 'en_US.UTF-8', 10:28:11 INFO - 'LANGUAGE': 'en_US:en', 10:28:11 INFO - 'LOGNAME': 'cltbld', 10:28:11 INFO - 'MAIL': '/var/mail/cltbld', 10:28:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:11 INFO - 'MOZ_NO_REMOTE': '1', 10:28:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:28:11 INFO - 'NO_EM_RESTART': '1', 10:28:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:28:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:11 INFO - 'PWD': '/builds/slave/test', 10:28:11 INFO - 'SHELL': '/bin/bash', 10:28:11 INFO - 'SHLVL': '1', 10:28:11 INFO - 'TERM': 'linux', 10:28:11 INFO - 'TMOUT': '86400', 10:28:11 INFO - 'USER': 'cltbld', 10:28:11 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535', 10:28:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:11 INFO - '_': '/tools/buildbot/bin/python'} 10:28:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:28:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:28:11 INFO - Cleaning up... 10:28:11 INFO - Return code: 0 10:28:11 INFO - Running post-action listener: _resource_record_post_action 10:28:11 INFO - Running post-action listener: _start_resource_monitoring 10:28:11 INFO - Starting resource monitoring. 10:28:11 INFO - [mozharness: 2016-09-05 17:28:11.961442Z] Finished create-virtualenv step (success) 10:28:11 INFO - [mozharness: 2016-09-05 17:28:11.961909Z] Running install step. 10:28:11 INFO - Running pre-action listener: _resource_record_pre_action 10:28:11 INFO - Running main action method: install 10:28:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:28:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:28:12 INFO - Reading from file tmpfile_stdout 10:28:12 INFO - Detecting whether we're running mozinstall >=1.0... 10:28:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:28:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:28:12 INFO - Reading from file tmpfile_stdout 10:28:12 INFO - Output received: 10:28:12 INFO - Usage: mozinstall [options] installer 10:28:12 INFO - Options: 10:28:12 INFO - -h, --help show this help message and exit 10:28:12 INFO - -d DEST, --destination=DEST 10:28:12 INFO - Directory to install application into. [default: 10:28:12 INFO - "/builds/slave/test"] 10:28:12 INFO - --app=APP Application being installed. [default: firefox] 10:28:12 INFO - mkdir: /builds/slave/test/build/application 10:28:12 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'] 10:28:12 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 10:28:28 INFO - Reading from file tmpfile_stdout 10:28:28 INFO - Output received: 10:28:28 INFO - /builds/slave/test/build/application/firefox/firefox 10:28:28 INFO - Running post-action listener: _resource_record_post_action 10:28:28 INFO - [mozharness: 2016-09-05 17:28:28.923613Z] Finished install step (success) 10:28:28 INFO - [mozharness: 2016-09-05 17:28:28.923890Z] Running run-tests step. 10:28:28 INFO - Running pre-action listener: _resource_record_pre_action 10:28:28 INFO - Running main action method: run_tests 10:28:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:28:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:28:28 INFO - Python 2.7.3 10:28:28 INFO - Return code: 0 10:28:28 INFO - grabbing minidump binary from tooltool 10:28:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:28:28 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 0x16db3b0>, '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 0x16dc2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x16dc740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:28:28 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 10:28:28 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 10:28:28 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 10:28:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:28:28 INFO - Return code: 0 10:28:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 10:28:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:28:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:28:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 10:28:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:28:29 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/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 10:28:29 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/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-059 --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 10:28:29 INFO - Using env: {'DISPLAY': ':0', 10:28:29 INFO - 'HOME': '/home/cltbld', 10:28:29 INFO - 'LANG': 'en_US.UTF-8', 10:28:29 INFO - 'LANGUAGE': 'en_US:en', 10:28:29 INFO - 'LOGNAME': 'cltbld', 10:28:29 INFO - 'MAIL': '/var/mail/cltbld', 10:28:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:28:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 10:28:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:28:29 INFO - 'MOZ_NO_REMOTE': '1', 10:28:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:28:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 10:28:29 INFO - 'NO_EM_RESTART': '1', 10:28:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 10:28:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:28:29 INFO - 'PWD': '/builds/slave/test', 10:28:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:28:29 INFO - 'SHELL': '/bin/bash', 10:28:29 INFO - 'SHLVL': '1', 10:28:29 INFO - 'TERM': 'linux', 10:28:29 INFO - 'TMOUT': '86400', 10:28:29 INFO - 'USER': 'cltbld', 10:28:29 INFO - 'XDG_SESSION_COOKIE': '3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535', 10:28:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:28:29 INFO - '_': '/tools/buildbot/bin/python'} 10:28:29 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/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-059', '--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 10:28:29 INFO - mozversion application_buildid: 20160905071519 10:28:29 INFO - mozversion application_changeset: 37c314d53f827e5fc094fcb9b1537b4101681507 10:28:29 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:28:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:28:29 INFO - mozversion application_name: Firefox 10:28:29 INFO - mozversion application_remotingname: firefox-dev 10:28:29 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:28:29 INFO - mozversion application_vendor: Mozilla 10:28:29 INFO - mozversion application_version: 50.0a2 10:28:29 INFO - mozversion platform_buildid: 20160905071519 10:28:29 INFO - mozversion platform_changeset: 37c314d53f827e5fc094fcb9b1537b4101681507 10:28:29 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:28:29 INFO - mozversion platform_version: 50.0a2 10:28:29 INFO - using testdate: 1473096509 10:28:29 INFO - actual date: 1473096509 10:28:29 INFO - starting webserver on 'localhost:49051' 10:28:29 INFO - SUITE-START | Running 2 tests 10:28:29 INFO - TEST-START | dromaeo_css 10:28:29 INFO - operating with platform_type : linux_ 10:28:29 INFO - Initialising browser for dromaeo_css test... 10:28:29 INFO - TEST-INFO | started process 4968 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_msAQY/profile http://localhost:49051/getInfo.html) 10:28:33 INFO - PROCESS | 4968 | __metrics Screen width/height:1600/1200 10:28:33 INFO - PROCESS | 4968 | colorDepth:24 10:28:33 INFO - PROCESS | 4968 | Browser inner width/height: 1024/697 10:28:33 INFO - PROCESS | 4968 | __metrics 10:28:34 INFO - PROCESS | 4968 | 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" 10:28:34 INFO - PROCESS | 4968 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:28:34 INFO - TEST-INFO | 4968: exit 0 10:28:34 INFO - Browser initialized. 10:28:34 INFO - Running cycle 1/1 for dromaeo_css test... 10:28:34 INFO - TEST-INFO | started process 5055 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_msAQY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:28:46 INFO - PROCESS | 5055 | RSS: Main: 145326080 10:28:46 INFO - PROCESS | 5055 | 10:29:37 INFO - PROCESS | 5055 | 1473096577553 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] 10:31:46 INFO - PROCESS | 5055 | Cycle 1(1): loaded http://localhost:49051/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49051/tests/dromaeo/cssquery-ext.html) 10:31:47 INFO - PROCESS | 5055 | RSS: Main: 275042304 10:31:47 INFO - PROCESS | 5055 | 10:34:50 INFO - PROCESS | 5055 | Cycle 1(1): loaded http://localhost:49051/tests/dromaeo/cssquery-ext.html (next: http://localhost:49051/tests/dromaeo/cssquery-jquery.html) 10:34:51 INFO - PROCESS | 5055 | RSS: Main: 232153088 10:34:51 INFO - PROCESS | 5055 | 10:37:53 INFO - PROCESS | 5055 | Cycle 1(1): loaded http://localhost:49051/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49051/tests/dromaeo/cssquery-mootools.html) 10:37:53 INFO - PROCESS | 5055 | RSS: Main: 174542848 10:37:53 INFO - PROCESS | 5055 | 10:40:55 INFO - PROCESS | 5055 | Cycle 1(1): loaded http://localhost:49051/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49051/tests/dromaeo/cssquery-prototype.html) 10:40:56 INFO - PROCESS | 5055 | RSS: Main: 221208576 10:40:56 INFO - PROCESS | 5055 | 10:43:58 INFO - PROCESS | 5055 | Cycle 1(1): loaded http://localhost:49051/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49051/tests/dromaeo/cssquery-yui.html) 10:43:59 INFO - PROCESS | 5055 | RSS: Main: 176992256 10:43:59 INFO - PROCESS | 5055 | 10:47:02 INFO - PROCESS | 5055 | Cycle 1(1): loaded http://localhost:49051/tests/dromaeo/cssquery-yui.html (next: http://localhost:49051/tests/dromaeo/cssquery-dojo.html) 10:47:02 INFO - PROCESS | 5055 | RSS: Main: 266149888 10:47:02 INFO - PROCESS | 5055 | 10:47:02 INFO - PROCESS | 5055 | __start_tp_report 10:47:02 INFO - PROCESS | 5055 | _x_x_mozilla_page_load 10:47:02 INFO - PROCESS | 5055 | _x_x_mozilla_page_load_details 10:47:02 INFO - PROCESS | 5055 | |i|pagename|runs| 10:47:02 INFO - PROCESS | 5055 | |0;dojo.html;3214.1321843102364;3238.1095198820335;3243.383757086154;3265.4285500037518;3278.9180270493234;5569.860753481165;5666.660000399974;5712.114622233556;5724.4561766632205;5746.0806270996545;5626.718664066791;5692.857678558038;5695.971520142393;5701.77192912284;5726.513246374053;5001.149804533238;5077.644564880441;5113.718745469007;5157.045946499891;5215.3741551013745;5168.224766285069;5212.400573933989;5222.9477705223035;5240.895182096356;5246.68519888806;5097;5134.101532231844;5138.511841375063;5170.431252562215;5185;5242.659227150242;5245.554127899095;5250.317458730341;5262.131748261532;5280.9471905281225;4923.950760492421;5026.095302845486;5052.898942021176;5146.022255771391;5152.433232344477;5619.157126431068;5625.437456254383;5651.434856514356;5661.065924122515;5677.659340439545;6179.783707570214;6184.536159788034;6185.474234690057;6188.628682279078;6188.81433556994;5971.552133589998;6081.452669259788;6093.695315234265;6143.692815359214;6181.598196117238;5806.767729290823;5826.213461182729;5829.7085145743395;5832.708364581754;5835.270591176145;6274.6548939808745;6317.368263173737;6329.715162817684;6333;6339.841503962438;5813.88372232553;5888.116782482662;5911.201987731602;5932;6024.97575412181;6090.23872015998;6104.511639068864;6108.140699511208;6118.571699980958;6118.5716999810475;6046.274447066292;6057.545684073712;6072.726727297282;6072.7570897164505;6072.908906366408;5873.970630146908;5929.881402371929;5937.376575459601;5983.6708981005595;5989.490893274121;4602.1025899949855;4643.187442197602;4646.024334889711;4667.299905014207;4676.929846052312;4143.917121657611;4148.854790082332;4151.937720934128;4154.480689913761;4177.791110444527;5687.886242275132;5695.857603559944;5728.054870946247;5729.0260655688635;5745.798897038583;6112.980625634706;6122.724477398528;6129.846753831101;6136.110264011782;6144.324124346318;5813.5349172066135;5816.534677225812;5817.331006934172;5824.301083869963;5824.504917082096;5027.271045698353;5053.69677819332;5085.796568137307;5119.6160287978555;5130.179171332569;6357.904631430532;6368.936310636835;6369.745210191726;6373.1714877066615;6375.362463753588;6064.4541991221;6094.786682466092;6096.939030609815;6102.084687296941;6104.969475152596;6110.77140286259;6255.186825712628;6255.78104766331;6321;6336.904946425715;6034.577579569564;6037.45662890342;6039.184710064261;6062.242219722535;6070.544709146743;6175.722092505938;6178.907316390348;6183.412575805291;6186.505079593711;6192.907106393318;5816.447437493432;5871.911921321098;5877.794277200277;6024.698765061817;6040.909386359121;5913.645181289136;5963.493103086113;5964.7912323068485;5983.850403739942;6005.39946005396;5875.911861322169;5896.941030589639;5899.91150132757;5903.970480147572;5908.852278692895;5927.851803704942;5961.463468287875;6022.759089636365;6034.818955431344;6035.094735789667;5900.822975310747;5910.083936989601;5912.083627037816;5916.053431450947;5931.13998470228 10:47:02 INFO - PROCESS | 5055 | |1;ext.html;580.4079838564664;600.6066026752477;610.6092101055323;623.9750409983601;641.7304732012554;34104.82947585262;45063.54936450635;46047.53952460474;46208.07583848325;46234.76882615582;48776.75611621937;48960.75519622397;49031.50968490314;49065.754671226685;49280.50719492804;8746.037935827055;9506.809863802737;9663.855042174373;9671.854922176173;11427.600033998811;11476.655700329004;11619.244749091282;11625.128115391337;11625.476853541568;11629.418529073513;11723.47244374001;11783.587574434896;11808.291502509877;11809.11431642626;11810.468528916177;11731.23746956445;11797.351145686984;11804;11815.881841181565;11832.112591555582;11534.653960381118;11540.422978851024;11594.072474202043;11608.4195790211;11611.129165312552;46507;46789.76605116986;46790.53209467896;46842;46895.29657055147;3982.7012974027066;4112.876613701594;4267.253230684618;4422.181896349186;4732.621390288768;34576.48135277973;34666.82666586676;35240.471392929125;35431.4685279721;37207.813960930296;4038.3538633818744;4185.623293903533;4305.354196870495;4419.801108950105;4670.299455081731;240681;240873;243032;243297.78351108308;243440;58986;59046.704766475894;59161.70419147877;59352;59521.70239148863;38860.22279554393;39753.40369894454;39885.20229595392;42897.571024289355;43046;18452.35416760407;18625;18637.81362186388;18640.16119274636;18655.160517776734;12360.134790564574;13622.795658065137;13855.930720346334;13911.652208694864;14055.367508462145;21342.14631414726;21388;21523.139074436847;21560.4609884751;21594.676079858815;7480.47636665439;7531;7659;7776.222377762178;7857.214278572212;1090.5801266512449;1090.7491277006175;1164.138537482257;1181.6927598824368;1197.700574856286;35743.64256357403;36575.634243657754;36610.45084323737;36622;36633;1150.1948635954866;1184.2775906696909;1206.849143857018;1214.2228973456818;1265.2282107914166;16938.153092345332;18474;18572.81427185738;18637.44087677372;18695.626087478173;717807;719567;720569;723214;726144;64916;65551.34448655548;65757.6712116446;66053;66159.66920165368;1357.2535105692027;1457.8979471436899;1465.0623600895435;1476.2987580899203;1509.9773503397678;34756.30487390289;37167;37228;37482.81258593799;37527;56102.43897560972;57029;57032.714836427214;57063.714681426325;57153.71423143024;472735;474273;474296;475325;475489;29688;31169.220769480944;31192.376152477278;32876.67123328736;32909.17727056843;52939.47060529499;53472;53573.73213134065;53728.46271537235;54105;44156.779216103714;45079.32381014353;46312.53687463217;46327.305090423;47896.28155577739;44440.7777961121;46252;46577.301340479244;46660.666966652214;48354.75822620864;44729.32906006347;46120.538794611624;46143.538564615264;46646.30030549477;49926.50073499219;38727;40845.79577102095;41064.384034238916;41124.38313425361;41828.90855457154;30609.693903060685;30674;30966;31334;31342.529862052543 10:47:02 INFO - PROCESS | 5055 | |2;jquery.html;379.59383459698097;398.7906349166687;410.3147743268741;418.78014042627626;431.99999999999983;16603.750943735853;20134.295299664514;20407.795922040776;20638.587228255445;20869.68695469569;19793.406197814053;19965.401037968848;20079.297224597143;20096;20329.28847490338;5263.605229607776;5779.682117483526;5900.498457631106;5973.611715238496;5980.671063091529;5851.473367396933;5925.911111333334;5937.732802023919;5937.970310148443;5940.792072277472;5653.632513886605;5938.762449502036;5959.940400595982;6042.395760423944;6049.758009679629;5825.621334613236;5959.910601340962;5977.312609049972;5983.132445795357;5983.222181116471;5840.970795145997;5844.941550584483;5847.532197424239;5848.1520179574145;5854.121881717734;14537.127772333693;17096.48710538686;21188.788112118837;24480.143194988268;24769.5046099077;2278.6012447821563;2314.8263880208883;2427.5873101572774;2433.9756602434104;3082.8766849326;9984.251181161442;10492.842607360895;10549.156067514657;10786.5685372585;10825.07986821121;2027.858049936505;2029.289748587997;2089.2896415218747;2119.311223852254;2126.415235810149;62046.22952520622;67604.3239567603;68663.31336686669;77682.6115869417;89786.55106724425;11221.551137954553;11282.887171128348;11361.772764544663;11690.941545292219;12046.277223366626;16220.918895405448;16340.591485212964;16363.509094727176;16382.344706211856;16397.18014099314;8560.61477233526;8582.828343433097;8631.78420539479;8680.435771674822;8742.737717868475;7552.962235188789;7629.237076292437;7942.44402891792;8019.1579884111325;8466.238038576557;4648.628109751212;4693.882652933704;4943.9258411123865;4963.553280204799;5065.442801291855;1941.7087436884299;1968.2815772243027;2000.2599038355902;2003.599280143977;2047.9385618431172;960.3709570231509;1018.7147598672384;1083.653230966099;1084.717973326925;1127.4081107418672;20087.096080676405;20759.377218683465;20829.79170208309;20983.265585704426;21225.469363265733;1046.4872212615765;1081.5673730507704;1085.316250762015;1109.8501702270253;1134.8411222428867;10905;11830.822537661941;11903.34531600753;11915;11922;183019;330627;528783;533948;539315;96966;97155.51422242844;100047.49976250072;103278.48360758148;104086.4795676032;997.3716558568055;1026.1790567546082;1032.2825636182931;1040.4173662748885;1042.968710938673;17709.645807084045;18037.819621804138;18153;18453.985030823434;18911.527211819262;43501.56498434975;44501.777491112334;44649.000188363025;45375.09249814919;45751.313730293405;66309.66845165743;67997.66001169963;68320.6583967077;69423;69493;7649;8517.91482085196;8856.557172141238;8891.822163556562;9009.27925765924;12499.625011249676;14030.438782448588;14059.859401406264;14450.132992020512;14452.060616059922;12260.877391225973;12274.570390036306;12459.75080498403;12535.373231338213;12557.686057848261;12179.939100304442;12357.876421235673;12508.374581271082;12770.425330859947;12778.041646876336;12391.938040310102;12408.131430799758;12617.43221555014;12799;13013.479460821462;7794.181610930771;8146;8189.385796065438;8304.086550479564;8359.331253499584;11713;12559.058070644922;12970.02724795663;13046.02154838372;13363.39864706071 10:47:02 INFO - PROCESS | 5055 | |3;mootools.html;1381.9447222111116;1504.217806740495;1550.751879699248;1708.4447554544777;1819.8271164239393;8901.243394311483;9266.860997085032;9270.721878343653;9297.814043719121;9568.712938611836;9148;9329.160375566198;9516.5241737913;9540.570674319655;9597.136257736802;2344.917927872529;2392.9760702393014;2393.1743548475747;2393.2580899920995;2395.760423957599;2349.835511514195;2375.6199008158696;2382.976170238293;2398.196604137616;2398.676178715877;2390.1395497620842;2393.1145476173842;2401.5076909233567;2419.600765873629;2420.9515809683794;2321.616933206019;2324.6745455636224;2326.6510023496526;2327.197116994643;2383.7735415135535;2281.1103669568884;2302.631578947377;2307.3539408965516;2308.0998410619873;2311.6416955371774;9380.061993800633;9559.617615295449;9615.615375385047;9654.662086826998;9667.516624168764;5414.675119492804;5538.141588053858;5545.584081193927;5560.972195139039;5575.832725018256;3615.8734444294587;3624.4200927851416;3644.180059486621;3695.0947017980498;3704.74066815323;1236.9381530923417;1259.6661884600587;1269.4351013798864;1275.7002104505434;1278.303324688038;13778.242196679179;13911.652208694864;13971.510997115052;13978.790318145237;13997.510087147002;4642.930356044662;4701.106789710013;4733.501417597893;4744.5492678195515;4908.067467181224;11799.292042477478;11831.171817972832;11835.763284734257;11845.170838041427;11845.466953987092;5182.97408512955;5218.582513398919;5235.790568377299;5250.501202385767;5252.474752524793;4329.740215587075;4578.175928332932;4666.673332866734;5023.598112151019;5081.23781432788;4347.7173983690955;4355.825766969349;4407.382966384709;4447.533009034069;4462.241418958784;1351.1892864281353;1361.339750221142;1377.9862201378057;1380.827396575428;1405.9789103163462;999.980000399988;1000.8748906386702;1001.1190152665753;1004.0712341084468;1013.5033833421718;6194.81415557534;6317.778877739257;6370.171877655967;6384.297727249998;6535.509836762262;407.95512493625677;408.22844823284015;412.13040484577516;413.98344066237615;415.60309904041566;4398.252297109499;4473.351364052194;4477.731336119843;4512.661550383735;4517.6837621366185;11898.881011189776;12413.131080824436;12428.502859885502;12430.192037517532;12430.316332601793;11585.420728963518;12067.818982715266;12075.396230188455;12311.076669249842;12370.62888113358;8831.60257788401;9004.324675649352;9038.231750301167;9042.140996605312;9059.456432613932;6407.871842563216;6467.094606754964;6519.967400163158;6539.967300163468;6615.933840661724;11157.107431405306;11345.716357091138;11418.714532136763;11535;11562.47968841421;10237.539310730886;11564.653060408202;11760;11778.939895409454;11815.763684726086;7564.810879728051;8380.497170169809;8386.496810191407;8600.39797214189;8610.61252243638;9609.375390599589;9630.662926797528;9709.46597937092;9712.854307185256;9719.076687714656;8353.916460835557;8464.322854171529;8488.236058754495;8596.91403085961;8722.694705685271;8287.337013038821;8312.002559692875;8339.041010283652;8435.114312997292;8464.66141354339;7287.781366559012;7302.087239095114;7323.450741194538;7341.9632901837285;7346.375558077623;5457.20870473787;5619.634723742944;5633.239512665901;5729.799457018984;5748.396418376177;5490.5071134685295;5608.298962629671;5656.886862262814;5758.942410575841;5807.780366122993 10:47:02 INFO - PROCESS | 5055 | |4;prototype.html;313.07174228412737;323.8105708160726;330.36075194498665;337.13356673349494;342.7138339486528;5317.5214230719275;5359.490848369408;5401.812266032263;5422.701751403672;5440.455954404558;5320.840374788753;5345.1714984177415;5372.650777699447;5396.217548455482;5397.028534863723;4328.242557552424;4334.696571239999;4339.826406943718;4349.543297953701;4353.76054317014;3865.5361356637104;4129.587041295877;4299.656027517791;4311.223979683656;4354.695171338008;4232.449781528397;4241.066965267636;4249.978750106246;4252.192083504125;4258.105797782469;4267.509236437819;4274.401583778274;4286.97856510717;4288.463942007249;4302.182585308781;3645.6718895299286;3745.962540374616;3820.8280627371837;3838.9424158637644;3860.305145073886;5289.482494141392;5292.126799078147;5293.735313234323;5320.467953204688;5332.280142180794;4944;4946.3817022872145;4948;4950.257461380786;4958.281049247875;5577.358603760579;5597.244372009767;5636.267285252891;5636.351819540723;5676.984670442016;4212.368144778277;4215.557366476506;4245.724027938174;4264.0832221072615;4278.144371125787;5558.666480011172;5777.537796976232;5873.823785286449;5942.821715348547;5946.910796338058;4254.744715317091;4271.743695378287;4277.486701595766;4288.935665965013;4318.870433886988;4356.259435895905;4401.295792673221;4403.810971037866;4408.382826404306;4415.763586195871;4794.904101917942;4877.658563900563;4891.779869905863;4935.383077115361;4945.826896058621;5621.931832951726;5624.775008999676;5658.886822263532;5685.232493613392;5691.402402747652;1469.955901322962;1497.3037537545094;1497.341169885247;1511.380334063051;1520.908745475253;927.3230541704594;969.583079275904;970.5244430229278;971.1502435369052;974.2157563161581;4825.4666300192675;4870.149343249543;4910.779014944336;4928.28539861718;4932.1861892788;5057.898842023139;5102.617303702163;5147.588192944556;5152.871178220575;5167.689938603696;4582.419065422424;4886.60907127429;4954.355933728592;4958.553730164303;4967.82612608557;2692.528807458726;2716.619673245747;2721.687005994296;2764.903228386997;2772.625695531118;5269.762860671279;5281.815136470205;5295.940811837648;5313.724706070592;5379.220013098157;5194.948050519522;5195.402528709247;5605.243292155588;5947.197128387699;5967.8209653710455;6076.635401875901;6103.725332359965;6108.786192483241;6114.57197996136;6142.6314421134875;5589.686423690511;5871.1486834409625;5879.941200587939;5915.290165180034;5922.5808385326945;5789.79735709265;5799.594028417971;5799.942000580109;5809.215755872903;5834.358220595816;5754.712264386848;5971.522278217645;6035.36628653985;6079.179310792986;6086;4243.93634095495;4339.609435150852;4352.042550638855;4375.321825117112;4375.38744575766;5714.200011998407;5937.376575459514;5941.079132734433;5951.434613711691;5969.164316995711;5699.9145012825675;5710.628809127394;5733.483986441241;5739.799107031234;5762;5643.786585884055;5688.516476099578;5701.1448282756255;5750.453706897838;5756.625819321731;5443.210734443404;5614.747336369792;5640.548756099584;5643.125315576092;5651.717414129195;5699.463738636433;5701.1448282756255;5702.657840529581;5729.341125770589;5780.248567686174;5528.530074943675;5573.219749235192;5576.33084029919;5584.989403814542;5600.467955544217 10:47:02 INFO - PROCESS | 5055 | |5;yui.html;783.6826085435398;811.9634616442256;830.7050996896102;838.1576515601819;888.5201990924901;14423.134611923278;15660.373585056599;16613.584660383498;16656.91671541642;16816.32734690614;15958.521244362659;16113.355465781355;16129;16140.919295403535;16168.029918204886;742.5767312631802;885.8051228729669;895.6327905558722;907.9364444488889;911.9179273865351;885.2784980241083;893.5576889439704;893.5621545442751;893.7542175901647;895.6865097215955;937.4984383354906;938.943663380196;938.9530523473833;939.0515579264924;940.3323640215456;768.6963649358528;913.0413066280446;931.3666706639483;932.9720108396759;946.5693109635168;701.7228194863052;702.3398005874461;702.4029574861378;702.4380495603497;703.0614130136223;15307.31117099722;15472.381104755796;15474.148921809296;15476.303566339542;15490.45783397587;13247;13447.462101516025;13642.522511712043;14050.227237501933;14220.360083796253;13164.60506184816;13203.339833008311;13477.05660603758;13573.11774734649;13804;4649.609791589814;4745.9525404745855;4866.124097662419;4927.260910863363;4937.629677774181;88737.55631221867;88984.55507722485;90644;91751.54124229403;91781;12404.75190496185;12473.438695258736;12550.1842380245;12643.87356126427;12677.239365638092;22980.310590682308;23389.41526461852;23409.180678676166;23463.178788742654;23646.408839779142;8486.702965396305;9054.185123338932;9069.138431849096;9172.082791720775;9285.257179425766;4766.094442055998;4873.80504779812;4963.131451995959;5123.538881500683;5309.283246761638;3595.6044835068124;3743.644353786386;3749.1376983293994;3753.849846006129;3754.342989976743;600.9519238460913;615.5906322295623;617.3517806303364;622.1787240842135;635.0252362623444;700.2297472779901;701.2426579294452;716.544993928859;720.5820624038046;721.9133703955454;9233.215176710055;9329.72010839676;9409.247260219166;9747.56135973883;9989.550470228856;556.5547561950424;577.4571902411719;609.0133982947565;691.4433880726064;723.598402886403;4591.7244965301525;4712.835050773207;4725.362076119749;4728.243481043017;4751.382320298339;129012;130142.34928825485;131369;131447;131677;69829.65085174542;111695;114007;117469;119133;97323.51338243263;99968;101898.62671660424;103039;103309.48345258377;8961.551922403984;9032.63869445215;9107.635694572144;9200.401973871676;9220.308476864127;57678.4232157673;58941.70529147327;59026.70486647539;59205;61979;84846.57576712077;85486.1451385503;85800;86274;87450;11828.70428239301;11948.402579871146;12026.338551379758;12035.338056406981;12200.389980501117;12669.493220271455;12963.287019214034;12999.805002925154;13001;13033.674158146123;12540.24758514492;12716.682082948;12733.490660373482;13001.739965200453;13090.672733181747;12878;13123.278219698008;13178.406971686489;13649.317534123453;13668.111572747739;12344.938275308567;12522.686932826753;13266.734665306833;13316.201027938354;13364.264965426995;9912.306138570519;10487.37075775456;10509.842352364567;10643.627473038407;10927.617533386294;10297.74255643615;10476.895231047592;10621.468926553487;10865.603871468627;10903.618373356894 10:47:02 INFO - PROCESS | 5055 | __end_tp_report 10:47:02 INFO - PROCESS | 5055 | __start_cc_report 10:47:02 INFO - PROCESS | 5055 | _x_x_mozilla_cycle_collect,1167 10:47:02 INFO - PROCESS | 5055 | __end_cc_report 10:47:02 INFO - PROCESS | 5055 | __startTimestamp1473097622288__endTimestamp 10:47:02 INFO - PROCESS | 5055 | 10:47:02 INFO - PROCESS | 5055 | ------- Summary: start ------- 10:47:02 INFO - PROCESS | 5055 | Number of tests: 6 10:47:02 INFO - PROCESS | 5055 | 10:47:02 INFO - PROCESS | 5055 | [#0] dojo.html Cycles:165 Average:5658.07 Median:5896.94 stddev:660.99 (11.2%) stddev-sans-first:634.60 10:47:02 INFO - PROCESS | 5055 | Values: 3214.1 3238.1 3243.4 3265.4 3278.9 5569.9 5666.7 5712.1 5724.5 5746.1 5626.7 5692.9 5696.0 5701.8 5726.5 5001.1 5077.6 5113.7 5157.0 5215.4 5168.2 5212.4 5222.9 5240.9 5246.7 5097.0 5134.1 5138.5 5170.4 5185.0 5242.7 5245.6 5250.3 5262.1 5280.9 4924.0 5026.1 5052.9 5146.0 5152.4 5619.2 5625.4 5651.4 5661.1 5677.7 6179.8 6184.5 6185.5 6188.6 6188.8 5971.6 6081.5 6093.7 6143.7 6181.6 5806.8 5826.2 5829.7 5832.7 5835.3 6274.7 6317.4 6329.7 6333.0 6339.8 5813.9 5888.1 5911.2 5932.0 6025.0 6090.2 6104.5 6108.1 6118.6 6118.6 6046.3 6057.5 6072.7 6072.8 6072.9 5874.0 5929.9 5937.4 5983.7 5989.5 4602.1 4643.2 4646.0 4667.3 4676.9 4143.9 4148.9 4151.9 4154.5 4177.8 5687.9 5695.9 5728.1 5729.0 5745.8 6113.0 6122.7 6129.8 6136.1 6144.3 5813.5 5816.5 5817.3 5824.3 5824.5 5027.3 5053.7 5085.8 5119.6 5130.2 6357.9 6368.9 6369.7 6373.2 6375.4 6064.5 6094.8 6096.9 6102.1 6105.0 6110.8 6255.2 6255.8 6321.0 6336.9 6034.6 6037.5 6039.2 6062.2 6070.5 6175.7 6178.9 6183.4 6186.5 6192.9 5816.4 5871.9 5877.8 6024.7 6040.9 5913.6 5963.5 5964.8 5983.9 6005.4 5875.9 5896.9 5899.9 5904.0 5908.9 5927.9 5961.5 6022.8 6034.8 6035.1 5900.8 5910.1 5912.1 5916.1 5931.1 10:47:02 INFO - PROCESS | 5055 | 10:47:02 INFO - PROCESS | 5055 | [#1] ext.html Cycles:180 Average:65387.69 Median:21933.95 stddev:138685.12 (632.3%) stddev-sans-first:138988.81 10:47:02 INFO - PROCESS | 5055 | Values: 580.4 600.6 610.6 624.0 641.7 34104.8 45063.5 46047.5 46208.1 46234.8 48776.8 48960.8 49031.5 49065.8 49280.5 8746.0 9506.8 9663.9 9671.9 11427.6 11476.7 11619.2 11625.1 11625.5 11629.4 11723.5 11783.6 11808.3 11809.1 11810.5 11731.2 11797.4 11804.0 11815.9 11832.1 11534.7 11540.4 11594.1 11608.4 11611.1 46507.0 46789.8 46790.5 46842.0 46895.3 3982.7 4112.9 4267.3 4422.2 4732.6 34576.5 34666.8 35240.5 35431.5 37207.8 4038.4 4185.6 4305.4 4419.8 4670.3 240681.0 240873.0 243032.0 243297.8 243440.0 58986.0 59046.7 59161.7 59352.0 59521.7 38860.2 39753.4 39885.2 42897.6 43046.0 18452.4 18625.0 18637.8 18640.2 18655.2 12360.1 13622.8 13855.9 13911.7 14055.4 21342.1 21388.0 21523.1 21560.5 21594.7 7480.5 7531.0 7659.0 7776.2 7857.2 1090.6 1090.7 1164.1 1181.7 1197.7 35743.6 36575.6 36610.5 36622.0 36633.0 1150.2 1184.3 1206.8 1214.2 1265.2 16938.2 18474.0 18572.8 18637.4 18695.6 717807.0 719567.0 720569.0 723214.0 726144.0 64916.0 65551.3 65757.7 66053.0 66159.7 1357.3 1457.9 1465.1 1476.3 1510.0 34756.3 37167.0 37228.0 37482.8 37527.0 56102.4 57029.0 57032.7 57063.7 57153.7 472735.0 474273.0 474296.0 475325.0 475489.0 29688.0 31169.2 31192.4 32876.7 32909.2 52939.5 53472.0 53573.7 53728.5 54105.0 44156.8 45079.3 46312.5 46327.3 47896.3 44440.8 46252.0 46577.3 46660.7 48354.8 44729.3 46120.5 46143.5 46646.3 49926.5 38727.0 40845.8 41064.4 41124.4 41828.9 30609.7 30674.0 30966.0 31334.0 31342.5 10:47:02 INFO - PROCESS | 5055 | 10:47:02 INFO - PROCESS | 5055 | [#2] jquery.html Cycles:180 Average:27803.87 Median:11654.05 stddev:74367.78 (638.1%) stddev-sans-first:74547.89 10:47:02 INFO - PROCESS | 5055 | Values: 379.6 398.8 410.3 418.8 432.0 16603.8 20134.3 20407.8 20638.6 20869.7 19793.4 19965.4 20079.3 20096.0 20329.3 5263.6 5779.7 5900.5 5973.6 5980.7 5851.5 5925.9 5937.7 5938.0 5940.8 5653.6 5938.8 5959.9 6042.4 6049.8 5825.6 5959.9 5977.3 5983.1 5983.2 5841.0 5844.9 5847.5 5848.2 5854.1 14537.1 17096.5 21188.8 24480.1 24769.5 2278.6 2314.8 2427.6 2434.0 3082.9 9984.3 10492.8 10549.2 10786.6 10825.1 2027.9 2029.3 2089.3 2119.3 2126.4 62046.2 67604.3 68663.3 77682.6 89786.6 11221.6 11282.9 11361.8 11690.9 12046.3 16220.9 16340.6 16363.5 16382.3 16397.2 8560.6 8582.8 8631.8 8680.4 8742.7 7553.0 7629.2 7942.4 8019.2 8466.2 4648.6 4693.9 4943.9 4963.6 5065.4 1941.7 1968.3 2000.3 2003.6 2047.9 960.4 1018.7 1083.7 1084.7 1127.4 20087.1 20759.4 20829.8 20983.3 21225.5 1046.5 1081.6 1085.3 1109.9 1134.8 10905.0 11830.8 11903.3 11915.0 11922.0 183019.0 330627.0 528783.0 533948.0 539315.0 96966.0 97155.5 100047.5 103278.5 104086.5 997.4 1026.2 1032.3 1040.4 1043.0 17709.6 18037.8 18153.0 18454.0 18911.5 43501.6 44501.8 44649.0 45375.1 45751.3 66309.7 67997.7 68320.7 69423.0 69493.0 7649.0 8517.9 8856.6 8891.8 9009.3 12499.6 14030.4 14059.9 14450.1 14452.1 12260.9 12274.6 12459.8 12535.4 12557.7 12179.9 12357.9 12508.4 12770.4 12778.0 12391.9 12408.1 12617.4 12799.0 13013.5 7794.2 8146.0 8189.4 8304.1 8359.3 11713.0 12559.1 12970.0 13046.0 13363.4 10:47:02 INFO - PROCESS | 5055 | 10:47:02 INFO - PROCESS | 5055 | [#3] mootools.html Cycles:180 Average:6301.56 Median:4467.80 stddev:3764.92 (84.3%) stddev-sans-first:3757.33 10:47:02 INFO - PROCESS | 5055 | Values: 1381.9 1504.2 1550.8 1708.4 1819.8 8901.2 9266.9 9270.7 9297.8 9568.7 9148.0 9329.2 9516.5 9540.6 9597.1 2344.9 2393.0 2393.2 2393.3 2395.8 2349.8 2375.6 2383.0 2398.2 2398.7 2390.1 2393.1 2401.5 2419.6 2421.0 2321.6 2324.7 2326.7 2327.2 2383.8 2281.1 2302.6 2307.4 2308.1 2311.6 9380.1 9559.6 9615.6 9654.7 9667.5 5414.7 5538.1 5545.6 5561.0 5575.8 3615.9 3624.4 3644.2 3695.1 3704.7 1236.9 1259.7 1269.4 1275.7 1278.3 13778.2 13911.7 13971.5 13978.8 13997.5 4642.9 4701.1 4733.5 4744.5 4908.1 11799.3 11831.2 11835.8 11845.2 11845.5 5183.0 5218.6 5235.8 5250.5 5252.5 4329.7 4578.2 4666.7 5023.6 5081.2 4347.7 4355.8 4407.4 4447.5 4462.2 1351.2 1361.3 1378.0 1380.8 1406.0 1000.0 1000.9 1001.1 1004.1 1013.5 6194.8 6317.8 6370.2 6384.3 6535.5 408.0 408.2 412.1 414.0 415.6 4398.3 4473.4 4477.7 4512.7 4517.7 11898.9 12413.1 12428.5 12430.2 12430.3 11585.4 12067.8 12075.4 12311.1 12370.6 8831.6 9004.3 9038.2 9042.1 9059.5 6407.9 6467.1 6520.0 6540.0 6615.9 11157.1 11345.7 11418.7 11535.0 11562.5 10237.5 11564.7 11760.0 11778.9 11815.8 7564.8 8380.5 8386.5 8600.4 8610.6 9609.4 9630.7 9709.5 9712.9 9719.1 8353.9 8464.3 8488.2 8596.9 8722.7 8287.3 8312.0 8339.0 8435.1 8464.7 7287.8 7302.1 7323.5 7342.0 7346.4 5457.2 5619.6 5633.2 5729.8 5748.4 5490.5 5608.3 5656.9 5758.9 5807.8 10:47:02 INFO - PROCESS | 5055 | 10:47:02 INFO - PROCESS | 5055 | [#4] prototype.html Cycles:180 Average:4732.82 Median:5292.93 stddev:1375.30 (26.0%) stddev-sans-first:1338.55 10:47:02 INFO - PROCESS | 5055 | Values: 313.1 323.8 330.4 337.1 342.7 5317.5 5359.5 5401.8 5422.7 5440.5 5320.8 5345.2 5372.7 5396.2 5397.0 4328.2 4334.7 4339.8 4349.5 4353.8 3865.5 4129.6 4299.7 4311.2 4354.7 4232.4 4241.1 4250.0 4252.2 4258.1 4267.5 4274.4 4287.0 4288.5 4302.2 3645.7 3746.0 3820.8 3838.9 3860.3 5289.5 5292.1 5293.7 5320.5 5332.3 4944.0 4946.4 4948.0 4950.3 4958.3 5577.4 5597.2 5636.3 5636.4 5677.0 4212.4 4215.6 4245.7 4264.1 4278.1 5558.7 5777.5 5873.8 5942.8 5946.9 4254.7 4271.7 4277.5 4288.9 4318.9 4356.3 4401.3 4403.8 4408.4 4415.8 4794.9 4877.7 4891.8 4935.4 4945.8 5621.9 5624.8 5658.9 5685.2 5691.4 1470.0 1497.3 1497.3 1511.4 1520.9 927.3 969.6 970.5 971.2 974.2 4825.5 4870.1 4910.8 4928.3 4932.2 5057.9 5102.6 5147.6 5152.9 5167.7 4582.4 4886.6 4954.4 4958.6 4967.8 2692.5 2716.6 2721.7 2764.9 2772.6 5269.8 5281.8 5295.9 5313.7 5379.2 5194.9 5195.4 5605.2 5947.2 5967.8 6076.6 6103.7 6108.8 6114.6 6142.6 5589.7 5871.1 5879.9 5915.3 5922.6 5789.8 5799.6 5799.9 5809.2 5834.4 5754.7 5971.5 6035.4 6079.2 6086.0 4243.9 4339.6 4352.0 4375.3 4375.4 5714.2 5937.4 5941.1 5951.4 5969.2 5699.9 5710.6 5733.5 5739.8 5762.0 5643.8 5688.5 5701.1 5750.5 5756.6 5443.2 5614.7 5640.5 5643.1 5651.7 5699.5 5701.1 5702.7 5729.3 5780.2 5528.5 5573.2 5576.3 5585.0 5600.5 10:47:02 INFO - PROCESS | 5055 | 10:47:02 INFO - PROCESS | 5055 | [#5] yui.html Cycles:180 Average:22855.87 Median:4737.10 stddev:34450.32 (727.2%) stddev-sans-first:34507.10 10:47:02 INFO - PROCESS | 5055 | Values: 783.7 812.0 830.7 838.2 888.5 14423.1 15660.4 16613.6 16656.9 16816.3 15958.5 16113.4 16129.0 16140.9 16168.0 742.6 885.8 895.6 907.9 911.9 885.3 893.6 893.6 893.8 895.7 937.5 938.9 939.0 939.1 940.3 768.7 913.0 931.4 933.0 946.6 701.7 702.3 702.4 702.4 703.1 15307.3 15472.4 15474.1 15476.3 15490.5 13247.0 13447.5 13642.5 14050.2 14220.4 13164.6 13203.3 13477.1 13573.1 13804.0 4649.6 4746.0 4866.1 4927.3 4937.6 88737.6 88984.6 90644.0 91751.5 91781.0 12404.8 12473.4 12550.2 12643.9 12677.2 22980.3 23389.4 23409.2 23463.2 23646.4 8486.7 9054.2 9069.1 9172.1 9285.3 4766.1 4873.8 4963.1 5123.5 5309.3 3595.6 3743.6 3749.1 3753.8 3754.3 601.0 615.6 617.4 622.2 635.0 700.2 701.2 716.5 720.6 721.9 9233.2 9329.7 9409.2 9747.6 9989.6 556.6 577.5 609.0 691.4 723.6 4591.7 4712.8 4725.4 4728.2 4751.4 129012.0 130142.3 131369.0 131447.0 131677.0 69829.7 111695.0 114007.0 117469.0 119133.0 97323.5 99968.0 101898.6 103039.0 103309.5 8961.6 9032.6 9107.6 9200.4 9220.3 57678.4 58941.7 59026.7 59205.0 61979.0 84846.6 85486.1 85800.0 86274.0 87450.0 11828.7 11948.4 12026.3 12035.3 12200.4 12669.5 12963.3 12999.8 13001.0 13033.7 12540.2 12716.7 12733.5 13001.7 13090.7 12878.0 13123.3 13178.4 13649.3 13668.1 12344.9 12522.7 13266.7 13316.2 13364.3 9912.3 10487.4 10509.8 10643.6 10927.6 10297.7 10476.9 10621.5 10865.6 10903.6 10:47:02 INFO - PROCESS | 5055 | -------- Summary: end -------- 10:47:02 INFO - PROCESS | 5055 | 10:47:03 INFO - TEST-INFO | 5055: exit 0 10:47:03 INFO - TEST-OK | dromaeo_css | took 1113930ms 10:47:03 INFO - TEST-START | kraken 10:47:03 INFO - operating with platform_type : linux_ 10:47:03 INFO - Initialising browser for kraken test... 10:47:03 INFO - TEST-INFO | started process 5254 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpx0SzZp/profile http://localhost:49051/getInfo.html) 10:47:07 INFO - PROCESS | 5254 | __metrics Screen width/height:1600/1200 10:47:07 INFO - PROCESS | 5254 | colorDepth:24 10:47:07 INFO - PROCESS | 5254 | Browser inner width/height: 1024/697 10:47:07 INFO - PROCESS | 5254 | __metrics 10:47:07 INFO - PROCESS | 5254 | 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" 10:47:07 INFO - PROCESS | 5254 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:47:08 INFO - TEST-INFO | 5254: exit 0 10:47:08 INFO - Browser initialized. 10:47:08 INFO - Running cycle 1/1 for kraken test... 10:47:08 INFO - TEST-INFO | started process 5344 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpx0SzZp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:47:20 INFO - PROCESS | 5344 | RSS: Main: 145428480 10:47:20 INFO - PROCESS | 5344 | 10:47:22 INFO - PROCESS | 5344 | Cycle 1(1): loaded http://localhost:49051/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49051/tests/kraken/driver.html?testName=audio-beat-detection) 10:47:23 INFO - PROCESS | 5344 | RSS: Main: 192057344 10:47:23 INFO - PROCESS | 5344 | 10:47:26 INFO - PROCESS | 5344 | Cycle 1(1): loaded http://localhost:49051/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49051/tests/kraken/driver.html?testName=audio-dft) 10:47:26 INFO - PROCESS | 5344 | RSS: Main: 182398976 10:47:26 INFO - PROCESS | 5344 | 10:47:29 INFO - PROCESS | 5344 | Cycle 1(1): loaded http://localhost:49051/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49051/tests/kraken/driver.html?testName=audio-fft) 10:47:30 INFO - PROCESS | 5344 | RSS: Main: 269934592 10:47:30 INFO - PROCESS | 5344 | 10:47:32 INFO - PROCESS | 5344 | Cycle 1(1): loaded http://localhost:49051/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49051/tests/kraken/driver.html?testName=audio-oscillator) 10:47:32 INFO - PROCESS | 5344 | RSS: Main: 175558656 10:47:32 INFO - PROCESS | 5344 | 10:47:35 INFO - PROCESS | 5344 | Cycle 1(1): loaded http://localhost:49051/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49051/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:47:35 INFO - PROCESS | 5344 | RSS: Main: 176623616 10:47:35 INFO - PROCESS | 5344 | 10:47:38 INFO - PROCESS | 5344 | Cycle 1(1): loaded http://localhost:49051/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49051/tests/kraken/driver.html?testName=imaging-darkroom) 10:47:39 INFO - PROCESS | 5344 | RSS: Main: 212885504 10:47:39 INFO - PROCESS | 5344 | 10:47:42 INFO - PROCESS | 5344 | Cycle 1(1): loaded http://localhost:49051/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49051/tests/kraken/driver.html?testName=imaging-desaturate) 10:47:43 INFO - PROCESS | 5344 | RSS: Main: 211918848 10:47:43 INFO - PROCESS | 5344 | 10:47:45 INFO - PROCESS | 5344 | Cycle 1(1): loaded http://localhost:49051/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49051/tests/kraken/driver.html?testName=json-parse-financial) 10:47:46 INFO - PROCESS | 5344 | RSS: Main: 211910656 10:47:46 INFO - PROCESS | 5344 | 10:47:48 INFO - PROCESS | 5344 | Cycle 1(1): loaded http://localhost:49051/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49051/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:47:48 INFO - PROCESS | 5344 | RSS: Main: 176144384 10:47:48 INFO - PROCESS | 5344 | 10:47:50 INFO - PROCESS | 5344 | Cycle 1(1): loaded http://localhost:49051/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49051/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:47:51 INFO - PROCESS | 5344 | RSS: Main: 182538240 10:47:51 INFO - PROCESS | 5344 | 10:47:52 INFO - PROCESS | 5344 | Cycle 1(1): loaded http://localhost:49051/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49051/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:47:53 INFO - PROCESS | 5344 | RSS: Main: 186404864 10:47:53 INFO - PROCESS | 5344 | 10:47:56 INFO - PROCESS | 5344 | Cycle 1(1): loaded http://localhost:49051/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49051/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:47:56 INFO - PROCESS | 5344 | RSS: Main: 180965376 10:47:56 INFO - PROCESS | 5344 | 10:47:59 INFO - PROCESS | 5344 | Cycle 1(1): loaded http://localhost:49051/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49051/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:47:59 INFO - PROCESS | 5344 | RSS: Main: 184836096 10:47:59 INFO - PROCESS | 5344 | 10:48:01 INFO - PROCESS | 5344 | Cycle 1(1): loaded http://localhost:49051/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49051/tests/kraken/driver.html?testName=ai-astar) 10:48:01 INFO - PROCESS | 5344 | RSS: Main: 235081728 10:48:01 INFO - PROCESS | 5344 | 10:48:01 INFO - PROCESS | 5344 | __start_tp_report 10:48:01 INFO - PROCESS | 5344 | _x_x_mozilla_page_load 10:48:01 INFO - PROCESS | 5344 | _x_x_mozilla_page_load_details 10:48:01 INFO - PROCESS | 5344 | |i|pagename|runs| 10:48:01 INFO - PROCESS | 5344 | |0;ai-astar;100;110;109;110;110;110;109;108;109;109 10:48:01 INFO - PROCESS | 5344 | |1;audio-beat-detection;146;115;117;188;121;122;149;122;151;120 10:48:01 INFO - PROCESS | 5344 | |2;audio-dft;162;160;161;159;160;161;160;160;158;159 10:48:01 INFO - PROCESS | 5344 | |3;audio-fft;74;75;74;116;76;76;75;75;104;74 10:48:01 INFO - PROCESS | 5344 | |4;audio-oscillator;84;83;123;83;83;84;120;83;83;129 10:48:01 INFO - PROCESS | 5344 | |5;imaging-gaussian-blur;116;117;116;117;117;116;117;117;116;116 10:48:01 INFO - PROCESS | 5344 | |6;imaging-darkroom;146;146;146;146;145;147;145;147;146;146 10:48:01 INFO - PROCESS | 5344 | |7;imaging-desaturate;95;95;95;95;96;95;95;96;96;94 10:48:01 INFO - PROCESS | 5344 | |8;json-parse-financial;52;51;54;51;51;52;52;51;52;51 10:48:01 INFO - PROCESS | 5344 | |9;json-stringify-tinderbox;61;60;61;60;61;60;60;59;59;60 10:48:01 INFO - PROCESS | 5344 | |10;stanford-crypto-aes;73;73;73;73;78;73;73;74;72;71 10:48:01 INFO - PROCESS | 5344 | |11;stanford-crypto-ccm;118;120;125;116;119;117;122;130;155;130 10:48:01 INFO - PROCESS | 5344 | |12;stanford-crypto-pbkdf2;153;154;155;156;153;155;154;156;159;152 10:48:01 INFO - PROCESS | 5344 | |13;stanford-crypto-sha256-iterative;63;61;63;62;63;61;63;60;60;61 10:48:01 INFO - PROCESS | 5344 | __end_tp_report 10:48:01 INFO - PROCESS | 5344 | __start_cc_report 10:48:01 INFO - PROCESS | 5344 | _x_x_mozilla_cycle_collect,1622 10:48:01 INFO - PROCESS | 5344 | __end_cc_report 10:48:01 INFO - PROCESS | 5344 | __startTimestamp1473097681771__endTimestamp 10:48:01 INFO - PROCESS | 5344 | 10:48:01 INFO - PROCESS | 5344 | ------- Summary: start ------- 10:48:01 INFO - PROCESS | 5344 | Number of tests: 14 10:48:01 INFO - PROCESS | 5344 | 10:48:01 INFO - PROCESS | 5344 | [#0] ai-astar Cycles:10 Average:108.40 Median:109.50 stddev:3.03 (2.8%) stddev-sans-first:0.71 10:48:01 INFO - PROCESS | 5344 | Values: 100.0 110.0 109.0 110.0 110.0 110.0 109.0 108.0 109.0 109.0 10:48:01 INFO - PROCESS | 5344 | 10:48:01 INFO - PROCESS | 5344 | [#1] audio-beat-detection Cycles:10 Average:135.10 Median:134.00 stddev:23.25 (17.4%) stddev-sans-first:24.32 10:48:01 INFO - PROCESS | 5344 | Values: 146.0 115.0 117.0 188.0 121.0 122.0 149.0 122.0 151.0 120.0 10:48:01 INFO - PROCESS | 5344 | 10:48:01 INFO - PROCESS | 5344 | [#2] audio-dft Cycles:10 Average:160.00 Median:160.00 stddev:1.15 (0.7%) stddev-sans-first:0.97 10:48:01 INFO - PROCESS | 5344 | Values: 162.0 160.0 161.0 159.0 160.0 161.0 160.0 160.0 158.0 159.0 10:48:01 INFO - PROCESS | 5344 | 10:48:01 INFO - PROCESS | 5344 | [#3] audio-fft Cycles:10 Average:81.90 Median:75.00 stddev:15.10 (20.1%) stddev-sans-first:15.74 10:48:01 INFO - PROCESS | 5344 | Values: 74.0 75.0 74.0 116.0 76.0 76.0 75.0 75.0 104.0 74.0 10:48:01 INFO - PROCESS | 5344 | 10:48:01 INFO - PROCESS | 5344 | [#4] audio-oscillator Cycles:10 Average:95.50 Median:83.00 stddev:19.79 (23.8%) stddev-sans-first:20.55 10:48:01 INFO - PROCESS | 5344 | Values: 84.0 83.0 123.0 83.0 83.0 84.0 120.0 83.0 83.0 129.0 10:48:01 INFO - PROCESS | 5344 | 10:48:01 INFO - PROCESS | 5344 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 10:48:01 INFO - PROCESS | 5344 | Values: 116.0 117.0 116.0 117.0 117.0 116.0 117.0 117.0 116.0 116.0 10:48:01 INFO - PROCESS | 5344 | 10:48:01 INFO - PROCESS | 5344 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.71 10:48:01 INFO - PROCESS | 5344 | Values: 146.0 146.0 146.0 146.0 145.0 147.0 145.0 147.0 146.0 146.0 10:48:01 INFO - PROCESS | 5344 | 10:48:01 INFO - PROCESS | 5344 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 10:48:01 INFO - PROCESS | 5344 | Values: 95.0 95.0 95.0 95.0 96.0 95.0 95.0 96.0 96.0 94.0 10:48:01 INFO - PROCESS | 5344 | 10:48:01 INFO - PROCESS | 5344 | [#8] json-parse-financial Cycles:10 Average:51.70 Median:52.00 stddev:0.95 (1.8%) stddev-sans-first:1.00 10:48:01 INFO - PROCESS | 5344 | Values: 52.0 51.0 54.0 51.0 51.0 52.0 52.0 51.0 52.0 51.0 10:48:01 INFO - PROCESS | 5344 | 10:48:01 INFO - PROCESS | 5344 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:60.00 stddev:0.74 (1.2%) stddev-sans-first:0.71 10:48:01 INFO - PROCESS | 5344 | Values: 61.0 60.0 61.0 60.0 61.0 60.0 60.0 59.0 59.0 60.0 10:48:01 INFO - PROCESS | 5344 | 10:48:01 INFO - PROCESS | 5344 | [#10] stanford-crypto-aes Cycles:10 Average:73.30 Median:73.00 stddev:1.83 (2.5%) stddev-sans-first:1.94 10:48:01 INFO - PROCESS | 5344 | Values: 73.0 73.0 73.0 73.0 78.0 73.0 73.0 74.0 72.0 71.0 10:48:01 INFO - PROCESS | 5344 | 10:48:01 INFO - PROCESS | 5344 | [#11] stanford-crypto-ccm Cycles:10 Average:125.20 Median:123.50 stddev:11.61 (9.4%) stddev-sans-first:12.02 10:48:01 INFO - PROCESS | 5344 | Values: 118.0 120.0 125.0 116.0 119.0 117.0 122.0 130.0 155.0 130.0 10:48:01 INFO - PROCESS | 5344 | 10:48:01 INFO - PROCESS | 5344 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:154.70 Median:155.00 stddev:2.00 (1.3%) stddev-sans-first:2.03 10:48:01 INFO - PROCESS | 5344 | Values: 153.0 154.0 155.0 156.0 153.0 155.0 154.0 156.0 159.0 152.0 10:48:01 INFO - PROCESS | 5344 | 10:48:01 INFO - PROCESS | 5344 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.70 Median:62.50 stddev:1.25 (2.0%) stddev-sans-first:1.24 10:48:01 INFO - PROCESS | 5344 | Values: 63.0 61.0 63.0 62.0 63.0 61.0 63.0 60.0 60.0 61.0 10:48:01 INFO - PROCESS | 5344 | -------- Summary: end -------- 10:48:01 INFO - PROCESS | 5344 | 10:48:02 INFO - TEST-INFO | 5344: exit 0 10:48:02 INFO - TEST-OK | kraken | took 59805ms 10:48:02 INFO - SUITE-END | took 1173s 10:48:02 INFO - Completed test suite (00:19:33) 10:48:02 INFO - javascript benchmark 10:48:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3214.1321843102364, 3238.1095198820335, 3243.383757086154, 3265.4285500037518, 3278.9180270493234, 5569.860753481165, 5666.660000399974, 5712.114622233556, 5724.4561766632205, 5746.0806270996545, 5626.718664066791, 5692.857678558038, 5695.971520142393, 5701.77192912284, 5726.513246374053, 5001.149804533238, 5077.644564880441, 5113.718745469007, 5157.045946499891, 5215.3741551013745, 5168.224766285069, 5212.400573933989, 5222.9477705223035, 5240.895182096356, 5246.68519888806, 5097.0, 5134.101532231844, 5138.511841375063, 5170.431252562215, 5185.0, 5242.659227150242, 5245.554127899095, 5250.317458730341, 5262.131748261532, 5280.9471905281225, 4923.950760492421, 5026.095302845486, 5052.898942021176, 5146.022255771391, 5152.433232344477, 5619.157126431068, 5625.437456254383, 5651.434856514356, 5661.065924122515, 5677.659340439545, 6179.783707570214, 6184.536159788034, 6185.474234690057, 6188.628682279078, 6188.81433556994, 5971.552133589998, 6081.452669259788, 6093.695315234265, 6143.692815359214, 6181.598196117238, 5806.767729290823, 5826.213461182729, 5829.7085145743395, 5832.708364581754, 5835.270591176145, 6274.6548939808745, 6317.368263173737, 6329.715162817684, 6333.0, 6339.841503962438, 5813.88372232553, 5888.116782482662, 5911.201987731602, 5932.0, 6024.97575412181, 6090.23872015998, 6104.511639068864, 6108.140699511208, 6118.571699980958, 6118.5716999810475, 6046.274447066292, 6057.545684073712, 6072.726727297282, 6072.7570897164505, 6072.908906366408, 5873.970630146908, 5929.881402371929, 5937.376575459601, 5983.6708981005595, 5989.490893274121, 4602.1025899949855, 4643.187442197602, 4646.024334889711, 4667.299905014207, 4676.929846052312, 4143.917121657611, 4148.854790082332, 4151.937720934128, 4154.480689913761, 4177.791110444527, 5687.886242275132, 5695.857603559944, 5728.054870946247, 5729.0260655688635, 5745.798897038583, 6112.980625634706, 6122.724477398528, 6129.846753831101, 6136.110264011782, 6144.324124346318, 5813.5349172066135, 5816.534677225812, 5817.331006934172, 5824.301083869963, 5824.504917082096, 5027.271045698353, 5053.69677819332, 5085.796568137307, 5119.6160287978555, 5130.179171332569, 6357.904631430532, 6368.936310636835, 6369.745210191726, 6373.1714877066615, 6375.362463753588, 6064.4541991221, 6094.786682466092, 6096.939030609815, 6102.084687296941, 6104.969475152596, 6110.77140286259, 6255.186825712628, 6255.78104766331, 6321.0, 6336.904946425715, 6034.577579569564, 6037.45662890342, 6039.184710064261, 6062.242219722535, 6070.544709146743, 6175.722092505938, 6178.907316390348, 6183.412575805291, 6186.505079593711, 6192.907106393318, 5816.447437493432, 5871.911921321098, 5877.794277200277, 6024.698765061817, 6040.909386359121, 5913.645181289136, 5963.493103086113, 5964.7912323068485, 5983.850403739942, 6005.39946005396, 5875.911861322169, 5896.941030589639, 5899.91150132757, 5903.970480147572, 5908.852278692895, 5927.851803704942, 5961.463468287875, 6022.759089636365, 6034.818955431344, 6035.094735789667, 5900.822975310747, 5910.083936989601, 5912.083627037816, 5916.053431450947, 5931.13998470228], "value": 5611.98016077409, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [580.4079838564664, 600.6066026752477, 610.6092101055323, 623.9750409983601, 641.7304732012554, 34104.82947585262, 45063.54936450635, 46047.53952460474, 46208.07583848325, 46234.76882615582, 48776.75611621937, 48960.75519622397, 49031.50968490314, 49065.754671226685, 49280.50719492804, 8746.037935827055, 9506.809863802737, 9663.855042174373, 9671.854922176173, 11427.600033998811, 11476.655700329004, 11619.244749091282, 11625.128115391337, 11625.476853541568, 11629.418529073513, 11723.47244374001, 11783.587574434896, 11808.291502509877, 11809.11431642626, 11810.468528916177, 11731.23746956445, 11797.351145686984, 11804.0, 11815.881841181565, 11832.112591555582, 11534.653960381118, 11540.422978851024, 11594.072474202043, 11608.4195790211, 11611.129165312552, 46507.0, 46789.76605116986, 46790.53209467896, 46842.0, 46895.29657055147, 3982.7012974027066, 4112.876613701594, 4267.253230684618, 4422.181896349186, 4732.621390288768, 34576.48135277973, 34666.82666586676, 35240.471392929125, 35431.4685279721, 37207.813960930296, 4038.3538633818744, 4185.623293903533, 4305.354196870495, 4419.801108950105, 4670.299455081731, 240681.0, 240873.0, 243032.0, 243297.78351108308, 243440.0, 58986.0, 59046.704766475894, 59161.70419147877, 59352.0, 59521.70239148863, 38860.22279554393, 39753.40369894454, 39885.20229595392, 42897.571024289355, 43046.0, 18452.35416760407, 18625.0, 18637.81362186388, 18640.16119274636, 18655.160517776734, 12360.134790564574, 13622.795658065137, 13855.930720346334, 13911.652208694864, 14055.367508462145, 21342.14631414726, 21388.0, 21523.139074436847, 21560.4609884751, 21594.676079858815, 7480.47636665439, 7531.0, 7659.0, 7776.222377762178, 7857.214278572212, 1090.5801266512449, 1090.7491277006175, 1164.138537482257, 1181.6927598824368, 1197.700574856286, 35743.64256357403, 36575.634243657754, 36610.45084323737, 36622.0, 36633.0, 1150.1948635954866, 1184.2775906696909, 1206.849143857018, 1214.2228973456818, 1265.2282107914166, 16938.153092345332, 18474.0, 18572.81427185738, 18637.44087677372, 18695.626087478173, 717807.0, 719567.0, 720569.0, 723214.0, 726144.0, 64916.0, 65551.34448655548, 65757.6712116446, 66053.0, 66159.66920165368, 1357.2535105692027, 1457.8979471436899, 1465.0623600895435, 1476.2987580899203, 1509.9773503397678, 34756.30487390289, 37167.0, 37228.0, 37482.81258593799, 37527.0, 56102.43897560972, 57029.0, 57032.714836427214, 57063.714681426325, 57153.71423143024, 472735.0, 474273.0, 474296.0, 475325.0, 475489.0, 29688.0, 31169.220769480944, 31192.376152477278, 32876.67123328736, 32909.17727056843, 52939.47060529499, 53472.0, 53573.73213134065, 53728.46271537235, 54105.0, 44156.779216103714, 45079.32381014353, 46312.53687463217, 46327.305090423, 47896.28155577739, 44440.7777961121, 46252.0, 46577.301340479244, 46660.666966652214, 48354.75822620864, 44729.32906006347, 46120.538794611624, 46143.538564615264, 46646.30030549477, 49926.50073499219, 38727.0, 40845.79577102095, 41064.384034238916, 41124.38313425361, 41828.90855457154, 30609.693903060685, 30674.0, 30966.0, 31334.0, 31342.529862052543], "value": 22075.87055051949, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.59383459698097, 398.7906349166687, 410.3147743268741, 418.78014042627626, 431.99999999999983, 16603.750943735853, 20134.295299664514, 20407.795922040776, 20638.587228255445, 20869.68695469569, 19793.406197814053, 19965.401037968848, 20079.297224597143, 20096.0, 20329.28847490338, 5263.605229607776, 5779.682117483526, 5900.498457631106, 5973.611715238496, 5980.671063091529, 5851.473367396933, 5925.911111333334, 5937.732802023919, 5937.970310148443, 5940.792072277472, 5653.632513886605, 5938.762449502036, 5959.940400595982, 6042.395760423944, 6049.758009679629, 5825.621334613236, 5959.910601340962, 5977.312609049972, 5983.132445795357, 5983.222181116471, 5840.970795145997, 5844.941550584483, 5847.532197424239, 5848.1520179574145, 5854.121881717734, 14537.127772333693, 17096.48710538686, 21188.788112118837, 24480.143194988268, 24769.5046099077, 2278.6012447821563, 2314.8263880208883, 2427.5873101572774, 2433.9756602434104, 3082.8766849326, 9984.251181161442, 10492.842607360895, 10549.156067514657, 10786.5685372585, 10825.07986821121, 2027.858049936505, 2029.289748587997, 2089.2896415218747, 2119.311223852254, 2126.415235810149, 62046.22952520622, 67604.3239567603, 68663.31336686669, 77682.6115869417, 89786.55106724425, 11221.551137954553, 11282.887171128348, 11361.772764544663, 11690.941545292219, 12046.277223366626, 16220.918895405448, 16340.591485212964, 16363.509094727176, 16382.344706211856, 16397.18014099314, 8560.61477233526, 8582.828343433097, 8631.78420539479, 8680.435771674822, 8742.737717868475, 7552.962235188789, 7629.237076292437, 7942.44402891792, 8019.1579884111325, 8466.238038576557, 4648.628109751212, 4693.882652933704, 4943.9258411123865, 4963.553280204799, 5065.442801291855, 1941.7087436884299, 1968.2815772243027, 2000.2599038355902, 2003.599280143977, 2047.9385618431172, 960.3709570231509, 1018.7147598672384, 1083.653230966099, 1084.717973326925, 1127.4081107418672, 20087.096080676405, 20759.377218683465, 20829.79170208309, 20983.265585704426, 21225.469363265733, 1046.4872212615765, 1081.5673730507704, 1085.316250762015, 1109.8501702270253, 1134.8411222428867, 10905.0, 11830.822537661941, 11903.34531600753, 11915.0, 11922.0, 183019.0, 330627.0, 528783.0, 533948.0, 539315.0, 96966.0, 97155.51422242844, 100047.49976250072, 103278.48360758148, 104086.4795676032, 997.3716558568055, 1026.1790567546082, 1032.2825636182931, 1040.4173662748885, 1042.968710938673, 17709.645807084045, 18037.819621804138, 18153.0, 18453.985030823434, 18911.527211819262, 43501.56498434975, 44501.777491112334, 44649.000188363025, 45375.09249814919, 45751.313730293405, 66309.66845165743, 67997.66001169963, 68320.6583967077, 69423.0, 69493.0, 7649.0, 8517.91482085196, 8856.557172141238, 8891.822163556562, 9009.27925765924, 12499.625011249676, 14030.438782448588, 14059.859401406264, 14450.132992020512, 14452.060616059922, 12260.877391225973, 12274.570390036306, 12459.75080498403, 12535.373231338213, 12557.686057848261, 12179.939100304442, 12357.876421235673, 12508.374581271082, 12770.425330859947, 12778.041646876336, 12391.938040310102, 12408.131430799758, 12617.43221555014, 12799.0, 13013.479460821462, 7794.181610930771, 8146.0, 8189.385796065438, 8304.086550479564, 8359.331253499584, 11713.0, 12559.058070644922, 12970.02724795663, 13046.02154838372, 13363.39864706071], "value": 9555.058239419013, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1381.9447222111116, 1504.217806740495, 1550.751879699248, 1708.4447554544777, 1819.8271164239393, 8901.243394311483, 9266.860997085032, 9270.721878343653, 9297.814043719121, 9568.712938611836, 9148.0, 9329.160375566198, 9516.5241737913, 9540.570674319655, 9597.136257736802, 2344.917927872529, 2392.9760702393014, 2393.1743548475747, 2393.2580899920995, 2395.760423957599, 2349.835511514195, 2375.6199008158696, 2382.976170238293, 2398.196604137616, 2398.676178715877, 2390.1395497620842, 2393.1145476173842, 2401.5076909233567, 2419.600765873629, 2420.9515809683794, 2321.616933206019, 2324.6745455636224, 2326.6510023496526, 2327.197116994643, 2383.7735415135535, 2281.1103669568884, 2302.631578947377, 2307.3539408965516, 2308.0998410619873, 2311.6416955371774, 9380.061993800633, 9559.617615295449, 9615.615375385047, 9654.662086826998, 9667.516624168764, 5414.675119492804, 5538.141588053858, 5545.584081193927, 5560.972195139039, 5575.832725018256, 3615.8734444294587, 3624.4200927851416, 3644.180059486621, 3695.0947017980498, 3704.74066815323, 1236.9381530923417, 1259.6661884600587, 1269.4351013798864, 1275.7002104505434, 1278.303324688038, 13778.242196679179, 13911.652208694864, 13971.510997115052, 13978.790318145237, 13997.510087147002, 4642.930356044662, 4701.106789710013, 4733.501417597893, 4744.5492678195515, 4908.067467181224, 11799.292042477478, 11831.171817972832, 11835.763284734257, 11845.170838041427, 11845.466953987092, 5182.97408512955, 5218.582513398919, 5235.790568377299, 5250.501202385767, 5252.474752524793, 4329.740215587075, 4578.175928332932, 4666.673332866734, 5023.598112151019, 5081.23781432788, 4347.7173983690955, 4355.825766969349, 4407.382966384709, 4447.533009034069, 4462.241418958784, 1351.1892864281353, 1361.339750221142, 1377.9862201378057, 1380.827396575428, 1405.9789103163462, 999.980000399988, 1000.8748906386702, 1001.1190152665753, 1004.0712341084468, 1013.5033833421718, 6194.81415557534, 6317.778877739257, 6370.171877655967, 6384.297727249998, 6535.509836762262, 407.95512493625677, 408.22844823284015, 412.13040484577516, 413.98344066237615, 415.60309904041566, 4398.252297109499, 4473.351364052194, 4477.731336119843, 4512.661550383735, 4517.6837621366185, 11898.881011189776, 12413.131080824436, 12428.502859885502, 12430.192037517532, 12430.316332601793, 11585.420728963518, 12067.818982715266, 12075.396230188455, 12311.076669249842, 12370.62888113358, 8831.60257788401, 9004.324675649352, 9038.231750301167, 9042.140996605312, 9059.456432613932, 6407.871842563216, 6467.094606754964, 6519.967400163158, 6539.967300163468, 6615.933840661724, 11157.107431405306, 11345.716357091138, 11418.714532136763, 11535.0, 11562.47968841421, 10237.539310730886, 11564.653060408202, 11760.0, 11778.939895409454, 11815.763684726086, 7564.810879728051, 8380.497170169809, 8386.496810191407, 8600.39797214189, 8610.61252243638, 9609.375390599589, 9630.662926797528, 9709.46597937092, 9712.854307185256, 9719.076687714656, 8353.916460835557, 8464.322854171529, 8488.236058754495, 8596.91403085961, 8722.694705685271, 8287.337013038821, 8312.002559692875, 8339.041010283652, 8435.114312997292, 8464.66141354339, 7287.781366559012, 7302.087239095114, 7323.450741194538, 7341.9632901837285, 7346.375558077623, 5457.20870473787, 5619.634723742944, 5633.239512665901, 5729.799457018984, 5748.396418376177, 5490.5071134685295, 5608.298962629671, 5656.886862262814, 5758.942410575841, 5807.780366122993], "value": 4860.989932307288, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.07174228412737, 323.8105708160726, 330.36075194498665, 337.13356673349494, 342.7138339486528, 5317.5214230719275, 5359.490848369408, 5401.812266032263, 5422.701751403672, 5440.455954404558, 5320.840374788753, 5345.1714984177415, 5372.650777699447, 5396.217548455482, 5397.028534863723, 4328.242557552424, 4334.696571239999, 4339.826406943718, 4349.543297953701, 4353.76054317014, 3865.5361356637104, 4129.587041295877, 4299.656027517791, 4311.223979683656, 4354.695171338008, 4232.449781528397, 4241.066965267636, 4249.978750106246, 4252.192083504125, 4258.105797782469, 4267.509236437819, 4274.401583778274, 4286.97856510717, 4288.463942007249, 4302.182585308781, 3645.6718895299286, 3745.962540374616, 3820.8280627371837, 3838.9424158637644, 3860.305145073886, 5289.482494141392, 5292.126799078147, 5293.735313234323, 5320.467953204688, 5332.280142180794, 4944.0, 4946.3817022872145, 4948.0, 4950.257461380786, 4958.281049247875, 5577.358603760579, 5597.244372009767, 5636.267285252891, 5636.351819540723, 5676.984670442016, 4212.368144778277, 4215.557366476506, 4245.724027938174, 4264.0832221072615, 4278.144371125787, 5558.666480011172, 5777.537796976232, 5873.823785286449, 5942.821715348547, 5946.910796338058, 4254.744715317091, 4271.743695378287, 4277.486701595766, 4288.935665965013, 4318.870433886988, 4356.259435895905, 4401.295792673221, 4403.810971037866, 4408.382826404306, 4415.763586195871, 4794.904101917942, 4877.658563900563, 4891.779869905863, 4935.383077115361, 4945.826896058621, 5621.931832951726, 5624.775008999676, 5658.886822263532, 5685.232493613392, 5691.402402747652, 1469.955901322962, 1497.3037537545094, 1497.341169885247, 1511.380334063051, 1520.908745475253, 927.3230541704594, 969.583079275904, 970.5244430229278, 971.1502435369052, 974.2157563161581, 4825.4666300192675, 4870.149343249543, 4910.779014944336, 4928.28539861718, 4932.1861892788, 5057.898842023139, 5102.617303702163, 5147.588192944556, 5152.871178220575, 5167.689938603696, 4582.419065422424, 4886.60907127429, 4954.355933728592, 4958.553730164303, 4967.82612608557, 2692.528807458726, 2716.619673245747, 2721.687005994296, 2764.903228386997, 2772.625695531118, 5269.762860671279, 5281.815136470205, 5295.940811837648, 5313.724706070592, 5379.220013098157, 5194.948050519522, 5195.402528709247, 5605.243292155588, 5947.197128387699, 5967.8209653710455, 6076.635401875901, 6103.725332359965, 6108.786192483241, 6114.57197996136, 6142.6314421134875, 5589.686423690511, 5871.1486834409625, 5879.941200587939, 5915.290165180034, 5922.5808385326945, 5789.79735709265, 5799.594028417971, 5799.942000580109, 5809.215755872903, 5834.358220595816, 5754.712264386848, 5971.522278217645, 6035.36628653985, 6079.179310792986, 6086.0, 4243.93634095495, 4339.609435150852, 4352.042550638855, 4375.321825117112, 4375.38744575766, 5714.200011998407, 5937.376575459514, 5941.079132734433, 5951.434613711691, 5969.164316995711, 5699.9145012825675, 5710.628809127394, 5733.483986441241, 5739.799107031234, 5762.0, 5643.786585884055, 5688.516476099578, 5701.1448282756255, 5750.453706897838, 5756.625819321731, 5443.210734443404, 5614.747336369792, 5640.548756099584, 5643.125315576092, 5651.717414129195, 5699.463738636433, 5701.1448282756255, 5702.657840529581, 5729.341125770589, 5780.248567686174, 5528.530074943675, 5573.219749235192, 5576.33084029919, 5584.989403814542, 5600.467955544217], "value": 4292.875651291931, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [783.6826085435398, 811.9634616442256, 830.7050996896102, 838.1576515601819, 888.5201990924901, 14423.134611923278, 15660.373585056599, 16613.584660383498, 16656.91671541642, 16816.32734690614, 15958.521244362659, 16113.355465781355, 16129.0, 16140.919295403535, 16168.029918204886, 742.5767312631802, 885.8051228729669, 895.6327905558722, 907.9364444488889, 911.9179273865351, 885.2784980241083, 893.5576889439704, 893.5621545442751, 893.7542175901647, 895.6865097215955, 937.4984383354906, 938.943663380196, 938.9530523473833, 939.0515579264924, 940.3323640215456, 768.6963649358528, 913.0413066280446, 931.3666706639483, 932.9720108396759, 946.5693109635168, 701.7228194863052, 702.3398005874461, 702.4029574861378, 702.4380495603497, 703.0614130136223, 15307.31117099722, 15472.381104755796, 15474.148921809296, 15476.303566339542, 15490.45783397587, 13247.0, 13447.462101516025, 13642.522511712043, 14050.227237501933, 14220.360083796253, 13164.60506184816, 13203.339833008311, 13477.05660603758, 13573.11774734649, 13804.0, 4649.609791589814, 4745.9525404745855, 4866.124097662419, 4927.260910863363, 4937.629677774181, 88737.55631221867, 88984.55507722485, 90644.0, 91751.54124229403, 91781.0, 12404.75190496185, 12473.438695258736, 12550.1842380245, 12643.87356126427, 12677.239365638092, 22980.310590682308, 23389.41526461852, 23409.180678676166, 23463.178788742654, 23646.408839779142, 8486.702965396305, 9054.185123338932, 9069.138431849096, 9172.082791720775, 9285.257179425766, 4766.094442055998, 4873.80504779812, 4963.131451995959, 5123.538881500683, 5309.283246761638, 3595.6044835068124, 3743.644353786386, 3749.1376983293994, 3753.849846006129, 3754.342989976743, 600.9519238460913, 615.5906322295623, 617.3517806303364, 622.1787240842135, 635.0252362623444, 700.2297472779901, 701.2426579294452, 716.544993928859, 720.5820624038046, 721.9133703955454, 9233.215176710055, 9329.72010839676, 9409.247260219166, 9747.56135973883, 9989.550470228856, 556.5547561950424, 577.4571902411719, 609.0133982947565, 691.4433880726064, 723.598402886403, 4591.7244965301525, 4712.835050773207, 4725.362076119749, 4728.243481043017, 4751.382320298339, 129012.0, 130142.34928825485, 131369.0, 131447.0, 131677.0, 69829.65085174542, 111695.0, 114007.0, 117469.0, 119133.0, 97323.51338243263, 99968.0, 101898.62671660424, 103039.0, 103309.48345258377, 8961.551922403984, 9032.63869445215, 9107.635694572144, 9200.401973871676, 9220.308476864127, 57678.4232157673, 58941.70529147327, 59026.70486647539, 59205.0, 61979.0, 84846.57576712077, 85486.1451385503, 85800.0, 86274.0, 87450.0, 11828.70428239301, 11948.402579871146, 12026.338551379758, 12035.338056406981, 12200.389980501117, 12669.493220271455, 12963.287019214034, 12999.805002925154, 13001.0, 13033.674158146123, 12540.24758514492, 12716.682082948, 12733.490660373482, 13001.739965200453, 13090.672733181747, 12878.0, 13123.278219698008, 13178.406971686489, 13649.317534123453, 13668.111572747739, 12344.938275308567, 12522.686932826753, 13266.734665306833, 13316.201027938354, 13364.264965426995, 9912.306138570519, 10487.37075775456, 10509.842352364567, 10643.627473038407, 10927.617533386294, 10297.74255643615, 10476.895231047592, 10621.468926553487, 10865.603871468627, 10903.618373356894], "value": 7881.990116257473, "unit": "score"}], "name": "dromaeo_css", "value": 7613.254426712054, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 110.0, 109.0, 110.0, 110.0, 110.0, 109.0, 108.0, 109.0, 109.0], "value": 108.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 115.0, 117.0, 188.0, 121.0, 122.0, 149.0, 122.0, 151.0, 120.0], "value": 135.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 161.0, 159.0, 160.0, 161.0, 160.0, 160.0, 158.0, 159.0], "value": 160.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 75.0, 74.0, 116.0, 76.0, 76.0, 75.0, 75.0, 104.0, 74.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 123.0, 83.0, 83.0, 84.0, 120.0, 83.0, 83.0, 129.0], "value": 95.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 145.0, 147.0, 145.0, 147.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 96.0, 94.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 54.0, 51.0, 51.0, 52.0, 52.0, 51.0, 52.0, 51.0], "value": 51.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 61.0, 60.0, 61.0, 60.0, 60.0, 59.0, 59.0, 60.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 73.0, 73.0, 78.0, 73.0, 73.0, 74.0, 72.0, 71.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 120.0, 125.0, 116.0, 119.0, 117.0, 122.0, 130.0, 155.0, 130.0], "value": 125.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 154.0, 155.0, 156.0, 153.0, 155.0, 154.0, 156.0, 159.0, 152.0], "value": 154.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 63.0, 62.0, 63.0, 61.0, 63.0, 60.0, 60.0, 61.0], "value": 61.7, "unit": "score"}], "name": "kraken", "value": 1465.3000000000002, "alertThreshold": 2.0}]} 10:48:03 INFO - Return code: 0 10:48:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:48:03 INFO - # TBPL SUCCESS # 10:48:03 INFO - Running post-action listener: _resource_record_post_action 10:48:03 INFO - [mozharness: 2016-09-05 17:48:03.064559Z] Finished run-tests step (success) 10:48:03 INFO - Running post-run listener: _resource_record_post_run 10:48:03 INFO - Total resource usage - Wall time: 1191s; CPU: 13.0%; Read bytes: 618496; Write bytes: 330690560; Read time: 104; Write time: 310528 10:48:03 INFO - TinderboxPrint: CPU usage
12.9% 10:48:03 INFO - TinderboxPrint: I/O read bytes / time
618,496 / 104 10:48:03 INFO - TinderboxPrint: I/O write bytes / time
330,690,560 / 310,528 10:48:03 INFO - TinderboxPrint: CPU idle
8,275.2 (87.0%) 10:48:03 INFO - TinderboxPrint: CPU user
1,185.0 (12.5%) 10:48:03 INFO - TinderboxPrint: Swap in / out
0 / 0 10:48:03 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 79241216; Read time: 0; Write time: 94072 10:48:03 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 618496; Write bytes: 251449344; Read time: 104; Write time: 216456 10:48:03 INFO - Running post-run listener: _upload_blobber_files 10:48:03 INFO - Blob upload gear active. 10:48:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:48:03 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 10:48:03 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'] 10:48:03 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 10:48:04 INFO - (blobuploader) - INFO - Open directory for files ... 10:48:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:48:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:48:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:48:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:48:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:48:15 INFO - (blobuploader) - INFO - Done attempting. 10:48:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:48:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:48:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:48:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:48:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:48:16 INFO - (blobuploader) - INFO - Done attempting. 10:48:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:48:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:48:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:48:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:48:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:48:17 INFO - (blobuploader) - INFO - Done attempting. 10:48:17 INFO - (blobuploader) - INFO - Iteration through files over. 10:48:17 INFO - Return code: 0 10:48:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:48:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:48:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6773d296b5f4e72014d40bf8c230e85ddc724b3a1fcbac58b6c8e22cb013b48c5ebe28763f9aea61d996dbd4eb3454896cd79843054f38445972f5a4f95a60a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/07b9b1317f21d42302cd4c85b34f9833847dcd3c982f8d967c5b196f626e3ca8a48f4280bfe5307554c3955aba3607770e6cc412cc87c30383fec8dbbf127a7b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/028764b2b015a00d2923d48df3e150318a0206c3e2764e1939e5e62dc423f542e2dc7c5c3158f6120644698c45556aaedae2b0a5084e4cf9783da07726f53412"} 10:48:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:48:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:48:17 INFO - Contents: 10:48:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6773d296b5f4e72014d40bf8c230e85ddc724b3a1fcbac58b6c8e22cb013b48c5ebe28763f9aea61d996dbd4eb3454896cd79843054f38445972f5a4f95a60a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/07b9b1317f21d42302cd4c85b34f9833847dcd3c982f8d967c5b196f626e3ca8a48f4280bfe5307554c3955aba3607770e6cc412cc87c30383fec8dbbf127a7b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/028764b2b015a00d2923d48df3e150318a0206c3e2764e1939e5e62dc423f542e2dc7c5c3158f6120644698c45556aaedae2b0a5084e4cf9783da07726f53412"} 10:48:17 INFO - Running post-run listener: copy_logs_to_upload_dir 10:48:17 INFO - Copying logs to upload dir... 10:48:17 INFO - mkdir: /builds/slave/test/build/upload/logs 10:48:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1686.904245 ========= master_lag: 0.09 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 6 secs) (at 2016-09-05 10:48:17.302250) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-05 10:48:17.308750) ========= 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=3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535 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/6773d296b5f4e72014d40bf8c230e85ddc724b3a1fcbac58b6c8e22cb013b48c5ebe28763f9aea61d996dbd4eb3454896cd79843054f38445972f5a4f95a60a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/07b9b1317f21d42302cd4c85b34f9833847dcd3c982f8d967c5b196f626e3ca8a48f4280bfe5307554c3955aba3607770e6cc412cc87c30383fec8dbbf127a7b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/028764b2b015a00d2923d48df3e150318a0206c3e2764e1939e5e62dc423f542e2dc7c5c3158f6120644698c45556aaedae2b0a5084e4cf9783da07726f53412"} build_url:https://queue.taskcluster.net/v1/task/AgXHR6EITOSNRTnEkhfJqA/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011272 build_url: 'https://queue.taskcluster.net/v1/task/AgXHR6EITOSNRTnEkhfJqA/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/6773d296b5f4e72014d40bf8c230e85ddc724b3a1fcbac58b6c8e22cb013b48c5ebe28763f9aea61d996dbd4eb3454896cd79843054f38445972f5a4f95a60a8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/07b9b1317f21d42302cd4c85b34f9833847dcd3c982f8d967c5b196f626e3ca8a48f4280bfe5307554c3955aba3607770e6cc412cc87c30383fec8dbbf127a7b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/028764b2b015a00d2923d48df3e150318a0206c3e2764e1939e5e62dc423f542e2dc7c5c3158f6120644698c45556aaedae2b0a5084e4cf9783da07726f53412"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-05 10:48:17.333459) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 10:48:17.334051) ========= 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=3a3620fb28aea1604ab609ef000001d2-1473095995.848160-1266176535 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004419 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-05 10:48:17.416275) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-05 10:48:17.416581) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-05 10:48:17.416886) ========= ========= Total master_lag: 0.28 =========