builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-011 starttime: 1474732270.69 results: success (0) buildid: 20160924055725 builduid: 6577eb05292041db839a72aab02b535e revision: 2663d882602b065945d483a206bb3a39f46cc479 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-24 08:51:10.693187) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-24 08:51:10.693560) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-24 08:51:10.694086) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-24 08:51:10.708470) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-24 08:51:10.708739) ========= 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=5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-24 08:51:11-- 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% 46.3M=0s 2016-09-24 08:51:11 (46.3 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.146146 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-24 08:51:10.868226) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-24 08:51:10.868502) ========= 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=5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015231 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-24 08:51:10.918082) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-24 08:51:10.918383) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2663d882602b065945d483a206bb3a39f46cc479 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2663d882602b065945d483a206bb3a39f46cc479 --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=5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-24 08:51:11,515 truncating revision to first 12 chars 2016-09-24 08:51:11,515 Setting DEBUG logging. 2016-09-24 08:51:11,515 attempt 1/10 2016-09-24 08:51:11,515 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/2663d882602b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-24 08:51:11,950 unpacking tar archive at: mozilla-beta-2663d882602b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.779899 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-24 08:51:11.711295) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-24 08:51:11.711565) ========= script_repo_revision: 2663d882602b065945d483a206bb3a39f46cc479 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-24 08:51:11.711974) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-24 08:51:11.712279) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-24 08:51:11.728537) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-09-24 08:51:11.728816) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 08:51:12 INFO - MultiFileLogger online at 20160924 08:51:12 in /builds/slave/test 08:51:12 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 08:51:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:51:12 INFO - {'append_to_log': False, 08:51:12 INFO - 'base_work_dir': '/builds/slave/test', 08:51:12 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:51:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:51:12 INFO - 'branch': 'Mozilla-Beta', 08:51:12 INFO - 'buildbot_json_path': 'buildprops.json', 08:51:12 INFO - 'config_files': ('talos/linux_config.py',), 08:51:12 INFO - 'default_actions': ('clobber', 08:51:12 INFO - 'read-buildbot-config', 08:51:12 INFO - 'download-and-extract', 08:51:12 INFO - 'populate-webroot', 08:51:12 INFO - 'create-virtualenv', 08:51:12 INFO - 'install', 08:51:12 INFO - 'run-tests'), 08:51:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:51:12 INFO - 'download_minidump_stackwalk': True, 08:51:12 INFO - 'download_symbols': 'ondemand', 08:51:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:51:12 INFO - 'tooltool.py': '/tools/tooltool.py', 08:51:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:51:12 INFO - '/tools/misc-python/virtualenv.py')}, 08:51:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:51:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:51:12 INFO - 'installer_path': 'installer.exe', 08:51:12 INFO - 'log_level': 'info', 08:51:12 INFO - 'log_name': 'talos', 08:51:12 INFO - 'log_to_console': True, 08:51:12 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 08:51:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 08:51:12 INFO - 'opt_config_files': (), 08:51:12 INFO - 'pip_index': False, 08:51:12 INFO - 'sps_profile': False, 08:51:12 INFO - 'sps_profile_interval': 0, 08:51:12 INFO - 'suite': 'dromaeojs', 08:51:12 INFO - 'system_bits': '32', 08:51:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:51:12 INFO - 'title': 'talos-linux64-ix-011', 08:51:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:51:12 INFO - 'use_talos_json': True, 08:51:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:51:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:51:12 INFO - 'work_dir': 'build'} 08:51:12 INFO - [mozharness: 2016-09-24 15:51:12.417940Z] Running clobber step. 08:51:12 INFO - Running pre-action listener: _resource_record_pre_action 08:51:12 INFO - Running main action method: clobber 08:51:12 INFO - rmtree: /builds/slave/test/build 08:51:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:51:12 INFO - Running post-action listener: _resource_record_post_action 08:51:12 INFO - [mozharness: 2016-09-24 15:51:12.875892Z] Finished clobber step (success) 08:51:12 INFO - [mozharness: 2016-09-24 15:51:12.875993Z] Running read-buildbot-config step. 08:51:12 INFO - Running pre-action listener: _resource_record_pre_action 08:51:12 INFO - Running main action method: read_buildbot_config 08:51:12 INFO - Using buildbot properties: 08:51:12 INFO - { 08:51:12 INFO - "project": "", 08:51:12 INFO - "product": "firefox", 08:51:12 INFO - "script_repo_revision": "production", 08:51:12 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 08:51:12 INFO - "repository": "", 08:51:12 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 08:51:12 INFO - "buildid": "20160924055725", 08:51:12 INFO - "pgo_build": "True", 08:51:12 INFO - "basedir": "/builds/slave/test", 08:51:12 INFO - "buildnumber": 401, 08:51:12 INFO - "slavename": "talos-linux64-ix-011", 08:51:12 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 08:51:12 INFO - "platform": "ubuntu64_hw", 08:51:12 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 08:51:12 INFO - "branch": "mozilla-beta", 08:51:12 INFO - "repo_path": "releases/mozilla-beta", 08:51:12 INFO - "revision": "2663d882602b065945d483a206bb3a39f46cc479", 08:51:12 INFO - "stage_platform": "linux64-pgo", 08:51:12 INFO - "builduid": "6577eb05292041db839a72aab02b535e", 08:51:12 INFO - "slavebuilddir": "test" 08:51:12 INFO - } 08:51:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 08:51:12 INFO - Running post-action listener: _resource_record_post_action 08:51:12 INFO - [mozharness: 2016-09-24 15:51:12.878508Z] Finished read-buildbot-config step (success) 08:51:12 INFO - [mozharness: 2016-09-24 15:51:12.878597Z] Running download-and-extract step. 08:51:12 INFO - Running pre-action listener: _resource_record_pre_action 08:51:12 INFO - Running main action method: download_and_extract 08:51:12 INFO - mkdir: /builds/slave/test/build/tests 08:51:12 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')]} 08:51:12 INFO - https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 08:51:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 08:51:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 08:51:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 08:51:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 08:51:12 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 08:51:12 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 08:51:12 INFO - retry: Failed, sleeping 30 seconds before retrying 08:51:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #2 08:51:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 08:51:42 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 08:51:42 INFO - retry: Failed, sleeping 60 seconds before retrying 08:52:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #3 08:52:42 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 08:52:42 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 08:52:42 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json! 08:52:42 INFO - Caught exception: HTTP Error 404: Not Found 08:52:42 INFO - Caught exception: HTTP Error 404: Not Found 08:52:42 INFO - Caught exception: HTTP Error 404: Not Found 08:52:42 INFO - trying https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 08:52:42 INFO - Downloading https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 08:52:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 08:52:43 INFO - Downloaded 1327 bytes. 08:52:43 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 08:52:43 INFO - Using the following test package requirements: 08:52:43 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 08:52:43 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 08:52:43 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 08:52:43 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 08:52:43 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 08:52:43 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 08:52:43 INFO - u'jsshell-linux-x86_64.zip'], 08:52:43 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 08:52:43 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 08:52:43 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 08:52:43 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 08:52:43 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 08:52:43 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 08:52:43 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 08:52:43 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 08:52:43 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 08:52:43 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 08:52:43 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 08:52:43 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 08:52:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:52:43 INFO - https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:52:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:52:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:52:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:52:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:52:43 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:52:43 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 08:52:43 INFO - retry: Failed, sleeping 30 seconds before retrying 08:53:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 08:53:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:53:13 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 08:53:13 INFO - retry: Failed, sleeping 60 seconds before retrying 08:54:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 08:54:13 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:54:13 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 08:54:13 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 08:54:13 INFO - Caught exception: HTTP Error 404: Not Found 08:54:13 INFO - Caught exception: HTTP Error 404: Not Found 08:54:13 INFO - Caught exception: HTTP Error 404: Not Found 08:54:13 INFO - trying https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:54:13 INFO - Downloading https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:54:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:54:14 INFO - Downloaded 21771370 bytes. 08:54:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:54:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:54:15 INFO - Return code: 0 08:54:15 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 08:54:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:54:15 INFO - https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 08:54:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:54:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:54:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:54:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:54:15 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:54:15 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 08:54:15 INFO - retry: Failed, sleeping 30 seconds before retrying 08:54:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #2 08:54:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:54:45 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 08:54:45 INFO - retry: Failed, sleeping 60 seconds before retrying 08:55:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #3 08:55:45 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:55:45 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 08:55:45 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip! 08:55:45 INFO - Caught exception: HTTP Error 404: Not Found 08:55:45 INFO - Caught exception: HTTP Error 404: Not Found 08:55:45 INFO - Caught exception: HTTP Error 404: Not Found 08:55:45 INFO - trying https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:55:45 INFO - Downloading https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 08:55:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 08:55:47 INFO - Downloaded 21771370 bytes. 08:55:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:55:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 08:55:48 INFO - Return code: 0 08:55:48 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')]} 08:55:48 INFO - https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 08:55:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 08:55:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 08:55:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 08:55:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:55:48 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 08:55:48 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 08:55:48 INFO - retry: Failed, sleeping 30 seconds before retrying 08:56:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #2 08:56:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 08:56:18 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 08:56:18 INFO - retry: Failed, sleeping 60 seconds before retrying 08:57:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #3 08:57:18 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 08:57:18 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 08:57:18 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip! 08:57:18 INFO - Caught exception: HTTP Error 404: Not Found 08:57:18 INFO - Caught exception: HTTP Error 404: Not Found 08:57:18 INFO - Caught exception: HTTP Error 404: Not Found 08:57:18 INFO - trying https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 08:57:18 INFO - Downloading https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 08:57:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 08:57:19 INFO - Downloaded 14413740 bytes. 08:57:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:57:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 08:57:19 INFO - Return code: 0 08:57:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:57:19 INFO - https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 08:57:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 08:57:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 08:57:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 08:57:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:57:19 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 08:57:19 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 08:57:19 INFO - retry: Failed, sleeping 30 seconds before retrying 08:57:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #2 08:57:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 08:57:49 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 08:57:49 INFO - retry: Failed, sleeping 60 seconds before retrying 08:58:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #3 08:58:49 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 08:58:49 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 08:58:49 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2! 08:58:49 INFO - Caught exception: HTTP Error 404: Not Found 08:58:49 INFO - Caught exception: HTTP Error 404: Not Found 08:58:49 INFO - Caught exception: HTTP Error 404: Not Found 08:58:49 INFO - trying https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 08:58:49 INFO - Downloading https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 08:58:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 08:58:51 INFO - Downloaded 56782756 bytes. 08:58:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 08:58:51 INFO - mkdir: /builds/slave/test/properties 08:58:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:58:51 INFO - Writing to file /builds/slave/test/properties/build_url 08:58:51 INFO - Contents: 08:58:51 INFO - build_url:https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 08:58:52 INFO - Running post-action listener: _resource_record_post_action 08:58:52 INFO - Running post-action listener: set_extra_try_arguments 08:58:52 INFO - [mozharness: 2016-09-24 15:58:52.560685Z] Finished download-and-extract step (success) 08:58:52 INFO - [mozharness: 2016-09-24 15:58:52.560916Z] Running populate-webroot step. 08:58:52 INFO - Running pre-action listener: _resource_record_pre_action 08:58:52 INFO - Running main action method: populate_webroot 08:58:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:58:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:58:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:58:52 INFO - '--tppagecycles', 08:58:52 INFO - '7'], 08:58:52 INFO - 'tests': ['tsvgm']}}, 08:58:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:58:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:58:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:58:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:58:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:58:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:58:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:58:52 INFO - 'talos_options': ['--disable-e10s'], 08:58:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:58:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:58:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:58:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:58:52 INFO - 'talos_options': ['--disable-e10s'], 08:58:52 INFO - 'tests': ['damp', 'tps']}, 08:58:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:58:52 INFO - 'tests': ['damp', 'tps']}, 08:58:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:58:52 INFO - 'tests': ['dromaeo_dom']}, 08:58:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:58:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:58:52 INFO - 'tests': ['basic_compositor_video']}, 08:58:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:58:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:58:52 INFO - 'tests': ['a11yr', 08:58:52 INFO - 'ts_paint', 08:58:52 INFO - 'tpaint', 08:58:52 INFO - 'sessionrestore', 08:58:52 INFO - 'sessionrestore_no_auto_restore', 08:58:52 INFO - 'tabpaint']}, 08:58:52 INFO - 'other-e10s': {'tests': ['a11yr', 08:58:52 INFO - 'ts_paint', 08:58:52 INFO - 'tpaint', 08:58:52 INFO - 'sessionrestore', 08:58:52 INFO - 'sessionrestore_no_auto_restore', 08:58:52 INFO - 'tabpaint']}, 08:58:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:58:52 INFO - 'ts_paint', 08:58:52 INFO - 'tpaint', 08:58:52 INFO - 'sessionrestore', 08:58:52 INFO - 'sessionrestore_no_auto_restore', 08:58:52 INFO - 'tabpaint']}, 08:58:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:58:52 INFO - 'ts_paint', 08:58:52 INFO - 'tpaint', 08:58:52 INFO - 'sessionrestore', 08:58:52 INFO - 'sessionrestore_no_auto_restore', 08:58:52 INFO - 'tabpaint']}, 08:58:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:58:52 INFO - 'tests': ['a11yr', 08:58:52 INFO - 'ts_paint', 08:58:52 INFO - 'tpaint', 08:58:52 INFO - 'sessionrestore', 08:58:52 INFO - 'sessionrestore_no_auto_restore', 08:58:52 INFO - 'tabpaint']}, 08:58:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:58:52 INFO - 'tests': ['a11yr', 08:58:52 INFO - 'ts_paint', 08:58:52 INFO - 'tpaint', 08:58:52 INFO - 'sessionrestore', 08:58:52 INFO - 'sessionrestore_no_auto_restore', 08:58:52 INFO - 'tabpaint']}, 08:58:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:58:52 INFO - 'tests': ['tsvgx', 08:58:52 INFO - 'tsvgr_opacity', 08:58:52 INFO - 'tart', 08:58:52 INFO - 'tscrollx', 08:58:52 INFO - 'cart']}, 08:58:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:58:52 INFO - 'tsvgr_opacity', 08:58:52 INFO - 'tart', 08:58:52 INFO - 'tscrollx', 08:58:52 INFO - 'cart']}, 08:58:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:58:52 INFO - 'talos_options': ['--disable-e10s'], 08:58:52 INFO - 'tests': ['tp5o']}, 08:58:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:58:52 INFO - 'tests': ['tp5o']}, 08:58:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:58:52 INFO - 'talos_options': ['--disable-e10s', 08:58:52 INFO - '--xperf_path', 08:58:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:58:52 INFO - 'tests': ['tp5n']}, 08:58:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:58:52 INFO - 'talos_options': ['--xperf_path', 08:58:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:58:52 INFO - 'tests': ['tp5n']}}, 08:58:52 INFO - 'talos.zip': {'path': '', 08:58:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:58:52 INFO - Running post-action listener: _resource_record_post_action 08:58:52 INFO - [mozharness: 2016-09-24 15:58:52.579469Z] Finished populate-webroot step (success) 08:58:52 INFO - [mozharness: 2016-09-24 15:58:52.579558Z] Running create-virtualenv step. 08:58:52 INFO - Running pre-action listener: _resource_record_pre_action 08:58:52 INFO - Running main action method: create_virtualenv 08:58:52 INFO - Creating virtualenv /builds/slave/test/build/venv 08:58:52 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 08:58:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:58:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:58:52 INFO - Using real prefix '/usr' 08:58:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:58:53 INFO - Installing distribute.............................................................................................................................................................................................done. 08:58:55 INFO - Installing pip.................done. 08:58:55 INFO - Return code: 0 08:58:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:58: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')]} 08:58:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58: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')]} 08:58:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26b08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280c3b0>, '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 0x280d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280d740>, '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': '5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866', '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 08:58: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', 'pip>=1.5'] in /builds/slave/test/build 08:58: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 pip>=1.5 08:58:55 INFO - Using env: {'DISPLAY': ':0', 08:58:55 INFO - 'HOME': '/home/cltbld', 08:58:55 INFO - 'LANG': 'en_US.UTF-8', 08:58:55 INFO - 'LANGUAGE': 'en_US:en', 08:58:55 INFO - 'LOGNAME': 'cltbld', 08:58:55 INFO - 'MAIL': '/var/mail/cltbld', 08:58:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:55 INFO - 'MOZ_NO_REMOTE': '1', 08:58:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:58:55 INFO - 'NO_EM_RESTART': '1', 08:58:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:58:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:55 INFO - 'PWD': '/builds/slave/test', 08:58:55 INFO - 'SHELL': '/bin/bash', 08:58:55 INFO - 'SHLVL': '1', 08:58:55 INFO - 'TERM': 'linux', 08:58:55 INFO - 'TMOUT': '86400', 08:58:55 INFO - 'USER': 'cltbld', 08:58:55 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866', 08:58:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:55 INFO - '_': '/tools/buildbot/bin/python'} 08:58:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:55 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 08:58:55 INFO - Cleaning up... 08:58:55 INFO - Return code: 0 08:58:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:58: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')]} 08:58:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58: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')]} 08:58:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58: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', '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 0x254d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26b08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280c3b0>, '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 0x280d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280d740>, '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': '5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866', '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 08:58: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', 'psutil>=3.1.1'] in /builds/slave/test/build 08:58: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 psutil>=3.1.1 08:58:55 INFO - Using env: {'DISPLAY': ':0', 08:58:55 INFO - 'HOME': '/home/cltbld', 08:58:55 INFO - 'LANG': 'en_US.UTF-8', 08:58:55 INFO - 'LANGUAGE': 'en_US:en', 08:58:55 INFO - 'LOGNAME': 'cltbld', 08:58:55 INFO - 'MAIL': '/var/mail/cltbld', 08:58:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:55 INFO - 'MOZ_NO_REMOTE': '1', 08:58:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:58:55 INFO - 'NO_EM_RESTART': '1', 08:58:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:58:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:55 INFO - 'PWD': '/builds/slave/test', 08:58:55 INFO - 'SHELL': '/bin/bash', 08:58:55 INFO - 'SHLVL': '1', 08:58:55 INFO - 'TERM': 'linux', 08:58:55 INFO - 'TMOUT': '86400', 08:58:55 INFO - 'USER': 'cltbld', 08:58:55 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866', 08:58:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:55 INFO - '_': '/tools/buildbot/bin/python'} 08:58:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:55 INFO - Downloading/unpacking psutil>=3.1.1 08:58: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 08:58: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 08:58: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 08:58: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 08:58: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 08:58: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 08:58:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:58:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:58:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:58:57 INFO - Installing collected packages: psutil 08:58:57 INFO - Running setup.py install for psutil 08:58:57 INFO - building 'psutil._psutil_linux' extension 08:58:57 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 08:58:57 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 08:58:57 INFO - building 'psutil._psutil_posix' extension 08:58:57 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 08:58:57 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 08:58:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:58:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:58:58 INFO - Successfully installed psutil 08:58:58 INFO - Cleaning up... 08:58:58 INFO - Return code: 0 08:58:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:58: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')]} 08:58:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:58:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58: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')]} 08:58:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:58:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:58:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x254d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26b08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280c3b0>, '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 0x280d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280d740>, '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': '5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866', '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 08:58:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:58:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:58:58 INFO - Using env: {'DISPLAY': ':0', 08:58:58 INFO - 'HOME': '/home/cltbld', 08:58:58 INFO - 'LANG': 'en_US.UTF-8', 08:58:58 INFO - 'LANGUAGE': 'en_US:en', 08:58:58 INFO - 'LOGNAME': 'cltbld', 08:58:58 INFO - 'MAIL': '/var/mail/cltbld', 08:58:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:58:58 INFO - 'MOZ_NO_REMOTE': '1', 08:58:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:58:58 INFO - 'NO_EM_RESTART': '1', 08:58:58 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:58:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:58:58 INFO - 'PWD': '/builds/slave/test', 08:58:58 INFO - 'SHELL': '/bin/bash', 08:58:58 INFO - 'SHLVL': '1', 08:58:58 INFO - 'TERM': 'linux', 08:58:58 INFO - 'TMOUT': '86400', 08:58:58 INFO - 'USER': 'cltbld', 08:58:58 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866', 08:58:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:58:58 INFO - '_': '/tools/buildbot/bin/python'} 08:58:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:58:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:58:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:58:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:58:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:58:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:59:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:59:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:59:00 INFO - Installing collected packages: mozsystemmonitor 08:59:00 INFO - Running setup.py install for mozsystemmonitor 08:59:00 INFO - Successfully installed mozsystemmonitor 08:59:00 INFO - Cleaning up... 08:59:00 INFO - Return code: 0 08:59:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:59:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x254d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26b08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280c3b0>, '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 0x280d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280d740>, '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': '5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866', '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 08:59:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:59:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:59:00 INFO - Using env: {'DISPLAY': ':0', 08:59:00 INFO - 'HOME': '/home/cltbld', 08:59:00 INFO - 'LANG': 'en_US.UTF-8', 08:59:00 INFO - 'LANGUAGE': 'en_US:en', 08:59:00 INFO - 'LOGNAME': 'cltbld', 08:59:00 INFO - 'MAIL': '/var/mail/cltbld', 08:59:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:00 INFO - 'MOZ_NO_REMOTE': '1', 08:59:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:59:00 INFO - 'NO_EM_RESTART': '1', 08:59:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:59:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:00 INFO - 'PWD': '/builds/slave/test', 08:59:00 INFO - 'SHELL': '/bin/bash', 08:59:00 INFO - 'SHLVL': '1', 08:59:00 INFO - 'TERM': 'linux', 08:59:00 INFO - 'TMOUT': '86400', 08:59:00 INFO - 'USER': 'cltbld', 08:59:00 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866', 08:59:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:00 INFO - '_': '/tools/buildbot/bin/python'} 08:59:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:00 INFO - Downloading/unpacking jsonschema==2.5.1 08:59:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:59:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:59:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:59:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:59:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:59:02 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 08:59:02 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 08:59:02 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 08:59:02 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 08:59:02 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 08:59:02 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 08:59:02 INFO - Downloading functools32-3.2.3-2.tar.gz 08:59:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:59:02 INFO - warning: no files found matching '*.txt' 08:59:02 INFO - no previously-included directories found matching 'build' 08:59:02 INFO - no previously-included directories found matching 'dist' 08:59:02 INFO - no previously-included directories found matching '.git*' 08:59:02 INFO - Installing collected packages: jsonschema, functools32 08:59:02 INFO - Running setup.py install for functools32 08:59:02 INFO - warning: no files found matching '*.txt' 08:59:02 INFO - no previously-included directories found matching 'build' 08:59:02 INFO - no previously-included directories found matching 'dist' 08:59:02 INFO - no previously-included directories found matching '.git*' 08:59:02 INFO - Successfully installed jsonschema functools32 08:59:02 INFO - Cleaning up... 08:59:03 INFO - Return code: 0 08:59:03 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:59:03 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')]} 08:59:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:03 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')]} 08:59:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:03 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 0x254d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26b08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280c3b0>, '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 0x280d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280d740>, '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': '5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866', '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 08:59:03 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 08:59:03 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 08:59:03 INFO - Using env: {'DISPLAY': ':0', 08:59:03 INFO - 'HOME': '/home/cltbld', 08:59:03 INFO - 'LANG': 'en_US.UTF-8', 08:59:03 INFO - 'LANGUAGE': 'en_US:en', 08:59:03 INFO - 'LOGNAME': 'cltbld', 08:59:03 INFO - 'MAIL': '/var/mail/cltbld', 08:59:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:03 INFO - 'MOZ_NO_REMOTE': '1', 08:59:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:59:03 INFO - 'NO_EM_RESTART': '1', 08:59:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:59:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:03 INFO - 'PWD': '/builds/slave/test', 08:59:03 INFO - 'SHELL': '/bin/bash', 08:59:03 INFO - 'SHLVL': '1', 08:59:03 INFO - 'TERM': 'linux', 08:59:03 INFO - 'TMOUT': '86400', 08:59:03 INFO - 'USER': 'cltbld', 08:59:03 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866', 08:59:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:03 INFO - '_': '/tools/buildbot/bin/python'} 08:59:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:59:03 INFO - Cleaning up... 08:59:03 INFO - Return code: 0 08:59:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:59:03 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')]} 08:59:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:03 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')]} 08:59:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:03 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 0x254d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26b08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280c3b0>, '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 0x280d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280d740>, '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': '5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866', '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 08:59:03 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 08:59:03 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 08:59:03 INFO - Using env: {'DISPLAY': ':0', 08:59:03 INFO - 'HOME': '/home/cltbld', 08:59:03 INFO - 'LANG': 'en_US.UTF-8', 08:59:03 INFO - 'LANGUAGE': 'en_US:en', 08:59:03 INFO - 'LOGNAME': 'cltbld', 08:59:03 INFO - 'MAIL': '/var/mail/cltbld', 08:59:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:03 INFO - 'MOZ_NO_REMOTE': '1', 08:59:03 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:59:03 INFO - 'NO_EM_RESTART': '1', 08:59:03 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:59:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:03 INFO - 'PWD': '/builds/slave/test', 08:59:03 INFO - 'SHELL': '/bin/bash', 08:59:03 INFO - 'SHLVL': '1', 08:59:03 INFO - 'TERM': 'linux', 08:59:03 INFO - 'TMOUT': '86400', 08:59:03 INFO - 'USER': 'cltbld', 08:59:03 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866', 08:59:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:03 INFO - '_': '/tools/buildbot/bin/python'} 08:59:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:03 INFO - Downloading/unpacking blobuploader==1.2.4 08:59: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 08:59: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 08:59: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 08:59: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 08:59: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 08:59: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 08:59:05 INFO - Downloading blobuploader-1.2.4.tar.gz 08:59:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:59:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:59:05 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 08:59:05 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 08:59:05 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 08:59:05 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 08:59:05 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 08:59:05 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 08:59:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:59:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:59:05 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 08:59:05 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 08:59:05 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 08:59:05 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 08:59:05 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 08:59:05 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 08:59:06 INFO - Downloading docopt-0.6.1.tar.gz 08:59:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:59:06 INFO - Installing collected packages: blobuploader, requests, docopt 08:59:06 INFO - Running setup.py install for blobuploader 08:59:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:59:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:59:06 INFO - Running setup.py install for requests 08:59:06 INFO - Running setup.py install for docopt 08:59:06 INFO - Successfully installed blobuploader requests docopt 08:59:06 INFO - Cleaning up... 08:59:06 INFO - Return code: 0 08:59:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:59:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:06 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 0x254d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26b08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280c3b0>, '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 0x280d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280d740>, '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': '5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866', '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 08:59:06 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 08:59:06 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 08:59:06 INFO - Using env: {'DISPLAY': ':0', 08:59:06 INFO - 'HOME': '/home/cltbld', 08:59:06 INFO - 'LANG': 'en_US.UTF-8', 08:59:06 INFO - 'LANGUAGE': 'en_US:en', 08:59:06 INFO - 'LOGNAME': 'cltbld', 08:59:06 INFO - 'MAIL': '/var/mail/cltbld', 08:59:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:06 INFO - 'MOZ_NO_REMOTE': '1', 08:59:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:59:06 INFO - 'NO_EM_RESTART': '1', 08:59:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:59:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:06 INFO - 'PWD': '/builds/slave/test', 08:59:06 INFO - 'SHELL': '/bin/bash', 08:59:06 INFO - 'SHLVL': '1', 08:59:06 INFO - 'TERM': 'linux', 08:59:06 INFO - 'TMOUT': '86400', 08:59:06 INFO - 'USER': 'cltbld', 08:59:06 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866', 08:59:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:06 INFO - '_': '/tools/buildbot/bin/python'} 08:59:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:59:06 INFO - Running setup.py (path:/tmp/pip-4rd6_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:59:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:59:06 INFO - Running setup.py (path:/tmp/pip-xpxSYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:59:07 INFO - Running setup.py (path:/tmp/pip-OhrGi9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:59:07 INFO - Running setup.py (path:/tmp/pip-Xi5v7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:59:07 INFO - Running setup.py (path:/tmp/pip-WwwIru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:59:07 INFO - Running setup.py (path:/tmp/pip-9QkB2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:59:07 INFO - Running setup.py (path:/tmp/pip-MboYQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:59:07 INFO - Running setup.py (path:/tmp/pip-p4gz30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:59:07 INFO - Running setup.py (path:/tmp/pip-PuEHZ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:59:07 INFO - Running setup.py (path:/tmp/pip-a9LFEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:59:07 INFO - Running setup.py (path:/tmp/pip-o3kRfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:59:07 INFO - Running setup.py (path:/tmp/pip-4bPI3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:59:07 INFO - Running setup.py (path:/tmp/pip-VbYkoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:59:07 INFO - Running setup.py (path:/tmp/pip-xm_wKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:59:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:59:07 INFO - Running setup.py (path:/tmp/pip-kxfYUz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:59:08 INFO - Running setup.py (path:/tmp/pip-9XRnag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:59:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:59:08 INFO - Running setup.py (path:/tmp/pip-RUAwOY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:59:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:59:08 INFO - Running setup.py install for manifestparser 08:59:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:59:08 INFO - Running setup.py install for mozcrash 08:59:08 INFO - Running setup.py install for mozdebug 08:59:08 INFO - Running setup.py install for mozdevice 08:59:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:59:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:59:08 INFO - Running setup.py install for mozfile 08:59:08 INFO - Running setup.py install for mozhttpd 08:59:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:59:08 INFO - Running setup.py install for mozinfo 08:59:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:59:08 INFO - Running setup.py install for mozInstall 08:59:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:59:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:59:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:59:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:59:08 INFO - Running setup.py install for mozleak 08:59:09 INFO - Running setup.py install for mozlog 08:59:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:59:09 INFO - Running setup.py install for moznetwork 08:59:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:59:09 INFO - Running setup.py install for mozprocess 08:59:09 INFO - Running setup.py install for mozprofile 08:59:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:59:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:59:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:59:09 INFO - Running setup.py install for mozrunner 08:59:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:59:09 INFO - Running setup.py install for mozscreenshot 08:59:09 INFO - Running setup.py install for moztest 08:59:09 INFO - Running setup.py install for mozversion 08:59:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:59:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:59:09 INFO - Cleaning up... 08:59:09 INFO - Return code: 0 08:59:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:59:09 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')]} 08:59:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:09 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')]} 08:59:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:09 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 0x254d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26b08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280c3b0>, '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 0x280d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280d740>, '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': '5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866', '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 08:59:09 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 08:59:09 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 08:59:09 INFO - Using env: {'DISPLAY': ':0', 08:59:09 INFO - 'HOME': '/home/cltbld', 08:59:09 INFO - 'LANG': 'en_US.UTF-8', 08:59:09 INFO - 'LANGUAGE': 'en_US:en', 08:59:09 INFO - 'LOGNAME': 'cltbld', 08:59:09 INFO - 'MAIL': '/var/mail/cltbld', 08:59:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:09 INFO - 'MOZ_NO_REMOTE': '1', 08:59:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:59:09 INFO - 'NO_EM_RESTART': '1', 08:59:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:59:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:09 INFO - 'PWD': '/builds/slave/test', 08:59:09 INFO - 'SHELL': '/bin/bash', 08:59:09 INFO - 'SHLVL': '1', 08:59:09 INFO - 'TERM': 'linux', 08:59:09 INFO - 'TMOUT': '86400', 08:59:09 INFO - 'USER': 'cltbld', 08:59:09 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866', 08:59:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:09 INFO - '_': '/tools/buildbot/bin/python'} 08:59:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:59:10 INFO - Running setup.py (path:/tmp/pip-60TGXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:59:10 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)) 08:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:59:10 INFO - Running setup.py (path:/tmp/pip-PhXUCq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:59:10 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)) 08:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:59:10 INFO - Running setup.py (path:/tmp/pip-D28PaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:59:10 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)) 08:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:59:10 INFO - Running setup.py (path:/tmp/pip-zhQbXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:59:10 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)) 08:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:59:10 INFO - Running setup.py (path:/tmp/pip-QWsKg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:59:10 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)) 08:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:59:10 INFO - Running setup.py (path:/tmp/pip-cLoZwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:59:10 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)) 08:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:59:10 INFO - Running setup.py (path:/tmp/pip-kOmkad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:59:10 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)) 08:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:59:10 INFO - Running setup.py (path:/tmp/pip-8STh3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:59:10 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)) 08:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:59:10 INFO - Running setup.py (path:/tmp/pip-1mtcO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:59:10 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)) 08:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:59:10 INFO - Running setup.py (path:/tmp/pip-6yaaET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:59:10 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)) 08:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:59:10 INFO - Running setup.py (path:/tmp/pip-a2cBIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:59:10 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)) 08:59:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:59:10 INFO - Running setup.py (path:/tmp/pip-Nuq4lu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:59:11 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)) 08:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:59:11 INFO - Running setup.py (path:/tmp/pip-8B8BEC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:59:11 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)) 08:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:59:11 INFO - Running setup.py (path:/tmp/pip-lKBmeD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:59:11 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)) 08:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:59:11 INFO - Running setup.py (path:/tmp/pip-HJA7WG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:59:11 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)) 08:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:59:11 INFO - Running setup.py (path:/tmp/pip-ww0O43-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:59:11 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)) 08:59:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:59:11 INFO - Running setup.py (path:/tmp/pip-Bs3OJO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:59:11 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)) 08:59:11 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)) 08:59:11 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)) 08:59:11 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)) 08:59:11 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)) 08:59:11 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)) 08:59:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:59:11 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 08:59:11 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 08:59:11 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 08:59:11 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 08:59:11 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 08:59:11 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 08:59:13 INFO - Downloading blessings-1.6.tar.gz 08:59:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:59:13 INFO - Installing collected packages: blessings 08:59:13 INFO - Running setup.py install for blessings 08:59:13 INFO - Successfully installed blessings 08:59:13 INFO - Cleaning up... 08:59:13 INFO - Return code: 0 08:59:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:59:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:59:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:59:13 INFO - Reading from file tmpfile_stdout 08:59:13 INFO - Current package versions: 08:59:13 INFO - argparse == 1.2.1 08:59:13 INFO - blessings == 1.6 08:59:13 INFO - blobuploader == 1.2.4 08:59:13 INFO - docopt == 0.6.1 08:59:13 INFO - functools32 == 3.2.3-2 08:59:13 INFO - jsonschema == 2.5.1 08:59:13 INFO - manifestparser == 1.1 08:59:13 INFO - mozInstall == 1.12 08:59:13 INFO - mozcrash == 0.17 08:59:13 INFO - mozdebug == 0.1 08:59:13 INFO - mozdevice == 0.48 08:59:13 INFO - mozfile == 1.2 08:59:13 INFO - mozhttpd == 0.7 08:59:13 INFO - mozinfo == 0.9 08:59:13 INFO - mozleak == 0.1 08:59:13 INFO - mozlog == 3.3 08:59:13 INFO - moznetwork == 0.27 08:59:13 INFO - mozprocess == 0.23 08:59:13 INFO - mozprofile == 0.28 08:59:13 INFO - mozrunner == 6.12 08:59:13 INFO - mozscreenshot == 0.1 08:59:13 INFO - mozsystemmonitor == 0.3 08:59:13 INFO - moztest == 0.7 08:59:13 INFO - mozversion == 1.4 08:59:13 INFO - psutil == 3.1.1 08:59:13 INFO - requests == 1.2.3 08:59:13 INFO - wsgiref == 0.1.2 08:59:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:59:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:13 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 0x254d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26b08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280c3b0>, '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 0x280d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280d740>, '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': '5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866', '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 08:59:13 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 08:59:13 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 08:59:13 INFO - Using env: {'DISPLAY': ':0', 08:59:13 INFO - 'HOME': '/home/cltbld', 08:59:13 INFO - 'LANG': 'en_US.UTF-8', 08:59:13 INFO - 'LANGUAGE': 'en_US:en', 08:59:13 INFO - 'LOGNAME': 'cltbld', 08:59:13 INFO - 'MAIL': '/var/mail/cltbld', 08:59:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:13 INFO - 'MOZ_NO_REMOTE': '1', 08:59:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:59:13 INFO - 'NO_EM_RESTART': '1', 08:59:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:59:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:13 INFO - 'PWD': '/builds/slave/test', 08:59:13 INFO - 'SHELL': '/bin/bash', 08:59:13 INFO - 'SHLVL': '1', 08:59:13 INFO - 'TERM': 'linux', 08:59:13 INFO - 'TMOUT': '86400', 08:59:13 INFO - 'USER': 'cltbld', 08:59:13 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866', 08:59:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:13 INFO - '_': '/tools/buildbot/bin/python'} 08:59:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:14 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)) 08:59:14 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)) 08:59:14 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)) 08:59:14 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)) 08:59:14 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)) 08:59:14 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)) 08:59:14 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)) 08:59:14 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)) 08:59:14 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)) 08:59:14 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)) 08:59:14 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)) 08:59:14 INFO - Cleaning up... 08:59:14 INFO - Return code: 0 08:59:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:59:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:59:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:59:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:59:14 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 0x254d030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26b08a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x280e180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280c3b0>, '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 0x280d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280d740>, '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': '5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866', '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 08:59:14 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 08:59:14 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 08:59:14 INFO - Using env: {'DISPLAY': ':0', 08:59:14 INFO - 'HOME': '/home/cltbld', 08:59:14 INFO - 'LANG': 'en_US.UTF-8', 08:59:14 INFO - 'LANGUAGE': 'en_US:en', 08:59:14 INFO - 'LOGNAME': 'cltbld', 08:59:14 INFO - 'MAIL': '/var/mail/cltbld', 08:59:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:14 INFO - 'MOZ_NO_REMOTE': '1', 08:59:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:59:14 INFO - 'NO_EM_RESTART': '1', 08:59:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:59:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:14 INFO - 'PWD': '/builds/slave/test', 08:59:14 INFO - 'SHELL': '/bin/bash', 08:59:14 INFO - 'SHLVL': '1', 08:59:14 INFO - 'TERM': 'linux', 08:59:14 INFO - 'TMOUT': '86400', 08:59:14 INFO - 'USER': 'cltbld', 08:59:14 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866', 08:59:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:14 INFO - '_': '/tools/buildbot/bin/python'} 08:59:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:59:14 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:59:14 INFO - Cleaning up... 08:59:14 INFO - Return code: 0 08:59:14 INFO - Running post-action listener: _resource_record_post_action 08:59:14 INFO - Running post-action listener: _start_resource_monitoring 08:59:14 INFO - Starting resource monitoring. 08:59:14 INFO - [mozharness: 2016-09-24 15:59:14.271493Z] Finished create-virtualenv step (success) 08:59:14 INFO - [mozharness: 2016-09-24 15:59:14.271929Z] Running install step. 08:59:14 INFO - Running pre-action listener: _resource_record_pre_action 08:59:14 INFO - Running main action method: install 08:59:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:59:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:59:14 INFO - Reading from file tmpfile_stdout 08:59:14 INFO - Detecting whether we're running mozinstall >=1.0... 08:59:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:59:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:59:14 INFO - Reading from file tmpfile_stdout 08:59:14 INFO - Output received: 08:59:14 INFO - Usage: mozinstall [options] installer 08:59:14 INFO - Options: 08:59:14 INFO - -h, --help show this help message and exit 08:59:14 INFO - -d DEST, --destination=DEST 08:59:14 INFO - Directory to install application into. [default: 08:59:14 INFO - "/builds/slave/test"] 08:59:14 INFO - --app=APP Application being installed. [default: firefox] 08:59:14 INFO - mkdir: /builds/slave/test/build/application 08:59:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 08:59:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 08:59:28 INFO - Reading from file tmpfile_stdout 08:59:28 INFO - Output received: 08:59:28 INFO - /builds/slave/test/build/application/firefox/firefox 08:59:28 INFO - Running post-action listener: _resource_record_post_action 08:59:28 INFO - [mozharness: 2016-09-24 15:59:28.907828Z] Finished install step (success) 08:59:28 INFO - [mozharness: 2016-09-24 15:59:28.908109Z] Running run-tests step. 08:59:28 INFO - Running pre-action listener: _resource_record_pre_action 08:59:28 INFO - Running main action method: run_tests 08:59:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:59:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:59:28 INFO - Python 2.7.3 08:59:28 INFO - Return code: 0 08:59:28 INFO - grabbing minidump binary from tooltool 08:59:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:59:28 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x280c3b0>, '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 0x280d2b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x280d740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:59:28 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:59:28 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 08:59:28 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:59:28 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:59:28 INFO - Return code: 0 08:59:28 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 08:59:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:59:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:59:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 08:59:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:59:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 08:59:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-011 --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 08:59:28 INFO - Using env: {'DISPLAY': ':0', 08:59:28 INFO - 'HOME': '/home/cltbld', 08:59:28 INFO - 'LANG': 'en_US.UTF-8', 08:59:28 INFO - 'LANGUAGE': 'en_US:en', 08:59:28 INFO - 'LOGNAME': 'cltbld', 08:59:28 INFO - 'MAIL': '/var/mail/cltbld', 08:59:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:59:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 08:59:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:59:28 INFO - 'MOZ_NO_REMOTE': '1', 08:59:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:59:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 08:59:28 INFO - 'NO_EM_RESTART': '1', 08:59:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 08:59:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:59:28 INFO - 'PWD': '/builds/slave/test', 08:59:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:59:28 INFO - 'SHELL': '/bin/bash', 08:59:28 INFO - 'SHLVL': '1', 08:59:28 INFO - 'TERM': 'linux', 08:59:28 INFO - 'TMOUT': '86400', 08:59:28 INFO - 'USER': 'cltbld', 08:59:28 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866', 08:59:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:59:28 INFO - '_': '/tools/buildbot/bin/python'} 08:59:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--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 08:59:29 INFO - mozversion application_buildid: 20160924055725 08:59:29 INFO - mozversion application_changeset: 2663d882602b065945d483a206bb3a39f46cc479 08:59:29 INFO - mozversion application_display_name: Firefox 08:59:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:59:29 INFO - mozversion application_name: Firefox 08:59:29 INFO - mozversion application_remotingname: firefox 08:59:29 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:59:29 INFO - mozversion application_vendor: Mozilla 08:59:29 INFO - mozversion application_version: 50.0 08:59:29 INFO - mozversion platform_buildid: 20160924055725 08:59:29 INFO - mozversion platform_changeset: 2663d882602b065945d483a206bb3a39f46cc479 08:59:29 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:59:29 INFO - mozversion platform_version: 50.0 08:59:29 INFO - using testdate: 1474732769 08:59:29 INFO - actual date: 1474732769 08:59:29 INFO - starting webserver on 'localhost:46147' 08:59:29 INFO - SUITE-START | Running 2 tests 08:59:29 INFO - TEST-START | dromaeo_css 08:59:29 INFO - operating with platform_type : linux_ 08:59:29 INFO - Initialising browser for dromaeo_css test... 08:59:29 INFO - TEST-INFO | started process 31673 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFq1Sjd/profile http://localhost:46147/getInfo.html) 08:59:33 INFO - PROCESS | 31673 | __metrics Screen width/height:1600/1200 08:59:33 INFO - PROCESS | 31673 | colorDepth:24 08:59:33 INFO - PROCESS | 31673 | Browser inner width/height: 1024/697 08:59:33 INFO - PROCESS | 31673 | __metrics 08:59:34 INFO - PROCESS | 31673 | 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" 08:59:34 INFO - PROCESS | 31673 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:59:35 INFO - TEST-INFO | 31673: exit 0 08:59:35 INFO - Browser initialized. 08:59:35 INFO - Running cycle 1/1 for dromaeo_css test... 08:59:35 INFO - TEST-INFO | started process 31763 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpFq1Sjd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:59:47 INFO - PROCESS | 31763 | RSS: Main: 142884864 08:59:47 INFO - PROCESS | 31763 | 09:00:38 INFO - PROCESS | 31763 | 1474732838464 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] 09:02:47 INFO - PROCESS | 31763 | Cycle 1(1): loaded http://localhost:46147/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46147/tests/dromaeo/cssquery-ext.html) 09:02:48 INFO - PROCESS | 31763 | RSS: Main: 219086848 09:02:48 INFO - PROCESS | 31763 | 09:05:50 INFO - PROCESS | 31763 | Cycle 1(1): loaded http://localhost:46147/tests/dromaeo/cssquery-ext.html (next: http://localhost:46147/tests/dromaeo/cssquery-jquery.html) 09:05:51 INFO - PROCESS | 31763 | RSS: Main: 222654464 09:05:51 INFO - PROCESS | 31763 | 09:08:53 INFO - PROCESS | 31763 | Cycle 1(1): loaded http://localhost:46147/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46147/tests/dromaeo/cssquery-mootools.html) 09:08:54 INFO - PROCESS | 31763 | RSS: Main: 175185920 09:08:54 INFO - PROCESS | 31763 | 09:11:55 INFO - PROCESS | 31763 | Cycle 1(1): loaded http://localhost:46147/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46147/tests/dromaeo/cssquery-prototype.html) 09:11:56 INFO - PROCESS | 31763 | RSS: Main: 176508928 09:11:56 INFO - PROCESS | 31763 | 09:14:58 INFO - PROCESS | 31763 | Cycle 1(1): loaded http://localhost:46147/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46147/tests/dromaeo/cssquery-yui.html) 09:14:59 INFO - PROCESS | 31763 | RSS: Main: 176005120 09:14:59 INFO - PROCESS | 31763 | 09:18:01 INFO - PROCESS | 31763 | Cycle 1(1): loaded http://localhost:46147/tests/dromaeo/cssquery-yui.html (next: http://localhost:46147/tests/dromaeo/cssquery-dojo.html) 09:18:02 INFO - PROCESS | 31763 | RSS: Main: 251604992 09:18:02 INFO - PROCESS | 31763 | 09:18:02 INFO - PROCESS | 31763 | __start_tp_report 09:18:02 INFO - PROCESS | 31763 | _x_x_mozilla_page_load 09:18:02 INFO - PROCESS | 31763 | _x_x_mozilla_page_load_details 09:18:02 INFO - PROCESS | 31763 | |i|pagename|runs| 09:18:02 INFO - PROCESS | 31763 | |0;dojo.html;3188.888388906388;3217.7425805935527;3228.1445416964525;3248.626407963085;3269.2971011232594;5553.750081246343;5658.773649054038;5659.462351076646;5659.943400565993;5668;5626.943730562693;5698.658080515172;5712.086066229404;5712.11462223355;5728.914066288999;4952.727599981999;5042.319286896259;5110.28456016158;5112.642115051957;5160.483951604829;5092.872678183038;5161.174212126042;5162.741862906859;5174.404943431516;5175.58595312376;5031.874203144914;5085.389753229618;5121.897562048757;5132.512411320938;5137.820176293831;5181.119209734316;5202.049685412209;5206.349206349207;5216.58267338612;5237;4837.4920633333695;4912.287718280865;4969.826056088055;5100.285959965608;5107.4892510749;5450.836474905759;5451.754671039773;5468.2891224141;5473.781048758045;5497.202905578709;5961.046232602806;5962.821115366502;5970.49250813677;5975.551833612496;5978.312494063195;5974.61165024272;6011.609245399079;6014.789482368096;6021.42796434338;6109.327973922865;5643;5651.26533550644;5668.716564171775;5671.744771485293;5700.030994730864;5948.0483122701025;6194.566380353332;6204.503639708812;6205.22434695663;6205.751769929152;5837.357890632027;5849.707514624251;5878.470937615635;6005.369436209223;6011.909821352683;6030.819075427744;6036.185115009505;6037.154798328237;6056.485198758155;6056.66688332146;5837.445442682938;5992.790252341147;6014.578979471395;6018.15745795589;6036.185115009417;5820.88358232833;5896.469317761336;5906.409359064145;5951.494122999594;5981.342052374235;4578.839740609063;4579.564941330568;4582.495925448198;4584.1977653910435;4607.654425918069;4003.179348233605;4077.8164982575854;4100.876973690794;4103.158852435244;4114.547399786021;5611.9438805612235;5616.157576363496;5654.773809047592;5778.682172480554;5808.82573522795;6099.146119543268;6139.048447490646;6139.048447490646;6140.846478838065;6145;5496.80761173357;5742.08126699726;5751.051076572402;5752.568557358215;5763.769449222068;5188.0661480933795;5216.686998780086;5219.295395121686;5275.208718692227;5286.682799032071;6167.753289868355;6169.321374648785;6171.537134714914;6182;6189.721462534197;5826.970865145647;6054.697265136638;6056.788012419544;6070.453659170696;6076.392360763888;6191.752329906753;6242.406971337716;6242.65665388408;6263.498920086291;6274.341194174546;5944.078667806497;5998.760049597967;5999.190109335358;6018.789342372996;6043.425874541911;6100.54245931548;6102.847428814315;6105.297890742621;6112.908306375497;6123.203983482119;5768.451997060272;5870.0607902735355;5897.882042359043;5918.585699001201;6000.789972350947;5929.584929054925;5931.051031835058;5937.079752638348;5937.732802024006;5938.257717785277;5811.651300921958;5886.175935368966;5886.764529418775;5889.352171261244;5889.6171748837905;5695.117256825199;5876.588638795243;5921.911171332519;5930.822075337746;5986.401359864153;5855.092460668603;5855.853603659943;5860.09168578871;5864.266966629171;5897.144913987534 09:18:02 INFO - PROCESS | 31763 | |1;ext.html;603.2881200183784;630.7666163519497;669.2704951602753;670.627801570129;676.5805200775513;34854;44604.776976115114;45803.31295030577;45854.77072614632;45858.3121253181;46713.532864671346;46835.29747053797;47102.05795884089;47136.76431617837;47150.52849471504;8701.129887011311;9317.860232096524;9477.383970041925;9498.572564234626;10920.453977301142;11356.26184298022;11471.139664525152;11496.71258218547;11502.769944601103;11503.884961150407;11132.10943124552;11140.442977851115;11164.274322169074;11171.329720216772;11181.273217240894;11462.312261264351;11512.424378781028;11521.193516453852;11535.307881527135;11546.134039946995;11229.831552526719;11275.041621462186;11294;11309.151813614011;11315.490802913888;44089.77955110204;44378.33432498515;44482.55517444816;44495.110097798184;44499;3750.081230098643;3919.7060220483577;3981.900452488682;4184.979075104636;4532.4334458192725;34607.4808877867;34793.30413391718;34922.30155396878;35301.29397412037;37185.44221836675;4514.187446259672;4576.679632425731;4577.702449340815;4604.309353596954;4729.290606409032;379309;382665;382750;384265;384485;62098.37901620926;62492;62590.0611490828;62613.68693156505;62617;40621.39067913983;41365.1726965459;41383.379249311285;64360.678196608715;64486;19620.3132890351;19785.802141978395;19802;19804.801951980586;19805.10877010538;14046.578602641937;14979.550613481613;15391.461298854487;15395.384184632714;15440.382384704712;20782;20804.271850485162;20895;20900.26849060306;21092.47268818261;7168.605726685082;7233.963830180815;7377.114746230487;7395.81510462232;7530.924690753022;983.6065573770549;1025.9794804104026;1042.968710938673;1068.9358638481715;1105.8562386889653;35393.46909796355;35405.54176303369;35415.22923853797;35643.28713425717;36017;1164.0861923390098;1171.2562522798085;1195.121585634558;1254.3414707278753;1261.4071386448354;17913.820861791217;18627.441176764718;18718.438446846616;18726.625467490572;18800.247990080243;533797;539533;542147;544735;544769;63391.683041584496;63587.682061589396;63817.36182638207;63845.68077159584;64090;1295;1391.547746982235;1405.0704281573035;1423.1532238318184;1425.180521200314;34423.139421514665;36111.523795374844;36971.630283697894;37109;37142.8142859284;53786.46213537815;54223.45776542184;54231;54279.18581221206;54324.45675543352;454142;458433;460603;461939;777376;30271;30469.847650761603;30479.542806857473;32396.35207295888;32727.18182045468;53595.732021339645;53900.73049634727;53946;54318.185227220834;54476.45523544714;44332.77833610812;44354.966677245015;45885.77057114826;46181;47841.76079119582;44522.10955780931;45195.32207016832;45846.31230531478;46216.537834621224;47613.76193119151;44681.776591118134;45064;45786;46095;46247.53752462432;36851.07872303214;39217.23522441554;40079.1984160321;40269.39595906005;40488.79755601203;29518.852405738693;30101.698983010767;30849.228769280948;31052.378952421277;31323.68676313208 09:18:02 INFO - PROCESS | 31763 | |2;jquery.html;406.7417190084296;409.1693861461235;427.08390502372407;431.39604553624923;433.557771073505;19314.51713707158;19974.70037949432;20596.48508787283;20762.584748305024;21149.682754758673;19634.60730785387;19934.302299419523;20062.297819576317;20489.590208195827;20746.792532074676;5897.085951677486;6108.205933228674;6113.663748493831;6124.96937515314;6128.601640893327;5833.32916714579;5931.406859314077;5950.196723442345;5980.970095149518;6018.037114061751;5939.316978547479;5943.940560594404;5946.524278057745;5948.107783832438;5949.4348036936635;5853.678047707374;5908.793192238272;5931.051031834907;5935.465808077251;5935.73289201987;5270.156774916033;5333.413324534298;5389.487998640123;5390.973045134789;5391.299131112969;24697.38256543583;25051.37371565707;25110.748892511023;25131.37171570707;25293.873530632412;2547.286759707285;3060.5103183490755;3149.937001259962;3205.759568032407;3275.082976766509;15525.767113493306;15537.766933495894;15745.606359840982;15877.285522151531;16399.754003689955;2000.7999200079876;2096.1720120552295;2100.222917520512;2100.6428907085833;2143.753468351144;67488.6625566874;70771;74376;85861.14138858594;86644.23456709879;11070.833937490945;11286.774264514828;11325.093992480583;11527.76944461106;11769.17615766888;16260.75608865868;16346.34614615426;16380.344786208418;16389.754153687703;16390.754138687927;8978.473659477904;9021.639134434681;9023.503707296162;9090.13643703847;9284.582193801296;9098.181163695299;9158.67944621935;9158.725238242863;9366.718998430058;9379.155875971059;6928.44572434204;7070.080889484334;7086.291370862872;7102.857942841218;7169.7849064528145;1973.842092632586;2052.8665636733563;2116.248731700255;2176.4994051367958;2179.814715749179;967.1585720423277;1033.390299723167;1041.2971244409994;1053.4679986606714;1129.6444266879778;20574.897125514275;21683.783162168176;21727.891360543414;21777.34667959964;22775.316740497812;1039.2828948025838;1062.585591619269;1065.5258410007627;1065.5790962569743;1068.1401471815263;11506.94246528762;11724.179307448567;11885.88114118865;11915.344656044;11936.522539098512;426231;551719;558244;571515;572255;96071.51964240134;98916;100255.49872250739;102382;103991;1039.9792004159874;1050.3907733514543;1110.6445937299986;1132.3319241647469;1138.362516990488;13878;18620.44138675842;18791.718124227875;18882.71675924835;19047.904760476107;40317.1936561273;40583.18833623252;41058.79470602628;42248.78875605603;43508;53166;60597.69701151466;61007;62075.6896215516;62691.373086268555;8468.745937621881;8821.603027863634;8910.732678019669;8950.776230594287;8973.192412682889;12574.497020119094;13293.534726284533;13339.266340351374;13465.326733663082;13490.662733431744;11412.600558980395;11600.18798684084;11612.54843144607;11623.186376953532;11968.820467692818;11238.269512481662;11330.036946859609;11333.546658133582;11653.708657283636;11916.10629202796;11803.822942655695;12187;12381.195222310522;12415.62753117408;12451.066170037102;8565.218904953017;8936.642534298555;8966.506842123747;9155.221806146552;9195.126462986005;10960.56157753681;11857.288562686266;11913.22564033335;12040.698982525508;12223.144379893678 09:18:02 INFO - PROCESS | 31763 | |3;mootools.html;1548.1794648836114;1568.5686436230035;1726.6633006563718;1781.6436712657471;1828.332658579618;8814.33892458065;9481.668141615037;9523.761905952355;9534.523273836307;9535.57089930953;8279.089300176976;8884.244839188661;9172.403793753418;9206.907930920688;9323.347365684405;2488.3779055236237;2527.6082207257905;2527.949441011179;2530.3421110511217;2532.227670560478;2424.4544977380124;2453.264020793764;2453.6196889482158;2454.0919859651954;2455.9754402456015;2451.2278632230878;2460.90156393744;2499.4126380300613;2503.198976327567;2504.26124293333;2392.940176495593;2401.9759802401927;2410.6866107406013;2445.461998360358;2450.9264722058365;2372.2290255666976;2399.004413168533;2404.603240465321;2406.3984003999;2435.0990133650484;9134.588943497558;9183.540822958825;9217;9217.815643687156;9226.53867306632;5591.636543624692;5706.686132262724;5708.20085188074;5714.628549144334;5719.771209151629;3644.1436262478296;3716.2753263113705;3739.981300093509;3750.7186960977765;3779.225258821935;1224.3449754381452;1247.3077442019676;1276.610633756704;1280.8911242544395;1282.9743405131937;12889.419976101099;12894.290814005226;12915.547955821501;12926.806097908539;12935.417906194243;4925.433575138905;4955.058538877602;5028.698278103326;5055.595552355876;5065.670731402447;11006.889931100746;11021.338719676685;11027.503762330714;11041.227114102096;11049;5727.083666613322;5731.283589551306;5746.454086861741;5748.655080695172;5753.769849205984;4560.817567297271;4728.858134255978;5280.683159010472;5284.947150528523;5323.547498462596;4354.825806967686;4371.475422949203;4421.53573874745;4453.42105526286;4466.798994045275;1340.1690951610065;1340.718449125675;1350.8784209421003;1353.2557093598678;1373.6977864869712;982.2240430060173;989.4607438945648;1018.8471729240525;1064.1327318235612;1085.6905781852133;6657.933420665731;6672.099266599044;6696.8995465068065;6741.0899528564;6844.794656160323;485.752266344167;498.82541110611;502.3519659639098;503.2752835916269;507.4114027727818;4375.321825117112;4414.735115893057;4419.889502762457;4514.232580461329;4613.792379342937;10574.629887953884;10959.726006849893;11004.39475828821;11014.118870490342;11057.50241239146;10750.516226769814;11054.281471704313;11350.886491135148;11358.829617555743;11807.88192118085;8359.95820020896;8532.53071081084;8541.231289184003;8560.91439085601;8579;6487.870242595216;6705.731770729311;6787.53929353574;6856.6228857413325;6880.552764070319;10844.511996960313;10887.51006204707;11016.834747478633;11019.283746556448;11103.278286911325;10904.781904362027;11074.889251107708;11258.774824503627;11376.94311528437;11377.488013039267;7751.379889608705;8202.876956845772;8417.494950303002;8520.74437766868;8742;9268.907310926805;9335.346525743404;9336.06639336061;9337.626494940127;9361.953190234006;7997.680092796223;7998.800029999296;8044.597770111588;8128.349732021542;8208.958955205186;7883.093444253755;7903.249191326815;7919.485233459806;7963.40274479428;8080.2727754502375;7689;7739.806504837424;7748.845023099394;7807.102183248999;7818.374530037697;5478.287822583039;5494.120940649477;5618.494335509824;5648.689322087324;5667.8866422672145;5448.782048718165;5545.528630066328;5546.805861794818;5573.470520300565;5663.830085097454 09:18:02 INFO - PROCESS | 31763 | |4;prototype.html;357.4566658678808;358.2816453011712;359.3621322153177;361.40187988878415;363.3477907156652;5505.724713764311;5609.551235901128;5653.5194508466775;5661.5187709044785;5671.376148623648;5498.1752737089455;5500.559955203584;5506.559475241981;5585.469380408855;5589.413111623282;4137.2552940470705;4255.212785634652;4330.155619654169;4335.804888779997;4376.606105450509;4355.390245365651;4356.368326592642;4379.474463064421;4393.319035549495;4399.362092496586;4340.348947657861;4346.565343465644;4346.913061738764;4368.912621747563;4382.189294980423;4154.646855017327;4324.762138082404;4339.696221264513;4346.9347959780625;4371.322445021026;3831.942520862189;3853.8843834685003;3859.6912247020173;3859.9614003859883;3877.5346958365176;5401.864953376158;5418.834950485664;5423.620346575742;5454.127339625641;5509.209506910271;4898.697612122332;4952.158133117378;4980.701157930535;5012.27322038306;5040.798368065273;5751.166080918243;5755.165501002373;5759.769609215627;5759.798407055733;5780.942190578125;4250.936235956463;4257.701960862741;4263.914721705549;4265.957340426587;4268.844233661981;5691.630044047124;5820.941790582082;5885.70571471429;6110.572259941806;6113.633182009094;4162.2091802558025;4195.517515485697;4209.6000879916355;4354.542773008789;4364.585364390377;4425.694420146065;4426.690131690815;4430.158269928703;4443.644508439317;4450.910981780411;4918.55732984026;5004.899902001939;5054.469280725544;5062.519060689228;5062.873428164252;5770.307563092456;5775.018246898037;5784.161296611967;5790.536757059425;5792.420757924174;1495.6260934766308;1505.6838064006681;1507.736146174437;1518.764591488321;1520.6882589069214;929.5956259027345;969.9757506062264;975.8536219567121;981.8478135888948;999.240577161348;4887.828925987574;4991.800327986913;4995.17579599362;5022.472640372781;5025.346704928336;5272.868178295574;5298.761555730001;5301.390340110936;5305.549028332635;5313.07021271276;4852.393450818648;4924.433690125681;4963.45402005779;5058.772355244023;5096.923546146811;2808.340040090577;2814.690384057752;2817.8027538072547;2820.1680504251294;2829;5284.9735751320995;5397.568194544506;5434.565234781208;5436.293281873332;5448.520922980035;5028.5977121830165;5263.6578622389425;5271.736413179402;5286.286351342596;5308.309919710413;5319.787208511693;5320.929488940963;5344.946550534522;5368.550491367387;5442.210879422461;5217.50433708797;5229.424763276113;5233.555147812326;5270.525652691123;5278.287431196739;5491.972540137273;5884.705764711662;5940.28716553999;5946.137810017437;5947.91078133837;5849.502792262705;5988.251468566429;6086.969565152323;6101.786437474667;6104.382338680164;4402.977985110054;4466;4475.910481790281;4484.529124442017;4491.416115905042;5771.8557036074435;6000.669963152069;6008.158857760004;6028.758849645965;6033.698315084141;5799.623024503394;5802.767889284551;5810;5819.476247137778;5850.970745146417;5798.652080875161;5798.98517759382;5802.071668533184;5803.361630220588;5818.359980402237;5543.944560554342;5764.567657425626;5765.94234057654;5798.710064496842;5847.1229315603;5842.707864606838;5848.970755146197;5910.113482977587;5920.822375328747;5922.348541660328;5563.638363506359;5709.22925405065;5773.6824474654295;5778.87311974161;5784.363719990882 09:18:02 INFO - PROCESS | 31763 | |5;yui.html;844.6705784743947;853.3685073045945;885.3359980014989;888.0852721696655;919.6827094652344;16049.67900641988;16370.754438683429;17364.13179341031;17379.652406951853;17403.303867845272;16069.598260043505;16140.83859161408;16161.434349797759;16271.755923661154;16273.349066037345;742.1242933338658;862.1206369503103;864.360373323739;882.3955590420551;888.2804928008302;878.6046279174365;887.5917078144029;888.2405543260517;888.7200531832484;889.8442772514817;915.0940568836837;919.9678011269607;922.598669578732;923.6166990698852;924.177482040984;916.5050872528826;917.1378903830378;917.8944421391559;918.237862574062;918.8208299381623;674.2650510943096;680.6766785776766;680.8706345794283;681.3867519232681;681.4854784637566;15875.60310992223;15923.363065477368;16030.11834349122;16106.919465402598;16124.274407651685;15699.293531791096;16010.51968440937;16123.596910077342;16426.83573164265;16493.83506164935;13176.209427434289;13246.933765331112;13468.865311346859;13633.182009079486;13711.108777929403;4048.068944142834;4098.364753463218;4108.815103320328;4152.771597562133;4241.660667146621;85361.57319213427;86844.5657771707;87111;87130;87530;10965.451727413598;11188.66434006981;11564.826527602092;12380.380980951097;12591.559295424799;22032.88983555072;22137.557248855253;22169.667454988186;22209.44476388103;22267.331980040624;8547.829043419097;8674.436161649473;8765.912340876637;8860.8670869937;8875.822483550293;5003.874903127451;5071.949280507221;5080.314157588752;5176.275321454999;5211.270422140828;3955.7033222508426;4060.5939406059156;4105.774182419996;4111.342185250345;4245;728.9416846652362;744.0550500863873;745.6942653512142;761;778.2023425988294;759.7758661194961;766.5860435364953;783.7922950418143;806.5443024691031;807.7051876065194;9586.520673966274;9732.41605503658;9796.216302695768;9834.164096051773;9946.458298963673;697.0659316515893;730.8319086610003;750.6847124207845;778.5212094561804;823.4812068396872;4621.630269578425;4800.255960326085;4814.903701925942;4849.75751212438;4870.878228044327;68971;68997.31002690009;69112.6544367285;70046;100468.49765751124;98270.50864745774;99982.5000874991;100818;101139.49430252949;101659;58307.70846145827;87938;88748.5562572183;92200;92275;9451.149396554343;9668.226541876493;10031.74920626961;10102.39385636864;10116.494175291353;54080;54458.72770636121;54673.72663136659;55790;55819.72090139523;68693;68781;69401.2649683762;77188.61405692935;79119.20880791348;12917.418716157608;12986.090973632134;13036.674083147996;13054.608361748782;13113.606591802261;14036.438542458596;14046.859531404556;14235.928820355832;14268.785968210277;14370.568882933529;12740.490380384681;13001.869981300066;13026.47894084226;13026.804597931228;13058.34708264602;13374.732505350034;13581.14722982572;13585.52450664222;13615.25116118623;13710.931445342709;13019.47922083144;13079.215247085205;13568.660783480493;13685.726285474035;13955.58133256004;10445.791084178121;10709.571617135227;10831;11421.428928553705;11505.42472876336;10150.289479736348;10591.785146922228;10759.46202689878;10895.727606809576;11373.374464404205 09:18:02 INFO - PROCESS | 31763 | __end_tp_report 09:18:02 INFO - PROCESS | 31763 | __start_cc_report 09:18:02 INFO - PROCESS | 31763 | _x_x_mozilla_cycle_collect,1000 09:18:02 INFO - PROCESS | 31763 | __end_cc_report 09:18:02 INFO - PROCESS | 31763 | __startTimestamp1474733882208__endTimestamp 09:18:02 INFO - PROCESS | 31763 | 09:18:02 INFO - PROCESS | 31763 | ------- Summary: start ------- 09:18:02 INFO - PROCESS | 31763 | Number of tests: 6 09:18:02 INFO - PROCESS | 31763 | 09:18:02 INFO - PROCESS | 31763 | [#0] dojo.html Cycles:165 Average:5595.46 Median:5849.71 stddev:646.10 (11.0%) stddev-sans-first:619.89 09:18:02 INFO - PROCESS | 31763 | Values: 3188.9 3217.7 3228.1 3248.6 3269.3 5553.8 5658.8 5659.5 5659.9 5668.0 5626.9 5698.7 5712.1 5712.1 5728.9 4952.7 5042.3 5110.3 5112.6 5160.5 5092.9 5161.2 5162.7 5174.4 5175.6 5031.9 5085.4 5121.9 5132.5 5137.8 5181.1 5202.0 5206.3 5216.6 5237.0 4837.5 4912.3 4969.8 5100.3 5107.5 5450.8 5451.8 5468.3 5473.8 5497.2 5961.0 5962.8 5970.5 5975.6 5978.3 5974.6 6011.6 6014.8 6021.4 6109.3 5643.0 5651.3 5668.7 5671.7 5700.0 5948.0 6194.6 6204.5 6205.2 6205.8 5837.4 5849.7 5878.5 6005.4 6011.9 6030.8 6036.2 6037.2 6056.5 6056.7 5837.4 5992.8 6014.6 6018.2 6036.2 5820.9 5896.5 5906.4 5951.5 5981.3 4578.8 4579.6 4582.5 4584.2 4607.7 4003.2 4077.8 4100.9 4103.2 4114.5 5611.9 5616.2 5654.8 5778.7 5808.8 6099.1 6139.0 6139.0 6140.8 6145.0 5496.8 5742.1 5751.1 5752.6 5763.8 5188.1 5216.7 5219.3 5275.2 5286.7 6167.8 6169.3 6171.5 6182.0 6189.7 5827.0 6054.7 6056.8 6070.5 6076.4 6191.8 6242.4 6242.7 6263.5 6274.3 5944.1 5998.8 5999.2 6018.8 6043.4 6100.5 6102.8 6105.3 6112.9 6123.2 5768.5 5870.1 5897.9 5918.6 6000.8 5929.6 5931.1 5937.1 5937.7 5938.3 5811.7 5886.2 5886.8 5889.4 5889.6 5695.1 5876.6 5921.9 5930.8 5986.4 5855.1 5855.9 5860.1 5864.3 5897.1 09:18:02 INFO - PROCESS | 31763 | 09:18:02 INFO - PROCESS | 31763 | [#1] ext.html Cycles:180 Average:65586.45 Median:22030.55 stddev:130727.50 (593.4%) stddev-sans-first:131003.18 09:18:02 INFO - PROCESS | 31763 | Values: 603.3 630.8 669.3 670.6 676.6 34854.0 44604.8 45803.3 45854.8 45858.3 46713.5 46835.3 47102.1 47136.8 47150.5 8701.1 9317.9 9477.4 9498.6 10920.5 11356.3 11471.1 11496.7 11502.8 11503.9 11132.1 11140.4 11164.3 11171.3 11181.3 11462.3 11512.4 11521.2 11535.3 11546.1 11229.8 11275.0 11294.0 11309.2 11315.5 44089.8 44378.3 44482.6 44495.1 44499.0 3750.1 3919.7 3981.9 4185.0 4532.4 34607.5 34793.3 34922.3 35301.3 37185.4 4514.2 4576.7 4577.7 4604.3 4729.3 379309.0 382665.0 382750.0 384265.0 384485.0 62098.4 62492.0 62590.1 62613.7 62617.0 40621.4 41365.2 41383.4 64360.7 64486.0 19620.3 19785.8 19802.0 19804.8 19805.1 14046.6 14979.6 15391.5 15395.4 15440.4 20782.0 20804.3 20895.0 20900.3 21092.5 7168.6 7234.0 7377.1 7395.8 7530.9 983.6 1026.0 1043.0 1068.9 1105.9 35393.5 35405.5 35415.2 35643.3 36017.0 1164.1 1171.3 1195.1 1254.3 1261.4 17913.8 18627.4 18718.4 18726.6 18800.2 533797.0 539533.0 542147.0 544735.0 544769.0 63391.7 63587.7 63817.4 63845.7 64090.0 1295.0 1391.5 1405.1 1423.2 1425.2 34423.1 36111.5 36971.6 37109.0 37142.8 53786.5 54223.5 54231.0 54279.2 54324.5 454142.0 458433.0 460603.0 461939.0 777376.0 30271.0 30469.8 30479.5 32396.4 32727.2 53595.7 53900.7 53946.0 54318.2 54476.5 44332.8 44355.0 45885.8 46181.0 47841.8 44522.1 45195.3 45846.3 46216.5 47613.8 44681.8 45064.0 45786.0 46095.0 46247.5 36851.1 39217.2 40079.2 40269.4 40488.8 29518.9 30101.7 30849.2 31052.4 31323.7 09:18:02 INFO - PROCESS | 31763 | 09:18:02 INFO - PROCESS | 31763 | [#2] jquery.html Cycles:180 Average:31082.63 Median:12477.57 stddev:88644.11 (710.4%) stddev-sans-first:88862.86 09:18:02 INFO - PROCESS | 31763 | Values: 406.7 409.2 427.1 431.4 433.6 19314.5 19974.7 20596.5 20762.6 21149.7 19634.6 19934.3 20062.3 20489.6 20746.8 5897.1 6108.2 6113.7 6125.0 6128.6 5833.3 5931.4 5950.2 5981.0 6018.0 5939.3 5943.9 5946.5 5948.1 5949.4 5853.7 5908.8 5931.1 5935.5 5935.7 5270.2 5333.4 5389.5 5391.0 5391.3 24697.4 25051.4 25110.7 25131.4 25293.9 2547.3 3060.5 3149.9 3205.8 3275.1 15525.8 15537.8 15745.6 15877.3 16399.8 2000.8 2096.2 2100.2 2100.6 2143.8 67488.7 70771.0 74376.0 85861.1 86644.2 11070.8 11286.8 11325.1 11527.8 11769.2 16260.8 16346.3 16380.3 16389.8 16390.8 8978.5 9021.6 9023.5 9090.1 9284.6 9098.2 9158.7 9158.7 9366.7 9379.2 6928.4 7070.1 7086.3 7102.9 7169.8 1973.8 2052.9 2116.2 2176.5 2179.8 967.2 1033.4 1041.3 1053.5 1129.6 20574.9 21683.8 21727.9 21777.3 22775.3 1039.3 1062.6 1065.5 1065.6 1068.1 11506.9 11724.2 11885.9 11915.3 11936.5 426231.0 551719.0 558244.0 571515.0 572255.0 96071.5 98916.0 100255.5 102382.0 103991.0 1040.0 1050.4 1110.6 1132.3 1138.4 13878.0 18620.4 18791.7 18882.7 19047.9 40317.2 40583.2 41058.8 42248.8 43508.0 53166.0 60597.7 61007.0 62075.7 62691.4 8468.7 8821.6 8910.7 8950.8 8973.2 12574.5 13293.5 13339.3 13465.3 13490.7 11412.6 11600.2 11612.5 11623.2 11968.8 11238.3 11330.0 11333.5 11653.7 11916.1 11803.8 12187.0 12381.2 12415.6 12451.1 8565.2 8936.6 8966.5 9155.2 9195.1 10960.6 11857.3 11913.2 12040.7 12223.1 09:18:02 INFO - PROCESS | 31763 | 09:18:02 INFO - PROCESS | 31763 | [#3] mootools.html Cycles:180 Average:6183.21 Median:2705.59 stddev:3484.93 (128.8%) stddev-sans-first:3477.30 09:18:02 INFO - PROCESS | 31763 | Values: 1548.2 1568.6 1726.7 1781.6 1828.3 8814.3 9481.7 9523.8 9534.5 9535.6 8279.1 8884.2 9172.4 9206.9 9323.3 2488.4 2527.6 2527.9 2530.3 2532.2 2424.5 2453.3 2453.6 2454.1 2456.0 2451.2 2460.9 2499.4 2503.2 2504.3 2392.9 2402.0 2410.7 2445.5 2450.9 2372.2 2399.0 2404.6 2406.4 2435.1 9134.6 9183.5 9217.0 9217.8 9226.5 5591.6 5706.7 5708.2 5714.6 5719.8 3644.1 3716.3 3740.0 3750.7 3779.2 1224.3 1247.3 1276.6 1280.9 1283.0 12889.4 12894.3 12915.5 12926.8 12935.4 4925.4 4955.1 5028.7 5055.6 5065.7 11006.9 11021.3 11027.5 11041.2 11049.0 5727.1 5731.3 5746.5 5748.7 5753.8 4560.8 4728.9 5280.7 5284.9 5323.5 4354.8 4371.5 4421.5 4453.4 4466.8 1340.2 1340.7 1350.9 1353.3 1373.7 982.2 989.5 1018.8 1064.1 1085.7 6657.9 6672.1 6696.9 6741.1 6844.8 485.8 498.8 502.4 503.3 507.4 4375.3 4414.7 4419.9 4514.2 4613.8 10574.6 10959.7 11004.4 11014.1 11057.5 10750.5 11054.3 11350.9 11358.8 11807.9 8360.0 8532.5 8541.2 8560.9 8579.0 6487.9 6705.7 6787.5 6856.6 6880.6 10844.5 10887.5 11016.8 11019.3 11103.3 10904.8 11074.9 11258.8 11376.9 11377.5 7751.4 8202.9 8417.5 8520.7 8742.0 9268.9 9335.3 9336.1 9337.6 9362.0 7997.7 7998.8 8044.6 8128.3 8209.0 7883.1 7903.2 7919.5 7963.4 8080.3 7689.0 7739.8 7748.8 7807.1 7818.4 5478.3 5494.1 5618.5 5648.7 5667.9 5448.8 5545.5 5546.8 5573.5 5663.8 09:18:02 INFO - PROCESS | 31763 | 09:18:02 INFO - PROCESS | 31763 | [#4] prototype.html Cycles:180 Average:4767.73 Median:5292.52 stddev:1368.13 (25.9%) stddev-sans-first:1331.32 09:18:02 INFO - PROCESS | 31763 | Values: 357.5 358.3 359.4 361.4 363.3 5505.7 5609.6 5653.5 5661.5 5671.4 5498.2 5500.6 5506.6 5585.5 5589.4 4137.3 4255.2 4330.2 4335.8 4376.6 4355.4 4356.4 4379.5 4393.3 4399.4 4340.3 4346.6 4346.9 4368.9 4382.2 4154.6 4324.8 4339.7 4346.9 4371.3 3831.9 3853.9 3859.7 3860.0 3877.5 5401.9 5418.8 5423.6 5454.1 5509.2 4898.7 4952.2 4980.7 5012.3 5040.8 5751.2 5755.2 5759.8 5759.8 5780.9 4250.9 4257.7 4263.9 4266.0 4268.8 5691.6 5820.9 5885.7 6110.6 6113.6 4162.2 4195.5 4209.6 4354.5 4364.6 4425.7 4426.7 4430.2 4443.6 4450.9 4918.6 5004.9 5054.5 5062.5 5062.9 5770.3 5775.0 5784.2 5790.5 5792.4 1495.6 1505.7 1507.7 1518.8 1520.7 929.6 970.0 975.9 981.8 999.2 4887.8 4991.8 4995.2 5022.5 5025.3 5272.9 5298.8 5301.4 5305.5 5313.1 4852.4 4924.4 4963.5 5058.8 5096.9 2808.3 2814.7 2817.8 2820.2 2829.0 5285.0 5397.6 5434.6 5436.3 5448.5 5028.6 5263.7 5271.7 5286.3 5308.3 5319.8 5320.9 5344.9 5368.6 5442.2 5217.5 5229.4 5233.6 5270.5 5278.3 5492.0 5884.7 5940.3 5946.1 5947.9 5849.5 5988.3 6087.0 6101.8 6104.4 4403.0 4466.0 4475.9 4484.5 4491.4 5771.9 6000.7 6008.2 6028.8 6033.7 5799.6 5802.8 5810.0 5819.5 5851.0 5798.7 5799.0 5802.1 5803.4 5818.4 5543.9 5764.6 5765.9 5798.7 5847.1 5842.7 5849.0 5910.1 5920.8 5922.3 5563.6 5709.2 5773.7 5778.9 5784.4 09:18:02 INFO - PROCESS | 31763 | 09:18:02 INFO - PROCESS | 31763 | [#5] yui.html Cycles:180 Average:20269.33 Median:4860.32 stddev:27755.07 (571.1%) stddev-sans-first:27794.61 09:18:02 INFO - PROCESS | 31763 | Values: 844.7 853.4 885.3 888.1 919.7 16049.7 16370.8 17364.1 17379.7 17403.3 16069.6 16140.8 16161.4 16271.8 16273.3 742.1 862.1 864.4 882.4 888.3 878.6 887.6 888.2 888.7 889.8 915.1 920.0 922.6 923.6 924.2 916.5 917.1 917.9 918.2 918.8 674.3 680.7 680.9 681.4 681.5 15875.6 15923.4 16030.1 16106.9 16124.3 15699.3 16010.5 16123.6 16426.8 16493.8 13176.2 13246.9 13468.9 13633.2 13711.1 4048.1 4098.4 4108.8 4152.8 4241.7 85361.6 86844.6 87111.0 87130.0 87530.0 10965.5 11188.7 11564.8 12380.4 12591.6 22032.9 22137.6 22169.7 22209.4 22267.3 8547.8 8674.4 8765.9 8860.9 8875.8 5003.9 5071.9 5080.3 5176.3 5211.3 3955.7 4060.6 4105.8 4111.3 4245.0 728.9 744.1 745.7 761.0 778.2 759.8 766.6 783.8 806.5 807.7 9586.5 9732.4 9796.2 9834.2 9946.5 697.1 730.8 750.7 778.5 823.5 4621.6 4800.3 4814.9 4849.8 4870.9 68971.0 68997.3 69112.7 70046.0 100468.5 98270.5 99982.5 100818.0 101139.5 101659.0 58307.7 87938.0 88748.6 92200.0 92275.0 9451.1 9668.2 10031.7 10102.4 10116.5 54080.0 54458.7 54673.7 55790.0 55819.7 68693.0 68781.0 69401.3 77188.6 79119.2 12917.4 12986.1 13036.7 13054.6 13113.6 14036.4 14046.9 14235.9 14268.8 14370.6 12740.5 13001.9 13026.5 13026.8 13058.3 13374.7 13581.1 13585.5 13615.3 13710.9 13019.5 13079.2 13568.7 13685.7 13955.6 10445.8 10709.6 10831.0 11421.4 11505.4 10150.3 10591.8 10759.5 10895.7 11373.4 09:18:02 INFO - PROCESS | 31763 | -------- Summary: end -------- 09:18:02 INFO - PROCESS | 31763 | 09:18:02 INFO - TEST-INFO | 31763: exit 0 09:18:02 INFO - TEST-OK | dromaeo_css | took 1113725ms 09:18:02 INFO - TEST-START | kraken 09:18:02 INFO - operating with platform_type : linux_ 09:18:02 INFO - Initialising browser for kraken test... 09:18:02 INFO - TEST-INFO | started process 31965 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJmxwYS/profile http://localhost:46147/getInfo.html) 09:18:07 INFO - PROCESS | 31965 | __metrics Screen width/height:1600/1200 09:18:07 INFO - PROCESS | 31965 | colorDepth:24 09:18:07 INFO - PROCESS | 31965 | Browser inner width/height: 1024/697 09:18:07 INFO - PROCESS | 31965 | __metrics 09:18:08 INFO - PROCESS | 31965 | 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" 09:18:08 INFO - PROCESS | 31965 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:18:09 INFO - TEST-INFO | 31965: exit 0 09:18:09 INFO - Browser initialized. 09:18:09 INFO - Running cycle 1/1 for kraken test... 09:18:09 INFO - TEST-INFO | started process 32051 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpJmxwYS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:18:21 INFO - PROCESS | 32051 | RSS: Main: 143724544 09:18:21 INFO - PROCESS | 32051 | 09:18:23 INFO - PROCESS | 32051 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46147/tests/kraken/driver.html?testName=audio-beat-detection) 09:18:24 INFO - PROCESS | 32051 | RSS: Main: 198836224 09:18:24 INFO - PROCESS | 32051 | 09:18:27 INFO - PROCESS | 32051 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46147/tests/kraken/driver.html?testName=audio-dft) 09:18:27 INFO - PROCESS | 32051 | RSS: Main: 182796288 09:18:27 INFO - PROCESS | 32051 | 09:18:30 INFO - PROCESS | 32051 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46147/tests/kraken/driver.html?testName=audio-fft) 09:18:31 INFO - PROCESS | 32051 | RSS: Main: 273862656 09:18:31 INFO - PROCESS | 32051 | 09:18:33 INFO - PROCESS | 32051 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46147/tests/kraken/driver.html?testName=audio-oscillator) 09:18:33 INFO - PROCESS | 32051 | RSS: Main: 179998720 09:18:33 INFO - PROCESS | 32051 | 09:18:36 INFO - PROCESS | 32051 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46147/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:18:36 INFO - PROCESS | 32051 | RSS: Main: 179019776 09:18:36 INFO - PROCESS | 32051 | 09:18:39 INFO - PROCESS | 32051 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46147/tests/kraken/driver.html?testName=imaging-darkroom) 09:18:40 INFO - PROCESS | 32051 | RSS: Main: 215228416 09:18:40 INFO - PROCESS | 32051 | 09:18:43 INFO - PROCESS | 32051 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46147/tests/kraken/driver.html?testName=imaging-desaturate) 09:18:44 INFO - PROCESS | 32051 | RSS: Main: 214024192 09:18:44 INFO - PROCESS | 32051 | 09:18:46 INFO - PROCESS | 32051 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46147/tests/kraken/driver.html?testName=json-parse-financial) 09:18:47 INFO - PROCESS | 32051 | RSS: Main: 213819392 09:18:47 INFO - PROCESS | 32051 | 09:18:49 INFO - PROCESS | 32051 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46147/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:18:49 INFO - PROCESS | 32051 | RSS: Main: 175218688 09:18:49 INFO - PROCESS | 32051 | 09:18:51 INFO - PROCESS | 32051 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46147/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:18:51 INFO - PROCESS | 32051 | RSS: Main: 182874112 09:18:51 INFO - PROCESS | 32051 | 09:18:53 INFO - PROCESS | 32051 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46147/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:18:54 INFO - PROCESS | 32051 | RSS: Main: 185348096 09:18:54 INFO - PROCESS | 32051 | 09:18:56 INFO - PROCESS | 32051 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46147/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:18:57 INFO - PROCESS | 32051 | RSS: Main: 185741312 09:18:57 INFO - PROCESS | 32051 | 09:19:00 INFO - PROCESS | 32051 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46147/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:19:00 INFO - PROCESS | 32051 | RSS: Main: 182669312 09:19:00 INFO - PROCESS | 32051 | 09:19:02 INFO - PROCESS | 32051 | Cycle 1(1): loaded http://localhost:46147/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46147/tests/kraken/driver.html?testName=ai-astar) 09:19:02 INFO - PROCESS | 32051 | RSS: Main: 234717184 09:19:02 INFO - PROCESS | 32051 | 09:19:02 INFO - PROCESS | 32051 | __start_tp_report 09:19:02 INFO - PROCESS | 32051 | _x_x_mozilla_page_load 09:19:02 INFO - PROCESS | 32051 | _x_x_mozilla_page_load_details 09:19:02 INFO - PROCESS | 32051 | |i|pagename|runs| 09:19:02 INFO - PROCESS | 32051 | |0;ai-astar;112;111;120;110;110;110;110;110;110;109 09:19:02 INFO - PROCESS | 32051 | |1;audio-beat-detection;153;119;138;172;116;146;139;116;185;117 09:19:02 INFO - PROCESS | 32051 | |2;audio-dft;165;160;161;160;161;161;160;160;160;159 09:19:02 INFO - PROCESS | 32051 | |3;audio-fft;75;74;73;176;75;74;73;76;165;76 09:19:02 INFO - PROCESS | 32051 | |4;audio-oscillator;82;83;152;82;83;83;141;82;82;162 09:19:02 INFO - PROCESS | 32051 | |5;imaging-gaussian-blur;117;116;116;117;117;116;117;117;117;116 09:19:02 INFO - PROCESS | 32051 | |6;imaging-darkroom;145;146;145;145;145;146;146;145;146;145 09:19:02 INFO - PROCESS | 32051 | |7;imaging-desaturate;95;95;95;94;95;94;95;97;95;94 09:19:02 INFO - PROCESS | 32051 | |8;json-parse-financial;50;50;51;51;51;52;50;51;51;50 09:19:02 INFO - PROCESS | 32051 | |9;json-stringify-tinderbox;58;59;61;60;60;59;59;60;60;60 09:19:02 INFO - PROCESS | 32051 | |10;stanford-crypto-aes;71;73;72;72;76;76;72;71;70;71 09:19:02 INFO - PROCESS | 32051 | |11;stanford-crypto-ccm;120;124;117;118;120;116;119;121;148;114 09:19:02 INFO - PROCESS | 32051 | |12;stanford-crypto-pbkdf2;152;153;154;154;151;151;151;154;153;153 09:19:02 INFO - PROCESS | 32051 | |13;stanford-crypto-sha256-iterative;62;65;60;61;64;63;61;62;60;59 09:19:02 INFO - PROCESS | 32051 | __end_tp_report 09:19:02 INFO - PROCESS | 32051 | __start_cc_report 09:19:02 INFO - PROCESS | 32051 | _x_x_mozilla_cycle_collect,1679 09:19:02 INFO - PROCESS | 32051 | __end_cc_report 09:19:02 INFO - PROCESS | 32051 | __startTimestamp1474733942734__endTimestamp 09:19:02 INFO - PROCESS | 32051 | 09:19:02 INFO - PROCESS | 32051 | ------- Summary: start ------- 09:19:02 INFO - PROCESS | 32051 | Number of tests: 14 09:19:02 INFO - PROCESS | 32051 | 09:19:02 INFO - PROCESS | 32051 | [#0] ai-astar Cycles:10 Average:111.20 Median:110.00 stddev:3.19 (2.9%) stddev-sans-first:3.37 09:19:02 INFO - PROCESS | 32051 | Values: 112.0 111.0 120.0 110.0 110.0 110.0 110.0 110.0 110.0 109.0 09:19:02 INFO - PROCESS | 32051 | 09:19:02 INFO - PROCESS | 32051 | [#1] audio-beat-detection Cycles:10 Average:140.10 Median:142.50 stddev:24.45 (17.2%) stddev-sans-first:25.49 09:19:02 INFO - PROCESS | 32051 | Values: 153.0 119.0 138.0 172.0 116.0 146.0 139.0 116.0 185.0 117.0 09:19:02 INFO - PROCESS | 32051 | 09:19:02 INFO - PROCESS | 32051 | [#2] audio-dft Cycles:10 Average:160.70 Median:160.50 stddev:1.64 (1.0%) stddev-sans-first:0.67 09:19:02 INFO - PROCESS | 32051 | Values: 165.0 160.0 161.0 160.0 161.0 161.0 160.0 160.0 160.0 159.0 09:19:02 INFO - PROCESS | 32051 | 09:19:02 INFO - PROCESS | 32051 | [#3] audio-fft Cycles:10 Average:93.70 Median:74.50 stddev:40.57 (54.5%) stddev-sans-first:42.47 09:19:02 INFO - PROCESS | 32051 | Values: 75.0 74.0 73.0 176.0 75.0 74.0 73.0 76.0 165.0 76.0 09:19:02 INFO - PROCESS | 32051 | 09:19:02 INFO - PROCESS | 32051 | [#4] audio-oscillator Cycles:10 Average:103.20 Median:82.00 stddev:33.81 (41.2%) stddev-sans-first:34.98 09:19:02 INFO - PROCESS | 32051 | Values: 82.0 83.0 152.0 82.0 83.0 83.0 141.0 82.0 82.0 162.0 09:19:02 INFO - PROCESS | 32051 | 09:19:02 INFO - PROCESS | 32051 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.53 09:19:02 INFO - PROCESS | 32051 | Values: 117.0 116.0 116.0 117.0 117.0 116.0 117.0 117.0 117.0 116.0 09:19:02 INFO - PROCESS | 32051 | 09:19:02 INFO - PROCESS | 32051 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 09:19:02 INFO - PROCESS | 32051 | Values: 145.0 146.0 145.0 145.0 145.0 146.0 146.0 145.0 146.0 145.0 09:19:02 INFO - PROCESS | 32051 | 09:19:02 INFO - PROCESS | 32051 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.88 (0.9%) stddev-sans-first:0.93 09:19:02 INFO - PROCESS | 32051 | Values: 95.0 95.0 95.0 94.0 95.0 94.0 95.0 97.0 95.0 94.0 09:19:02 INFO - PROCESS | 32051 | 09:19:02 INFO - PROCESS | 32051 | [#8] json-parse-financial Cycles:10 Average:50.70 Median:51.00 stddev:0.67 (1.3%) stddev-sans-first:0.67 09:19:02 INFO - PROCESS | 32051 | Values: 50.0 50.0 51.0 51.0 51.0 52.0 50.0 51.0 51.0 50.0 09:19:02 INFO - PROCESS | 32051 | 09:19:02 INFO - PROCESS | 32051 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:60.00 stddev:0.84 (1.4%) stddev-sans-first:0.67 09:19:02 INFO - PROCESS | 32051 | Values: 58.0 59.0 61.0 60.0 60.0 59.0 59.0 60.0 60.0 60.0 09:19:02 INFO - PROCESS | 32051 | 09:19:02 INFO - PROCESS | 32051 | [#10] stanford-crypto-aes Cycles:10 Average:72.40 Median:72.00 stddev:2.07 (2.9%) stddev-sans-first:2.13 09:19:02 INFO - PROCESS | 32051 | Values: 71.0 73.0 72.0 72.0 76.0 76.0 72.0 71.0 70.0 71.0 09:19:02 INFO - PROCESS | 32051 | 09:19:02 INFO - PROCESS | 32051 | [#11] stanford-crypto-ccm Cycles:10 Average:121.70 Median:120.00 stddev:9.65 (8.0%) stddev-sans-first:10.22 09:19:02 INFO - PROCESS | 32051 | Values: 120.0 124.0 117.0 118.0 120.0 116.0 119.0 121.0 148.0 114.0 09:19:02 INFO - PROCESS | 32051 | 09:19:02 INFO - PROCESS | 32051 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:152.60 Median:153.00 stddev:1.26 (0.8%) stddev-sans-first:1.32 09:19:02 INFO - PROCESS | 32051 | Values: 152.0 153.0 154.0 154.0 151.0 151.0 151.0 154.0 153.0 153.0 09:19:02 INFO - PROCESS | 32051 | 09:19:02 INFO - PROCESS | 32051 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.70 Median:62.00 stddev:1.89 (3.0%) stddev-sans-first:2.00 09:19:02 INFO - PROCESS | 32051 | Values: 62.0 65.0 60.0 61.0 64.0 63.0 61.0 62.0 60.0 59.0 09:19:02 INFO - PROCESS | 32051 | -------- Summary: end -------- 09:19:02 INFO - PROCESS | 32051 | 09:19:03 INFO - TEST-INFO | 32051: exit 0 09:19:03 INFO - TEST-OK | kraken | took 60802ms 09:19:03 INFO - SUITE-END | took 1174s 09:19:03 INFO - Completed test suite (00:19:34) 09:19:03 INFO - javascript benchmark 09:19:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3188.888388906388, 3217.7425805935527, 3228.1445416964525, 3248.626407963085, 3269.2971011232594, 5553.750081246343, 5658.773649054038, 5659.462351076646, 5659.943400565993, 5668.0, 5626.943730562693, 5698.658080515172, 5712.086066229404, 5712.11462223355, 5728.914066288999, 4952.727599981999, 5042.319286896259, 5110.28456016158, 5112.642115051957, 5160.483951604829, 5092.872678183038, 5161.174212126042, 5162.741862906859, 5174.404943431516, 5175.58595312376, 5031.874203144914, 5085.389753229618, 5121.897562048757, 5132.512411320938, 5137.820176293831, 5181.119209734316, 5202.049685412209, 5206.349206349207, 5216.58267338612, 5237.0, 4837.4920633333695, 4912.287718280865, 4969.826056088055, 5100.285959965608, 5107.4892510749, 5450.836474905759, 5451.754671039773, 5468.2891224141, 5473.781048758045, 5497.202905578709, 5961.046232602806, 5962.821115366502, 5970.49250813677, 5975.551833612496, 5978.312494063195, 5974.61165024272, 6011.609245399079, 6014.789482368096, 6021.42796434338, 6109.327973922865, 5643.0, 5651.26533550644, 5668.716564171775, 5671.744771485293, 5700.030994730864, 5948.0483122701025, 6194.566380353332, 6204.503639708812, 6205.22434695663, 6205.751769929152, 5837.357890632027, 5849.707514624251, 5878.470937615635, 6005.369436209223, 6011.909821352683, 6030.819075427744, 6036.185115009505, 6037.154798328237, 6056.485198758155, 6056.66688332146, 5837.445442682938, 5992.790252341147, 6014.578979471395, 6018.15745795589, 6036.185115009417, 5820.88358232833, 5896.469317761336, 5906.409359064145, 5951.494122999594, 5981.342052374235, 4578.839740609063, 4579.564941330568, 4582.495925448198, 4584.1977653910435, 4607.654425918069, 4003.179348233605, 4077.8164982575854, 4100.876973690794, 4103.158852435244, 4114.547399786021, 5611.9438805612235, 5616.157576363496, 5654.773809047592, 5778.682172480554, 5808.82573522795, 6099.146119543268, 6139.048447490646, 6139.048447490646, 6140.846478838065, 6145.0, 5496.80761173357, 5742.08126699726, 5751.051076572402, 5752.568557358215, 5763.769449222068, 5188.0661480933795, 5216.686998780086, 5219.295395121686, 5275.208718692227, 5286.682799032071, 6167.753289868355, 6169.321374648785, 6171.537134714914, 6182.0, 6189.721462534197, 5826.970865145647, 6054.697265136638, 6056.788012419544, 6070.453659170696, 6076.392360763888, 6191.752329906753, 6242.406971337716, 6242.65665388408, 6263.498920086291, 6274.341194174546, 5944.078667806497, 5998.760049597967, 5999.190109335358, 6018.789342372996, 6043.425874541911, 6100.54245931548, 6102.847428814315, 6105.297890742621, 6112.908306375497, 6123.203983482119, 5768.451997060272, 5870.0607902735355, 5897.882042359043, 5918.585699001201, 6000.789972350947, 5929.584929054925, 5931.051031835058, 5937.079752638348, 5937.732802024006, 5938.257717785277, 5811.651300921958, 5886.175935368966, 5886.764529418775, 5889.352171261244, 5889.6171748837905, 5695.117256825199, 5876.588638795243, 5921.911171332519, 5930.822075337746, 5986.401359864153, 5855.092460668603, 5855.853603659943, 5860.09168578871, 5864.266966629171, 5897.144913987534], "value": 5550.942226726839, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [603.2881200183784, 630.7666163519497, 669.2704951602753, 670.627801570129, 676.5805200775513, 34854.0, 44604.776976115114, 45803.31295030577, 45854.77072614632, 45858.3121253181, 46713.532864671346, 46835.29747053797, 47102.05795884089, 47136.76431617837, 47150.52849471504, 8701.129887011311, 9317.860232096524, 9477.383970041925, 9498.572564234626, 10920.453977301142, 11356.26184298022, 11471.139664525152, 11496.71258218547, 11502.769944601103, 11503.884961150407, 11132.10943124552, 11140.442977851115, 11164.274322169074, 11171.329720216772, 11181.273217240894, 11462.312261264351, 11512.424378781028, 11521.193516453852, 11535.307881527135, 11546.134039946995, 11229.831552526719, 11275.041621462186, 11294.0, 11309.151813614011, 11315.490802913888, 44089.77955110204, 44378.33432498515, 44482.55517444816, 44495.110097798184, 44499.0, 3750.081230098643, 3919.7060220483577, 3981.900452488682, 4184.979075104636, 4532.4334458192725, 34607.4808877867, 34793.30413391718, 34922.30155396878, 35301.29397412037, 37185.44221836675, 4514.187446259672, 4576.679632425731, 4577.702449340815, 4604.309353596954, 4729.290606409032, 379309.0, 382665.0, 382750.0, 384265.0, 384485.0, 62098.37901620926, 62492.0, 62590.0611490828, 62613.68693156505, 62617.0, 40621.39067913983, 41365.1726965459, 41383.379249311285, 64360.678196608715, 64486.0, 19620.3132890351, 19785.802141978395, 19802.0, 19804.801951980586, 19805.10877010538, 14046.578602641937, 14979.550613481613, 15391.461298854487, 15395.384184632714, 15440.382384704712, 20782.0, 20804.271850485162, 20895.0, 20900.26849060306, 21092.47268818261, 7168.605726685082, 7233.963830180815, 7377.114746230487, 7395.81510462232, 7530.924690753022, 983.6065573770549, 1025.9794804104026, 1042.968710938673, 1068.9358638481715, 1105.8562386889653, 35393.46909796355, 35405.54176303369, 35415.22923853797, 35643.28713425717, 36017.0, 1164.0861923390098, 1171.2562522798085, 1195.121585634558, 1254.3414707278753, 1261.4071386448354, 17913.820861791217, 18627.441176764718, 18718.438446846616, 18726.625467490572, 18800.247990080243, 533797.0, 539533.0, 542147.0, 544735.0, 544769.0, 63391.683041584496, 63587.682061589396, 63817.36182638207, 63845.68077159584, 64090.0, 1295.0, 1391.547746982235, 1405.0704281573035, 1423.1532238318184, 1425.180521200314, 34423.139421514665, 36111.523795374844, 36971.630283697894, 37109.0, 37142.8142859284, 53786.46213537815, 54223.45776542184, 54231.0, 54279.18581221206, 54324.45675543352, 454142.0, 458433.0, 460603.0, 461939.0, 777376.0, 30271.0, 30469.847650761603, 30479.542806857473, 32396.35207295888, 32727.18182045468, 53595.732021339645, 53900.73049634727, 53946.0, 54318.185227220834, 54476.45523544714, 44332.77833610812, 44354.966677245015, 45885.77057114826, 46181.0, 47841.76079119582, 44522.10955780931, 45195.32207016832, 45846.31230531478, 46216.537834621224, 47613.76193119151, 44681.776591118134, 45064.0, 45786.0, 46095.0, 46247.53752462432, 36851.07872303214, 39217.23522441554, 40079.1984160321, 40269.39595906005, 40488.79755601203, 29518.852405738693, 30101.698983010767, 30849.228769280948, 31052.378952421277, 31323.68676313208], "value": 22157.139478334644, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.7417190084296, 409.1693861461235, 427.08390502372407, 431.39604553624923, 433.557771073505, 19314.51713707158, 19974.70037949432, 20596.48508787283, 20762.584748305024, 21149.682754758673, 19634.60730785387, 19934.302299419523, 20062.297819576317, 20489.590208195827, 20746.792532074676, 5897.085951677486, 6108.205933228674, 6113.663748493831, 6124.96937515314, 6128.601640893327, 5833.32916714579, 5931.406859314077, 5950.196723442345, 5980.970095149518, 6018.037114061751, 5939.316978547479, 5943.940560594404, 5946.524278057745, 5948.107783832438, 5949.4348036936635, 5853.678047707374, 5908.793192238272, 5931.051031834907, 5935.465808077251, 5935.73289201987, 5270.156774916033, 5333.413324534298, 5389.487998640123, 5390.973045134789, 5391.299131112969, 24697.38256543583, 25051.37371565707, 25110.748892511023, 25131.37171570707, 25293.873530632412, 2547.286759707285, 3060.5103183490755, 3149.937001259962, 3205.759568032407, 3275.082976766509, 15525.767113493306, 15537.766933495894, 15745.606359840982, 15877.285522151531, 16399.754003689955, 2000.7999200079876, 2096.1720120552295, 2100.222917520512, 2100.6428907085833, 2143.753468351144, 67488.6625566874, 70771.0, 74376.0, 85861.14138858594, 86644.23456709879, 11070.833937490945, 11286.774264514828, 11325.093992480583, 11527.76944461106, 11769.17615766888, 16260.75608865868, 16346.34614615426, 16380.344786208418, 16389.754153687703, 16390.754138687927, 8978.473659477904, 9021.639134434681, 9023.503707296162, 9090.13643703847, 9284.582193801296, 9098.181163695299, 9158.67944621935, 9158.725238242863, 9366.718998430058, 9379.155875971059, 6928.44572434204, 7070.080889484334, 7086.291370862872, 7102.857942841218, 7169.7849064528145, 1973.842092632586, 2052.8665636733563, 2116.248731700255, 2176.4994051367958, 2179.814715749179, 967.1585720423277, 1033.390299723167, 1041.2971244409994, 1053.4679986606714, 1129.6444266879778, 20574.897125514275, 21683.783162168176, 21727.891360543414, 21777.34667959964, 22775.316740497812, 1039.2828948025838, 1062.585591619269, 1065.5258410007627, 1065.5790962569743, 1068.1401471815263, 11506.94246528762, 11724.179307448567, 11885.88114118865, 11915.344656044, 11936.522539098512, 426231.0, 551719.0, 558244.0, 571515.0, 572255.0, 96071.51964240134, 98916.0, 100255.49872250739, 102382.0, 103991.0, 1039.9792004159874, 1050.3907733514543, 1110.6445937299986, 1132.3319241647469, 1138.362516990488, 13878.0, 18620.44138675842, 18791.718124227875, 18882.71675924835, 19047.904760476107, 40317.1936561273, 40583.18833623252, 41058.79470602628, 42248.78875605603, 43508.0, 53166.0, 60597.69701151466, 61007.0, 62075.6896215516, 62691.373086268555, 8468.745937621881, 8821.603027863634, 8910.732678019669, 8950.776230594287, 8973.192412682889, 12574.497020119094, 13293.534726284533, 13339.266340351374, 13465.326733663082, 13490.662733431744, 11412.600558980395, 11600.18798684084, 11612.54843144607, 11623.186376953532, 11968.820467692818, 11238.269512481662, 11330.036946859609, 11333.546658133582, 11653.708657283636, 11916.10629202796, 11803.822942655695, 12187.0, 12381.195222310522, 12415.62753117408, 12451.066170037102, 8565.218904953017, 8936.642534298555, 8966.506842123747, 9155.221806146552, 9195.126462986005, 10960.56157753681, 11857.288562686266, 11913.22564033335, 12040.698982525508, 12223.144379893678], "value": 9938.283766378672, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1548.1794648836114, 1568.5686436230035, 1726.6633006563718, 1781.6436712657471, 1828.332658579618, 8814.33892458065, 9481.668141615037, 9523.761905952355, 9534.523273836307, 9535.57089930953, 8279.089300176976, 8884.244839188661, 9172.403793753418, 9206.907930920688, 9323.347365684405, 2488.3779055236237, 2527.6082207257905, 2527.949441011179, 2530.3421110511217, 2532.227670560478, 2424.4544977380124, 2453.264020793764, 2453.6196889482158, 2454.0919859651954, 2455.9754402456015, 2451.2278632230878, 2460.90156393744, 2499.4126380300613, 2503.198976327567, 2504.26124293333, 2392.940176495593, 2401.9759802401927, 2410.6866107406013, 2445.461998360358, 2450.9264722058365, 2372.2290255666976, 2399.004413168533, 2404.603240465321, 2406.3984003999, 2435.0990133650484, 9134.588943497558, 9183.540822958825, 9217.0, 9217.815643687156, 9226.53867306632, 5591.636543624692, 5706.686132262724, 5708.20085188074, 5714.628549144334, 5719.771209151629, 3644.1436262478296, 3716.2753263113705, 3739.981300093509, 3750.7186960977765, 3779.225258821935, 1224.3449754381452, 1247.3077442019676, 1276.610633756704, 1280.8911242544395, 1282.9743405131937, 12889.419976101099, 12894.290814005226, 12915.547955821501, 12926.806097908539, 12935.417906194243, 4925.433575138905, 4955.058538877602, 5028.698278103326, 5055.595552355876, 5065.670731402447, 11006.889931100746, 11021.338719676685, 11027.503762330714, 11041.227114102096, 11049.0, 5727.083666613322, 5731.283589551306, 5746.454086861741, 5748.655080695172, 5753.769849205984, 4560.817567297271, 4728.858134255978, 5280.683159010472, 5284.947150528523, 5323.547498462596, 4354.825806967686, 4371.475422949203, 4421.53573874745, 4453.42105526286, 4466.798994045275, 1340.1690951610065, 1340.718449125675, 1350.8784209421003, 1353.2557093598678, 1373.6977864869712, 982.2240430060173, 989.4607438945648, 1018.8471729240525, 1064.1327318235612, 1085.6905781852133, 6657.933420665731, 6672.099266599044, 6696.8995465068065, 6741.0899528564, 6844.794656160323, 485.752266344167, 498.82541110611, 502.3519659639098, 503.2752835916269, 507.4114027727818, 4375.321825117112, 4414.735115893057, 4419.889502762457, 4514.232580461329, 4613.792379342937, 10574.629887953884, 10959.726006849893, 11004.39475828821, 11014.118870490342, 11057.50241239146, 10750.516226769814, 11054.281471704313, 11350.886491135148, 11358.829617555743, 11807.88192118085, 8359.95820020896, 8532.53071081084, 8541.231289184003, 8560.91439085601, 8579.0, 6487.870242595216, 6705.731770729311, 6787.53929353574, 6856.6228857413325, 6880.552764070319, 10844.511996960313, 10887.51006204707, 11016.834747478633, 11019.283746556448, 11103.278286911325, 10904.781904362027, 11074.889251107708, 11258.774824503627, 11376.94311528437, 11377.488013039267, 7751.379889608705, 8202.876956845772, 8417.494950303002, 8520.74437766868, 8742.0, 9268.907310926805, 9335.346525743404, 9336.06639336061, 9337.626494940127, 9361.953190234006, 7997.680092796223, 7998.800029999296, 8044.597770111588, 8128.349732021542, 8208.958955205186, 7883.093444253755, 7903.249191326815, 7919.485233459806, 7963.40274479428, 8080.2727754502375, 7689.0, 7739.806504837424, 7748.845023099394, 7807.102183248999, 7818.374530037697, 5478.287822583039, 5494.120940649477, 5618.494335509824, 5648.689322087324, 5667.8866422672145, 5448.782048718165, 5545.528630066328, 5546.805861794818, 5573.470520300565, 5663.830085097454], "value": 4882.711994813516, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.4566658678808, 358.2816453011712, 359.3621322153177, 361.40187988878415, 363.3477907156652, 5505.724713764311, 5609.551235901128, 5653.5194508466775, 5661.5187709044785, 5671.376148623648, 5498.1752737089455, 5500.559955203584, 5506.559475241981, 5585.469380408855, 5589.413111623282, 4137.2552940470705, 4255.212785634652, 4330.155619654169, 4335.804888779997, 4376.606105450509, 4355.390245365651, 4356.368326592642, 4379.474463064421, 4393.319035549495, 4399.362092496586, 4340.348947657861, 4346.565343465644, 4346.913061738764, 4368.912621747563, 4382.189294980423, 4154.646855017327, 4324.762138082404, 4339.696221264513, 4346.9347959780625, 4371.322445021026, 3831.942520862189, 3853.8843834685003, 3859.6912247020173, 3859.9614003859883, 3877.5346958365176, 5401.864953376158, 5418.834950485664, 5423.620346575742, 5454.127339625641, 5509.209506910271, 4898.697612122332, 4952.158133117378, 4980.701157930535, 5012.27322038306, 5040.798368065273, 5751.166080918243, 5755.165501002373, 5759.769609215627, 5759.798407055733, 5780.942190578125, 4250.936235956463, 4257.701960862741, 4263.914721705549, 4265.957340426587, 4268.844233661981, 5691.630044047124, 5820.941790582082, 5885.70571471429, 6110.572259941806, 6113.633182009094, 4162.2091802558025, 4195.517515485697, 4209.6000879916355, 4354.542773008789, 4364.585364390377, 4425.694420146065, 4426.690131690815, 4430.158269928703, 4443.644508439317, 4450.910981780411, 4918.55732984026, 5004.899902001939, 5054.469280725544, 5062.519060689228, 5062.873428164252, 5770.307563092456, 5775.018246898037, 5784.161296611967, 5790.536757059425, 5792.420757924174, 1495.6260934766308, 1505.6838064006681, 1507.736146174437, 1518.764591488321, 1520.6882589069214, 929.5956259027345, 969.9757506062264, 975.8536219567121, 981.8478135888948, 999.240577161348, 4887.828925987574, 4991.800327986913, 4995.17579599362, 5022.472640372781, 5025.346704928336, 5272.868178295574, 5298.761555730001, 5301.390340110936, 5305.549028332635, 5313.07021271276, 4852.393450818648, 4924.433690125681, 4963.45402005779, 5058.772355244023, 5096.923546146811, 2808.340040090577, 2814.690384057752, 2817.8027538072547, 2820.1680504251294, 2829.0, 5284.9735751320995, 5397.568194544506, 5434.565234781208, 5436.293281873332, 5448.520922980035, 5028.5977121830165, 5263.6578622389425, 5271.736413179402, 5286.286351342596, 5308.309919710413, 5319.787208511693, 5320.929488940963, 5344.946550534522, 5368.550491367387, 5442.210879422461, 5217.50433708797, 5229.424763276113, 5233.555147812326, 5270.525652691123, 5278.287431196739, 5491.972540137273, 5884.705764711662, 5940.28716553999, 5946.137810017437, 5947.91078133837, 5849.502792262705, 5988.251468566429, 6086.969565152323, 6101.786437474667, 6104.382338680164, 4402.977985110054, 4466.0, 4475.910481790281, 4484.529124442017, 4491.416115905042, 5771.8557036074435, 6000.669963152069, 6008.158857760004, 6028.758849645965, 6033.698315084141, 5799.623024503394, 5802.767889284551, 5810.0, 5819.476247137778, 5850.970745146417, 5798.652080875161, 5798.98517759382, 5802.071668533184, 5803.361630220588, 5818.359980402237, 5543.944560554342, 5764.567657425626, 5765.94234057654, 5798.710064496842, 5847.1229315603, 5842.707864606838, 5848.970755146197, 5910.113482977587, 5920.822375328747, 5922.348541660328, 5563.638363506359, 5709.22925405065, 5773.6824474654295, 5778.87311974161, 5784.363719990882], "value": 4337.405120186843, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [844.6705784743947, 853.3685073045945, 885.3359980014989, 888.0852721696655, 919.6827094652344, 16049.67900641988, 16370.754438683429, 17364.13179341031, 17379.652406951853, 17403.303867845272, 16069.598260043505, 16140.83859161408, 16161.434349797759, 16271.755923661154, 16273.349066037345, 742.1242933338658, 862.1206369503103, 864.360373323739, 882.3955590420551, 888.2804928008302, 878.6046279174365, 887.5917078144029, 888.2405543260517, 888.7200531832484, 889.8442772514817, 915.0940568836837, 919.9678011269607, 922.598669578732, 923.6166990698852, 924.177482040984, 916.5050872528826, 917.1378903830378, 917.8944421391559, 918.237862574062, 918.8208299381623, 674.2650510943096, 680.6766785776766, 680.8706345794283, 681.3867519232681, 681.4854784637566, 15875.60310992223, 15923.363065477368, 16030.11834349122, 16106.919465402598, 16124.274407651685, 15699.293531791096, 16010.51968440937, 16123.596910077342, 16426.83573164265, 16493.83506164935, 13176.209427434289, 13246.933765331112, 13468.865311346859, 13633.182009079486, 13711.108777929403, 4048.068944142834, 4098.364753463218, 4108.815103320328, 4152.771597562133, 4241.660667146621, 85361.57319213427, 86844.5657771707, 87111.0, 87130.0, 87530.0, 10965.451727413598, 11188.66434006981, 11564.826527602092, 12380.380980951097, 12591.559295424799, 22032.88983555072, 22137.557248855253, 22169.667454988186, 22209.44476388103, 22267.331980040624, 8547.829043419097, 8674.436161649473, 8765.912340876637, 8860.8670869937, 8875.822483550293, 5003.874903127451, 5071.949280507221, 5080.314157588752, 5176.275321454999, 5211.270422140828, 3955.7033222508426, 4060.5939406059156, 4105.774182419996, 4111.342185250345, 4245.0, 728.9416846652362, 744.0550500863873, 745.6942653512142, 761.0, 778.2023425988294, 759.7758661194961, 766.5860435364953, 783.7922950418143, 806.5443024691031, 807.7051876065194, 9586.520673966274, 9732.41605503658, 9796.216302695768, 9834.164096051773, 9946.458298963673, 697.0659316515893, 730.8319086610003, 750.6847124207845, 778.5212094561804, 823.4812068396872, 4621.630269578425, 4800.255960326085, 4814.903701925942, 4849.75751212438, 4870.878228044327, 68971.0, 68997.31002690009, 69112.6544367285, 70046.0, 100468.49765751124, 98270.50864745774, 99982.5000874991, 100818.0, 101139.49430252949, 101659.0, 58307.70846145827, 87938.0, 88748.5562572183, 92200.0, 92275.0, 9451.149396554343, 9668.226541876493, 10031.74920626961, 10102.39385636864, 10116.494175291353, 54080.0, 54458.72770636121, 54673.72663136659, 55790.0, 55819.72090139523, 68693.0, 68781.0, 69401.2649683762, 77188.61405692935, 79119.20880791348, 12917.418716157608, 12986.090973632134, 13036.674083147996, 13054.608361748782, 13113.606591802261, 14036.438542458596, 14046.859531404556, 14235.928820355832, 14268.785968210277, 14370.568882933529, 12740.490380384681, 13001.869981300066, 13026.47894084226, 13026.804597931228, 13058.34708264602, 13374.732505350034, 13581.14722982572, 13585.52450664222, 13615.25116118623, 13710.931445342709, 13019.47922083144, 13079.215247085205, 13568.660783480493, 13685.726285474035, 13955.58133256004, 10445.791084178121, 10709.571617135227, 10831.0, 11421.428928553705, 11505.42472876336, 10150.289479736348, 10591.785146922228, 10759.46202689878, 10895.727606809576, 11373.374464404205], "value": 7855.608671397151, "unit": "score"}], "name": "dromaeo_css", "value": 7668.636215313813, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 120.0, 110.0, 110.0, 110.0, 110.0, 110.0, 110.0, 109.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 119.0, 138.0, 172.0, 116.0, 146.0, 139.0, 116.0, 185.0, 117.0], "value": 140.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 160.0, 161.0, 160.0, 161.0, 161.0, 160.0, 160.0, 160.0, 159.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 73.0, 176.0, 75.0, 74.0, 73.0, 76.0, 165.0, 76.0], "value": 93.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 152.0, 82.0, 83.0, 83.0, 141.0, 82.0, 82.0, 162.0], "value": 103.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 145.0, 145.0, 146.0, 146.0, 145.0, 146.0, 145.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 97.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 51.0, 51.0, 51.0, 52.0, 50.0, 51.0, 51.0, 50.0], "value": 50.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 61.0, 60.0, 60.0, 59.0, 59.0, 60.0, 60.0, 60.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 73.0, 72.0, 72.0, 76.0, 76.0, 72.0, 71.0, 70.0, 71.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 124.0, 117.0, 118.0, 120.0, 116.0, 119.0, 121.0, 148.0, 114.0], "value": 121.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 153.0, 154.0, 154.0, 151.0, 151.0, 151.0, 154.0, 153.0, 153.0], "value": 152.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 65.0, 60.0, 61.0, 64.0, 63.0, 61.0, 62.0, 60.0, 59.0], "value": 61.7, "unit": "score"}], "name": "kraken", "value": 1484.5, "alertThreshold": 2.0}]} 09:19:03 INFO - Return code: 0 09:19:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:19:04 INFO - # TBPL SUCCESS # 09:19:04 INFO - Running post-action listener: _resource_record_post_action 09:19:04 INFO - [mozharness: 2016-09-24 16:19:04.028892Z] Finished run-tests step (success) 09:19:04 INFO - Running post-run listener: _resource_record_post_run 09:19:04 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 285900800; Read time: 4; Write time: 357892 09:19:04 INFO - TinderboxPrint: CPU usage
13.0% 09:19:04 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 4 09:19:04 INFO - TinderboxPrint: I/O write bytes / time
285,900,800 / 357,892 09:19:04 INFO - TinderboxPrint: CPU idle
8,258.6 (87.0%) 09:19:04 INFO - TinderboxPrint: CPU user
1,182.9 (12.5%) 09:19:04 INFO - TinderboxPrint: Swap in / out
0 / 0 09:19:04 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 71307264; Read time: 0; Write time: 121740 09:19:04 INFO - run-tests - Wall time: 1175s; CPU: 13.0%; Read bytes: 0; Write bytes: 214593536; Read time: 0; Write time: 236152 09:19:04 INFO - Running post-run listener: _upload_blobber_files 09:19:04 INFO - Blob upload gear active. 09:19:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:19:04 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 09:19:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:19:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:19:05 INFO - (blobuploader) - INFO - Open directory for files ... 09:19:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:19:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:19:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:19:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:19:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:19:06 INFO - (blobuploader) - INFO - Done attempting. 09:19:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:19:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:19:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:19:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:19:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:19:08 INFO - (blobuploader) - INFO - Done attempting. 09:19:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:19:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:19:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:19:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:19:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:19:12 INFO - (blobuploader) - INFO - Done attempting. 09:19:12 INFO - (blobuploader) - INFO - Iteration through files over. 09:19:12 INFO - Return code: 0 09:19:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:19:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:19:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/acae017deb7fb59c37b94d95024bb892f154d8a6c089e4e3a3b799dbf052ca067aba454a68cefc653f257909026e5e562a7a69b6e8c67a91b2328359146d92a9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6ce7c8ad09851a41043b7ca79e89a348d7ae5bc5d0e20bd060f212f4744a91ec538b060cd708a3fb9c093ee15f5dcff2ac9e4e41954ab900bb362cd3f706e099", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0298a46db151a5513d95d663fd1ab242fe31a4fca1f4f34a2d70230b0c308c99f719b97e7b68c7ab21b21df2ae3064ccc6ed72542ec6bdb2aef2458d8d51cd22"} 09:19:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:19:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:19:12 INFO - Contents: 09:19:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/acae017deb7fb59c37b94d95024bb892f154d8a6c089e4e3a3b799dbf052ca067aba454a68cefc653f257909026e5e562a7a69b6e8c67a91b2328359146d92a9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6ce7c8ad09851a41043b7ca79e89a348d7ae5bc5d0e20bd060f212f4744a91ec538b060cd708a3fb9c093ee15f5dcff2ac9e4e41954ab900bb362cd3f706e099", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0298a46db151a5513d95d663fd1ab242fe31a4fca1f4f34a2d70230b0c308c99f719b97e7b68c7ab21b21df2ae3064ccc6ed72542ec6bdb2aef2458d8d51cd22"} 09:19:12 INFO - Running post-run listener: copy_logs_to_upload_dir 09:19:12 INFO - Copying logs to upload dir... 09:19:12 INFO - mkdir: /builds/slave/test/build/upload/logs 09:19:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1680.387495 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 28 mins, 0 secs) (at 2016-09-24 09:19:12.132805) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-24 09:19:12.136143) ========= 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=5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/acae017deb7fb59c37b94d95024bb892f154d8a6c089e4e3a3b799dbf052ca067aba454a68cefc653f257909026e5e562a7a69b6e8c67a91b2328359146d92a9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6ce7c8ad09851a41043b7ca79e89a348d7ae5bc5d0e20bd060f212f4744a91ec538b060cd708a3fb9c093ee15f5dcff2ac9e4e41954ab900bb362cd3f706e099", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0298a46db151a5513d95d663fd1ab242fe31a4fca1f4f34a2d70230b0c308c99f719b97e7b68c7ab21b21df2ae3064ccc6ed72542ec6bdb2aef2458d8d51cd22"} build_url:https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011414 build_url: 'https://queue.taskcluster.net/v1/task/DkvwMiU6TdOpJHfAkSVy2w/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/acae017deb7fb59c37b94d95024bb892f154d8a6c089e4e3a3b799dbf052ca067aba454a68cefc653f257909026e5e562a7a69b6e8c67a91b2328359146d92a9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6ce7c8ad09851a41043b7ca79e89a348d7ae5bc5d0e20bd060f212f4744a91ec538b060cd708a3fb9c093ee15f5dcff2ac9e4e41954ab900bb362cd3f706e099", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0298a46db151a5513d95d663fd1ab242fe31a4fca1f4f34a2d70230b0c308c99f719b97e7b68c7ab21b21df2ae3064ccc6ed72542ec6bdb2aef2458d8d51cd22"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-24 09:19:12.161600) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-24 09:19:12.161947) ========= 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=5cf47e208febbcd81da23b2e0000026f-1474731794.961088-445279866 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004479 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-24 09:19:12.246638) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-24 09:19:12.246955) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-24 09:19:12.247251) ========= ========= Total master_lag: 0.17 =========