builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-017 starttime: 1472588342.56 results: success (0) buildid: 20160830092921 builduid: 8e1dfd2c1eae469385e3043951d3b536 revision: a163da3582e35a2e58d844fc23c28193149891ee ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 13:19:02.559525) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 13:19:02.559957) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 13:19:02.560230) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 13:19:02.586518) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 13:19:02.586818) ========= 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=0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-30 13:19:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 32.9M=0s 2016-08-30 13:19:03 (32.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.099115 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 13:19:02.705023) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 13:19:02.705294) ========= 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=0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015156 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 13:19:02.756012) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 13:19:02.756693) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a163da3582e35a2e58d844fc23c28193149891ee --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 a163da3582e35a2e58d844fc23c28193149891ee --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=0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-30 13:19:03,757 truncating revision to first 12 chars 2016-08-30 13:19:03,758 Setting DEBUG logging. 2016-08-30 13:19:03,758 attempt 1/10 2016-08-30 13:19:03,758 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/a163da3582e3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-30 13:19:04,041 unpacking tar archive at: mozilla-aurora-a163da3582e3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.630699 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 13:19:03.422446) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 13:19:03.422790) ========= script_repo_revision: a163da3582e35a2e58d844fc23c28193149891ee ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 13:19:03.423178) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 13:19:03.423456) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 13:19:03.441859) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-08-30 13:19:03.442149) ========= /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=0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:19:04 INFO - MultiFileLogger online at 20160830 13:19:04 in /builds/slave/test 13:19:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 13:19:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:19:04 INFO - {'append_to_log': False, 13:19:04 INFO - 'base_work_dir': '/builds/slave/test', 13:19:04 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:19:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:19:04 INFO - 'branch': 'Mozilla-Aurora', 13:19:04 INFO - 'buildbot_json_path': 'buildprops.json', 13:19:04 INFO - 'config_files': ('talos/linux_config.py',), 13:19:04 INFO - 'default_actions': ('clobber', 13:19:04 INFO - 'read-buildbot-config', 13:19:04 INFO - 'download-and-extract', 13:19:04 INFO - 'populate-webroot', 13:19:04 INFO - 'create-virtualenv', 13:19:04 INFO - 'install', 13:19:04 INFO - 'run-tests'), 13:19:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:19:04 INFO - 'download_minidump_stackwalk': True, 13:19:04 INFO - 'download_symbols': 'ondemand', 13:19:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:19:04 INFO - 'tooltool.py': '/tools/tooltool.py', 13:19:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:19:04 INFO - '/tools/misc-python/virtualenv.py')}, 13:19:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:19:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:19:04 INFO - 'installer_path': 'installer.exe', 13:19:04 INFO - 'log_level': 'info', 13:19:04 INFO - 'log_name': 'talos', 13:19:04 INFO - 'log_to_console': True, 13:19:04 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:19:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:19:04 INFO - 'opt_config_files': (), 13:19:04 INFO - 'pip_index': False, 13:19:04 INFO - 'sps_profile': False, 13:19:04 INFO - 'sps_profile_interval': 0, 13:19:04 INFO - 'suite': 'dromaeojs', 13:19:04 INFO - 'system_bits': '32', 13:19:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:19:04 INFO - 'title': 'talos-linux64-ix-017', 13:19:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:19:04 INFO - 'use_talos_json': True, 13:19:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:19:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:19:04 INFO - 'work_dir': 'build'} 13:19:04 INFO - [mozharness: 2016-08-30 20:19:04.524886Z] Running clobber step. 13:19:04 INFO - Running pre-action listener: _resource_record_pre_action 13:19:04 INFO - Running main action method: clobber 13:19:04 INFO - rmtree: /builds/slave/test/build 13:19:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:19:05 INFO - Running post-action listener: _resource_record_post_action 13:19:05 INFO - [mozharness: 2016-08-30 20:19:05.508302Z] Finished clobber step (success) 13:19:05 INFO - [mozharness: 2016-08-30 20:19:05.508402Z] Running read-buildbot-config step. 13:19:05 INFO - Running pre-action listener: _resource_record_pre_action 13:19:05 INFO - Running main action method: read_buildbot_config 13:19:05 INFO - Using buildbot properties: 13:19:05 INFO - { 13:19:05 INFO - "project": "", 13:19:05 INFO - "product": "firefox", 13:19:05 INFO - "script_repo_revision": "production", 13:19:05 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 13:19:05 INFO - "repository": "", 13:19:05 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 13:19:05 INFO - "buildid": "20160830092921", 13:19:05 INFO - "pgo_build": "True", 13:19:05 INFO - "basedir": "/builds/slave/test", 13:19:05 INFO - "buildnumber": 706, 13:19:05 INFO - "slavename": "talos-linux64-ix-017", 13:19:05 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 13:19:05 INFO - "platform": "ubuntu64_hw", 13:19:05 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 13:19:05 INFO - "branch": "mozilla-aurora", 13:19:05 INFO - "repo_path": "releases/mozilla-aurora", 13:19:05 INFO - "revision": "a163da3582e35a2e58d844fc23c28193149891ee", 13:19:05 INFO - "stage_platform": "linux64-pgo", 13:19:05 INFO - "builduid": "8e1dfd2c1eae469385e3043951d3b536", 13:19:05 INFO - "slavebuilddir": "test" 13:19:05 INFO - } 13:19:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2. 13:19:05 INFO - Running post-action listener: _resource_record_post_action 13:19:05 INFO - [mozharness: 2016-08-30 20:19:05.510833Z] Finished read-buildbot-config step (success) 13:19:05 INFO - [mozharness: 2016-08-30 20:19:05.510923Z] Running download-and-extract step. 13:19:05 INFO - Running pre-action listener: _resource_record_pre_action 13:19:05 INFO - Running main action method: download_and_extract 13:19:05 INFO - mkdir: /builds/slave/test/build/tests 13:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:19:05 INFO - https://queue.taskcluster.net/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 13:19:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:19:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:19:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:19:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 13:19:05 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:19:05 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:19:05 INFO - retry: Failed, sleeping 30 seconds before retrying 13:19:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #2 13:19:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:19:35 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:19:35 INFO - retry: Failed, sleeping 60 seconds before retrying 13:20:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #3 13:20:35 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:20:35 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:20:35 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json! 13:20:35 INFO - Caught exception: HTTP Error 404: Not Found 13:20:35 INFO - Caught exception: HTTP Error 404: Not Found 13:20:35 INFO - Caught exception: HTTP Error 404: Not Found 13:20:35 INFO - trying https://queue.taskcluster.net/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:20:35 INFO - Downloading https://queue.taskcluster.net/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:20:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 13:20:36 INFO - Downloaded 1361 bytes. 13:20:36 INFO - Reading from file /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.test_packages.json 13:20:36 INFO - Using the following test package requirements: 13:20:36 INFO - {u'common': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 13:20:36 INFO - u'cppunittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:20:36 INFO - u'firefox-50.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 13:20:36 INFO - u'gtest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:20:36 INFO - u'firefox-50.0a2.en-US.linux-x86_64.gtest.tests.zip'], 13:20:36 INFO - u'jittest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:20:36 INFO - u'jsshell-linux-x86_64.zip'], 13:20:36 INFO - u'mochitest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:20:36 INFO - u'firefox-50.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 13:20:36 INFO - u'mozbase': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'], 13:20:36 INFO - u'reftest': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:20:36 INFO - u'firefox-50.0a2.en-US.linux-x86_64.reftest.tests.zip'], 13:20:36 INFO - u'talos': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:20:36 INFO - u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'], 13:20:36 INFO - u'web-platform': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:20:36 INFO - u'firefox-50.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 13:20:36 INFO - u'xpcshell': [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 13:20:36 INFO - u'firefox-50.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 13:20:36 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:20:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:20:36 INFO - https://queue.taskcluster.net/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:20:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:20:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:20:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:20:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:20:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:20:36 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:20:36 INFO - retry: Failed, sleeping 30 seconds before retrying 13:21:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 13:21:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:21:06 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:21:06 INFO - retry: Failed, sleeping 60 seconds before retrying 13:22:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 13:22:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:22:06 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:22:06 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 13:22:06 INFO - Caught exception: HTTP Error 404: Not Found 13:22:06 INFO - Caught exception: HTTP Error 404: Not Found 13:22:06 INFO - Caught exception: HTTP Error 404: Not Found 13:22:06 INFO - trying https://queue.taskcluster.net/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:22:06 INFO - Downloading https://queue.taskcluster.net/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:22:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:22:07 INFO - Downloaded 21836890 bytes. 13:22:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:22:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 13:22:08 INFO - Return code: 0 13:22:08 INFO - Downloading packages: [u'firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 13:22:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:22:08 INFO - https://queue.taskcluster.net/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:22:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:22:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:22:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:22:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:22:08 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:22:08 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:22:08 INFO - retry: Failed, sleeping 30 seconds before retrying 13:22:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #2 13:22:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:22:38 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:22:38 INFO - retry: Failed, sleeping 60 seconds before retrying 13:23:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #3 13:23:38 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:23:38 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:23:38 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip! 13:23:38 INFO - Caught exception: HTTP Error 404: Not Found 13:23:38 INFO - Caught exception: HTTP Error 404: Not Found 13:23:38 INFO - Caught exception: HTTP Error 404: Not Found 13:23:38 INFO - trying https://queue.taskcluster.net/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:23:38 INFO - Downloading https://queue.taskcluster.net/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip 13:23:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 13:23:39 INFO - Downloaded 21836890 bytes. 13:23:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:23:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 13:23:40 INFO - Return code: 0 13:23:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:23:40 INFO - https://queue.taskcluster.net/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 13:23:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:23:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:23:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:23:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 13:23:40 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:23:40 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:23:40 INFO - retry: Failed, sleeping 30 seconds before retrying 13:24:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 13:24:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:24:10 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:24:10 INFO - retry: Failed, sleeping 60 seconds before retrying 13:25:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 13:25:10 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:25:10 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:25:10 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip! 13:25:10 INFO - Caught exception: HTTP Error 404: Not Found 13:25:10 INFO - Caught exception: HTTP Error 404: Not Found 13:25:10 INFO - Caught exception: HTTP Error 404: Not Found 13:25:10 INFO - trying https://queue.taskcluster.net/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:25:10 INFO - Downloading https://queue.taskcluster.net/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip 13:25:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 13:25:11 INFO - Downloaded 14413723 bytes. 13:25:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:25:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 13:25:11 INFO - Return code: 0 13:25: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')]} 13:25:11 INFO - https://queue.taskcluster.net/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 13:25:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:25:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:25:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:25:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 13:25:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:25:11 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:25:11 INFO - retry: Failed, sleeping 30 seconds before retrying 13:25:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #2 13:25:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:25:41 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:25:41 INFO - retry: Failed, sleeping 60 seconds before retrying 13:26:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #3 13:26:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:26:41 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:26:41 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2! 13:26:41 INFO - Caught exception: HTTP Error 404: Not Found 13:26:41 INFO - Caught exception: HTTP Error 404: Not Found 13:26:41 INFO - Caught exception: HTTP Error 404: Not Found 13:26:41 INFO - trying https://queue.taskcluster.net/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:26:41 INFO - Downloading https://queue.taskcluster.net/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:26:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 13:26:43 INFO - Downloaded 63888090 bytes. 13:26:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:26:43 INFO - mkdir: /builds/slave/test/properties 13:26:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:26:43 INFO - Writing to file /builds/slave/test/properties/build_url 13:26:43 INFO - Contents: 13:26:43 INFO - build_url:https://queue.taskcluster.net/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 13:26:44 INFO - Running post-action listener: _resource_record_post_action 13:26:44 INFO - Running post-action listener: set_extra_try_arguments 13:26:44 INFO - [mozharness: 2016-08-30 20:26:44.534999Z] Finished download-and-extract step (success) 13:26:44 INFO - [mozharness: 2016-08-30 20:26:44.535233Z] Running populate-webroot step. 13:26:44 INFO - Running pre-action listener: _resource_record_pre_action 13:26:44 INFO - Running main action method: populate_webroot 13:26:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:26:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:26:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:26:44 INFO - '--tppagecycles', 13:26:44 INFO - '7'], 13:26:44 INFO - 'tests': ['tsvgm']}}, 13:26:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:26:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:26:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:26:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:26:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:26:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:26:44 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:26:44 INFO - 'talos_options': ['--disable-e10s'], 13:26:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:26:44 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:26:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:26:44 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:26:44 INFO - 'talos_options': ['--disable-e10s'], 13:26:44 INFO - 'tests': ['damp', 'tps']}, 13:26:44 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:26:44 INFO - 'tests': ['damp', 'tps']}, 13:26:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:26:44 INFO - 'tests': ['dromaeo_dom']}, 13:26:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:26:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:26:44 INFO - 'tests': ['basic_compositor_video']}, 13:26:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:26:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:26:44 INFO - 'tests': ['a11yr', 13:26:44 INFO - 'ts_paint', 13:26:44 INFO - 'tpaint', 13:26:44 INFO - 'sessionrestore', 13:26:44 INFO - 'sessionrestore_no_auto_restore', 13:26:44 INFO - 'tabpaint']}, 13:26:44 INFO - 'other-e10s': {'tests': ['a11yr', 13:26:44 INFO - 'ts_paint', 13:26:44 INFO - 'tpaint', 13:26:44 INFO - 'sessionrestore', 13:26:44 INFO - 'sessionrestore_no_auto_restore', 13:26:44 INFO - 'tabpaint']}, 13:26:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:26:44 INFO - 'ts_paint', 13:26:44 INFO - 'tpaint', 13:26:44 INFO - 'sessionrestore', 13:26:44 INFO - 'sessionrestore_no_auto_restore', 13:26:44 INFO - 'tabpaint']}, 13:26:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:26:44 INFO - 'ts_paint', 13:26:44 INFO - 'tpaint', 13:26:44 INFO - 'sessionrestore', 13:26:44 INFO - 'sessionrestore_no_auto_restore', 13:26:44 INFO - 'tabpaint']}, 13:26:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:26:44 INFO - 'tests': ['a11yr', 13:26:44 INFO - 'ts_paint', 13:26:44 INFO - 'tpaint', 13:26:44 INFO - 'sessionrestore', 13:26:44 INFO - 'sessionrestore_no_auto_restore', 13:26:44 INFO - 'tabpaint']}, 13:26:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:26:44 INFO - 'tests': ['a11yr', 13:26:44 INFO - 'ts_paint', 13:26:44 INFO - 'tpaint', 13:26:44 INFO - 'sessionrestore', 13:26:44 INFO - 'sessionrestore_no_auto_restore', 13:26:44 INFO - 'tabpaint']}, 13:26:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:26:44 INFO - 'tests': ['tsvgx', 13:26:44 INFO - 'tsvgr_opacity', 13:26:44 INFO - 'tart', 13:26:44 INFO - 'tscrollx', 13:26:44 INFO - 'cart']}, 13:26:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:26:44 INFO - 'tsvgr_opacity', 13:26:44 INFO - 'tart', 13:26:44 INFO - 'tscrollx', 13:26:44 INFO - 'cart']}, 13:26:44 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:26:44 INFO - 'talos_options': ['--disable-e10s'], 13:26:44 INFO - 'tests': ['tp5o']}, 13:26:44 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:26:44 INFO - 'tests': ['tp5o']}, 13:26:44 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:26:44 INFO - 'talos_options': ['--disable-e10s', 13:26:44 INFO - '--xperf_path', 13:26:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:26:44 INFO - 'tests': ['tp5n']}, 13:26:44 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:26:44 INFO - 'talos_options': ['--xperf_path', 13:26:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:26:44 INFO - 'tests': ['tp5n']}}, 13:26:44 INFO - 'talos.zip': {'path': '', 13:26:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:26:44 INFO - Running post-action listener: _resource_record_post_action 13:26:44 INFO - [mozharness: 2016-08-30 20:26:44.551820Z] Finished populate-webroot step (success) 13:26:44 INFO - [mozharness: 2016-08-30 20:26:44.551909Z] Running create-virtualenv step. 13:26:44 INFO - Running pre-action listener: _resource_record_pre_action 13:26:44 INFO - Running main action method: create_virtualenv 13:26:44 INFO - Creating virtualenv /builds/slave/test/build/venv 13:26:44 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 13:26:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:26:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:26:44 INFO - Using real prefix '/usr' 13:26:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:26:45 INFO - Installing distribute.............................................................................................................................................................................................done. 13:26:47 INFO - Installing pip.................done. 13:26:47 INFO - Return code: 0 13:26:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:26:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:26:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:26:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:26:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26:47 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 0x2449030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27083b0>, '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 0x27092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2709740>, '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': '0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:26:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:26:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:26:47 INFO - Using env: {'DISPLAY': ':0', 13:26:47 INFO - 'HOME': '/home/cltbld', 13:26:47 INFO - 'LANG': 'en_US.UTF-8', 13:26:47 INFO - 'LANGUAGE': 'en_US:en', 13:26:47 INFO - 'LOGNAME': 'cltbld', 13:26:47 INFO - 'MAIL': '/var/mail/cltbld', 13:26:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:26:47 INFO - 'MOZ_NO_REMOTE': '1', 13:26:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:26:47 INFO - 'NO_EM_RESTART': '1', 13:26:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:26:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:26:47 INFO - 'PWD': '/builds/slave/test', 13:26:47 INFO - 'SHELL': '/bin/bash', 13:26:47 INFO - 'SHLVL': '1', 13:26:47 INFO - 'TERM': 'linux', 13:26:47 INFO - 'TMOUT': '86400', 13:26:47 INFO - 'USER': 'cltbld', 13:26:47 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003', 13:26:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:26:47 INFO - '_': '/tools/buildbot/bin/python'} 13:26:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:26:47 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 13:26:47 INFO - Cleaning up... 13:26:47 INFO - Return code: 0 13:26:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:26:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:26:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:26:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:26:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26:47 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 0x2449030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27083b0>, '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 0x27092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2709740>, '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': '0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:26:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 13:26:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 13:26:47 INFO - Using env: {'DISPLAY': ':0', 13:26:47 INFO - 'HOME': '/home/cltbld', 13:26:47 INFO - 'LANG': 'en_US.UTF-8', 13:26:47 INFO - 'LANGUAGE': 'en_US:en', 13:26:47 INFO - 'LOGNAME': 'cltbld', 13:26:47 INFO - 'MAIL': '/var/mail/cltbld', 13:26:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:26:47 INFO - 'MOZ_NO_REMOTE': '1', 13:26:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:26:47 INFO - 'NO_EM_RESTART': '1', 13:26:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:26:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:26:47 INFO - 'PWD': '/builds/slave/test', 13:26:47 INFO - 'SHELL': '/bin/bash', 13:26:47 INFO - 'SHLVL': '1', 13:26:47 INFO - 'TERM': 'linux', 13:26:47 INFO - 'TMOUT': '86400', 13:26:47 INFO - 'USER': 'cltbld', 13:26:47 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003', 13:26:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:26:47 INFO - '_': '/tools/buildbot/bin/python'} 13:26:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:26:47 INFO - Downloading/unpacking psutil>=3.1.1 13:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:26:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:26:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:26:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:26:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:26:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:26:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:26:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:26:49 INFO - Installing collected packages: psutil 13:26:49 INFO - Running setup.py install for psutil 13:26:49 INFO - building 'psutil._psutil_linux' extension 13:26:49 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 13:26:49 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 13:26:49 INFO - building 'psutil._psutil_posix' extension 13:26:49 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 13:26:49 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 13:26:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:26:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:26:50 INFO - Successfully installed psutil 13:26:50 INFO - Cleaning up... 13:26:50 INFO - Return code: 0 13:26:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:26:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:26:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:26:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2449030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27083b0>, '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 0x27092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2709740>, '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': '0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:26:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:26:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:26:50 INFO - Using env: {'DISPLAY': ':0', 13:26:50 INFO - 'HOME': '/home/cltbld', 13:26:50 INFO - 'LANG': 'en_US.UTF-8', 13:26:50 INFO - 'LANGUAGE': 'en_US:en', 13:26:50 INFO - 'LOGNAME': 'cltbld', 13:26:50 INFO - 'MAIL': '/var/mail/cltbld', 13:26:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:26:50 INFO - 'MOZ_NO_REMOTE': '1', 13:26:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:26:50 INFO - 'NO_EM_RESTART': '1', 13:26:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:26:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:26:50 INFO - 'PWD': '/builds/slave/test', 13:26:50 INFO - 'SHELL': '/bin/bash', 13:26:50 INFO - 'SHLVL': '1', 13:26:50 INFO - 'TERM': 'linux', 13:26:50 INFO - 'TMOUT': '86400', 13:26:50 INFO - 'USER': 'cltbld', 13:26:50 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003', 13:26:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:26:50 INFO - '_': '/tools/buildbot/bin/python'} 13:26:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:26:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:26:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:26:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:26:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:26:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:26:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:26:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:26:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:26:52 INFO - Installing collected packages: mozsystemmonitor 13:26:52 INFO - Running setup.py install for mozsystemmonitor 13:26:52 INFO - Successfully installed mozsystemmonitor 13:26:52 INFO - Cleaning up... 13:26:52 INFO - Return code: 0 13:26:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:26: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')]} 13:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:26:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26: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')]} 13:26:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:26:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26: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', '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 0x2449030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27083b0>, '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 0x27092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2709740>, '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': '0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:26: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 13:26: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 jsonschema==2.5.1 13:26:52 INFO - Using env: {'DISPLAY': ':0', 13:26:52 INFO - 'HOME': '/home/cltbld', 13:26:52 INFO - 'LANG': 'en_US.UTF-8', 13:26:52 INFO - 'LANGUAGE': 'en_US:en', 13:26:52 INFO - 'LOGNAME': 'cltbld', 13:26:52 INFO - 'MAIL': '/var/mail/cltbld', 13:26:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:26:52 INFO - 'MOZ_NO_REMOTE': '1', 13:26:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:26:52 INFO - 'NO_EM_RESTART': '1', 13:26:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:26:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:26:52 INFO - 'PWD': '/builds/slave/test', 13:26:52 INFO - 'SHELL': '/bin/bash', 13:26:52 INFO - 'SHLVL': '1', 13:26:52 INFO - 'TERM': 'linux', 13:26:52 INFO - 'TMOUT': '86400', 13:26:52 INFO - 'USER': 'cltbld', 13:26:52 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003', 13:26:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:26:52 INFO - '_': '/tools/buildbot/bin/python'} 13:26:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:26:52 INFO - Downloading/unpacking jsonschema==2.5.1 13:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:26:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:26:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:26:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:26:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:26:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:26:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:26:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:26:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:26:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:26:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:26:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:26:54 INFO - Downloading functools32-3.2.3-2.tar.gz 13:26:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:26:54 INFO - warning: no files found matching '*.txt' 13:26:54 INFO - no previously-included directories found matching 'build' 13:26:54 INFO - no previously-included directories found matching 'dist' 13:26:54 INFO - no previously-included directories found matching '.git*' 13:26:54 INFO - Installing collected packages: jsonschema, functools32 13:26:54 INFO - Running setup.py install for functools32 13:26:54 INFO - warning: no files found matching '*.txt' 13:26:54 INFO - no previously-included directories found matching 'build' 13:26:54 INFO - no previously-included directories found matching 'dist' 13:26:54 INFO - no previously-included directories found matching '.git*' 13:26:54 INFO - Successfully installed jsonschema functools32 13:26:54 INFO - Cleaning up... 13:26:54 INFO - Return code: 0 13:26:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:26:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:26:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:26:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:26:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26:54 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 0x2449030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27083b0>, '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 0x27092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2709740>, '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': '0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:26:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 13:26:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 13:26:54 INFO - Using env: {'DISPLAY': ':0', 13:26:54 INFO - 'HOME': '/home/cltbld', 13:26:54 INFO - 'LANG': 'en_US.UTF-8', 13:26:54 INFO - 'LANGUAGE': 'en_US:en', 13:26:54 INFO - 'LOGNAME': 'cltbld', 13:26:54 INFO - 'MAIL': '/var/mail/cltbld', 13:26:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:26:54 INFO - 'MOZ_NO_REMOTE': '1', 13:26:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:26:54 INFO - 'NO_EM_RESTART': '1', 13:26:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:26:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:26:54 INFO - 'PWD': '/builds/slave/test', 13:26:54 INFO - 'SHELL': '/bin/bash', 13:26:54 INFO - 'SHLVL': '1', 13:26:54 INFO - 'TERM': 'linux', 13:26:54 INFO - 'TMOUT': '86400', 13:26:54 INFO - 'USER': 'cltbld', 13:26:54 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003', 13:26:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:26:54 INFO - '_': '/tools/buildbot/bin/python'} 13:26:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:26:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:26:55 INFO - Cleaning up... 13:26:55 INFO - Return code: 0 13:26:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:26: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')]} 13:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:26:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26: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')]} 13:26:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:26:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26: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', '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 0x2449030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27083b0>, '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 0x27092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2709740>, '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': '0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:26: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:26: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 blobuploader==1.2.4 13:26:55 INFO - Using env: {'DISPLAY': ':0', 13:26:55 INFO - 'HOME': '/home/cltbld', 13:26:55 INFO - 'LANG': 'en_US.UTF-8', 13:26:55 INFO - 'LANGUAGE': 'en_US:en', 13:26:55 INFO - 'LOGNAME': 'cltbld', 13:26:55 INFO - 'MAIL': '/var/mail/cltbld', 13:26:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:26:55 INFO - 'MOZ_NO_REMOTE': '1', 13:26:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:26:55 INFO - 'NO_EM_RESTART': '1', 13:26:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:26:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:26:55 INFO - 'PWD': '/builds/slave/test', 13:26:55 INFO - 'SHELL': '/bin/bash', 13:26:55 INFO - 'SHLVL': '1', 13:26:55 INFO - 'TERM': 'linux', 13:26:55 INFO - 'TMOUT': '86400', 13:26:55 INFO - 'USER': 'cltbld', 13:26:55 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003', 13:26:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:26:55 INFO - '_': '/tools/buildbot/bin/python'} 13:26:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:26:55 INFO - Downloading/unpacking blobuploader==1.2.4 13:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:26:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:26:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:26:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:26:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:26:57 INFO - Downloading blobuploader-1.2.4.tar.gz 13:26:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:26:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:26:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:26:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:26:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:26:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:26:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:26:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:26:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:26:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:26:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:26:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:26:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:26:57 INFO - Downloading docopt-0.6.1.tar.gz 13:26:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:26:58 INFO - Installing collected packages: blobuploader, requests, docopt 13:26:58 INFO - Running setup.py install for blobuploader 13:26:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:26:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:26:58 INFO - Running setup.py install for requests 13:26:58 INFO - Running setup.py install for docopt 13:26:58 INFO - Successfully installed blobuploader requests docopt 13:26:58 INFO - Cleaning up... 13:26:58 INFO - Return code: 0 13:26:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:26: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')]} 13:26:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:26:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26: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')]} 13:26:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:26:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26:58 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 0x2449030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27083b0>, '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 0x27092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2709740>, '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': '0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:26:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:26:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:26:58 INFO - Using env: {'DISPLAY': ':0', 13:26:58 INFO - 'HOME': '/home/cltbld', 13:26:58 INFO - 'LANG': 'en_US.UTF-8', 13:26:58 INFO - 'LANGUAGE': 'en_US:en', 13:26:58 INFO - 'LOGNAME': 'cltbld', 13:26:58 INFO - 'MAIL': '/var/mail/cltbld', 13:26:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:26:58 INFO - 'MOZ_NO_REMOTE': '1', 13:26:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:26:58 INFO - 'NO_EM_RESTART': '1', 13:26:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:26:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:26:58 INFO - 'PWD': '/builds/slave/test', 13:26:58 INFO - 'SHELL': '/bin/bash', 13:26:58 INFO - 'SHLVL': '1', 13:26:58 INFO - 'TERM': 'linux', 13:26:58 INFO - 'TMOUT': '86400', 13:26:58 INFO - 'USER': 'cltbld', 13:26:58 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003', 13:26:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:26:58 INFO - '_': '/tools/buildbot/bin/python'} 13:26:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:26:58 INFO - Running setup.py (path:/tmp/pip-dT2ggM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:26:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:26:58 INFO - Running setup.py (path:/tmp/pip-IjxbTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:26:59 INFO - Running setup.py (path:/tmp/pip-8HUIde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:26:59 INFO - Running setup.py (path:/tmp/pip-EKuJcV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:26:59 INFO - Running setup.py (path:/tmp/pip-N4ZVzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:26:59 INFO - Running setup.py (path:/tmp/pip-M2Hh9l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:26:59 INFO - Running setup.py (path:/tmp/pip-OYhEyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:26:59 INFO - Running setup.py (path:/tmp/pip-N0fo6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:26:59 INFO - Running setup.py (path:/tmp/pip-OqTzZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:26:59 INFO - Running setup.py (path:/tmp/pip-ufExtn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:26:59 INFO - Running setup.py (path:/tmp/pip-MM6ZfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:26:59 INFO - Running setup.py (path:/tmp/pip-udxfnR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:26:59 INFO - Running setup.py (path:/tmp/pip-nk24Wo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:26:59 INFO - Running setup.py (path:/tmp/pip-FX2ZOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:26:59 INFO - Running setup.py (path:/tmp/pip-fQFj_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:26:59 INFO - Running setup.py (path:/tmp/pip-moBbbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:26:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:26:59 INFO - Running setup.py (path:/tmp/pip-CMYa6K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:27:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:27:00 INFO - Running setup.py install for manifestparser 13:27:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:27:00 INFO - Running setup.py install for mozcrash 13:27:00 INFO - Running setup.py install for mozdebug 13:27:00 INFO - Running setup.py install for mozdevice 13:27:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:27:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:27:00 INFO - Running setup.py install for mozfile 13:27:00 INFO - Running setup.py install for mozhttpd 13:27:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:27:00 INFO - Running setup.py install for mozinfo 13:27:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:27:00 INFO - Running setup.py install for mozInstall 13:27:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:27:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:27:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:27:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:27:00 INFO - Running setup.py install for mozleak 13:27:00 INFO - Running setup.py install for mozlog 13:27:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:27:01 INFO - Running setup.py install for moznetwork 13:27:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:27:01 INFO - Running setup.py install for mozprocess 13:27:01 INFO - Running setup.py install for mozprofile 13:27:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:27:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:27:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:27:01 INFO - Running setup.py install for mozrunner 13:27:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:27:01 INFO - Running setup.py install for mozscreenshot 13:27:01 INFO - Running setup.py install for moztest 13:27:01 INFO - Running setup.py install for mozversion 13:27:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:27:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:27:01 INFO - Cleaning up... 13:27:01 INFO - Return code: 0 13:27:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:27:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:27:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:01 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 0x2449030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27083b0>, '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 0x27092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2709740>, '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': '0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:27:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:27:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:27:01 INFO - Using env: {'DISPLAY': ':0', 13:27:01 INFO - 'HOME': '/home/cltbld', 13:27:01 INFO - 'LANG': 'en_US.UTF-8', 13:27:01 INFO - 'LANGUAGE': 'en_US:en', 13:27:01 INFO - 'LOGNAME': 'cltbld', 13:27:01 INFO - 'MAIL': '/var/mail/cltbld', 13:27:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:01 INFO - 'MOZ_NO_REMOTE': '1', 13:27:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:27:01 INFO - 'NO_EM_RESTART': '1', 13:27:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:27:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:01 INFO - 'PWD': '/builds/slave/test', 13:27:01 INFO - 'SHELL': '/bin/bash', 13:27:01 INFO - 'SHLVL': '1', 13:27:01 INFO - 'TERM': 'linux', 13:27:01 INFO - 'TMOUT': '86400', 13:27:01 INFO - 'USER': 'cltbld', 13:27:01 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003', 13:27:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:01 INFO - '_': '/tools/buildbot/bin/python'} 13:27:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:27:02 INFO - Running setup.py (path:/tmp/pip-4HAvCl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 13:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:27:02 INFO - Running setup.py (path:/tmp/pip-HlU0Jt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:27:02 INFO - Running setup.py (path:/tmp/pip-HN_jUJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:27:02 INFO - Running setup.py (path:/tmp/pip-8AEamV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:27:02 INFO - Running setup.py (path:/tmp/pip-E1q_Q8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 13:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:27:02 INFO - Running setup.py (path:/tmp/pip-09m6LS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 13:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:27:02 INFO - Running setup.py (path:/tmp/pip-tLv_TW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 13:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:27:02 INFO - Running setup.py (path:/tmp/pip-cb3Gqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 13:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:27:02 INFO - Running setup.py (path:/tmp/pip-5MRTut-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 13:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:27:02 INFO - Running setup.py (path:/tmp/pip-BEfkaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:27:02 INFO - Running setup.py (path:/tmp/pip-gZ93KK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 13:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:27:02 INFO - Running setup.py (path:/tmp/pip-kUngtr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 13:27:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:27:02 INFO - Running setup.py (path:/tmp/pip-YWNnhH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:27:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 13:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:27:03 INFO - Running setup.py (path:/tmp/pip-B6Ct4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:27:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 13:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:27:03 INFO - Running setup.py (path:/tmp/pip-PN9qtw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:27:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 13:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:27:03 INFO - Running setup.py (path:/tmp/pip-JA4NnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:27:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 13:27:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:27:03 INFO - Running setup.py (path:/tmp/pip-I4yScQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:27:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 13:27:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:27:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:27:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:27:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:27:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:27:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:27: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 13:27: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 13:27: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 13:27: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 13:27: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 13:27: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 13:27:05 INFO - Downloading blessings-1.6.tar.gz 13:27:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:27:05 INFO - Installing collected packages: blessings 13:27:05 INFO - Running setup.py install for blessings 13:27:05 INFO - Successfully installed blessings 13:27:05 INFO - Cleaning up... 13:27:05 INFO - Return code: 0 13:27:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:27:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:27:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:27:05 INFO - Reading from file tmpfile_stdout 13:27:05 INFO - Current package versions: 13:27:05 INFO - argparse == 1.2.1 13:27:05 INFO - blessings == 1.6 13:27:05 INFO - blobuploader == 1.2.4 13:27:05 INFO - docopt == 0.6.1 13:27:05 INFO - functools32 == 3.2.3-2 13:27:05 INFO - jsonschema == 2.5.1 13:27:05 INFO - manifestparser == 1.1 13:27:05 INFO - mozInstall == 1.12 13:27:05 INFO - mozcrash == 0.17 13:27:05 INFO - mozdebug == 0.1 13:27:05 INFO - mozdevice == 0.48 13:27:05 INFO - mozfile == 1.2 13:27:05 INFO - mozhttpd == 0.7 13:27:05 INFO - mozinfo == 0.9 13:27:05 INFO - mozleak == 0.1 13:27:05 INFO - mozlog == 3.3 13:27:05 INFO - moznetwork == 0.27 13:27:05 INFO - mozprocess == 0.23 13:27:05 INFO - mozprofile == 0.28 13:27:05 INFO - mozrunner == 6.12 13:27:05 INFO - mozscreenshot == 0.1 13:27:05 INFO - mozsystemmonitor == 0.3 13:27:05 INFO - moztest == 0.7 13:27:05 INFO - mozversion == 1.4 13:27:05 INFO - psutil == 3.1.1 13:27:05 INFO - requests == 1.2.3 13:27:05 INFO - wsgiref == 0.1.2 13:27:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:27:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:05 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 0x2449030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27083b0>, '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 0x27092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2709740>, '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': '0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:27:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 13:27:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:27:05 INFO - Using env: {'DISPLAY': ':0', 13:27:05 INFO - 'HOME': '/home/cltbld', 13:27:05 INFO - 'LANG': 'en_US.UTF-8', 13:27:05 INFO - 'LANGUAGE': 'en_US:en', 13:27:05 INFO - 'LOGNAME': 'cltbld', 13:27:05 INFO - 'MAIL': '/var/mail/cltbld', 13:27:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:05 INFO - 'MOZ_NO_REMOTE': '1', 13:27:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:27:05 INFO - 'NO_EM_RESTART': '1', 13:27:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:27:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:05 INFO - 'PWD': '/builds/slave/test', 13:27:05 INFO - 'SHELL': '/bin/bash', 13:27:05 INFO - 'SHLVL': '1', 13:27:05 INFO - 'TERM': 'linux', 13:27:05 INFO - 'TMOUT': '86400', 13:27:05 INFO - 'USER': 'cltbld', 13:27:05 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003', 13:27:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:05 INFO - '_': '/tools/buildbot/bin/python'} 13:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 13:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 13:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 13:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 13:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 13:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 13:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 13:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:27:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:27:05 INFO - Cleaning up... 13:27:05 INFO - Return code: 0 13:27:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:27:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:05 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 0x2449030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25ac8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x270a180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x27083b0>, '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 0x27092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2709740>, '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': '0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:27:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:27:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:27:05 INFO - Using env: {'DISPLAY': ':0', 13:27:05 INFO - 'HOME': '/home/cltbld', 13:27:05 INFO - 'LANG': 'en_US.UTF-8', 13:27:05 INFO - 'LANGUAGE': 'en_US:en', 13:27:05 INFO - 'LOGNAME': 'cltbld', 13:27:05 INFO - 'MAIL': '/var/mail/cltbld', 13:27:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:05 INFO - 'MOZ_NO_REMOTE': '1', 13:27:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:27:05 INFO - 'NO_EM_RESTART': '1', 13:27:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:27:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:05 INFO - 'PWD': '/builds/slave/test', 13:27:05 INFO - 'SHELL': '/bin/bash', 13:27:05 INFO - 'SHLVL': '1', 13:27:05 INFO - 'TERM': 'linux', 13:27:05 INFO - 'TMOUT': '86400', 13:27:05 INFO - 'USER': 'cltbld', 13:27:05 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003', 13:27:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:05 INFO - '_': '/tools/buildbot/bin/python'} 13:27:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:27:06 INFO - Cleaning up... 13:27:06 INFO - Return code: 0 13:27:06 INFO - Running post-action listener: _resource_record_post_action 13:27:06 INFO - Running post-action listener: _start_resource_monitoring 13:27:06 INFO - Starting resource monitoring. 13:27:06 INFO - [mozharness: 2016-08-30 20:27:06.135810Z] Finished create-virtualenv step (success) 13:27:06 INFO - [mozharness: 2016-08-30 20:27:06.136320Z] Running install step. 13:27:06 INFO - Running pre-action listener: _resource_record_pre_action 13:27:06 INFO - Running main action method: install 13:27:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:27:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:27:06 INFO - Reading from file tmpfile_stdout 13:27:06 INFO - Detecting whether we're running mozinstall >=1.0... 13:27:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:27:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:27:06 INFO - Reading from file tmpfile_stdout 13:27:06 INFO - Output received: 13:27:06 INFO - Usage: mozinstall [options] installer 13:27:06 INFO - Options: 13:27:06 INFO - -h, --help show this help message and exit 13:27:06 INFO - -d DEST, --destination=DEST 13:27:06 INFO - Directory to install application into. [default: 13:27:06 INFO - "/builds/slave/test"] 13:27:06 INFO - --app=APP Application being installed. [default: firefox] 13:27:06 INFO - mkdir: /builds/slave/test/build/application 13:27:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 13:27:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 13:27:22 INFO - Reading from file tmpfile_stdout 13:27:22 INFO - Output received: 13:27:22 INFO - /builds/slave/test/build/application/firefox/firefox 13:27:22 INFO - Running post-action listener: _resource_record_post_action 13:27:22 INFO - [mozharness: 2016-08-30 20:27:22.482632Z] Finished install step (success) 13:27:22 INFO - [mozharness: 2016-08-30 20:27:22.482906Z] Running run-tests step. 13:27:22 INFO - Running pre-action listener: _resource_record_pre_action 13:27:22 INFO - Running main action method: run_tests 13:27:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:27:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:27:22 INFO - Python 2.7.3 13:27:22 INFO - Return code: 0 13:27:22 INFO - grabbing minidump binary from tooltool 13:27:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:27:22 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 0x27083b0>, '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 0x27092b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2709740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:27:22 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 13:27:22 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 13:27:22 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 13:27:22 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:27:22 INFO - Return code: 0 13:27:22 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:27:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:27:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:27:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:27:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:27:22 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/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 13:27:22 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/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-017 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 13:27:22 INFO - Using env: {'DISPLAY': ':0', 13:27:22 INFO - 'HOME': '/home/cltbld', 13:27:22 INFO - 'LANG': 'en_US.UTF-8', 13:27:22 INFO - 'LANGUAGE': 'en_US:en', 13:27:22 INFO - 'LOGNAME': 'cltbld', 13:27:22 INFO - 'MAIL': '/var/mail/cltbld', 13:27:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:27:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:27:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:22 INFO - 'MOZ_NO_REMOTE': '1', 13:27:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:27:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:27:22 INFO - 'NO_EM_RESTART': '1', 13:27:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:27:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:22 INFO - 'PWD': '/builds/slave/test', 13:27:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:27:22 INFO - 'SHELL': '/bin/bash', 13:27:22 INFO - 'SHLVL': '1', 13:27:22 INFO - 'TERM': 'linux', 13:27:22 INFO - 'TMOUT': '86400', 13:27:22 INFO - 'USER': 'cltbld', 13:27:22 INFO - 'XDG_SESSION_COOKIE': '0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003', 13:27:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:22 INFO - '_': '/tools/buildbot/bin/python'} 13:27:22 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/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-017', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 13:27:22 INFO - mozversion application_buildid: 20160830092921 13:27:22 INFO - mozversion application_changeset: a163da3582e35a2e58d844fc23c28193149891ee 13:27:22 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:27:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:27:22 INFO - mozversion application_name: Firefox 13:27:22 INFO - mozversion application_remotingname: firefox-dev 13:27:22 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:27:22 INFO - mozversion application_vendor: Mozilla 13:27:22 INFO - mozversion application_version: 50.0a2 13:27:22 INFO - mozversion platform_buildid: 20160830092921 13:27:22 INFO - mozversion platform_changeset: a163da3582e35a2e58d844fc23c28193149891ee 13:27:22 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:27:22 INFO - mozversion platform_version: 50.0a2 13:27:22 INFO - using testdate: 1472588842 13:27:22 INFO - actual date: 1472588842 13:27:22 INFO - starting webserver on 'localhost:49575' 13:27:22 INFO - SUITE-START | Running 2 tests 13:27:22 INFO - TEST-START | dromaeo_css 13:27:22 INFO - operating with platform_type : linux_ 13:27:22 INFO - Initialising browser for dromaeo_css test... 13:27:22 INFO - TEST-INFO | started process 26006 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpT8Y8GE/profile http://localhost:49575/getInfo.html) 13:27:26 INFO - PROCESS | 26006 | __metrics Screen width/height:1600/1200 13:27:26 INFO - PROCESS | 26006 | colorDepth:24 13:27:26 INFO - PROCESS | 26006 | Browser inner width/height: 1024/697 13:27:26 INFO - PROCESS | 26006 | __metrics 13:27:27 INFO - PROCESS | 26006 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:27:27 INFO - PROCESS | 26006 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:27:27 INFO - TEST-INFO | 26006: exit 0 13:27:27 INFO - Browser initialized. 13:27:27 INFO - Running cycle 1/1 for dromaeo_css test... 13:27:27 INFO - TEST-INFO | started process 26094 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpT8Y8GE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:27:39 INFO - PROCESS | 26094 | RSS: Main: 145321984 13:27:39 INFO - PROCESS | 26094 | 13:28:30 INFO - PROCESS | 26094 | 1472588910829 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 13:30:39 INFO - PROCESS | 26094 | Cycle 1(1): loaded http://localhost:49575/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49575/tests/dromaeo/cssquery-ext.html) 13:30:40 INFO - PROCESS | 26094 | RSS: Main: 229212160 13:30:40 INFO - PROCESS | 26094 | 13:33:43 INFO - PROCESS | 26094 | Cycle 1(1): loaded http://localhost:49575/tests/dromaeo/cssquery-ext.html (next: http://localhost:49575/tests/dromaeo/cssquery-jquery.html) 13:33:44 INFO - PROCESS | 26094 | RSS: Main: 228577280 13:33:44 INFO - PROCESS | 26094 | 13:36:45 INFO - PROCESS | 26094 | Cycle 1(1): loaded http://localhost:49575/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49575/tests/dromaeo/cssquery-mootools.html) 13:36:46 INFO - PROCESS | 26094 | RSS: Main: 222650368 13:36:46 INFO - PROCESS | 26094 | 13:39:48 INFO - PROCESS | 26094 | Cycle 1(1): loaded http://localhost:49575/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49575/tests/dromaeo/cssquery-prototype.html) 13:39:49 INFO - PROCESS | 26094 | RSS: Main: 225013760 13:39:49 INFO - PROCESS | 26094 | 13:42:51 INFO - PROCESS | 26094 | Cycle 1(1): loaded http://localhost:49575/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49575/tests/dromaeo/cssquery-yui.html) 13:42:52 INFO - PROCESS | 26094 | RSS: Main: 176050176 13:42:52 INFO - PROCESS | 26094 | 13:45:54 INFO - PROCESS | 26094 | Cycle 1(1): loaded http://localhost:49575/tests/dromaeo/cssquery-yui.html (next: http://localhost:49575/tests/dromaeo/cssquery-dojo.html) 13:45:55 INFO - PROCESS | 26094 | RSS: Main: 261230592 13:45:55 INFO - PROCESS | 26094 | 13:45:55 INFO - PROCESS | 26094 | __start_tp_report 13:45:55 INFO - PROCESS | 26094 | _x_x_mozilla_page_load 13:45:55 INFO - PROCESS | 26094 | _x_x_mozilla_page_load_details 13:45:55 INFO - PROCESS | 26094 | |i|pagename|runs| 13:45:55 INFO - PROCESS | 26094 | |0;dojo.html;3259.299250661107;3276.5412842202095;3276.606807183137;3308.073739352982;3316.286998295366;5644.350899646537;5712.685802280878;5742.598018138727;5746.224259724936;5746.885062298751;5743.626664266831;5757.078867381219;5795.449432303924;5804.883902321951;5815.651060936347;5067.138586440314;5112.846614601568;5217.243499692561;5223.138182199932;5278.076336641092;5178.223266510035;5221.895562088756;5236.528712415882;5247.212918062302;5253;5021.17150670139;5037.823676171335;5049.217371307434;5050.823221187259;5247.947520524803;5119.12974794284;5141.048905952373;5154.7680354383765;5158.252053452228;5186.299849520305;4976.825811096631;5012.273220383024;5025.547700706918;5092.949070509285;5134.204198349262;5521.558275337963;5576.832695019156;5628.521575666074;5628.859278518029;5647.0964645656495;6101.542384321149;6104.145419641254;6108.175396321484;6112.388761123852;6123.357047509992;6046.667433291167;6079.787207447718;6110.694465276719;6115.149072927049;6173.475254603364;5736.713164341808;5782.710864456761;5791.189233507313;5791.88416231673;5791.88416231673;6300.401461861115;6301.968490157612;6303.2751233607805;6309.179806625201;6309.526785491014;5776.971115144482;5914.526837852962;5958.463738263577;5962.135490353874;6050.42520960508;6115.938840611626;6123.557162997653;6123.785667501706;6127.708789264043;6141.293751218667;6019.608725432833;6075.878482430327;6084.847878802977;6085.604435711664;6097.75608975636;5886.529077673776;6015.037593985029;6064.75740970365;6066.363031881677;6122.459144589048;4646.930296045562;4682.367880336179;4685.1332503486965;4708.788104535236;4709.646776491777;4151.501819781645;4169.249535083653;4170.04089059518;4181.91636167281;4193.5387107418155;5679.091345384719;5709.286339207599;5733.226014487991;5737.741801618937;5751.22358481608;6054.788082417094;6056.121862329937;6060.33336333003;6062.757489700451;6068.848278793065;5612.494875461228;5740.856478588069;5746.080627099644;5750.137479378127;5754.309482862083;5272.446393128743;5326.5472434842695;5336.706481143575;5353.544948679328;5355.758990845421;6358.745650173942;6360.52296077796;6395.232572091193;6416.844967905822;6429.646369449725;5886.793962211302;6174.351693072163;6174.629522228681;6230.875382492234;6234.532410069353;6196.81409557714;6295.370462953668;6305.243370795534;6414.03789431589;6425.4217120457515;6086.02623580225;6090.177825993434;6094.238220222473;6095.512359011357;6111.960966635772;5940.821775346746;6235.127082208403;6236.594621349598;6239.032949892774;6240.875182496416;5945.435183657545;5979.252593425822;5986.102084687332;6036.758529658764;6073.119397687399;5978.7608495661425;6004.339522652417;6014.609050411709;6017.428344307284;6022.307434644897;5730.885382292414;5866;5914.763409463573;5915.201447804491;5915.556333274935;6007.849803754941;6071.969640151771;6084.787032453843;6124.106319906441;6139.982816839433;5979.1031345296815;5982.641041537521;5986.07215881539;5986.6707331097205;6012.278526576839 13:45:55 INFO - PROCESS | 26094 | |1;ext.html;619.8295468746097;651.9902201466978;663.5421559124203;675.0818886314609;695.1901035293877;34280.4857927131;37402.438963415574;49921.50078499214;51055.48944510554;51213;48810.26784598234;49056;49082.50917490824;49113.754431227884;49115.754421227844;9262.490563019048;9374.718758437259;9401.670941517048;9403.529823508832;9409.482478463682;8636.611352489123;9254.53727313635;10911.345319280828;11156.721081972975;11170.944145279262;11506.597269095584;11577.884221157807;11590.30458172508;11599.536018559247;11600.941995290012;11433.714157146096;11490.655280341603;11503.942480287546;11511.539538418496;11512.769744605144;8498.362622803254;10854.185936054828;11198.328100314007;11230.10159187263;11621.76756464866;46339.53660463386;46648.533514664756;46671.76664116658;46771.53228467706;46862;3878.3212937735784;3945.782981935992;4044.5146582410007;4081.3469844824685;4544.363789069533;35527.644723552694;36232.094197645005;36234;36240;36440.81779591111;4186.644135248507;4215.91568168638;4218.198542276958;4243.596858298457;4249.596288352602;386943;388191;389256.99999999715;390500;392447;67290.66354668196;67606;67704.32295677079;67796.6610166946;67933.66033169802;70819.29180708127;70957;71023.64488177525;71235.64382178056;71317.64341178365;15837.604059898364;16207.918960405359;16212.837871621368;16214.675706485805;16237.51287461378;12803;13015.804762928563;13123.803142952864;13169.078164528582;13201.603951881267;18334.503580957582;21159.25942592002;21222.151113955268;21262.362129136152;21279;7803.258690424401;7830.2952734254195;7848.332891704269;7898.328642065374;7917.287444129941;1031.6492392586406;1083.5611577311201;1109.8834622364698;1145.8796826333282;1227.1900545639835;34955.650443495746;35307;35316;35372.82313588416;35433.46849797255;1165.930044197351;1205.180477275461;1244.3031902134653;1256.9120161588785;1270.9046821488425;18422.539436514195;18433.170507327202;18557.814421855877;18558.257669693332;18657.346992855186;714431;722289;722782.316530505;726214;727857;65081;65283.34716652868;65989.34010659928;65997;66042.33957660459;1364.2292104960864;1416.85123062077;1436.1885534672876;1468.2658670664669;1482.918439485817;30987.211150996518;36061.45907811437;36114.638853611126;36275.45586816147;36390.454143187344;53615.463845361046;54721.452785471636;54833.177502338294;54874.72562637161;54994;487635;489731;489799;491097;491970;30850.228744281572;30883.536746948364;31016.224594384417;31533;33100.83449582737;53530.73234633958;53755.19367209573;53794.46205537894;54012;54557.090501832245;44334.33498497461;45151;45491.77254113841;45845.31232031455;46998;44689.77655111834;46353.53646463492;46565.76717116528;47182.29226561535;47556.76221619008;44341.11317773691;44601;45477;48264.758676206395;48341.27488087752;39279.80360098295;40619.7969010153;40674.38988415117;40792.796036020816;41617.79191104025;30458.695413045585;30519.54220686647;30679.386412272077;30686;31223.375532489677 13:45:55 INFO - PROCESS | 26094 | |2;jquery.html;398.69300638508366;407.37467986640513;428.5543035243347;429.2702405909954;439.8768344863439;15571.688566228682;20136.69794953073;20762.584748305024;20776.376708698725;20887.164513419444;19778;19835.60328793423;19837.900810495965;19903.701444478345;19971.900140499314;5722.456366645162;5754.16564598133;5895.528357731371;5909.822705318826;5932.406759324076;5863.061910094386;5953.345132035494;5957.553183511232;5959.433853783905;5962.850928726773;5815.098659707752;6028.457438830526;6042.57701960863;6150.876982460348;6173.043178307369;5988.850278743066;6068;6101.267847858263;6103.115048318014;6162.414570615784;5959.791407300767;6020.458158765732;6031.065184896348;6031.728572214261;6035.698215089228;23110.884445577834;23968;24231.636525452133;24302;24575.50848983028;2485.763852434016;2543.1480454047914;2548.413864811085;2727.7954153438573;2840.1621521651164;10635.989580989793;11288.379139147344;13569.05016648835;15326.693466130726;15788.447404340908;1952.746143001415;2029.5027718208985;2048.805363490481;2064.0814837397365;2083.9895800521053;66289.66855165693;80096.59951700205;85498;85541.57229213876;86475.56762216211;11391.829122563167;11537.711557210969;11649.76700465986;11886.346250956107;11908.285502869856;16727.16364181786;16765.916170419314;16848.32606695743;16848.494545163663;16899.662006759794;8055.63749631268;8365.874511882326;8389.203025712548;8512.574371281411;8582.485050896967;8010.639521221559;8035.088757980379;8089.9191008089165;8173.223543763333;8323.6670533178;5058.266551350033;5078.289039534468;5130.48695130484;5140.640155189101;5257.526822585985;2049.938501844917;2053.373721015075;2131.179495894061;2152.343535221741;2163.5023944492855;966.2318456826839;1064.8030114428855;1075.1143237927186;1134.1493879590307;1150.0914277720674;19957.600847982958;20317.695234571493;20483.97580120988;20592;20678.48303792387;991.3457118301886;1023.4319952426475;1062.0706881478707;1075.3977772447454;1075.693427373195;10527.368357898398;11430.199886008066;11444.427778611036;11602.593909213138;11625;494470;496848;512730;519025;521897;59584;60750.696246518484;86969.56515217383;87321;88597.55701221453;1034.8033873564;1069.9461030884572;1081.8431327457631;1088.5210507376887;1089.5696200001114;18138.455846324632;18313.450596482126;18566.443006709822;18603.90698046501;18712.43862684067;44402.11195776002;44692.62070818052;44902.10195796131;45107.32339014983;46131.53868461272;55946;56651;56762.14856777069;62221;64235.71528569496;9073.773155670897;9245.214156796748;9303.813923721624;9378.624855005723;9591.136797688241;10824.567017319534;12846;14036.719265614835;14152.22162781057;14404.135751854923;11892.405379731152;11927.821082683593;12105.455254513392;12389;12742.299173545543;7120.0387947626405;8118.715844945398;12191.207571507824;12227.327496987751;12326.198797078161;12231.816522752344;12279.570215042431;12427.689307767378;12590.433430495466;12602.747945041232;8826.691065812665;8911.73264802057;9076.727698169065;9228.630854765734;9297.395669281475;9277.489738064201;11481.885181148082;12134.33261170644;12513.874861251272;12656.050796190506 13:45:55 INFO - PROCESS | 26094 | |3;mootools.html;1384.238668732197;1515.4695856450244;1582.2088955522238;1725.792904851418;1832.3311991123235;8453.49279043257;8765.517896515697;8857.025727170007;8860.512671803048;9094.45433274004;8554.272886804629;8758.34312426569;8978;8991.640334386617;8999.235065019464;2436.7685069918375;2488.415222422729;2494.837835540684;2498.1631153563576;2500.6749122614033;2440.3289095498703;2494.4761600063916;2498.4503409249937;2500.4874000829786;2503.962440563384;2271.2277825539313;2288.5766133265397;2299.8505097168713;2306.08909480755;2306.2850516339904;2377.8573285602833;2387.55830171418;2391.1152873436768;2405.0620258099357;2457.213691618683;2372.5966585680476;2380.4048987753063;2392.629142482918;2395.317334559642;2396.808255339569;8939.687110951085;8974.102589741038;8975.775605609846;8987.775305617346;9011.864822027675;5482.451754824526;5706.77172913083;5716.1997320375185;5720.427957204288;5725.914111288334;3390.1016230698883;3420.675035871588;3428.125827913866;3436.9828150859335;3483.8432270547632;1036.1296510930779;1045.8954104589557;1075.854759607451;1100.878903320634;1102.4432661505969;13145.60563183106;13344.666383340396;13369.465221391132;13410;13436.328183590878;4843.709377437365;4871.439784424837;4898.387701537308;4958.727270000185;5130.127878260705;11211.439428028565;11357.261777984404;11394.1454390921;11397.373144477133;11430.714232144097;5524.640898341595;5549.639273447213;5553.1670249462095;5553.444655534495;5555.027870122713;4222.4299719538085;4426.623736982328;4524.547545245515;5047.520485553867;5094.719790411563;4160.793369922749;4172.165566886635;4232.090100628379;4261.808218630169;4272.359146128106;1320.742455221242;1330.60081975407;1398.7622104242303;1404.1785555449972;1413.4699487692114;1024.5082360467018;1025.2566889005411;1035.4563853976572;1039.4958445154093;1040.9115225205792;6335.239771227482;6474.708638111297;6638.236602790645;6656.707499253024;6693.966530167318;471.5308268273022;475.4484797634796;476.0384024270953;481.0379241516966;493.7703967655058;4691.695039822401;4699.882502937454;4721.220998535203;4797.424309082932;4811.639127065484;11860.584879529175;12218.633440996784;12290.20113692607;12313.753724925451;12329.198602090835;11270.267432616854;12201.328926908927;12244.571439999736;12248.510059597516;12270.570530031406;8741.91258087411;8926.821463570823;8952;8953.865692014626;8961.865572016424;6659.966700166468;6688.662267546413;6727.730890764315;6840.110785597841;6857;10682.305650132717;11158.163137764537;11182.552697892319;11325.263857849213;11429.542818287175;9995.950020249853;11427.771444571228;11476.483558240066;11547.711307217387;11557.653270401901;7590.886136708064;8588.26999705032;8619.396642234984;8830.293576513992;8838.98351689564;8865.734027979172;9512.762180945256;9601.37591056579;9604;9608.615655373707;7888.881666775118;8165;8230.341572674051;8249.711260105867;8333.083360830426;8015.7595272141925;8024.2376974187355;8094.716684915999;8115.188481151838;8161.632726527439;7601.543907365576;7763.611819408894;7813;8002.239787220205;8023.277904988579;5416.160495123262;5488.5334746545395;5526.115821468546;5641.548676105819;5713.171590119493;5511.834644960658;5575.135853942646;5609.074502707008;5690.231818704422;5710.828675139753 13:45:55 INFO - PROCESS | 26094 | |4;prototype.html;336.06908862451013;342.375165323285;347.6037317458098;349.695764684724;350.3675865063559;5446.591505637079;5478.45215478452;5499;5518.834434966952;5553.667459666939;5185.637005409623;5188.42927278;5355.598330125236;5381.408045115034;5481;4276.952146724041;4279.272523670983;4286.914261714764;4288.421063156465;4300.892477688051;4295.484541854966;4300.440942677447;4303.505096913848;4313.676474264427;4316.330968699856;4018;4036.8183431745497;4250.319948808191;4272.786360681969;4278.144371125772;4242.172776308621;4258.148370325947;4266.594673506043;4278.42241297424;4278.9786051069705;3812.5996770338993;3813.523309586302;3817.5800661927165;3819.2170605025904;3824.9043773905596;5082.567981721558;5228.973855130738;5250.579953603703;5312.335958005249;5323.254744335796;4888.560029597354;4888.973315603727;4895.461499235082;4897.3878265216845;4897.853064408074;5654.264945557093;5657.717114144276;5659.801906933238;5669.461401166883;5681;4087.734297270668;4152.792360381969;4159.08500129974;4180.665546756252;4197.370394440828;5789.739461724233;5834.620749651259;5845.2401187845735;5847.181394604716;5854.180414741897;4275.764832934156;4296.677749168824;4304.849330273425;4320.200762858881;4323.524412314643;4090.406891000788;4273.145370925828;4313;4373.825046998085;4379.956200437955;4864.343313652612;4868.732219727878;4869.074875773592;4878.731669758197;4881.780319885614;5737.426257374229;5739;5739.454751798572;5743.913841292384;5745.649772303505;1449.4564538298139;1470.264867566217;1474.2555009720022;1476.8375478697335;1489.7392956232616;944.9086305317369;962.4417837654144;965.2663975378763;972.8346181149221;982.6315131825565;5553;5558.221848941152;5567.721613919369;5574.804881829116;5589.636673616284;5841;5854.4730974212525;5862.179294898718;5864.827034593099;5865.976238197815;5368.8389348319615;5532.280803495601;5563.497855579052;5567.554595632339;5570.637908535932;3073.9385212296074;3085.969140308613;3094.381123775254;3097.6127984002;3101.4882544380375;5663.461971112738;5935.6735379553675;5974.2830860297045;5995.580309378389;6004.819855404345;5814.883702325929;5819.214406055213;5850.308857925317;5875.3243377012195;5879.118132280192;5954.851128721816;5977.312609049928;5978.880422391615;5981.517670166919;5994.610350327215;5571.832845014656;5798.971005144947;5822.243108395882;5867.178594996789;5958.676116314728;5862.589618726648;5864.32560255576;5868.677222752619;5870.7945221917025;5883.55873309512;5854.765809367577;5960.225170727777;6106.267247930276;6142.416470435302;6144.846378840565;4190.979045104877;4230.8519201827785;4244.596763307481;4252.787360632018;4253.149370126049;5905.822825315247;5909.91135132982;5912.881742365214;5922.082077278029;5922.555808314308;5588.3573389060775;5712.742926568231;5746.683932383759;5747.19539264495;5761.222234998221;5649.4350564944825;5681.642118520767;5700.828975130753;5703.889560862342;5705.172749951317;5265.21021846726;5428.755705993319;5634.267545219095;5671.262735844314;5704.857378565569;5781.94218057814;5789.913151302818;5790.855228619318;5792.420757924174;5808.94191058084;5645.7579332546775;5649.20911072442;5650.491455769001;5658.490735833795;5662.046593925089 13:45:55 INFO - PROCESS | 26094 | |5;yui.html;837.7612380471567;844.4975239732357;888.422525358517;908.1473793461337;933.9346245762799;14681;15427.768583471257;15436.845631543682;15460.072395656269;15470.4585339513;14614.707705845876;14626.926865365685;14633.92683036586;14641.19473428961;14648.487302944399;731.8646050480652;853.3088198559187;874.5933141089408;880.8502554565707;885.4510203673699;871.2681347667958;871.4074429388014;871.7297637732291;872.6640243506264;881.9382643214977;919.8758167647383;923.1691477670083;923.3444254579257;923.5474617437441;923.8983711791698;776.1036003416035;901.2699713232303;904.276578737011;915.0894859614659;920.5397301349325;684.0833283400218;684.3293572299125;684.3601232847303;684.4832151725465;684.5413572906165;16097.356105755756;16272.43046493367;16284;16289.104099274535;16297.511074667778;14549.34527946245;15053.924730376279;15205.543833685007;15475.380984760595;15812.604684882856;14275.928620356832;14282.500112496116;14387.496437624633;14559.12645241289;14744.852551474456;4744.264638980963;4744.762761861927;4784.330193772875;4835.709857408531;4852.951470485285;85559.57220213921;86050;86986;87438.56280718556;87731;12413.689657758448;12526.74946501065;12622.179558328678;12638.620841374774;12868.613941581767;23894.52210955771;23900;23921.760782391953;23932.7606723934;23962.88018559896;8765.824683506422;8815.955920220358;9041.276697864287;9246.953765231265;9599.088086631758;4610.907781844345;5020.849374518771;5055.9241611375855;5123.205903085028;5236.869078273074;3685.9262814743556;3816.9236615268096;3821.082940094357;3822.7324087314178;3837.347650899298;644.3781750610683;648.0667838312909;653.4054010850103;654.456800855289;659.7064306383709;568.4116938968148;621.8942779727457;650.9869802603921;685.1572565744063;768.3660979691776;10284.177265818716;10421.895781042092;10564.313319634199;10701.678949631523;11035;746.555799299416;778.1984555907425;780.6292011294589;786.4494853602387;795.3080819686794;4988.850334489971;5052.9242061369105;5056.028686687526;5084.415292241366;5137.922931156036;75450.24549754433;79682;128121.35939320244;135065;138537;109432;109896.4505177469;110493;117131;118996;76765;80518.79221811678;97965;98007;98499;9878.654247101316;9933.801323973625;10288.382697038202;10460.790784184426;10583.682489525327;53152.4684753163;53559;53924.46075539194;54118.729406352715;54965.175522367994;69646.95529567162;71472.64263678648;74315.42634302557;77174.61412692901;81666.18333816585;13562.118462299919;13809;13865.306734663429;14084.859151408355;14309.64225894361;13778.862211377758;13953.093048951785;15173.696526069638;15375.538733838002;15431;10521.309778579824;12672.303023333805;13889.305534723426;14177.574672759834;14222.217778021894;13596.59210223695;14154.575362739135;14296;14518.274086295854;14604.926975365055;13459.192448453125;13481.393337300042;13508.86491135076;13643.727125457634;13805;11187.72030699239;12070;12075.335856527976;12134.817977730165;12541.561045363369;12412.317322547347;12509;12683.556075537312;12829.358532073173;12855.185560254944 13:45:55 INFO - PROCESS | 26094 | __end_tp_report 13:45:55 INFO - PROCESS | 26094 | __start_cc_report 13:45:55 INFO - PROCESS | 26094 | _x_x_mozilla_cycle_collect,1060 13:45:55 INFO - PROCESS | 26094 | __end_cc_report 13:45:55 INFO - PROCESS | 26094 | __startTimestamp1472589955187__endTimestamp 13:45:55 INFO - PROCESS | 26094 | 13:45:55 INFO - PROCESS | 26094 | ------- Summary: start ------- 13:45:55 INFO - PROCESS | 26094 | Number of tests: 6 13:45:55 INFO - PROCESS | 26094 | 13:45:55 INFO - PROCESS | 26094 | [#0] dojo.html Cycles:165 Average:5676.41 Median:5915.20 stddev:658.68 (11.1%) stddev-sans-first:632.81 13:45:55 INFO - PROCESS | 26094 | Values: 3259.3 3276.5 3276.6 3308.1 3316.3 5644.4 5712.7 5742.6 5746.2 5746.9 5743.6 5757.1 5795.4 5804.9 5815.7 5067.1 5112.8 5217.2 5223.1 5278.1 5178.2 5221.9 5236.5 5247.2 5253.0 5021.2 5037.8 5049.2 5050.8 5247.9 5119.1 5141.0 5154.8 5158.3 5186.3 4976.8 5012.3 5025.5 5092.9 5134.2 5521.6 5576.8 5628.5 5628.9 5647.1 6101.5 6104.1 6108.2 6112.4 6123.4 6046.7 6079.8 6110.7 6115.1 6173.5 5736.7 5782.7 5791.2 5791.9 5791.9 6300.4 6302.0 6303.3 6309.2 6309.5 5777.0 5914.5 5958.5 5962.1 6050.4 6115.9 6123.6 6123.8 6127.7 6141.3 6019.6 6075.9 6084.8 6085.6 6097.8 5886.5 6015.0 6064.8 6066.4 6122.5 4646.9 4682.4 4685.1 4708.8 4709.6 4151.5 4169.2 4170.0 4181.9 4193.5 5679.1 5709.3 5733.2 5737.7 5751.2 6054.8 6056.1 6060.3 6062.8 6068.8 5612.5 5740.9 5746.1 5750.1 5754.3 5272.4 5326.5 5336.7 5353.5 5355.8 6358.7 6360.5 6395.2 6416.8 6429.6 5886.8 6174.4 6174.6 6230.9 6234.5 6196.8 6295.4 6305.2 6414.0 6425.4 6086.0 6090.2 6094.2 6095.5 6112.0 5940.8 6235.1 6236.6 6239.0 6240.9 5945.4 5979.3 5986.1 6036.8 6073.1 5978.8 6004.3 6014.6 6017.4 6022.3 5730.9 5866.0 5914.8 5915.2 5915.6 6007.8 6072.0 6084.8 6124.1 6140.0 5979.1 5982.6 5986.1 5986.7 6012.3 13:45:55 INFO - PROCESS | 26094 | 13:45:55 INFO - PROCESS | 26094 | [#1] ext.html Cycles:180 Average:70711.20 Median:22437.07 stddev:147253.80 (656.3%) stddev-sans-first:147572.85 13:45:55 INFO - PROCESS | 26094 | Values: 619.8 652.0 663.5 675.1 695.2 34280.5 37402.4 49921.5 51055.5 51213.0 48810.3 49056.0 49082.5 49113.8 49115.8 9262.5 9374.7 9401.7 9403.5 9409.5 8636.6 9254.5 10911.3 11156.7 11170.9 11506.6 11577.9 11590.3 11599.5 11600.9 11433.7 11490.7 11503.9 11511.5 11512.8 8498.4 10854.2 11198.3 11230.1 11621.8 46339.5 46648.5 46671.8 46771.5 46862.0 3878.3 3945.8 4044.5 4081.3 4544.4 35527.6 36232.1 36234.0 36240.0 36440.8 4186.6 4215.9 4218.2 4243.6 4249.6 386943.0 388191.0 389257.0 390500.0 392447.0 67290.7 67606.0 67704.3 67796.7 67933.7 70819.3 70957.0 71023.6 71235.6 71317.6 15837.6 16207.9 16212.8 16214.7 16237.5 12803.0 13015.8 13123.8 13169.1 13201.6 18334.5 21159.3 21222.2 21262.4 21279.0 7803.3 7830.3 7848.3 7898.3 7917.3 1031.6 1083.6 1109.9 1145.9 1227.2 34955.7 35307.0 35316.0 35372.8 35433.5 1165.9 1205.2 1244.3 1256.9 1270.9 18422.5 18433.2 18557.8 18558.3 18657.3 714431.0 722289.0 722782.3 726214.0 727857.0 65081.0 65283.3 65989.3 65997.0 66042.3 1364.2 1416.9 1436.2 1468.3 1482.9 30987.2 36061.5 36114.6 36275.5 36390.5 53615.5 54721.5 54833.2 54874.7 54994.0 487635.0 489731.0 489799.0 491097.0 491970.0 30850.2 30883.5 31016.2 31533.0 33100.8 53530.7 53755.2 53794.5 54012.0 54557.1 44334.3 45151.0 45491.8 45845.3 46998.0 44689.8 46353.5 46565.8 47182.3 47556.8 44341.1 44601.0 45477.0 48264.8 48341.3 39279.8 40619.8 40674.4 40792.8 41617.8 30458.7 30519.5 30679.4 30686.0 31223.4 13:45:55 INFO - PROCESS | 26094 | 13:45:55 INFO - PROCESS | 26094 | [#2] jquery.html Cycles:180 Average:29622.69 Median:2514.46 stddev:83549.53 (3322.8%) stddev-sans-first:83755.10 13:45:55 INFO - PROCESS | 26094 | Values: 398.7 407.4 428.6 429.3 439.9 15571.7 20136.7 20762.6 20776.4 20887.2 19778.0 19835.6 19837.9 19903.7 19971.9 5722.5 5754.2 5895.5 5909.8 5932.4 5863.1 5953.3 5957.6 5959.4 5962.9 5815.1 6028.5 6042.6 6150.9 6173.0 5988.9 6068.0 6101.3 6103.1 6162.4 5959.8 6020.5 6031.1 6031.7 6035.7 23110.9 23968.0 24231.6 24302.0 24575.5 2485.8 2543.1 2548.4 2727.8 2840.2 10636.0 11288.4 13569.1 15326.7 15788.4 1952.7 2029.5 2048.8 2064.1 2084.0 66289.7 80096.6 85498.0 85541.6 86475.6 11391.8 11537.7 11649.8 11886.3 11908.3 16727.2 16765.9 16848.3 16848.5 16899.7 8055.6 8365.9 8389.2 8512.6 8582.5 8010.6 8035.1 8089.9 8173.2 8323.7 5058.3 5078.3 5130.5 5140.6 5257.5 2049.9 2053.4 2131.2 2152.3 2163.5 966.2 1064.8 1075.1 1134.1 1150.1 19957.6 20317.7 20484.0 20592.0 20678.5 991.3 1023.4 1062.1 1075.4 1075.7 10527.4 11430.2 11444.4 11602.6 11625.0 494470.0 496848.0 512730.0 519025.0 521897.0 59584.0 60750.7 86969.6 87321.0 88597.6 1034.8 1069.9 1081.8 1088.5 1089.6 18138.5 18313.5 18566.4 18603.9 18712.4 44402.1 44692.6 44902.1 45107.3 46131.5 55946.0 56651.0 56762.1 62221.0 64235.7 9073.8 9245.2 9303.8 9378.6 9591.1 10824.6 12846.0 14036.7 14152.2 14404.1 11892.4 11927.8 12105.5 12389.0 12742.3 7120.0 8118.7 12191.2 12227.3 12326.2 12231.8 12279.6 12427.7 12590.4 12602.7 8826.7 8911.7 9076.7 9228.6 9297.4 9277.5 11481.9 12134.3 12513.9 12656.1 13:45:55 INFO - PROCESS | 26094 | 13:45:55 INFO - PROCESS | 26094 | [#3] mootools.html Cycles:180 Average:6228.16 Median:2598.33 stddev:3641.94 (140.2%) stddev-sans-first:3633.96 13:45:55 INFO - PROCESS | 26094 | Values: 1384.2 1515.5 1582.2 1725.8 1832.3 8453.5 8765.5 8857.0 8860.5 9094.5 8554.3 8758.3 8978.0 8991.6 8999.2 2436.8 2488.4 2494.8 2498.2 2500.7 2440.3 2494.5 2498.5 2500.5 2504.0 2271.2 2288.6 2299.9 2306.1 2306.3 2377.9 2387.6 2391.1 2405.1 2457.2 2372.6 2380.4 2392.6 2395.3 2396.8 8939.7 8974.1 8975.8 8987.8 9011.9 5482.5 5706.8 5716.2 5720.4 5725.9 3390.1 3420.7 3428.1 3437.0 3483.8 1036.1 1045.9 1075.9 1100.9 1102.4 13145.6 13344.7 13369.5 13410.0 13436.3 4843.7 4871.4 4898.4 4958.7 5130.1 11211.4 11357.3 11394.1 11397.4 11430.7 5524.6 5549.6 5553.2 5553.4 5555.0 4222.4 4426.6 4524.5 5047.5 5094.7 4160.8 4172.2 4232.1 4261.8 4272.4 1320.7 1330.6 1398.8 1404.2 1413.5 1024.5 1025.3 1035.5 1039.5 1040.9 6335.2 6474.7 6638.2 6656.7 6694.0 471.5 475.4 476.0 481.0 493.8 4691.7 4699.9 4721.2 4797.4 4811.6 11860.6 12218.6 12290.2 12313.8 12329.2 11270.3 12201.3 12244.6 12248.5 12270.6 8741.9 8926.8 8952.0 8953.9 8961.9 6660.0 6688.7 6727.7 6840.1 6857.0 10682.3 11158.2 11182.6 11325.3 11429.5 9996.0 11427.8 11476.5 11547.7 11557.7 7590.9 8588.3 8619.4 8830.3 8839.0 8865.7 9512.8 9601.4 9604.0 9608.6 7888.9 8165.0 8230.3 8249.7 8333.1 8015.8 8024.2 8094.7 8115.2 8161.6 7601.5 7763.6 7813.0 8002.2 8023.3 5416.2 5488.5 5526.1 5641.5 5713.2 5511.8 5575.1 5609.1 5690.2 5710.8 13:45:55 INFO - PROCESS | 26094 | 13:45:55 INFO - PROCESS | 26094 | [#4] prototype.html Cycles:180 Average:4818.51 Median:5560.86 stddev:1403.41 (25.2%) stddev-sans-first:1366.42 13:45:55 INFO - PROCESS | 26094 | Values: 336.1 342.4 347.6 349.7 350.4 5446.6 5478.5 5499.0 5518.8 5553.7 5185.6 5188.4 5355.6 5381.4 5481.0 4277.0 4279.3 4286.9 4288.4 4300.9 4295.5 4300.4 4303.5 4313.7 4316.3 4018.0 4036.8 4250.3 4272.8 4278.1 4242.2 4258.1 4266.6 4278.4 4279.0 3812.6 3813.5 3817.6 3819.2 3824.9 5082.6 5229.0 5250.6 5312.3 5323.3 4888.6 4889.0 4895.5 4897.4 4897.9 5654.3 5657.7 5659.8 5669.5 5681.0 4087.7 4152.8 4159.1 4180.7 4197.4 5789.7 5834.6 5845.2 5847.2 5854.2 4275.8 4296.7 4304.8 4320.2 4323.5 4090.4 4273.1 4313.0 4373.8 4380.0 4864.3 4868.7 4869.1 4878.7 4881.8 5737.4 5739.0 5739.5 5743.9 5745.6 1449.5 1470.3 1474.3 1476.8 1489.7 944.9 962.4 965.3 972.8 982.6 5553.0 5558.2 5567.7 5574.8 5589.6 5841.0 5854.5 5862.2 5864.8 5866.0 5368.8 5532.3 5563.5 5567.6 5570.6 3073.9 3086.0 3094.4 3097.6 3101.5 5663.5 5935.7 5974.3 5995.6 6004.8 5814.9 5819.2 5850.3 5875.3 5879.1 5954.9 5977.3 5978.9 5981.5 5994.6 5571.8 5799.0 5822.2 5867.2 5958.7 5862.6 5864.3 5868.7 5870.8 5883.6 5854.8 5960.2 6106.3 6142.4 6144.8 4191.0 4230.9 4244.6 4252.8 4253.1 5905.8 5909.9 5912.9 5922.1 5922.6 5588.4 5712.7 5746.7 5747.2 5761.2 5649.4 5681.6 5700.8 5703.9 5705.2 5265.2 5428.8 5634.3 5671.3 5704.9 5781.9 5789.9 5790.9 5792.4 5808.9 5645.8 5649.2 5650.5 5658.5 5662.0 13:45:55 INFO - PROCESS | 26094 | 13:45:55 INFO - PROCESS | 26094 | [#5] yui.html Cycles:180 Average:22023.67 Median:4224.13 stddev:31893.22 (755.0%) stddev-sans-first:31943.02 13:45:55 INFO - PROCESS | 26094 | Values: 837.8 844.5 888.4 908.1 933.9 14681.0 15427.8 15436.8 15460.1 15470.5 14614.7 14626.9 14633.9 14641.2 14648.5 731.9 853.3 874.6 880.9 885.5 871.3 871.4 871.7 872.7 881.9 919.9 923.2 923.3 923.5 923.9 776.1 901.3 904.3 915.1 920.5 684.1 684.3 684.4 684.5 684.5 16097.4 16272.4 16284.0 16289.1 16297.5 14549.3 15053.9 15205.5 15475.4 15812.6 14275.9 14282.5 14387.5 14559.1 14744.9 4744.3 4744.8 4784.3 4835.7 4853.0 85559.6 86050.0 86986.0 87438.6 87731.0 12413.7 12526.7 12622.2 12638.6 12868.6 23894.5 23900.0 23921.8 23932.8 23962.9 8765.8 8816.0 9041.3 9247.0 9599.1 4610.9 5020.8 5055.9 5123.2 5236.9 3685.9 3816.9 3821.1 3822.7 3837.3 644.4 648.1 653.4 654.5 659.7 568.4 621.9 651.0 685.2 768.4 10284.2 10421.9 10564.3 10701.7 11035.0 746.6 778.2 780.6 786.4 795.3 4988.9 5052.9 5056.0 5084.4 5137.9 75450.2 79682.0 128121.4 135065.0 138537.0 109432.0 109896.5 110493.0 117131.0 118996.0 76765.0 80518.8 97965.0 98007.0 98499.0 9878.7 9933.8 10288.4 10460.8 10583.7 53152.5 53559.0 53924.5 54118.7 54965.2 69647.0 71472.6 74315.4 77174.6 81666.2 13562.1 13809.0 13865.3 14084.9 14309.6 13778.9 13953.1 15173.7 15375.5 15431.0 10521.3 12672.3 13889.3 14177.6 14222.2 13596.6 14154.6 14296.0 14518.3 14604.9 13459.2 13481.4 13508.9 13643.7 13805.0 11187.7 12070.0 12075.3 12134.8 12541.6 12412.3 12509.0 12683.6 12829.4 12855.2 13:45:55 INFO - PROCESS | 26094 | -------- Summary: end -------- 13:45:55 INFO - PROCESS | 26094 | 13:45:55 INFO - TEST-INFO | 26094: exit 0 13:45:55 INFO - TEST-OK | dromaeo_css | took 1113122ms 13:45:55 INFO - TEST-START | kraken 13:45:55 INFO - operating with platform_type : linux_ 13:45:55 INFO - Initialising browser for kraken test... 13:45:55 INFO - TEST-INFO | started process 26294 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxmKmtC/profile http://localhost:49575/getInfo.html) 13:45:59 INFO - PROCESS | 26294 | __metrics Screen width/height:1600/1200 13:45:59 INFO - PROCESS | 26294 | colorDepth:24 13:45:59 INFO - PROCESS | 26294 | Browser inner width/height: 1024/697 13:45:59 INFO - PROCESS | 26294 | __metrics 13:46:00 INFO - PROCESS | 26294 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:46:00 INFO - PROCESS | 26294 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:46:01 INFO - TEST-INFO | 26294: exit 0 13:46:01 INFO - Browser initialized. 13:46:01 INFO - Running cycle 1/1 for kraken test... 13:46:01 INFO - TEST-INFO | started process 26382 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxmKmtC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:46:12 INFO - PROCESS | 26382 | RSS: Main: 145330176 13:46:12 INFO - PROCESS | 26382 | 13:46:15 INFO - PROCESS | 26382 | Cycle 1(1): loaded http://localhost:49575/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49575/tests/kraken/driver.html?testName=audio-beat-detection) 13:46:16 INFO - PROCESS | 26382 | RSS: Main: 191152128 13:46:16 INFO - PROCESS | 26382 | 13:46:18 INFO - PROCESS | 26382 | Cycle 1(1): loaded http://localhost:49575/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49575/tests/kraken/driver.html?testName=audio-dft) 13:46:19 INFO - PROCESS | 26382 | RSS: Main: 181686272 13:46:19 INFO - PROCESS | 26382 | 13:46:22 INFO - PROCESS | 26382 | Cycle 1(1): loaded http://localhost:49575/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49575/tests/kraken/driver.html?testName=audio-fft) 13:46:22 INFO - PROCESS | 26382 | RSS: Main: 270966784 13:46:22 INFO - PROCESS | 26382 | 13:46:24 INFO - PROCESS | 26382 | Cycle 1(1): loaded http://localhost:49575/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49575/tests/kraken/driver.html?testName=audio-oscillator) 13:46:25 INFO - PROCESS | 26382 | RSS: Main: 176033792 13:46:25 INFO - PROCESS | 26382 | 13:46:27 INFO - PROCESS | 26382 | Cycle 1(1): loaded http://localhost:49575/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49575/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:46:28 INFO - PROCESS | 26382 | RSS: Main: 176959488 13:46:28 INFO - PROCESS | 26382 | 13:46:31 INFO - PROCESS | 26382 | Cycle 1(1): loaded http://localhost:49575/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49575/tests/kraken/driver.html?testName=imaging-darkroom) 13:46:31 INFO - PROCESS | 26382 | RSS: Main: 212869120 13:46:31 INFO - PROCESS | 26382 | 13:46:35 INFO - PROCESS | 26382 | Cycle 1(1): loaded http://localhost:49575/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49575/tests/kraken/driver.html?testName=imaging-desaturate) 13:46:35 INFO - PROCESS | 26382 | RSS: Main: 212090880 13:46:35 INFO - PROCESS | 26382 | 13:46:38 INFO - PROCESS | 26382 | Cycle 1(1): loaded http://localhost:49575/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49575/tests/kraken/driver.html?testName=json-parse-financial) 13:46:39 INFO - PROCESS | 26382 | RSS: Main: 211726336 13:46:39 INFO - PROCESS | 26382 | 13:46:40 INFO - PROCESS | 26382 | Cycle 1(1): loaded http://localhost:49575/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49575/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:46:41 INFO - PROCESS | 26382 | RSS: Main: 175820800 13:46:41 INFO - PROCESS | 26382 | 13:46:42 INFO - PROCESS | 26382 | Cycle 1(1): loaded http://localhost:49575/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49575/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:46:43 INFO - PROCESS | 26382 | RSS: Main: 181727232 13:46:43 INFO - PROCESS | 26382 | 13:46:45 INFO - PROCESS | 26382 | Cycle 1(1): loaded http://localhost:49575/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49575/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:46:46 INFO - PROCESS | 26382 | RSS: Main: 186388480 13:46:46 INFO - PROCESS | 26382 | 13:46:48 INFO - PROCESS | 26382 | Cycle 1(1): loaded http://localhost:49575/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49575/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:46:49 INFO - PROCESS | 26382 | RSS: Main: 181714944 13:46:49 INFO - PROCESS | 26382 | 13:46:51 INFO - PROCESS | 26382 | Cycle 1(1): loaded http://localhost:49575/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49575/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:46:52 INFO - PROCESS | 26382 | RSS: Main: 184541184 13:46:52 INFO - PROCESS | 26382 | 13:46:53 INFO - PROCESS | 26382 | Cycle 1(1): loaded http://localhost:49575/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49575/tests/kraken/driver.html?testName=ai-astar) 13:46:54 INFO - PROCESS | 26382 | RSS: Main: 235405312 13:46:54 INFO - PROCESS | 26382 | 13:46:54 INFO - PROCESS | 26382 | __start_tp_report 13:46:54 INFO - PROCESS | 26382 | _x_x_mozilla_page_load 13:46:54 INFO - PROCESS | 26382 | _x_x_mozilla_page_load_details 13:46:54 INFO - PROCESS | 26382 | |i|pagename|runs| 13:46:54 INFO - PROCESS | 26382 | |0;ai-astar;107;111;110;111;111;111;110;112;111;111 13:46:54 INFO - PROCESS | 26382 | |1;audio-beat-detection;137;115;116;181;116;118;146;114;148;118 13:46:54 INFO - PROCESS | 26382 | |2;audio-dft;161;161;162;167;160;163;163;162;161;160 13:46:54 INFO - PROCESS | 26382 | |3;audio-fft;75;76;75;102;76;77;78;75;112;75 13:46:54 INFO - PROCESS | 26382 | |4;audio-oscillator;84;82;128;84;83;84;121;83;84;130 13:46:54 INFO - PROCESS | 26382 | |5;imaging-gaussian-blur;117;118;117;117;117;117;117;117;117;117 13:46:54 INFO - PROCESS | 26382 | |6;imaging-darkroom;147;146;147;146;146;146;147;146;146;147 13:46:54 INFO - PROCESS | 26382 | |7;imaging-desaturate;94;95;94;95;95;94;94;95;95;95 13:46:54 INFO - PROCESS | 26382 | |8;json-parse-financial;53;51;50;50;51;51;51;50;50;50 13:46:54 INFO - PROCESS | 26382 | |9;json-stringify-tinderbox;59;59;60;59;60;60;59;59;61;60 13:46:54 INFO - PROCESS | 26382 | |10;stanford-crypto-aes;72;73;72;72;72;71;78;72;72;72 13:46:54 INFO - PROCESS | 26382 | |11;stanford-crypto-ccm;117;115;117;116;116;114;117;124;162;125 13:46:54 INFO - PROCESS | 26382 | |12;stanford-crypto-pbkdf2;153;149;150;149;150;155;149;149;148;150 13:46:54 INFO - PROCESS | 26382 | |13;stanford-crypto-sha256-iterative;62;65;62;62;61;63;61;62;61;64 13:46:54 INFO - PROCESS | 26382 | __end_tp_report 13:46:54 INFO - PROCESS | 26382 | __start_cc_report 13:46:54 INFO - PROCESS | 26382 | _x_x_mozilla_cycle_collect,1614 13:46:54 INFO - PROCESS | 26382 | __end_cc_report 13:46:54 INFO - PROCESS | 26382 | __startTimestamp1472590014231__endTimestamp 13:46:54 INFO - PROCESS | 26382 | 13:46:54 INFO - PROCESS | 26382 | ------- Summary: start ------- 13:46:54 INFO - PROCESS | 26382 | Number of tests: 14 13:46:54 INFO - PROCESS | 26382 | 13:46:54 INFO - PROCESS | 26382 | [#0] ai-astar Cycles:10 Average:110.50 Median:111.00 stddev:1.35 (1.2%) stddev-sans-first:0.60 13:46:54 INFO - PROCESS | 26382 | Values: 107.0 111.0 110.0 111.0 111.0 111.0 110.0 112.0 111.0 111.0 13:46:54 INFO - PROCESS | 26382 | 13:46:54 INFO - PROCESS | 26382 | [#1] audio-beat-detection Cycles:10 Average:130.90 Median:127.50 stddev:22.07 (17.3%) stddev-sans-first:23.30 13:46:54 INFO - PROCESS | 26382 | Values: 137.0 115.0 116.0 181.0 116.0 118.0 146.0 114.0 148.0 118.0 13:46:54 INFO - PROCESS | 26382 | 13:46:54 INFO - PROCESS | 26382 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:2.05 (1.3%) stddev-sans-first:2.15 13:46:54 INFO - PROCESS | 26382 | Values: 161.0 161.0 162.0 167.0 160.0 163.0 163.0 162.0 161.0 160.0 13:46:54 INFO - PROCESS | 26382 | 13:46:54 INFO - PROCESS | 26382 | [#3] audio-fft Cycles:10 Average:82.10 Median:75.50 stddev:13.37 (17.7%) stddev-sans-first:13.93 13:46:54 INFO - PROCESS | 26382 | Values: 75.0 76.0 75.0 102.0 76.0 77.0 78.0 75.0 112.0 75.0 13:46:54 INFO - PROCESS | 26382 | 13:46:54 INFO - PROCESS | 26382 | [#4] audio-oscillator Cycles:10 Average:96.30 Median:83.50 stddev:20.85 (25.0%) stddev-sans-first:21.64 13:46:54 INFO - PROCESS | 26382 | Values: 84.0 82.0 128.0 84.0 83.0 84.0 121.0 83.0 84.0 130.0 13:46:54 INFO - PROCESS | 26382 | 13:46:54 INFO - PROCESS | 26382 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 13:46:54 INFO - PROCESS | 26382 | Values: 117.0 118.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 13:46:54 INFO - PROCESS | 26382 | 13:46:54 INFO - PROCESS | 26382 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.52 (0.4%) stddev-sans-first:0.50 13:46:54 INFO - PROCESS | 26382 | Values: 147.0 146.0 147.0 146.0 146.0 146.0 147.0 146.0 146.0 147.0 13:46:54 INFO - PROCESS | 26382 | 13:46:54 INFO - PROCESS | 26382 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:95.00 stddev:0.52 (0.5%) stddev-sans-first:0.50 13:46:54 INFO - PROCESS | 26382 | Values: 94.0 95.0 94.0 95.0 95.0 94.0 94.0 95.0 95.0 95.0 13:46:54 INFO - PROCESS | 26382 | 13:46:54 INFO - PROCESS | 26382 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.00 stddev:0.95 (1.9%) stddev-sans-first:0.53 13:46:54 INFO - PROCESS | 26382 | Values: 53.0 51.0 50.0 50.0 51.0 51.0 51.0 50.0 50.0 50.0 13:46:54 INFO - PROCESS | 26382 | 13:46:54 INFO - PROCESS | 26382 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:60.00 stddev:0.70 (1.2%) stddev-sans-first:0.71 13:46:54 INFO - PROCESS | 26382 | Values: 59.0 59.0 60.0 59.0 60.0 60.0 59.0 59.0 61.0 60.0 13:46:54 INFO - PROCESS | 26382 | 13:46:54 INFO - PROCESS | 26382 | [#10] stanford-crypto-aes Cycles:10 Average:72.60 Median:72.00 stddev:1.96 (2.7%) stddev-sans-first:2.06 13:46:54 INFO - PROCESS | 26382 | Values: 72.0 73.0 72.0 72.0 72.0 71.0 78.0 72.0 72.0 72.0 13:46:54 INFO - PROCESS | 26382 | 13:46:54 INFO - PROCESS | 26382 | [#11] stanford-crypto-ccm Cycles:10 Average:122.30 Median:117.00 stddev:14.42 (12.3%) stddev-sans-first:15.17 13:46:54 INFO - PROCESS | 26382 | Values: 117.0 115.0 117.0 116.0 116.0 114.0 117.0 124.0 162.0 125.0 13:46:54 INFO - PROCESS | 26382 | 13:46:54 INFO - PROCESS | 26382 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.20 Median:150.00 stddev:2.15 (1.4%) stddev-sans-first:2.03 13:46:54 INFO - PROCESS | 26382 | Values: 153.0 149.0 150.0 149.0 150.0 155.0 149.0 149.0 148.0 150.0 13:46:54 INFO - PROCESS | 26382 | 13:46:54 INFO - PROCESS | 26382 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.30 Median:62.00 stddev:1.34 (2.2%) stddev-sans-first:1.41 13:46:54 INFO - PROCESS | 26382 | Values: 62.0 65.0 62.0 62.0 61.0 63.0 61.0 62.0 61.0 64.0 13:46:54 INFO - PROCESS | 26382 | -------- Summary: end -------- 13:46:54 INFO - PROCESS | 26382 | 13:46:55 INFO - TEST-INFO | 26382: exit 0 13:46:55 INFO - TEST-OK | kraken | took 59328ms 13:46:55 INFO - SUITE-END | took 1172s 13:46:55 INFO - Completed test suite (00:19:32) 13:46:55 INFO - javascript benchmark 13:46:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3259.299250661107, 3276.5412842202095, 3276.606807183137, 3308.073739352982, 3316.286998295366, 5644.350899646537, 5712.685802280878, 5742.598018138727, 5746.224259724936, 5746.885062298751, 5743.626664266831, 5757.078867381219, 5795.449432303924, 5804.883902321951, 5815.651060936347, 5067.138586440314, 5112.846614601568, 5217.243499692561, 5223.138182199932, 5278.076336641092, 5178.223266510035, 5221.895562088756, 5236.528712415882, 5247.212918062302, 5253.0, 5021.17150670139, 5037.823676171335, 5049.217371307434, 5050.823221187259, 5247.947520524803, 5119.12974794284, 5141.048905952373, 5154.7680354383765, 5158.252053452228, 5186.299849520305, 4976.825811096631, 5012.273220383024, 5025.547700706918, 5092.949070509285, 5134.204198349262, 5521.558275337963, 5576.832695019156, 5628.521575666074, 5628.859278518029, 5647.0964645656495, 6101.542384321149, 6104.145419641254, 6108.175396321484, 6112.388761123852, 6123.357047509992, 6046.667433291167, 6079.787207447718, 6110.694465276719, 6115.149072927049, 6173.475254603364, 5736.713164341808, 5782.710864456761, 5791.189233507313, 5791.88416231673, 5791.88416231673, 6300.401461861115, 6301.968490157612, 6303.2751233607805, 6309.179806625201, 6309.526785491014, 5776.971115144482, 5914.526837852962, 5958.463738263577, 5962.135490353874, 6050.42520960508, 6115.938840611626, 6123.557162997653, 6123.785667501706, 6127.708789264043, 6141.293751218667, 6019.608725432833, 6075.878482430327, 6084.847878802977, 6085.604435711664, 6097.75608975636, 5886.529077673776, 6015.037593985029, 6064.75740970365, 6066.363031881677, 6122.459144589048, 4646.930296045562, 4682.367880336179, 4685.1332503486965, 4708.788104535236, 4709.646776491777, 4151.501819781645, 4169.249535083653, 4170.04089059518, 4181.91636167281, 4193.5387107418155, 5679.091345384719, 5709.286339207599, 5733.226014487991, 5737.741801618937, 5751.22358481608, 6054.788082417094, 6056.121862329937, 6060.33336333003, 6062.757489700451, 6068.848278793065, 5612.494875461228, 5740.856478588069, 5746.080627099644, 5750.137479378127, 5754.309482862083, 5272.446393128743, 5326.5472434842695, 5336.706481143575, 5353.544948679328, 5355.758990845421, 6358.745650173942, 6360.52296077796, 6395.232572091193, 6416.844967905822, 6429.646369449725, 5886.793962211302, 6174.351693072163, 6174.629522228681, 6230.875382492234, 6234.532410069353, 6196.81409557714, 6295.370462953668, 6305.243370795534, 6414.03789431589, 6425.4217120457515, 6086.02623580225, 6090.177825993434, 6094.238220222473, 6095.512359011357, 6111.960966635772, 5940.821775346746, 6235.127082208403, 6236.594621349598, 6239.032949892774, 6240.875182496416, 5945.435183657545, 5979.252593425822, 5986.102084687332, 6036.758529658764, 6073.119397687399, 5978.7608495661425, 6004.339522652417, 6014.609050411709, 6017.428344307284, 6022.307434644897, 5730.885382292414, 5866.0, 5914.763409463573, 5915.201447804491, 5915.556333274935, 6007.849803754941, 6071.969640151771, 6084.787032453843, 6124.106319906441, 6139.982816839433, 5979.1031345296815, 5982.641041537521, 5986.07215881539, 5986.6707331097205, 6012.278526576839], "value": 5631.090352958584, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [619.8295468746097, 651.9902201466978, 663.5421559124203, 675.0818886314609, 695.1901035293877, 34280.4857927131, 37402.438963415574, 49921.50078499214, 51055.48944510554, 51213.0, 48810.26784598234, 49056.0, 49082.50917490824, 49113.754431227884, 49115.754421227844, 9262.490563019048, 9374.718758437259, 9401.670941517048, 9403.529823508832, 9409.482478463682, 8636.611352489123, 9254.53727313635, 10911.345319280828, 11156.721081972975, 11170.944145279262, 11506.597269095584, 11577.884221157807, 11590.30458172508, 11599.536018559247, 11600.941995290012, 11433.714157146096, 11490.655280341603, 11503.942480287546, 11511.539538418496, 11512.769744605144, 8498.362622803254, 10854.185936054828, 11198.328100314007, 11230.10159187263, 11621.76756464866, 46339.53660463386, 46648.533514664756, 46671.76664116658, 46771.53228467706, 46862.0, 3878.3212937735784, 3945.782981935992, 4044.5146582410007, 4081.3469844824685, 4544.363789069533, 35527.644723552694, 36232.094197645005, 36234.0, 36240.0, 36440.81779591111, 4186.644135248507, 4215.91568168638, 4218.198542276958, 4243.596858298457, 4249.596288352602, 386943.0, 388191.0, 389256.99999999715, 390500.0, 392447.0, 67290.66354668196, 67606.0, 67704.32295677079, 67796.6610166946, 67933.66033169802, 70819.29180708127, 70957.0, 71023.64488177525, 71235.64382178056, 71317.64341178365, 15837.604059898364, 16207.918960405359, 16212.837871621368, 16214.675706485805, 16237.51287461378, 12803.0, 13015.804762928563, 13123.803142952864, 13169.078164528582, 13201.603951881267, 18334.503580957582, 21159.25942592002, 21222.151113955268, 21262.362129136152, 21279.0, 7803.258690424401, 7830.2952734254195, 7848.332891704269, 7898.328642065374, 7917.287444129941, 1031.6492392586406, 1083.5611577311201, 1109.8834622364698, 1145.8796826333282, 1227.1900545639835, 34955.650443495746, 35307.0, 35316.0, 35372.82313588416, 35433.46849797255, 1165.930044197351, 1205.180477275461, 1244.3031902134653, 1256.9120161588785, 1270.9046821488425, 18422.539436514195, 18433.170507327202, 18557.814421855877, 18558.257669693332, 18657.346992855186, 714431.0, 722289.0, 722782.316530505, 726214.0, 727857.0, 65081.0, 65283.34716652868, 65989.34010659928, 65997.0, 66042.33957660459, 1364.2292104960864, 1416.85123062077, 1436.1885534672876, 1468.2658670664669, 1482.918439485817, 30987.211150996518, 36061.45907811437, 36114.638853611126, 36275.45586816147, 36390.454143187344, 53615.463845361046, 54721.452785471636, 54833.177502338294, 54874.72562637161, 54994.0, 487635.0, 489731.0, 489799.0, 491097.0, 491970.0, 30850.228744281572, 30883.536746948364, 31016.224594384417, 31533.0, 33100.83449582737, 53530.73234633958, 53755.19367209573, 53794.46205537894, 54012.0, 54557.090501832245, 44334.33498497461, 45151.0, 45491.77254113841, 45845.31232031455, 46998.0, 44689.77655111834, 46353.53646463492, 46565.76717116528, 47182.29226561535, 47556.76221619008, 44341.11317773691, 44601.0, 45477.0, 48264.758676206395, 48341.27488087752, 39279.80360098295, 40619.7969010153, 40674.38988415117, 40792.796036020816, 41617.79191104025, 30458.695413045585, 30519.54220686647, 30679.386412272077, 30686.0, 31223.375532489677], "value": 22452.948173598565, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.69300638508366, 407.37467986640513, 428.5543035243347, 429.2702405909954, 439.8768344863439, 15571.688566228682, 20136.69794953073, 20762.584748305024, 20776.376708698725, 20887.164513419444, 19778.0, 19835.60328793423, 19837.900810495965, 19903.701444478345, 19971.900140499314, 5722.456366645162, 5754.16564598133, 5895.528357731371, 5909.822705318826, 5932.406759324076, 5863.061910094386, 5953.345132035494, 5957.553183511232, 5959.433853783905, 5962.850928726773, 5815.098659707752, 6028.457438830526, 6042.57701960863, 6150.876982460348, 6173.043178307369, 5988.850278743066, 6068.0, 6101.267847858263, 6103.115048318014, 6162.414570615784, 5959.791407300767, 6020.458158765732, 6031.065184896348, 6031.728572214261, 6035.698215089228, 23110.884445577834, 23968.0, 24231.636525452133, 24302.0, 24575.50848983028, 2485.763852434016, 2543.1480454047914, 2548.413864811085, 2727.7954153438573, 2840.1621521651164, 10635.989580989793, 11288.379139147344, 13569.05016648835, 15326.693466130726, 15788.447404340908, 1952.746143001415, 2029.5027718208985, 2048.805363490481, 2064.0814837397365, 2083.9895800521053, 66289.66855165693, 80096.59951700205, 85498.0, 85541.57229213876, 86475.56762216211, 11391.829122563167, 11537.711557210969, 11649.76700465986, 11886.346250956107, 11908.285502869856, 16727.16364181786, 16765.916170419314, 16848.32606695743, 16848.494545163663, 16899.662006759794, 8055.63749631268, 8365.874511882326, 8389.203025712548, 8512.574371281411, 8582.485050896967, 8010.639521221559, 8035.088757980379, 8089.9191008089165, 8173.223543763333, 8323.6670533178, 5058.266551350033, 5078.289039534468, 5130.48695130484, 5140.640155189101, 5257.526822585985, 2049.938501844917, 2053.373721015075, 2131.179495894061, 2152.343535221741, 2163.5023944492855, 966.2318456826839, 1064.8030114428855, 1075.1143237927186, 1134.1493879590307, 1150.0914277720674, 19957.600847982958, 20317.695234571493, 20483.97580120988, 20592.0, 20678.48303792387, 991.3457118301886, 1023.4319952426475, 1062.0706881478707, 1075.3977772447454, 1075.693427373195, 10527.368357898398, 11430.199886008066, 11444.427778611036, 11602.593909213138, 11625.0, 494470.0, 496848.0, 512730.0, 519025.0, 521897.0, 59584.0, 60750.696246518484, 86969.56515217383, 87321.0, 88597.55701221453, 1034.8033873564, 1069.9461030884572, 1081.8431327457631, 1088.5210507376887, 1089.5696200001114, 18138.455846324632, 18313.450596482126, 18566.443006709822, 18603.90698046501, 18712.43862684067, 44402.11195776002, 44692.62070818052, 44902.10195796131, 45107.32339014983, 46131.53868461272, 55946.0, 56651.0, 56762.14856777069, 62221.0, 64235.71528569496, 9073.773155670897, 9245.214156796748, 9303.813923721624, 9378.624855005723, 9591.136797688241, 10824.567017319534, 12846.0, 14036.719265614835, 14152.22162781057, 14404.135751854923, 11892.405379731152, 11927.821082683593, 12105.455254513392, 12389.0, 12742.299173545543, 7120.0387947626405, 8118.715844945398, 12191.207571507824, 12227.327496987751, 12326.198797078161, 12231.816522752344, 12279.570215042431, 12427.689307767378, 12590.433430495466, 12602.747945041232, 8826.691065812665, 8911.73264802057, 9076.727698169065, 9228.630854765734, 9297.395669281475, 9277.489738064201, 11481.885181148082, 12134.33261170644, 12513.874861251272, 12656.050796190506], "value": 9652.110300627042, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1384.238668732197, 1515.4695856450244, 1582.2088955522238, 1725.792904851418, 1832.3311991123235, 8453.49279043257, 8765.517896515697, 8857.025727170007, 8860.512671803048, 9094.45433274004, 8554.272886804629, 8758.34312426569, 8978.0, 8991.640334386617, 8999.235065019464, 2436.7685069918375, 2488.415222422729, 2494.837835540684, 2498.1631153563576, 2500.6749122614033, 2440.3289095498703, 2494.4761600063916, 2498.4503409249937, 2500.4874000829786, 2503.962440563384, 2271.2277825539313, 2288.5766133265397, 2299.8505097168713, 2306.08909480755, 2306.2850516339904, 2377.8573285602833, 2387.55830171418, 2391.1152873436768, 2405.0620258099357, 2457.213691618683, 2372.5966585680476, 2380.4048987753063, 2392.629142482918, 2395.317334559642, 2396.808255339569, 8939.687110951085, 8974.102589741038, 8975.775605609846, 8987.775305617346, 9011.864822027675, 5482.451754824526, 5706.77172913083, 5716.1997320375185, 5720.427957204288, 5725.914111288334, 3390.1016230698883, 3420.675035871588, 3428.125827913866, 3436.9828150859335, 3483.8432270547632, 1036.1296510930779, 1045.8954104589557, 1075.854759607451, 1100.878903320634, 1102.4432661505969, 13145.60563183106, 13344.666383340396, 13369.465221391132, 13410.0, 13436.328183590878, 4843.709377437365, 4871.439784424837, 4898.387701537308, 4958.727270000185, 5130.127878260705, 11211.439428028565, 11357.261777984404, 11394.1454390921, 11397.373144477133, 11430.714232144097, 5524.640898341595, 5549.639273447213, 5553.1670249462095, 5553.444655534495, 5555.027870122713, 4222.4299719538085, 4426.623736982328, 4524.547545245515, 5047.520485553867, 5094.719790411563, 4160.793369922749, 4172.165566886635, 4232.090100628379, 4261.808218630169, 4272.359146128106, 1320.742455221242, 1330.60081975407, 1398.7622104242303, 1404.1785555449972, 1413.4699487692114, 1024.5082360467018, 1025.2566889005411, 1035.4563853976572, 1039.4958445154093, 1040.9115225205792, 6335.239771227482, 6474.708638111297, 6638.236602790645, 6656.707499253024, 6693.966530167318, 471.5308268273022, 475.4484797634796, 476.0384024270953, 481.0379241516966, 493.7703967655058, 4691.695039822401, 4699.882502937454, 4721.220998535203, 4797.424309082932, 4811.639127065484, 11860.584879529175, 12218.633440996784, 12290.20113692607, 12313.753724925451, 12329.198602090835, 11270.267432616854, 12201.328926908927, 12244.571439999736, 12248.510059597516, 12270.570530031406, 8741.91258087411, 8926.821463570823, 8952.0, 8953.865692014626, 8961.865572016424, 6659.966700166468, 6688.662267546413, 6727.730890764315, 6840.110785597841, 6857.0, 10682.305650132717, 11158.163137764537, 11182.552697892319, 11325.263857849213, 11429.542818287175, 9995.950020249853, 11427.771444571228, 11476.483558240066, 11547.711307217387, 11557.653270401901, 7590.886136708064, 8588.26999705032, 8619.396642234984, 8830.293576513992, 8838.98351689564, 8865.734027979172, 9512.762180945256, 9601.37591056579, 9604.0, 9608.615655373707, 7888.881666775118, 8165.0, 8230.341572674051, 8249.711260105867, 8333.083360830426, 8015.7595272141925, 8024.2376974187355, 8094.716684915999, 8115.188481151838, 8161.632726527439, 7601.543907365576, 7763.611819408894, 7813.0, 8002.239787220205, 8023.277904988579, 5416.160495123262, 5488.5334746545395, 5526.115821468546, 5641.548676105819, 5713.171590119493, 5511.834644960658, 5575.135853942646, 5609.074502707008, 5690.231818704422, 5710.828675139753], "value": 4847.129611571046, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.06908862451013, 342.375165323285, 347.6037317458098, 349.695764684724, 350.3675865063559, 5446.591505637079, 5478.45215478452, 5499.0, 5518.834434966952, 5553.667459666939, 5185.637005409623, 5188.42927278, 5355.598330125236, 5381.408045115034, 5481.0, 4276.952146724041, 4279.272523670983, 4286.914261714764, 4288.421063156465, 4300.892477688051, 4295.484541854966, 4300.440942677447, 4303.505096913848, 4313.676474264427, 4316.330968699856, 4018.0, 4036.8183431745497, 4250.319948808191, 4272.786360681969, 4278.144371125772, 4242.172776308621, 4258.148370325947, 4266.594673506043, 4278.42241297424, 4278.9786051069705, 3812.5996770338993, 3813.523309586302, 3817.5800661927165, 3819.2170605025904, 3824.9043773905596, 5082.567981721558, 5228.973855130738, 5250.579953603703, 5312.335958005249, 5323.254744335796, 4888.560029597354, 4888.973315603727, 4895.461499235082, 4897.3878265216845, 4897.853064408074, 5654.264945557093, 5657.717114144276, 5659.801906933238, 5669.461401166883, 5681.0, 4087.734297270668, 4152.792360381969, 4159.08500129974, 4180.665546756252, 4197.370394440828, 5789.739461724233, 5834.620749651259, 5845.2401187845735, 5847.181394604716, 5854.180414741897, 4275.764832934156, 4296.677749168824, 4304.849330273425, 4320.200762858881, 4323.524412314643, 4090.406891000788, 4273.145370925828, 4313.0, 4373.825046998085, 4379.956200437955, 4864.343313652612, 4868.732219727878, 4869.074875773592, 4878.731669758197, 4881.780319885614, 5737.426257374229, 5739.0, 5739.454751798572, 5743.913841292384, 5745.649772303505, 1449.4564538298139, 1470.264867566217, 1474.2555009720022, 1476.8375478697335, 1489.7392956232616, 944.9086305317369, 962.4417837654144, 965.2663975378763, 972.8346181149221, 982.6315131825565, 5553.0, 5558.221848941152, 5567.721613919369, 5574.804881829116, 5589.636673616284, 5841.0, 5854.4730974212525, 5862.179294898718, 5864.827034593099, 5865.976238197815, 5368.8389348319615, 5532.280803495601, 5563.497855579052, 5567.554595632339, 5570.637908535932, 3073.9385212296074, 3085.969140308613, 3094.381123775254, 3097.6127984002, 3101.4882544380375, 5663.461971112738, 5935.6735379553675, 5974.2830860297045, 5995.580309378389, 6004.819855404345, 5814.883702325929, 5819.214406055213, 5850.308857925317, 5875.3243377012195, 5879.118132280192, 5954.851128721816, 5977.312609049928, 5978.880422391615, 5981.517670166919, 5994.610350327215, 5571.832845014656, 5798.971005144947, 5822.243108395882, 5867.178594996789, 5958.676116314728, 5862.589618726648, 5864.32560255576, 5868.677222752619, 5870.7945221917025, 5883.55873309512, 5854.765809367577, 5960.225170727777, 6106.267247930276, 6142.416470435302, 6144.846378840565, 4190.979045104877, 4230.8519201827785, 4244.596763307481, 4252.787360632018, 4253.149370126049, 5905.822825315247, 5909.91135132982, 5912.881742365214, 5922.082077278029, 5922.555808314308, 5588.3573389060775, 5712.742926568231, 5746.683932383759, 5747.19539264495, 5761.222234998221, 5649.4350564944825, 5681.642118520767, 5700.828975130753, 5703.889560862342, 5705.172749951317, 5265.21021846726, 5428.755705993319, 5634.267545219095, 5671.262735844314, 5704.857378565569, 5781.94218057814, 5789.913151302818, 5790.855228619318, 5792.420757924174, 5808.94191058084, 5645.7579332546775, 5649.20911072442, 5650.491455769001, 5658.490735833795, 5662.046593925089], "value": 4370.571596027478, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [837.7612380471567, 844.4975239732357, 888.422525358517, 908.1473793461337, 933.9346245762799, 14681.0, 15427.768583471257, 15436.845631543682, 15460.072395656269, 15470.4585339513, 14614.707705845876, 14626.926865365685, 14633.92683036586, 14641.19473428961, 14648.487302944399, 731.8646050480652, 853.3088198559187, 874.5933141089408, 880.8502554565707, 885.4510203673699, 871.2681347667958, 871.4074429388014, 871.7297637732291, 872.6640243506264, 881.9382643214977, 919.8758167647383, 923.1691477670083, 923.3444254579257, 923.5474617437441, 923.8983711791698, 776.1036003416035, 901.2699713232303, 904.276578737011, 915.0894859614659, 920.5397301349325, 684.0833283400218, 684.3293572299125, 684.3601232847303, 684.4832151725465, 684.5413572906165, 16097.356105755756, 16272.43046493367, 16284.0, 16289.104099274535, 16297.511074667778, 14549.34527946245, 15053.924730376279, 15205.543833685007, 15475.380984760595, 15812.604684882856, 14275.928620356832, 14282.500112496116, 14387.496437624633, 14559.12645241289, 14744.852551474456, 4744.264638980963, 4744.762761861927, 4784.330193772875, 4835.709857408531, 4852.951470485285, 85559.57220213921, 86050.0, 86986.0, 87438.56280718556, 87731.0, 12413.689657758448, 12526.74946501065, 12622.179558328678, 12638.620841374774, 12868.613941581767, 23894.52210955771, 23900.0, 23921.760782391953, 23932.7606723934, 23962.88018559896, 8765.824683506422, 8815.955920220358, 9041.276697864287, 9246.953765231265, 9599.088086631758, 4610.907781844345, 5020.849374518771, 5055.9241611375855, 5123.205903085028, 5236.869078273074, 3685.9262814743556, 3816.9236615268096, 3821.082940094357, 3822.7324087314178, 3837.347650899298, 644.3781750610683, 648.0667838312909, 653.4054010850103, 654.456800855289, 659.7064306383709, 568.4116938968148, 621.8942779727457, 650.9869802603921, 685.1572565744063, 768.3660979691776, 10284.177265818716, 10421.895781042092, 10564.313319634199, 10701.678949631523, 11035.0, 746.555799299416, 778.1984555907425, 780.6292011294589, 786.4494853602387, 795.3080819686794, 4988.850334489971, 5052.9242061369105, 5056.028686687526, 5084.415292241366, 5137.922931156036, 75450.24549754433, 79682.0, 128121.35939320244, 135065.0, 138537.0, 109432.0, 109896.4505177469, 110493.0, 117131.0, 118996.0, 76765.0, 80518.79221811678, 97965.0, 98007.0, 98499.0, 9878.654247101316, 9933.801323973625, 10288.382697038202, 10460.790784184426, 10583.682489525327, 53152.4684753163, 53559.0, 53924.46075539194, 54118.729406352715, 54965.175522367994, 69646.95529567162, 71472.64263678648, 74315.42634302557, 77174.61412692901, 81666.18333816585, 13562.118462299919, 13809.0, 13865.306734663429, 14084.859151408355, 14309.64225894361, 13778.862211377758, 13953.093048951785, 15173.696526069638, 15375.538733838002, 15431.0, 10521.309778579824, 12672.303023333805, 13889.305534723426, 14177.574672759834, 14222.217778021894, 13596.59210223695, 14154.575362739135, 14296.0, 14518.274086295854, 14604.926975365055, 13459.192448453125, 13481.393337300042, 13508.86491135076, 13643.727125457634, 13805.0, 11187.72030699239, 12070.0, 12075.335856527976, 12134.817977730165, 12541.561045363369, 12412.317322547347, 12509.0, 12683.556075537312, 12829.358532073173, 12855.185560254944], "value": 8059.7765588856055, "unit": "score"}], "name": "dromaeo_css", "value": 7699.8095520943225, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 111.0, 110.0, 111.0, 111.0, 111.0, 110.0, 112.0, 111.0, 111.0], "value": 110.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 115.0, 116.0, 181.0, 116.0, 118.0, 146.0, 114.0, 148.0, 118.0], "value": 130.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 161.0, 162.0, 167.0, 160.0, 163.0, 163.0, 162.0, 161.0, 160.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 102.0, 76.0, 77.0, 78.0, 75.0, 112.0, 75.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 128.0, 84.0, 83.0, 84.0, 121.0, 83.0, 84.0, 130.0], "value": 96.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 147.0, 146.0, 146.0, 146.0, 147.0, 146.0, 146.0, 147.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 95.0, 95.0, 94.0, 94.0, 95.0, 95.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 51.0, 50.0, 50.0, 51.0, 51.0, 51.0, 50.0, 50.0, 50.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 60.0, 59.0, 60.0, 60.0, 59.0, 59.0, 61.0, 60.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 73.0, 72.0, 72.0, 72.0, 71.0, 78.0, 72.0, 72.0, 72.0], "value": 72.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 115.0, 117.0, 116.0, 116.0, 114.0, 117.0, 124.0, 162.0, 125.0], "value": 122.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 149.0, 150.0, 149.0, 150.0, 155.0, 149.0, 149.0, 148.0, 150.0], "value": 150.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 65.0, 62.0, 62.0, 61.0, 63.0, 61.0, 62.0, 61.0, 64.0], "value": 62.3, "unit": "score"}], "name": "kraken", "value": 1457.6, "alertThreshold": 2.0}]} 13:46:55 INFO - Return code: 0 13:46:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:46:55 INFO - # TBPL SUCCESS # 13:46:55 INFO - Running post-action listener: _resource_record_post_action 13:46:55 INFO - [mozharness: 2016-08-30 20:46:55.527585Z] Finished run-tests step (success) 13:46:55 INFO - Running post-run listener: _resource_record_post_run 13:46:55 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 331264000; Read time: 12; Write time: 259244 13:46:55 INFO - TinderboxPrint: CPU usage
12.9% 13:46:55 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 13:46:55 INFO - TinderboxPrint: I/O write bytes / time
331,264,000 / 259,244 13:46:55 INFO - TinderboxPrint: CPU idle
8,264.5 (87.1%) 13:46:55 INFO - TinderboxPrint: CPU user
1,181.8 (12.5%) 13:46:55 INFO - TinderboxPrint: Swap in / out
0 / 0 13:46:55 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 78872576; Read time: 0; Write time: 105788 13:46:55 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 251777024; Read time: 0; Write time: 153456 13:46:56 INFO - Running post-run listener: _upload_blobber_files 13:46:56 INFO - Blob upload gear active. 13:46:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:46:56 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:46:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:46:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:46:56 INFO - (blobuploader) - INFO - Open directory for files ... 13:46:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:46:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:46:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:46:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:46:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:46:57 INFO - (blobuploader) - INFO - Done attempting. 13:46:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:46:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:46:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:46:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:46:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:46:58 INFO - (blobuploader) - INFO - Done attempting. 13:46:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:46:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:46:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:47:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:47:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:47:01 INFO - (blobuploader) - INFO - Done attempting. 13:47:01 INFO - (blobuploader) - INFO - Iteration through files over. 13:47:01 INFO - Return code: 0 13:47:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:47:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:47:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c4308b6b99ff4d4d8960eabd3283c0ad26061117d0a16451775081dee78c9cd7879acf4b7446ceff4e2f20227cc18a9e739a8d96bd41467c6667065505edc69e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bafba43146cf25347de09a7a086eca93475a9819bbc6c0e0c62e175b45bf0b13cbc5b2d534b9128fabda2c8b20b5c7b4401ba75c0c904fa6c737626f18b4a6a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59af578076f6b42088dbe628e3cec18c26a2e66628e9705f4008b414b97aee062315b6d31607e68fd701e25bfd8ff479da65c90ce6c5819d947366871c48c884"} 13:47:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:47:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:47:01 INFO - Contents: 13:47:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c4308b6b99ff4d4d8960eabd3283c0ad26061117d0a16451775081dee78c9cd7879acf4b7446ceff4e2f20227cc18a9e739a8d96bd41467c6667065505edc69e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bafba43146cf25347de09a7a086eca93475a9819bbc6c0e0c62e175b45bf0b13cbc5b2d534b9128fabda2c8b20b5c7b4401ba75c0c904fa6c737626f18b4a6a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59af578076f6b42088dbe628e3cec18c26a2e66628e9705f4008b414b97aee062315b6d31607e68fd701e25bfd8ff479da65c90ce6c5819d947366871c48c884"} 13:47:01 INFO - Running post-run listener: copy_logs_to_upload_dir 13:47:01 INFO - Copying logs to upload dir... 13:47:01 INFO - mkdir: /builds/slave/test/build/upload/logs 13:47:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1677.173073 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 57 secs) (at 2016-08-30 13:47:00.624544) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 13:47:00.627985) ========= 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=0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003 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/c4308b6b99ff4d4d8960eabd3283c0ad26061117d0a16451775081dee78c9cd7879acf4b7446ceff4e2f20227cc18a9e739a8d96bd41467c6667065505edc69e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bafba43146cf25347de09a7a086eca93475a9819bbc6c0e0c62e175b45bf0b13cbc5b2d534b9128fabda2c8b20b5c7b4401ba75c0c904fa6c737626f18b4a6a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59af578076f6b42088dbe628e3cec18c26a2e66628e9705f4008b414b97aee062315b6d31607e68fd701e25bfd8ff479da65c90ce6c5819d947366871c48c884"} build_url:https://queue.taskcluster.net/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/artifacts/public/build/firefox-50.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011188 build_url: 'https://queue.taskcluster.net/v1/task/Lg9QnoZZTvWx3nFUH8JbaQ/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/c4308b6b99ff4d4d8960eabd3283c0ad26061117d0a16451775081dee78c9cd7879acf4b7446ceff4e2f20227cc18a9e739a8d96bd41467c6667065505edc69e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bafba43146cf25347de09a7a086eca93475a9819bbc6c0e0c62e175b45bf0b13cbc5b2d534b9128fabda2c8b20b5c7b4401ba75c0c904fa6c737626f18b4a6a9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/59af578076f6b42088dbe628e3cec18c26a2e66628e9705f4008b414b97aee062315b6d31607e68fd701e25bfd8ff479da65c90ce6c5819d947366871c48c884"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 13:47:00.652601) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 13:47:00.652922) ========= 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=0333a256a5e4f052382dd180000001b1-1472588178.957481-973823003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004467 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 13:47:00.734592) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 13:47:00.734922) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 13:47:00.735208) ========= ========= Total master_lag: 0.19 =========