builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-005 starttime: 1474490793.59 results: success (0) buildid: 20160921110159 builduid: 4c4b4d54ca8644598c2f52a6ce505d54 revision: eb11246a0e01ac22ccb15dff47616d9e7e6d5dc2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 13:46:33.593373) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 13:46:33.594362) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 13:46:33.595108) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 13:46:33.611311) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 13:46:33.611998) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-09-21 13:46:33-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 219M=0s 2016-09-21 13:46:33 (219 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121363 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 13:46:33.748843) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 13:46:33.749212) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.023367 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 13:46:33.798507) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-21 13:46:33.798887) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev eb11246a0e01ac22ccb15dff47616d9e7e6d5dc2 --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 eb11246a0e01ac22ccb15dff47616d9e7e6d5dc2 --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=7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-09-21 13:46:34,081 truncating revision to first 12 chars 2016-09-21 13:46:34,081 Setting DEBUG logging. 2016-09-21 13:46:34,081 attempt 1/10 2016-09-21 13:46:34,081 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/eb11246a0e01?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-21 13:46:35,320 unpacking tar archive at: mozilla-beta-eb11246a0e01/testing/mozharness/ program finished with exit code 0 elapsedTime=1.554518 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-21 13:46:35.368850) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 13:46:35.369251) ========= script_repo_revision: eb11246a0e01ac22ccb15dff47616d9e7e6d5dc2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 13:46:35.369711) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 13:46:35.370018) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 13:46:35.381002) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-09-21 13:46:35.381308) ========= /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=7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:46:35 INFO - MultiFileLogger online at 20160921 13:46:35 in /builds/slave/test 13:46:35 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 13:46:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:46:35 INFO - {'append_to_log': False, 13:46:35 INFO - 'base_work_dir': '/builds/slave/test', 13:46:35 INFO - 'blob_upload_branch': 'Mozilla-Beta', 13:46:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:46:35 INFO - 'branch': 'Mozilla-Beta', 13:46:35 INFO - 'buildbot_json_path': 'buildprops.json', 13:46:35 INFO - 'config_files': ('talos/linux_config.py',), 13:46:35 INFO - 'default_actions': ('clobber', 13:46:35 INFO - 'read-buildbot-config', 13:46:35 INFO - 'download-and-extract', 13:46:35 INFO - 'populate-webroot', 13:46:35 INFO - 'create-virtualenv', 13:46:35 INFO - 'install', 13:46:35 INFO - 'run-tests'), 13:46:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:46:35 INFO - 'download_minidump_stackwalk': True, 13:46:35 INFO - 'download_symbols': 'ondemand', 13:46:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:46:35 INFO - 'tooltool.py': '/tools/tooltool.py', 13:46:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:46:35 INFO - '/tools/misc-python/virtualenv.py')}, 13:46:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:46:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:46:35 INFO - 'installer_path': 'installer.exe', 13:46:35 INFO - 'log_level': 'info', 13:46:35 INFO - 'log_name': 'talos', 13:46:35 INFO - 'log_to_console': True, 13:46:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:46:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:46:35 INFO - 'opt_config_files': (), 13:46:35 INFO - 'pip_index': False, 13:46:35 INFO - 'sps_profile': False, 13:46:35 INFO - 'sps_profile_interval': 0, 13:46:35 INFO - 'suite': 'dromaeojs', 13:46:35 INFO - 'system_bits': '32', 13:46:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:46:35 INFO - 'title': 'talos-linux64-ix-005', 13:46:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:46:35 INFO - 'use_talos_json': True, 13:46:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:46:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:46:35 INFO - 'work_dir': 'build'} 13:46:35 INFO - [mozharness: 2016-09-21 20:46:35.753463Z] Running clobber step. 13:46:35 INFO - Running pre-action listener: _resource_record_pre_action 13:46:35 INFO - Running main action method: clobber 13:46:35 INFO - rmtree: /builds/slave/test/build 13:46:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:46:36 INFO - Running post-action listener: _resource_record_post_action 13:46:36 INFO - [mozharness: 2016-09-21 20:46:36.211728Z] Finished clobber step (success) 13:46:36 INFO - [mozharness: 2016-09-21 20:46:36.211829Z] Running read-buildbot-config step. 13:46:36 INFO - Running pre-action listener: _resource_record_pre_action 13:46:36 INFO - Running main action method: read_buildbot_config 13:46:36 INFO - Using buildbot properties: 13:46:36 INFO - { 13:46:36 INFO - "project": "", 13:46:36 INFO - "product": "firefox", 13:46:36 INFO - "script_repo_revision": "production", 13:46:36 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 13:46:36 INFO - "repository": "", 13:46:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 13:46:36 INFO - "buildid": "20160921110159", 13:46:36 INFO - "pgo_build": "True", 13:46:36 INFO - "basedir": "/builds/slave/test", 13:46:36 INFO - "buildnumber": 253, 13:46:36 INFO - "slavename": "talos-linux64-ix-005", 13:46:36 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 13:46:36 INFO - "platform": "ubuntu64_hw", 13:46:36 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 13:46:36 INFO - "branch": "mozilla-beta", 13:46:36 INFO - "repo_path": "releases/mozilla-beta", 13:46:36 INFO - "revision": "eb11246a0e01ac22ccb15dff47616d9e7e6d5dc2", 13:46:36 INFO - "stage_platform": "linux64-pgo", 13:46:36 INFO - "builduid": "4c4b4d54ca8644598c2f52a6ce505d54", 13:46:36 INFO - "slavebuilddir": "test" 13:46:36 INFO - } 13:46:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2. 13:46:36 INFO - Running post-action listener: _resource_record_post_action 13:46:36 INFO - [mozharness: 2016-09-21 20:46:36.214297Z] Finished read-buildbot-config step (success) 13:46:36 INFO - [mozharness: 2016-09-21 20:46:36.214386Z] Running download-and-extract step. 13:46:36 INFO - Running pre-action listener: _resource_record_pre_action 13:46:36 INFO - Running main action method: download_and_extract 13:46:36 INFO - mkdir: /builds/slave/test/build/tests 13:46:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:46:36 INFO - https://queue.taskcluster.net/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 13:46:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 13:46:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 13:46:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:46:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:46:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 13:46:36 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:46:36 INFO - retry: Failed, sleeping 30 seconds before retrying 13:47:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:47:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 13:47:06 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:47:06 INFO - retry: Failed, sleeping 60 seconds before retrying 13:48:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:48:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 13:48:06 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:48:06 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/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! 13:48:06 INFO - Caught exception: HTTP Error 404: Not Found 13:48:06 INFO - Caught exception: HTTP Error 404: Not Found 13:48:06 INFO - Caught exception: HTTP Error 404: Not Found 13:48:06 INFO - trying https://queue.taskcluster.net/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 13:48:06 INFO - Downloading https://queue.taskcluster.net/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:48:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:48:06 INFO - Downloaded 1327 bytes. 13:48:06 INFO - Reading from file /builds/slave/test/build/firefox-50.0.en-US.linux-x86_64.test_packages.json 13:48:06 INFO - Using the following test package requirements: 13:48:06 INFO - {u'common': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 13:48:06 INFO - u'cppunittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 13:48:06 INFO - u'firefox-50.0.en-US.linux-x86_64.cppunittest.tests.zip'], 13:48:06 INFO - u'gtest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 13:48:06 INFO - u'firefox-50.0.en-US.linux-x86_64.gtest.tests.zip'], 13:48:06 INFO - u'jittest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 13:48:06 INFO - u'jsshell-linux-x86_64.zip'], 13:48:06 INFO - u'mochitest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 13:48:06 INFO - u'firefox-50.0.en-US.linux-x86_64.mochitest.tests.zip'], 13:48:06 INFO - u'mozbase': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'], 13:48:06 INFO - u'reftest': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 13:48:06 INFO - u'firefox-50.0.en-US.linux-x86_64.reftest.tests.zip'], 13:48:06 INFO - u'talos': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 13:48:06 INFO - u'firefox-50.0.en-US.linux-x86_64.talos.tests.zip'], 13:48:06 INFO - u'web-platform': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 13:48:06 INFO - u'firefox-50.0.en-US.linux-x86_64.web-platform.tests.zip'], 13:48:06 INFO - u'xpcshell': [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip', 13:48:06 INFO - u'firefox-50.0.en-US.linux-x86_64.xpcshell.tests.zip']} 13:48:06 INFO - Downloading packages: [u'firefox-50.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 13:48: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')]} 13:48:06 INFO - https://queue.taskcluster.net/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:48:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 13:48:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 13:48:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:48:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:48:06 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 13:48:06 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:48:06 INFO - retry: Failed, sleeping 30 seconds before retrying 13:48:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:48:36 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 13:48:36 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:48:36 INFO - retry: Failed, sleeping 60 seconds before retrying 13:49:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:49:37 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 13:49:37 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:49:37 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/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! 13:49:37 INFO - Caught exception: HTTP Error 404: Not Found 13:49:37 INFO - Caught exception: HTTP Error 404: Not Found 13:49:37 INFO - Caught exception: HTTP Error 404: Not Found 13:49:37 INFO - trying https://queue.taskcluster.net/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 13:49:37 INFO - Downloading https://queue.taskcluster.net/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:49:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:49:37 INFO - Downloaded 21770261 bytes. 13:49:37 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'] 13:49:37 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 13:49:38 INFO - Return code: 0 13:49:38 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 13:49:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:49:38 INFO - https://queue.taskcluster.net/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 13:49:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 13:49:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 13:49:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:49:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:49:39 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 13:49:39 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:49:39 INFO - retry: Failed, sleeping 30 seconds before retrying 13:50:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:50:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 13:50:09 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:50:09 INFO - retry: Failed, sleeping 60 seconds before retrying 13:51:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:51:09 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 13:51:09 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:51:09 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/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! 13:51:09 INFO - Caught exception: HTTP Error 404: Not Found 13:51:09 INFO - Caught exception: HTTP Error 404: Not Found 13:51:09 INFO - Caught exception: HTTP Error 404: Not Found 13:51:09 INFO - trying https://queue.taskcluster.net/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.common.tests.zip 13:51:09 INFO - Downloading https://queue.taskcluster.net/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:51:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:51:09 INFO - Downloaded 21770261 bytes. 13:51:09 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'] 13:51:09 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 13:51:11 INFO - Return code: 0 13:51:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:51:11 INFO - https://queue.taskcluster.net/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 13:51:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 13:51:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 13:51:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:51:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:51:11 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 13:51:11 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:51:11 INFO - retry: Failed, sleeping 30 seconds before retrying 13:51:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:51:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 13:51:41 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:51:41 INFO - retry: Failed, sleeping 60 seconds before retrying 13:52:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:52:41 WARNING - Server returned status 404 HTTP Error 404: Not Found for http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 13:52:41 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:52:41 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/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! 13:52:41 INFO - Caught exception: HTTP Error 404: Not Found 13:52:41 INFO - Caught exception: HTTP Error 404: Not Found 13:52:41 INFO - Caught exception: HTTP Error 404: Not Found 13:52:41 INFO - trying https://queue.taskcluster.net/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.talos.tests.zip 13:52:41 INFO - Downloading https://queue.taskcluster.net/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:52:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:52:43 INFO - Downloaded 14413740 bytes. 13:52:43 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'] 13:52:43 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 13:52:43 INFO - Return code: 0 13: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')]} 13:52:43 INFO - https://queue.taskcluster.net/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 13:52:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 13:52:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 13:52:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:52:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13: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/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 13:52:43 INFO - retry: attempt #1 caught exception: HTTP Error 404: Not Found 13:52:43 INFO - retry: Failed, sleeping 30 seconds before retrying 13:53:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13: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/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 13:53:13 INFO - retry: attempt #2 caught exception: HTTP Error 404: Not Found 13:53:13 INFO - retry: Failed, sleeping 60 seconds before retrying 13:54:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13: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/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 13:54:13 INFO - retry: attempt #3 caught exception: HTTP Error 404: Not Found 13:54:13 INFO - Can't download from http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/I64Vk5nZRMij8TXY9r__2g/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! 13:54:13 INFO - Caught exception: HTTP Error 404: Not Found 13:54:13 INFO - Caught exception: HTTP Error 404: Not Found 13:54:13 INFO - Caught exception: HTTP Error 404: Not Found 13:54:13 INFO - trying https://queue.taskcluster.net/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 13:54:13 INFO - Downloading https://queue.taskcluster.net/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:54:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/I64Vk5nZRMij8TXY9r__2g/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 13:54:14 INFO - Downloaded 56764327 bytes. 13:54:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 13:54:14 INFO - mkdir: /builds/slave/test/properties 13:54:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:54:14 INFO - Writing to file /builds/slave/test/properties/build_url 13:54:14 INFO - Contents: 13:54:14 INFO - build_url:https://queue.taskcluster.net/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 13:54:15 INFO - Running post-action listener: _resource_record_post_action 13:54:15 INFO - Running post-action listener: set_extra_try_arguments 13:54:15 INFO - [mozharness: 2016-09-21 20:54:15.227091Z] Finished download-and-extract step (success) 13:54:15 INFO - [mozharness: 2016-09-21 20:54:15.227327Z] Running populate-webroot step. 13:54:15 INFO - Running pre-action listener: _resource_record_pre_action 13:54:15 INFO - Running main action method: populate_webroot 13:54:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:54:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:54:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:54:15 INFO - '--tppagecycles', 13:54:15 INFO - '7'], 13:54:15 INFO - 'tests': ['tsvgm']}}, 13:54:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:54:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:54:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:54:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:54:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:54:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:54:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:54:15 INFO - 'talos_options': ['--disable-e10s'], 13:54:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:54:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:54:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:54:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:54:15 INFO - 'talos_options': ['--disable-e10s'], 13:54:15 INFO - 'tests': ['damp', 'tps']}, 13:54:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:54:15 INFO - 'tests': ['damp', 'tps']}, 13:54:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:54:15 INFO - 'tests': ['dromaeo_dom']}, 13:54:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:54:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:54:15 INFO - 'tests': ['basic_compositor_video']}, 13:54:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:54:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:54:15 INFO - 'tests': ['a11yr', 13:54:15 INFO - 'ts_paint', 13:54:15 INFO - 'tpaint', 13:54:15 INFO - 'sessionrestore', 13:54:15 INFO - 'sessionrestore_no_auto_restore', 13:54:15 INFO - 'tabpaint']}, 13:54:15 INFO - 'other-e10s': {'tests': ['a11yr', 13:54:15 INFO - 'ts_paint', 13:54:15 INFO - 'tpaint', 13:54:15 INFO - 'sessionrestore', 13:54:15 INFO - 'sessionrestore_no_auto_restore', 13:54:15 INFO - 'tabpaint']}, 13:54:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:54:15 INFO - 'ts_paint', 13:54:15 INFO - 'tpaint', 13:54:15 INFO - 'sessionrestore', 13:54:15 INFO - 'sessionrestore_no_auto_restore', 13:54:15 INFO - 'tabpaint']}, 13:54:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:54:15 INFO - 'ts_paint', 13:54:15 INFO - 'tpaint', 13:54:15 INFO - 'sessionrestore', 13:54:15 INFO - 'sessionrestore_no_auto_restore', 13:54:15 INFO - 'tabpaint']}, 13:54:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:54:15 INFO - 'tests': ['a11yr', 13:54:15 INFO - 'ts_paint', 13:54:15 INFO - 'tpaint', 13:54:15 INFO - 'sessionrestore', 13:54:15 INFO - 'sessionrestore_no_auto_restore', 13:54:15 INFO - 'tabpaint']}, 13:54:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:54:15 INFO - 'tests': ['a11yr', 13:54:15 INFO - 'ts_paint', 13:54:15 INFO - 'tpaint', 13:54:15 INFO - 'sessionrestore', 13:54:15 INFO - 'sessionrestore_no_auto_restore', 13:54:15 INFO - 'tabpaint']}, 13:54:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:54:15 INFO - 'tests': ['tsvgx', 13:54:15 INFO - 'tsvgr_opacity', 13:54:15 INFO - 'tart', 13:54:15 INFO - 'tscrollx', 13:54:15 INFO - 'cart']}, 13:54:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:54:15 INFO - 'tsvgr_opacity', 13:54:15 INFO - 'tart', 13:54:15 INFO - 'tscrollx', 13:54:15 INFO - 'cart']}, 13:54:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:54:15 INFO - 'talos_options': ['--disable-e10s'], 13:54:15 INFO - 'tests': ['tp5o']}, 13:54:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:54:15 INFO - 'tests': ['tp5o']}, 13:54:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:54:15 INFO - 'talos_options': ['--disable-e10s', 13:54:15 INFO - '--xperf_path', 13:54:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:54:15 INFO - 'tests': ['tp5n']}, 13:54:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:54:15 INFO - 'talos_options': ['--xperf_path', 13:54:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:54:15 INFO - 'tests': ['tp5n']}}, 13:54:15 INFO - 'talos.zip': {'path': '', 13:54:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:54:15 INFO - Running post-action listener: _resource_record_post_action 13:54:15 INFO - [mozharness: 2016-09-21 20:54:15.251262Z] Finished populate-webroot step (success) 13:54:15 INFO - [mozharness: 2016-09-21 20:54:15.251351Z] Running create-virtualenv step. 13:54:15 INFO - Running pre-action listener: _resource_record_pre_action 13:54:15 INFO - Running main action method: create_virtualenv 13:54:15 INFO - Creating virtualenv /builds/slave/test/build/venv 13:54:15 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 13:54:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:54:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:54:15 INFO - Using real prefix '/usr' 13:54:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:54:15 INFO - Installing distribute.............................................................................................................................................................................................done. 13:54:17 INFO - Installing pip.................done. 13:54:17 INFO - Return code: 0 13:54:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:54:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:17 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 0x15d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1897180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18953b0>, '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 0x18962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1896740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:54:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:54:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:54:17 INFO - Using env: {'DISPLAY': ':0', 13:54:17 INFO - 'HOME': '/home/cltbld', 13:54:17 INFO - 'LANG': 'en_US.UTF-8', 13:54:17 INFO - 'LANGUAGE': 'en_US:en', 13:54:17 INFO - 'LOGNAME': 'cltbld', 13:54:17 INFO - 'MAIL': '/var/mail/cltbld', 13:54:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:17 INFO - 'MOZ_NO_REMOTE': '1', 13:54:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:54:17 INFO - 'NO_EM_RESTART': '1', 13:54:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:54:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:17 INFO - 'PWD': '/builds/slave/test', 13:54:17 INFO - 'SHELL': '/bin/bash', 13:54:17 INFO - 'SHLVL': '1', 13:54:17 INFO - 'TERM': 'linux', 13:54:17 INFO - 'TMOUT': '86400', 13:54:17 INFO - 'USER': 'cltbld', 13:54:17 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509', 13:54:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:17 INFO - '_': '/tools/buildbot/bin/python'} 13:54:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:18 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 13:54:18 INFO - Cleaning up... 13:54:18 INFO - Return code: 0 13:54:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:54:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:18 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 0x15d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1897180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18953b0>, '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 0x18962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1896740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:54:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 13:54:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 13:54:18 INFO - Using env: {'DISPLAY': ':0', 13:54:18 INFO - 'HOME': '/home/cltbld', 13:54:18 INFO - 'LANG': 'en_US.UTF-8', 13:54:18 INFO - 'LANGUAGE': 'en_US:en', 13:54:18 INFO - 'LOGNAME': 'cltbld', 13:54:18 INFO - 'MAIL': '/var/mail/cltbld', 13:54:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:18 INFO - 'MOZ_NO_REMOTE': '1', 13:54:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:54:18 INFO - 'NO_EM_RESTART': '1', 13:54:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:54:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:18 INFO - 'PWD': '/builds/slave/test', 13:54:18 INFO - 'SHELL': '/bin/bash', 13:54:18 INFO - 'SHLVL': '1', 13:54:18 INFO - 'TERM': 'linux', 13:54:18 INFO - 'TMOUT': '86400', 13:54:18 INFO - 'USER': 'cltbld', 13:54:18 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509', 13:54:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:18 INFO - '_': '/tools/buildbot/bin/python'} 13:54:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:18 INFO - Downloading/unpacking psutil>=3.1.1 13:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:54:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:54:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:54:20 INFO - Installing collected packages: psutil 13:54:20 INFO - Running setup.py install for psutil 13:54:20 INFO - building 'psutil._psutil_linux' extension 13:54:20 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 13:54:20 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 13:54:20 INFO - building 'psutil._psutil_posix' extension 13:54:20 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 13:54:20 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 13:54:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:54:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:54:20 INFO - Successfully installed psutil 13:54:20 INFO - Cleaning up... 13:54:20 INFO - Return code: 0 13:54:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:54:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:20 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 0x15d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1897180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18953b0>, '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 0x18962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1896740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:54:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:54:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:54:20 INFO - Using env: {'DISPLAY': ':0', 13:54:20 INFO - 'HOME': '/home/cltbld', 13:54:20 INFO - 'LANG': 'en_US.UTF-8', 13:54:20 INFO - 'LANGUAGE': 'en_US:en', 13:54:20 INFO - 'LOGNAME': 'cltbld', 13:54:20 INFO - 'MAIL': '/var/mail/cltbld', 13:54:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:20 INFO - 'MOZ_NO_REMOTE': '1', 13:54:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:54:20 INFO - 'NO_EM_RESTART': '1', 13:54:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:54:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:20 INFO - 'PWD': '/builds/slave/test', 13:54:20 INFO - 'SHELL': '/bin/bash', 13:54:20 INFO - 'SHLVL': '1', 13:54:20 INFO - 'TERM': 'linux', 13:54:20 INFO - 'TMOUT': '86400', 13:54:20 INFO - 'USER': 'cltbld', 13:54:20 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509', 13:54:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:20 INFO - '_': '/tools/buildbot/bin/python'} 13:54:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:54:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:54:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:54:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:54:22 INFO - Installing collected packages: mozsystemmonitor 13:54:22 INFO - Running setup.py install for mozsystemmonitor 13:54:23 INFO - Successfully installed mozsystemmonitor 13:54:23 INFO - Cleaning up... 13:54:23 INFO - Return code: 0 13:54:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:54:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:23 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 0x15d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1897180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18953b0>, '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 0x18962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1896740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:54:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 13:54:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 13:54:23 INFO - Using env: {'DISPLAY': ':0', 13:54:23 INFO - 'HOME': '/home/cltbld', 13:54:23 INFO - 'LANG': 'en_US.UTF-8', 13:54:23 INFO - 'LANGUAGE': 'en_US:en', 13:54:23 INFO - 'LOGNAME': 'cltbld', 13:54:23 INFO - 'MAIL': '/var/mail/cltbld', 13:54:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:23 INFO - 'MOZ_NO_REMOTE': '1', 13:54:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:54:23 INFO - 'NO_EM_RESTART': '1', 13:54:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:54:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:23 INFO - 'PWD': '/builds/slave/test', 13:54:23 INFO - 'SHELL': '/bin/bash', 13:54:23 INFO - 'SHLVL': '1', 13:54:23 INFO - 'TERM': 'linux', 13:54:23 INFO - 'TMOUT': '86400', 13:54:23 INFO - 'USER': 'cltbld', 13:54:23 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509', 13:54:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:23 INFO - '_': '/tools/buildbot/bin/python'} 13:54:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:23 INFO - Downloading/unpacking jsonschema==2.5.1 13:54:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:54:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:54:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:25 INFO - Downloading functools32-3.2.3-2.tar.gz 13:54:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:54:25 INFO - warning: no files found matching '*.txt' 13:54:25 INFO - no previously-included directories found matching 'build' 13:54:25 INFO - no previously-included directories found matching 'dist' 13:54:25 INFO - no previously-included directories found matching '.git*' 13:54:25 INFO - Installing collected packages: jsonschema, functools32 13:54:25 INFO - Running setup.py install for functools32 13:54:25 INFO - warning: no files found matching '*.txt' 13:54:25 INFO - no previously-included directories found matching 'build' 13:54:25 INFO - no previously-included directories found matching 'dist' 13:54:25 INFO - no previously-included directories found matching '.git*' 13:54:25 INFO - Successfully installed jsonschema functools32 13:54:25 INFO - Cleaning up... 13:54:25 INFO - Return code: 0 13:54:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:54:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:25 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 0x15d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1897180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18953b0>, '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 0x18962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1896740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:54:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 13:54:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 13:54:25 INFO - Using env: {'DISPLAY': ':0', 13:54:25 INFO - 'HOME': '/home/cltbld', 13:54:25 INFO - 'LANG': 'en_US.UTF-8', 13:54:25 INFO - 'LANGUAGE': 'en_US:en', 13:54:25 INFO - 'LOGNAME': 'cltbld', 13:54:25 INFO - 'MAIL': '/var/mail/cltbld', 13:54:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:25 INFO - 'MOZ_NO_REMOTE': '1', 13:54:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:54:25 INFO - 'NO_EM_RESTART': '1', 13:54:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:54:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:25 INFO - 'PWD': '/builds/slave/test', 13:54:25 INFO - 'SHELL': '/bin/bash', 13:54:25 INFO - 'SHLVL': '1', 13:54:25 INFO - 'TERM': 'linux', 13:54:25 INFO - 'TMOUT': '86400', 13:54:25 INFO - 'USER': 'cltbld', 13:54:25 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509', 13:54:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:25 INFO - '_': '/tools/buildbot/bin/python'} 13:54:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:54:25 INFO - Cleaning up... 13:54:25 INFO - Return code: 0 13:54:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:54:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:25 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 0x15d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1897180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18953b0>, '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 0x18962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1896740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:54:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:54:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:54:25 INFO - Using env: {'DISPLAY': ':0', 13:54:25 INFO - 'HOME': '/home/cltbld', 13:54:25 INFO - 'LANG': 'en_US.UTF-8', 13:54:25 INFO - 'LANGUAGE': 'en_US:en', 13:54:25 INFO - 'LOGNAME': 'cltbld', 13:54:25 INFO - 'MAIL': '/var/mail/cltbld', 13:54:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:25 INFO - 'MOZ_NO_REMOTE': '1', 13:54:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:54:25 INFO - 'NO_EM_RESTART': '1', 13:54:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:54:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:25 INFO - 'PWD': '/builds/slave/test', 13:54:25 INFO - 'SHELL': '/bin/bash', 13:54:25 INFO - 'SHLVL': '1', 13:54:25 INFO - 'TERM': 'linux', 13:54:25 INFO - 'TMOUT': '86400', 13:54:25 INFO - 'USER': 'cltbld', 13:54:25 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509', 13:54:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:25 INFO - '_': '/tools/buildbot/bin/python'} 13:54:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:26 INFO - Downloading/unpacking blobuploader==1.2.4 13:54:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:27 INFO - Downloading blobuploader-1.2.4.tar.gz 13:54:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:54:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:54:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:54:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:54:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:28 INFO - Downloading docopt-0.6.1.tar.gz 13:54:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:54:28 INFO - Installing collected packages: blobuploader, requests, docopt 13:54:28 INFO - Running setup.py install for blobuploader 13:54:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:54:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:54:28 INFO - Running setup.py install for requests 13:54:29 INFO - Running setup.py install for docopt 13:54:29 INFO - Successfully installed blobuploader requests docopt 13:54:29 INFO - Cleaning up... 13:54:29 INFO - Return code: 0 13:54:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:54:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:29 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 0x15d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1897180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18953b0>, '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 0x18962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1896740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:54:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:54:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:54:29 INFO - Using env: {'DISPLAY': ':0', 13:54:29 INFO - 'HOME': '/home/cltbld', 13:54:29 INFO - 'LANG': 'en_US.UTF-8', 13:54:29 INFO - 'LANGUAGE': 'en_US:en', 13:54:29 INFO - 'LOGNAME': 'cltbld', 13:54:29 INFO - 'MAIL': '/var/mail/cltbld', 13:54:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:29 INFO - 'MOZ_NO_REMOTE': '1', 13:54:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:54:29 INFO - 'NO_EM_RESTART': '1', 13:54:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:54:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:29 INFO - 'PWD': '/builds/slave/test', 13:54:29 INFO - 'SHELL': '/bin/bash', 13:54:29 INFO - 'SHLVL': '1', 13:54:29 INFO - 'TERM': 'linux', 13:54:29 INFO - 'TMOUT': '86400', 13:54:29 INFO - 'USER': 'cltbld', 13:54:29 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509', 13:54:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:29 INFO - '_': '/tools/buildbot/bin/python'} 13:54:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:54:29 INFO - Running setup.py (path:/tmp/pip-pepjqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:54:29 INFO - Running setup.py (path:/tmp/pip-xN8EYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:54:29 INFO - Running setup.py (path:/tmp/pip-xEGs1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:54:29 INFO - Running setup.py (path:/tmp/pip-DUhL0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:54:29 INFO - Running setup.py (path:/tmp/pip-0uCBWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:54:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:54:29 INFO - Running setup.py (path:/tmp/pip-lIIKiJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:54:30 INFO - Running setup.py (path:/tmp/pip-lv2TqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:54:30 INFO - Running setup.py (path:/tmp/pip-L_Af6W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:54:30 INFO - Running setup.py (path:/tmp/pip-CfWV3m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:54:30 INFO - Running setup.py (path:/tmp/pip-8xNFLX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:54:30 INFO - Running setup.py (path:/tmp/pip-gJGs6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:54:30 INFO - Running setup.py (path:/tmp/pip-Jcn1TA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:54:30 INFO - Running setup.py (path:/tmp/pip-M6pkHW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:54:30 INFO - Running setup.py (path:/tmp/pip-nfT5jp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:54:30 INFO - Running setup.py (path:/tmp/pip-vdLS0H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:54:30 INFO - Running setup.py (path:/tmp/pip-5RgQgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:54:30 INFO - Running setup.py (path:/tmp/pip-tlTT3j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:54:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:54:30 INFO - Running setup.py install for manifestparser 13:54:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:54:30 INFO - Running setup.py install for mozcrash 13:54:30 INFO - Running setup.py install for mozdebug 13:54:31 INFO - Running setup.py install for mozdevice 13:54:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:54:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:54:31 INFO - Running setup.py install for mozfile 13:54:31 INFO - Running setup.py install for mozhttpd 13:54:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:54:31 INFO - Running setup.py install for mozinfo 13:54:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:54:31 INFO - Running setup.py install for mozInstall 13:54:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:54:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:54:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:54:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:54:31 INFO - Running setup.py install for mozleak 13:54:31 INFO - Running setup.py install for mozlog 13:54:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:54:31 INFO - Running setup.py install for moznetwork 13:54:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:54:31 INFO - Running setup.py install for mozprocess 13:54:32 INFO - Running setup.py install for mozprofile 13:54:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:54:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:54:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:54:32 INFO - Running setup.py install for mozrunner 13:54:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:54:32 INFO - Running setup.py install for mozscreenshot 13:54:32 INFO - Running setup.py install for moztest 13:54:32 INFO - Running setup.py install for mozversion 13:54:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:54:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:54:32 INFO - Cleaning up... 13:54:32 INFO - Return code: 0 13:54:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:54:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:32 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 0x15d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1897180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18953b0>, '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 0x18962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1896740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:54:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:54:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:54:32 INFO - Using env: {'DISPLAY': ':0', 13:54:32 INFO - 'HOME': '/home/cltbld', 13:54:32 INFO - 'LANG': 'en_US.UTF-8', 13:54:32 INFO - 'LANGUAGE': 'en_US:en', 13:54:32 INFO - 'LOGNAME': 'cltbld', 13:54:32 INFO - 'MAIL': '/var/mail/cltbld', 13:54:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:32 INFO - 'MOZ_NO_REMOTE': '1', 13:54:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:54:32 INFO - 'NO_EM_RESTART': '1', 13:54:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:54:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:32 INFO - 'PWD': '/builds/slave/test', 13:54:32 INFO - 'SHELL': '/bin/bash', 13:54:32 INFO - 'SHLVL': '1', 13:54:32 INFO - 'TERM': 'linux', 13:54:32 INFO - 'TMOUT': '86400', 13:54:32 INFO - 'USER': 'cltbld', 13:54:32 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509', 13:54:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:32 INFO - '_': '/tools/buildbot/bin/python'} 13:54:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:54:32 INFO - Running setup.py (path:/tmp/pip-vDMo1n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:54:32 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 13:54:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:54:32 INFO - Running setup.py (path:/tmp/pip-TMkYYg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:54:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:54:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:54:32 INFO - Running setup.py (path:/tmp/pip-0iqh30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:54:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:54:33 INFO - Running setup.py (path:/tmp/pip-kNrPxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:54:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:54:33 INFO - Running setup.py (path:/tmp/pip-505wou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:54:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 13:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:54:33 INFO - Running setup.py (path:/tmp/pip-WMs7ws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:54:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 13:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:54:33 INFO - Running setup.py (path:/tmp/pip-UVDLh2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:54:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 13:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:54:33 INFO - Running setup.py (path:/tmp/pip-K_ISjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:54:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 13:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:54:33 INFO - Running setup.py (path:/tmp/pip-jIBbC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:54:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 13:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:54:33 INFO - Running setup.py (path:/tmp/pip-7d5ATO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:54:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:54:33 INFO - Running setup.py (path:/tmp/pip-kk8dub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:54:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 13:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:54:33 INFO - Running setup.py (path:/tmp/pip-hC0NHV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:54:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 13:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:54:33 INFO - Running setup.py (path:/tmp/pip-fpl0I1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:54:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 13:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:54:33 INFO - Running setup.py (path:/tmp/pip-XgsepZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:54:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 13:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:54:33 INFO - Running setup.py (path:/tmp/pip-JTDIWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:54:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 13:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:54:33 INFO - Running setup.py (path:/tmp/pip-HxLiPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:54:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 13:54:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:54:33 INFO - Running setup.py (path:/tmp/pip-I_yngD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:54:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 13:54:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:54:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:54:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:54:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:54:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:54:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:54:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:54:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:54:35 INFO - Downloading blessings-1.6.tar.gz 13:54:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:54:36 INFO - Installing collected packages: blessings 13:54:36 INFO - Running setup.py install for blessings 13:54:36 INFO - Successfully installed blessings 13:54:36 INFO - Cleaning up... 13:54:36 INFO - Return code: 0 13:54:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:54:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:54:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:54:36 INFO - Reading from file tmpfile_stdout 13:54:36 INFO - Current package versions: 13:54:36 INFO - argparse == 1.2.1 13:54:36 INFO - blessings == 1.6 13:54:36 INFO - blobuploader == 1.2.4 13:54:36 INFO - docopt == 0.6.1 13:54:36 INFO - functools32 == 3.2.3-2 13:54:36 INFO - jsonschema == 2.5.1 13:54:36 INFO - manifestparser == 1.1 13:54:36 INFO - mozInstall == 1.12 13:54:36 INFO - mozcrash == 0.17 13:54:36 INFO - mozdebug == 0.1 13:54:36 INFO - mozdevice == 0.48 13:54:36 INFO - mozfile == 1.2 13:54:36 INFO - mozhttpd == 0.7 13:54:36 INFO - mozinfo == 0.9 13:54:36 INFO - mozleak == 0.1 13:54:36 INFO - mozlog == 3.3 13:54:36 INFO - moznetwork == 0.27 13:54:36 INFO - mozprocess == 0.23 13:54:36 INFO - mozprofile == 0.28 13:54:36 INFO - mozrunner == 6.12 13:54:36 INFO - mozscreenshot == 0.1 13:54:36 INFO - mozsystemmonitor == 0.3 13:54:36 INFO - moztest == 0.7 13:54:36 INFO - mozversion == 1.4 13:54:36 INFO - psutil == 3.1.1 13:54:36 INFO - requests == 1.2.3 13:54:36 INFO - wsgiref == 0.1.2 13:54:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:54:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:36 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 0x15d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1897180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18953b0>, '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 0x18962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1896740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:54:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 13:54:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:54:36 INFO - Using env: {'DISPLAY': ':0', 13:54:36 INFO - 'HOME': '/home/cltbld', 13:54:36 INFO - 'LANG': 'en_US.UTF-8', 13:54:36 INFO - 'LANGUAGE': 'en_US:en', 13:54:36 INFO - 'LOGNAME': 'cltbld', 13:54:36 INFO - 'MAIL': '/var/mail/cltbld', 13:54:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:36 INFO - 'MOZ_NO_REMOTE': '1', 13:54:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:54:36 INFO - 'NO_EM_RESTART': '1', 13:54:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:54:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:36 INFO - 'PWD': '/builds/slave/test', 13:54:36 INFO - 'SHELL': '/bin/bash', 13:54:36 INFO - 'SHLVL': '1', 13:54:36 INFO - 'TERM': 'linux', 13:54:36 INFO - 'TMOUT': '86400', 13:54:36 INFO - 'USER': 'cltbld', 13:54:36 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509', 13:54:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:36 INFO - '_': '/tools/buildbot/bin/python'} 13:54:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:54:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 13:54:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 13:54:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:54:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 13:54:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 13:54:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 13:54:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 13:54:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 13:54:36 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:54:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:54:36 INFO - Cleaning up... 13:54:36 INFO - Return code: 0 13:54:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:54:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:54:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:54:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:54:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15d6030>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17398a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1897180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18953b0>, '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 0x18962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1896740>, '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': '7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 13:54:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:54:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:54:36 INFO - Using env: {'DISPLAY': ':0', 13:54:36 INFO - 'HOME': '/home/cltbld', 13:54:36 INFO - 'LANG': 'en_US.UTF-8', 13:54:36 INFO - 'LANGUAGE': 'en_US:en', 13:54:36 INFO - 'LOGNAME': 'cltbld', 13:54:36 INFO - 'MAIL': '/var/mail/cltbld', 13:54:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:36 INFO - 'MOZ_NO_REMOTE': '1', 13:54:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:54:36 INFO - 'NO_EM_RESTART': '1', 13:54:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:54:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:36 INFO - 'PWD': '/builds/slave/test', 13:54:36 INFO - 'SHELL': '/bin/bash', 13:54:36 INFO - 'SHLVL': '1', 13:54:36 INFO - 'TERM': 'linux', 13:54:36 INFO - 'TMOUT': '86400', 13:54:36 INFO - 'USER': 'cltbld', 13:54:36 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509', 13:54:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:36 INFO - '_': '/tools/buildbot/bin/python'} 13:54:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:54:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:54:36 INFO - Cleaning up... 13:54:36 INFO - Return code: 0 13:54:36 INFO - Running post-action listener: _resource_record_post_action 13:54:36 INFO - Running post-action listener: _start_resource_monitoring 13:54:36 INFO - Starting resource monitoring. 13:54:36 INFO - [mozharness: 2016-09-21 20:54:36.869984Z] Finished create-virtualenv step (success) 13:54:36 INFO - [mozharness: 2016-09-21 20:54:36.870411Z] Running install step. 13:54:36 INFO - Running pre-action listener: _resource_record_pre_action 13:54:36 INFO - Running main action method: install 13:54:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:54:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:54:37 INFO - Reading from file tmpfile_stdout 13:54:37 INFO - Detecting whether we're running mozinstall >=1.0... 13:54:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:54:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:54:37 INFO - Reading from file tmpfile_stdout 13:54:37 INFO - Output received: 13:54:37 INFO - Usage: mozinstall [options] installer 13:54:37 INFO - Options: 13:54:37 INFO - -h, --help show this help message and exit 13:54:37 INFO - -d DEST, --destination=DEST 13:54:37 INFO - Directory to install application into. [default: 13:54:37 INFO - "/builds/slave/test"] 13:54:37 INFO - --app=APP Application being installed. [default: firefox] 13:54:37 INFO - mkdir: /builds/slave/test/build/application 13:54:37 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'] 13:54:37 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 13:54:51 INFO - Reading from file tmpfile_stdout 13:54:51 INFO - Output received: 13:54:51 INFO - /builds/slave/test/build/application/firefox/firefox 13:54:51 INFO - Running post-action listener: _resource_record_post_action 13:54:51 INFO - [mozharness: 2016-09-21 20:54:51.110605Z] Finished install step (success) 13:54:51 INFO - [mozharness: 2016-09-21 20:54:51.110883Z] Running run-tests step. 13:54:51 INFO - Running pre-action listener: _resource_record_pre_action 13:54:51 INFO - Running main action method: run_tests 13:54:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:54:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:54:51 INFO - Python 2.7.3 13:54:51 INFO - Return code: 0 13:54:51 INFO - grabbing minidump binary from tooltool 13:54:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:54:51 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 0x18953b0>, '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 0x18962b0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1896740>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:54:51 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 13:54:51 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 13:54:51 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 13:54:51 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:54:51 INFO - Return code: 0 13:54:51 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:54:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:54:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:54:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:54:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:54:51 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/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 13:54:51 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/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-005 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 13:54:51 INFO - Using env: {'DISPLAY': ':0', 13:54:51 INFO - 'HOME': '/home/cltbld', 13:54:51 INFO - 'LANG': 'en_US.UTF-8', 13:54:51 INFO - 'LANGUAGE': 'en_US:en', 13:54:51 INFO - 'LOGNAME': 'cltbld', 13:54:51 INFO - 'MAIL': '/var/mail/cltbld', 13:54:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:54:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:54:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:51 INFO - 'MOZ_NO_REMOTE': '1', 13:54:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:54:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:54:51 INFO - 'NO_EM_RESTART': '1', 13:54:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:54:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:51 INFO - 'PWD': '/builds/slave/test', 13:54:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:54:51 INFO - 'SHELL': '/bin/bash', 13:54:51 INFO - 'SHLVL': '1', 13:54:51 INFO - 'TERM': 'linux', 13:54:51 INFO - 'TMOUT': '86400', 13:54:51 INFO - 'USER': 'cltbld', 13:54:51 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509', 13:54:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:51 INFO - '_': '/tools/buildbot/bin/python'} 13:54:51 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/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 13:54:51 INFO - mozversion application_buildid: 20160921110159 13:54:51 INFO - mozversion application_changeset: eb11246a0e01ac22ccb15dff47616d9e7e6d5dc2 13:54:51 INFO - mozversion application_display_name: Firefox 13:54:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:54:51 INFO - mozversion application_name: Firefox 13:54:51 INFO - mozversion application_remotingname: firefox 13:54:51 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 13:54:51 INFO - mozversion application_vendor: Mozilla 13:54:51 INFO - mozversion application_version: 50.0 13:54:51 INFO - mozversion platform_buildid: 20160921110159 13:54:51 INFO - mozversion platform_changeset: eb11246a0e01ac22ccb15dff47616d9e7e6d5dc2 13:54:51 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 13:54:51 INFO - mozversion platform_version: 50.0 13:54:51 INFO - using testdate: 1474491291 13:54:51 INFO - actual date: 1474491291 13:54:51 INFO - starting webserver on 'localhost:60002' 13:54:51 INFO - SUITE-START | Running 2 tests 13:54:51 INFO - TEST-START | dromaeo_css 13:54:51 INFO - operating with platform_type : linux_ 13:54:51 INFO - Initialising browser for dromaeo_css test... 13:54:51 INFO - TEST-INFO | started process 2301 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMzdnUF/profile http://localhost:60002/getInfo.html) 13:54:55 INFO - PROCESS | 2301 | __metrics Screen width/height:1600/1200 13:54:55 INFO - PROCESS | 2301 | colorDepth:24 13:54:55 INFO - PROCESS | 2301 | Browser inner width/height: 1024/697 13:54:55 INFO - PROCESS | 2301 | __metrics 13:54:55 INFO - PROCESS | 2301 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:54:55 INFO - PROCESS | 2301 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:54:56 INFO - TEST-INFO | 2301: exit 0 13:54:56 INFO - Browser initialized. 13:54:56 INFO - Running cycle 1/1 for dromaeo_css test... 13:54:56 INFO - TEST-INFO | started process 2461 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpMzdnUF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:55:08 INFO - PROCESS | 2461 | RSS: Main: 143626240 13:55:08 INFO - PROCESS | 2461 | 13:55:59 INFO - PROCESS | 2461 | 1474491359429 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 13:58:08 INFO - PROCESS | 2461 | Cycle 1(1): loaded http://localhost:60002/tests/dromaeo/cssquery-dojo.html (next: http://localhost:60002/tests/dromaeo/cssquery-ext.html) 13:58:09 INFO - PROCESS | 2461 | RSS: Main: 271802368 13:58:09 INFO - PROCESS | 2461 | 14:01:11 INFO - PROCESS | 2461 | Cycle 1(1): loaded http://localhost:60002/tests/dromaeo/cssquery-ext.html (next: http://localhost:60002/tests/dromaeo/cssquery-jquery.html) 14:01:12 INFO - PROCESS | 2461 | RSS: Main: 236687360 14:01:12 INFO - PROCESS | 2461 | 14:04:14 INFO - PROCESS | 2461 | Cycle 1(1): loaded http://localhost:60002/tests/dromaeo/cssquery-jquery.html (next: http://localhost:60002/tests/dromaeo/cssquery-mootools.html) 14:04:14 INFO - PROCESS | 2461 | RSS: Main: 208203776 14:04:14 INFO - PROCESS | 2461 | 14:07:16 INFO - PROCESS | 2461 | Cycle 1(1): loaded http://localhost:60002/tests/dromaeo/cssquery-mootools.html (next: http://localhost:60002/tests/dromaeo/cssquery-prototype.html) 14:07:17 INFO - PROCESS | 2461 | RSS: Main: 221917184 14:07:17 INFO - PROCESS | 2461 | 14:10:19 INFO - PROCESS | 2461 | Cycle 1(1): loaded http://localhost:60002/tests/dromaeo/cssquery-prototype.html (next: http://localhost:60002/tests/dromaeo/cssquery-yui.html) 14:10:20 INFO - PROCESS | 2461 | RSS: Main: 175742976 14:10:20 INFO - PROCESS | 2461 | 14:13:22 INFO - PROCESS | 2461 | Cycle 1(1): loaded http://localhost:60002/tests/dromaeo/cssquery-yui.html (next: http://localhost:60002/tests/dromaeo/cssquery-dojo.html) 14:13:22 INFO - PROCESS | 2461 | RSS: Main: 263188480 14:13:22 INFO - PROCESS | 2461 | 14:13:22 INFO - PROCESS | 2461 | __start_tp_report 14:13:22 INFO - PROCESS | 2461 | _x_x_mozilla_page_load 14:13:22 INFO - PROCESS | 2461 | _x_x_mozilla_page_load_details 14:13:22 INFO - PROCESS | 2461 | |i|pagename|runs| 14:13:22 INFO - PROCESS | 2461 | |0;dojo.html;3182.602174728159;3187.633422156451;3189.441847676656;3229.5963004624423;3236.158598764322;5523.447655234475;5575.330960284761;5630.296212973379;5630.324361076667;5634.3802181759975;5625.128105143699;5696.25948626679;5701.2018317435595;5711.857203569913;5750.712464376786;4976.651634385595;4991.076650819592;5068.239764035387;5147.510986456281;5203.063448579254;5028.974855125737;5058.898822023557;5082.491750824925;5114.820981265657;5121.66709163905;5010.423801262865;5051.090803655341;5091.032703786287;5107.514786095334;5112.948870511303;5175.767090480918;5184.351956005499;5189.42916279209;5192.870178245574;5223.425423203445;4904.5831104356175;4985.5513003829465;5013.89972200554;5087.058894104565;5090.643654944155;5514.393416724157;5515.09001014832;5536.889262214773;5540.861478463071;5541.307336582927;6023.036314189752;6045.818625441244;6056.091586262052;6057.878842423171;6058;5827.883442331172;5960.314563825173;6024.277086749575;6046.455818976269;6080.513558915276;5675.659460432387;5693.3168019837885;5708.857278568069;5719.771209151629;5734.6845923473775;6152.538559608047;6154.076888466766;6154.599951003171;6156.784512542129;6157.907631385443;5730.455606717439;5779.971100144472;5831.125331200354;5834.358220595731;5962.850928726816;5978.970105149447;5979.372165922602;5980.282366116007;5982.581219314607;6017.969910150421;5942.346341902387;5953.077273022688;5954.821355359346;5958.821235362946;5964.075568286922;5744.770209191669;5895.793647222326;5923.911141332884;5927.4665280125;5947.107933809876;4593.19619066662;4619.23782575878;4625.074985003012;4632.69887457321;4654.534546545387;4112.629863312316;4115.5678653741525;4117.155983023473;4123.463949686521;4149.481314835645;5540.972295138579;5658.943410565924;5699.287589051369;5768.192453056576;5773.624714393551;5964.075568286922;6009.06859436788;6022.427869352405;6022.5784195105925;6055.182550355734;5738.885222295531;5749.655020698771;5762.827115186551;5769.105788602773;5769.134629805562;4996.075725990703;5101;5138.794448222029;5192.688438693691;5203.8178663746585;6210.037444196157;6246.593971391845;6266.342034086355;6270.749170033238;6279.874402511924;6006;6016.87966240664;6030.4572588467245;6043.395660433922;6051.394860513913;6009.90985135232;6123.571350005457;6140.166841500682;6193.828153491885;6223.471004964629;5946.583739138392;5956.374580668968;5962.284525856925;5980.282366116094;6001.4298641628975;5989.460948514655;6015.308239552507;6046.183765191643;6046.820869930321;6052.818415447544;5731.656100633975;5772.307323121252;5792.884142317214;5902.852428689317;5911.881762364815;5849.444302791228;5855.560832937632;5858;5883.176355310175;5894.2926848778425;5806.883862322815;5807.76768929255;5812.041013232769;5814.069748840166;5815.505682017076;5728.627639203439;5815.5929084963645;5839.240898683144;5861.560382971209;5890.764369425175;5760.049591817303;5766.942330576809;5767.596268261349;5770.971145144247;5796.333421656394 14:13:22 INFO - PROCESS | 2461 | |1;ext.html;590.3506143242433;640.5772190354367;656.9638669873156;662.2251965200714;667.5427332277388;34946;38215.426768598496;38242.04394890126;46236.76881615587;47373.526264737346;49400.75299623506;49474.50525494744;49862.50137498624;49953;49973;9259.490728009974;9335.626574937029;9412.62349506019;9421.199198068172;9423.057694230556;8512.14878512147;10856.511456984455;11166.832497512503;11172.217944743872;11500.022498087672;11364.886351136465;11430.142739294544;11445.885541144566;11447.942760286229;11468.19722619417;11172.944135279313;11183.776324473547;11209.439528023566;11250.774984500305;11254.324740515594;11022.889771102347;11094.057005154571;11096.500657470433;11098.167637427145;11102.278351907182;46768.532314676755;47046.2943055851;47151.29273060907;47178;47198.05603887903;3759.1166075972124;3802.543694756619;3878.340682084024;4100.17996400721;4545.977270113662;36713.265734685425;37184;37219;38690.806545967374;38953.80523097395;4315.503717072514;4363;4388.912221755547;4523.185826551219;4578.633709303248;359382;360800;361336;361684;363076;65646;66029;66078.66960665166;66097.33902660912;66106;39760.20479590392;40717.796411017756;40723;42968.570314296456;42998.78500607539;17029.659406811796;17188.140592970303;17203.483895483154;17206.913965430344;17208.31166753341;11146.386948717736;11681.357525336189;12495.562655307021;12562.183458075197;12568.245905245714;20238.494037649176;21126;21258;21288.254911078348;21377.251796187058;7406.55560666351;7468.701251949861;7590.468667193243;7611.961940190264;7677.9232207677205;1023.9692809215735;1070.550368845087;1145.0438883532177;1150.0908491903567;1165.516310731054;35030.824845875606;35037;35318.117047074025;35368.82315588457;35420.645793541735;1170.6605084525563;1181.911356648255;1213.245006704249;1216.9634910952686;1237.4740735187509;18134.637307253783;18502.167402466923;18524.351647692267;18583.628327433376;18715.81284187168;616394;620510;620740;621838;623451;64572;65496.34503654998;65574.67212663907;65598.34401656018;65621.67189164023;1334.9799753003713;1378.5657517881834;1383.4189640351076;1392.2899321346185;1451.267110109388;34080.48879266763;36215.21298979722;36448.27103457969;36882.44676329804;37320.44019339766;56726.43273567211;57013.71493142508;57135.142972854606;57311.71344143253;57343.13985290308;479912;480977;483975;485847;528687;31628.841855790575;32303.35393292074;32349.191270218435;33827.32345353128;33865.15337116592;54528;54605;54719.452805473025;54745.452545474036;54865.45134548763;44821.32768008548;46687.76656116698;47104.528954711386;47883;47958.76020619875;46267.305990410845;46398.53601463942;46674.29988550243;47188;47454.05091898212;44895.32657010213;44934.32598510959;45194.77402612966;45480.54519454763;45522;39349.2130157401;40248.3962740565;40633.59366406298;41023.58976410198;41075.58924410718;29723.702762972094;30201.395972080874;30207.848960755055;30287.394252115275;30344.696553034188 14:13:22 INFO - PROCESS | 2461 | |2;jquery.html;410.49714100227226;411.24536475567334;411.32542630086664;411.38908720549983;434.2227412930854;19604.215831366746;21139.894300528515;21213.893930530347;21388.25141120061;21972.121115155416;19776.901115494402;20232.898835505803;20437.693434598492;20514.589708205825;20563.794362056375;5627.493525582707;5808.2449281593335;5810.651360918338;5812.680302583356;5837.182794408786;5439.48324909133;5669.433056694318;5700.315962084555;5792.7972520961775;5834.649921004732;5693.259876216107;5723.885522289552;5741.311042674884;5759.68321742304;5767.45209205127;5671.262735844334;5715.71421428929;5840.649561026352;5977.731002104916;5983.521318294526;5660.179274005287;5812.767489300423;5813.418658134153;5859.7949071782705;5868.383819698913;13526.256055916921;13814.861851381458;13819.654508637264;22579.435514112116;24606.138785142433;2300.229423143249;2357.3752955466816;2527.3934255778663;2672.599110133476;2898.9130326090253;9897;10282.12601928837;10308.845367319496;10745.355278683226;16145.515634530866;2048.2114385961513;2140.625390556662;2171.457135716071;2176.1077958037285;2243.9887800561055;64882;90992.54503727505;91299.54350228273;93425;100585.4970725149;18449;18920.43238702841;19089.427317180507;19241.4227573173;20001.59996800056;33794.49308260378;34054.48918266228;34173;34224.48663270053;34426.13934651654;9602;9970;9973.401595904268;10217.897821021843;10504;8270.503769773833;8282.088970213272;8354.707585234488;8520.829583408296;8642.481451112953;8923.866142007746;9218.21645160169;9411.43531388119;9550.713478595653;9623.759406014906;3327.7670563060356;3575.94636080459;3624.3113808376324;3678.319510890494;3739.8691045813266;445.09867518275104;454.0714239380475;469.12976428724227;469.2562288772266;983.8524221366853;12252.938735306445;12394.194377365442;12406.75186496265;12454.31501267421;12483.876451119442;455.8427144442638;461.1238646571541;486.6642017008253;493.4054464370425;495.8363739965803;6344;6494.772682956168;6528.543001989815;6529.7388104475285;6584.11114499546;593428;596720;606195;628515;633208;89902;91689.5415522918;93560;100807;101705;1023.8515415264745;1068.4711068021224;1075.499892549968;1076.0530732954949;1078.2075174746556;18352.541186470444;18592.721109183643;18849.528761780515;18906.338278160198;18922.621547569248;31719.841400792848;36044.4593331095;43194.1361172781;45254.54745452635;45285.54714452814;60344;63931.680341597996;64425;65301.67349163224;65841.34158658353;7895.447318687637;8090;9079.727608171765;9169.449833009774;9482.85775713351;12529.749405012031;13283;14082.436702532194;14802.62993425173;14850.925745371203;6071.908921366271;11104.666859994213;12620.36898155107;12942.805857912328;13010.544630937871;12208.572699955459;12627.116101873147;12878.484860605471;13101.148425352321;13340.866591333963;12404.81392779091;13116.409761560944;13277.535286264934;13394.9330253352;13518.662033448849;7601.3918886490055;7987.720429784929;9001.639934402812;9292.72121836346;9564.282678798978;12881.677958051123;12913.225206487641;12913.677158071123;13013.544525941546;13352.399142038437 14:13:22 INFO - PROCESS | 2461 | |3;mootools.html;1420.9644758881034;1513.9243037848105;1536.1013806922952;1682.3775203174828;1841.9355322563713;8655.35084868635;9038.18656320931;9146.222571081458;9392.71821845344;9405.341626086176;9133.634654613825;9218.492982885939;9244.02937691541;9248.861267080982;9257.07429257074;2486.925392238236;2530.544501989641;2531.4304281536693;2533.024785457603;2550.987245063772;2437.463757973252;2500.949981000379;2508.2224510401743;2508.3854455658297;2508.7240403555593;2467.383154211447;2476.6037434010564;2537.264193383926;2539.6317533957567;2542.2373288013614;2389.1160270699866;2397.472556037678;2408.819338549607;2455.9631605525933;2463.7905778008712;2418.7702168293986;2421.128393778238;2455.938601534976;2457.913973010936;2458.299384675359;9082.77293067672;9149.634014639407;9272.304577156672;9277.860832087525;9278.164965153168;5443.564514838803;5579.609427340088;5600.131979543177;5613.578981576357;5623.297087864017;3676.5955744868043;3740.663340299386;3778.1876896467115;3805.7906815125157;3809.5047643806406;1153.0832987774656;1153.6712037069478;1163.0521125282867;1178.116412690482;2175.7715439878743;12991.61025169237;13027.739445211137;13119.737605247938;13138.737225255441;13154.934225328907;4713.2458806590785;4727;4801.303810947393;4989.725565093955;5046.949530504648;10564.630237941636;10582.841257381146;10600.734981625521;10603.257771956043;10619.522121504551;5760.683162426107;5762.049261871828;5764.7029418380525;5768.625039372428;5768.625039372428;4594.701344412602;4605.907881842411;5107.131787596118;5170.276161337416;5404.702741349185;4080.204360149742;4092.570280120604;4113.382992551082;4151.626353628188;4186.706930514834;1224.7611715715527;1251.7058491254547;1252.4927404401233;1270.358468973181;1276.24063682109;896.7264984179757;975.7609385700549;990.1138481059412;1028.141501845952;1040.5265604150093;6030.698465076729;6048.727807248596;6196.3803619638575;6222.097795819581;6283.937160628427;492.2370325994694;514.26460161968;514.7234857553289;517.4851023231834;518.3158231134865;4215.536291007987;4293.205756934977;4349.043210493686;4359.520452750195;4400.44994375703;11029.062529685067;11383.54465821358;11504.194706370641;11528.711782205344;11547;10977.231593788518;11466.369349685847;11500.424978751196;11502.07983361329;11502.654920352235;8623.267022303175;9149.5882685278;9156.267498600097;9163.58763855628;9168.312376571785;6090.786822461193;6160.784372546939;6295.622262664255;6479.481641468766;6553.967230163818;10614.73463163427;10684.89315106839;10909.945450272699;10923.180761442765;10950;9722.854157187789;10046.346987445793;10878.020978112008;11137.498812553617;11205.43972801373;8100.392470564849;8219.342452603896;8273.089960104504;8329.83340333202;8390.035145958049;9006.684766033157;9019.458832470073;9053.321000924825;9058.275337973078;9072.047435019227;7938.563379013978;8037.196280371916;8040.557769322743;8210.63052162642;8246.299064579578;7815.6092195388865;8177.959110204649;8201.79495512617;8289.4197406181;8391.16088391156;7638.541687498768;7660.157382687788;7676.424268179797;7748.496347737379;7764.728234511765;5402.540784033401;5499.147632116868;5537.335519737658;5551.389347171727;5563.360213575491;5423.864403389947;5519.448055194448;5544.3346798384455;5574.358948720949;5654.802081927113 14:13:22 INFO - PROCESS | 2461 | |4;prototype.html;340.787008119925;344.2220581485842;346.83525325470396;348.82907375386037;355.9288142371525;5431.375391829941;5543.205127564658;5545.1682247662875;5572.219889215513;5583.86040348991;5389.326334208224;5422.620416570841;5445.591580631449;5450.400455949843;5472.26124473197;4063.5936406359274;4342.261815491358;4354.216241076605;4367.794263896736;4394.055278115205;4106.958930410688;4197.18154959783;4287.678424118188;4294.592013758688;4319.2441322768545;4255.63827074699;4262.9147417051645;4269.0789144884775;4271.722338048033;4279.679024073192;4254.957535403815;4281.678874084456;4287.849925252601;4307.289297265947;4309.35359696045;3583.211693427442;3750.493683352767;3751.8124093795473;3759.3797023491093;3804.619538046201;5317.46825317469;5341.813036543741;5349.384820745586;5375;5390.652336915771;4849.660523763338;4886.828960986384;4925.7783399747095;4928.556429921325;4935.777889994959;5489.149181876815;5652.72813616937;5660.801871934504;5703.429657034305;5723.77104915807;4121.753644263883;4139.3583994480905;4147.087640719037;4147.4815648044;4165.312723400635;5715.542756579505;5922.911156332615;5940.079287710412;5960.195373624549;5994.55040871932;4171.395147703627;4175.770332631734;4176.081262122329;4176.2691528982305;4188.895277618084;4224.366345048206;4230.196262710137;4251.341042138473;4270.743755374688;4280.5505421930875;4842.539958703917;4848.200046992278;4865.245886887538;4882.780274887638;4897.681650692765;5661.886762264731;5683.943160568341;5699.487046165865;5701.315842098975;5771.826845194651;1439.7768345906402;1449.9782503262459;1453.1644304524941;1473.8820894328428;1478.9277773614258;895.8611415230649;927.7263207353847;928.2342067794096;934.0332755597924;949.5110018340561;4930.6795058321095;4952.851414457572;5390.380106287748;5512.173174023928;5608;5819.359870414251;5824.6796426196115;5836.795712150054;5853.2976042875125;5880.558958078162;5590.888182236414;5594.748236329375;5598.188262701967;5603.971980140073;5616.241807356037;2964.985175074111;3104;3112.486439737463;3116.6415862175686;3119.5632611434417;6062.6352374431735;6066.66633335162;6068.878622427526;6086.786962456293;6102;5838.970805146032;5853.358894717772;5868.823935281948;5962.940370596239;6015.428534289235;6020.608660437058;6044.848878777978;6063.423974722395;6077.696115194222;6096.451319381277;5591.804286849941;5803;5843.766249349978;5855.858107669134;5884.823035393024;5802.448767367094;5809.390014048464;5816.214811000461;5817.534597232296;5887.52899768009;6070.7875224366935;6078.635281883101;6081.239845019372;6121.877562448637;6162.22972128484;4145.730527515701;4239.15216956616;4240.872773816791;4275.551067137906;4281.614654681093;5890.558208134493;5894.587378883437;5898.498627616701;5900.173975643327;5904.0553511437965;5622.465865742748;5790.420957904176;5801.839632073517;5832.9708351457975;5845.561582881216;5715.2855893013375;5750;5750.763585829067;5751.252337196138;5776.56675749312;5705.857353566194;5716.28546431696;5722.513586345207;5730.799422020209;5749.885002300014;5421.864453388697;5582.804601838916;5690.544756419559;5788.27646544182;5846.532277417881;5575.606098475381;5583.302087239095;5596.720163991866;5616.69108199053;5622.172793842099 14:13:22 INFO - PROCESS | 2461 | |5;yui.html;716.0834132310641;721.0986267166043;776.8019155115445;802.807326241702;804.585638396226;16696.332146714132;18254.908725456353;18281.908590457064;18296.359627413043;18297.35959241427;16496;16649.66700665986;16666;16668.83331166688;16682.83317166828;738.4424759306715;858.9527575983318;864.9610767515477;878.8154487557621;879.7624641346832;867.5185272173941;874.8643960186149;876.6318146378536;880.6653471680752;882.2324577617482;936.4662142578733;938.5776400619715;940.3229674634252;940.5673390240497;940.7977284883749;932.2355668351954;935.256471105466;936.1574582875398;936.3539157980971;936.559816886059;686.4439803758971;687.6596084937938;687.7730348984823;687.8349196192878;687.9862402751917;15924.920375398166;15937.760933586005;16020.4392846251;16054.197290135446;16067.196640168062;14475;14492.710145797131;14712.411503539952;14900.70198596022;14973.326200320902;14412.567622971328;14413.78379324311;14549;14676.559703208814;14741.11553306805;4765.785539650724;4775.904481910342;4815.807367705288;4869.732164730938;4917.631177661689;85076;86089.56955215184;88052.5597372009;89623.55188224082;89676;11673.182877198515;11870.347130907883;11939;12720.618381448572;13032.609021729364;22629.547409051727;22631.094756209895;22667;22673.88663056674;22705.432364190696;8758.47449153053;8910.732678019669;8951.104889510996;8988.370814043084;9289.721308360626;4985.376827896513;5137.357830271216;5208.609354298367;5264.92102618461;5308.46915308466;3782.5650050244026;3887.6501114899793;3889.922201555953;3890.358090915024;3891.0855948851995;649.1852724830308;654.1398061549048;661.0777964739162;667.209356912051;669.0733334331985;626.3329554024949;722.0973782771536;725.419664268583;725.9818504537429;727.519836907649;9254;9632;9756.512174391251;9902.30683852138;10060.346077504939;607.1742430294884;607.3774381259244;692.0000599134303;708.143146792387;759.2559291893975;4663.556962088596;4828.251620998751;4871.098846713369;4926.9014619707405;4979.950200498021;74904.62547687227;111463.44268278607;136141;137181;140533;70276.29723702697;86879.56560217158;112253;112961.43519282351;119465.40267298782;103808.92382152466;104867;105165.99999999847;107104.46447767712;108862.45568772106;9483.430994140374;9717.465539395402;9722.951385243028;9751.122398984126;9798.608055677978;59888.101678473984;60916;61645.38354616396;62066.379336207865;62558.374416255254;85429;89389.55305223432;89931;90430.54784726034;91811;11605.419729013684;11946.34295113777;12159.513619455123;12287.508499659914;12494.937525312314;14016.509422170175;14035.228062456663;14045.859541404456;14141.36363863608;14602;11945.82081268799;11978.221415607957;12241.265524068584;12880.677983050498;13002.219866807643;12859.16415432994;13168.60494185176;13305.401256943267;13587;13588.660283492993;13111.475540978254;13330.466781328638;13571.86428135706;13688.110272832235;13871.722565548835;10802.783944321227;10836.729081773019;10843.512041958289;10887.564497420015;11433.771324573296;10943.890561094287;10948.616798412017;11604.419779011185;11635.360055197045;11681 14:13:22 INFO - PROCESS | 2461 | __end_tp_report 14:13:22 INFO - PROCESS | 2461 | __start_cc_report 14:13:22 INFO - PROCESS | 2461 | _x_x_mozilla_cycle_collect,1081 14:13:22 INFO - PROCESS | 2461 | __end_cc_report 14:13:22 INFO - PROCESS | 2461 | __startTimestamp1474492402939__endTimestamp 14:13:22 INFO - PROCESS | 2461 | 14:13:22 INFO - PROCESS | 2461 | ------- Summary: start ------- 14:13:22 INFO - PROCESS | 2461 | Number of tests: 6 14:13:22 INFO - PROCESS | 2461 | 14:13:22 INFO - PROCESS | 2461 | [#0] dojo.html Cycles:165 Average:5572.37 Median:5772.31 stddev:635.15 (11.0%) stddev-sans-first:608.80 14:13:22 INFO - PROCESS | 2461 | Values: 3182.6 3187.6 3189.4 3229.6 3236.2 5523.4 5575.3 5630.3 5630.3 5634.4 5625.1 5696.3 5701.2 5711.9 5750.7 4976.7 4991.1 5068.2 5147.5 5203.1 5029.0 5058.9 5082.5 5114.8 5121.7 5010.4 5051.1 5091.0 5107.5 5112.9 5175.8 5184.4 5189.4 5192.9 5223.4 4904.6 4985.6 5013.9 5087.1 5090.6 5514.4 5515.1 5536.9 5540.9 5541.3 6023.0 6045.8 6056.1 6057.9 6058.0 5827.9 5960.3 6024.3 6046.5 6080.5 5675.7 5693.3 5708.9 5719.8 5734.7 6152.5 6154.1 6154.6 6156.8 6157.9 5730.5 5780.0 5831.1 5834.4 5962.9 5979.0 5979.4 5980.3 5982.6 6018.0 5942.3 5953.1 5954.8 5958.8 5964.1 5744.8 5895.8 5923.9 5927.5 5947.1 4593.2 4619.2 4625.1 4632.7 4654.5 4112.6 4115.6 4117.2 4123.5 4149.5 5541.0 5658.9 5699.3 5768.2 5773.6 5964.1 6009.1 6022.4 6022.6 6055.2 5738.9 5749.7 5762.8 5769.1 5769.1 4996.1 5101.0 5138.8 5192.7 5203.8 6210.0 6246.6 6266.3 6270.7 6279.9 6006.0 6016.9 6030.5 6043.4 6051.4 6009.9 6123.6 6140.2 6193.8 6223.5 5946.6 5956.4 5962.3 5980.3 6001.4 5989.5 6015.3 6046.2 6046.8 6052.8 5731.7 5772.3 5792.9 5902.9 5911.9 5849.4 5855.6 5858.0 5883.2 5894.3 5806.9 5807.8 5812.0 5814.1 5815.5 5728.6 5815.6 5839.2 5861.6 5890.8 5760.0 5766.9 5767.6 5771.0 5796.3 14:13:22 INFO - PROCESS | 2461 | 14:13:22 INFO - PROCESS | 2461 | [#1] ext.html Cycles:180 Average:66402.85 Median:39554.71 stddev:133509.61 (337.5%) stddev-sans-first:133792.70 14:13:22 INFO - PROCESS | 2461 | Values: 590.4 640.6 657.0 662.2 667.5 34946.0 38215.4 38242.0 46236.8 47373.5 49400.8 49474.5 49862.5 49953.0 49973.0 9259.5 9335.6 9412.6 9421.2 9423.1 8512.1 10856.5 11166.8 11172.2 11500.0 11364.9 11430.1 11445.9 11447.9 11468.2 11172.9 11183.8 11209.4 11250.8 11254.3 11022.9 11094.1 11096.5 11098.2 11102.3 46768.5 47046.3 47151.3 47178.0 47198.1 3759.1 3802.5 3878.3 4100.2 4546.0 36713.3 37184.0 37219.0 38690.8 38953.8 4315.5 4363.0 4388.9 4523.2 4578.6 359382.0 360800.0 361336.0 361684.0 363076.0 65646.0 66029.0 66078.7 66097.3 66106.0 39760.2 40717.8 40723.0 42968.6 42998.8 17029.7 17188.1 17203.5 17206.9 17208.3 11146.4 11681.4 12495.6 12562.2 12568.2 20238.5 21126.0 21258.0 21288.3 21377.3 7406.6 7468.7 7590.5 7612.0 7677.9 1024.0 1070.6 1145.0 1150.1 1165.5 35030.8 35037.0 35318.1 35368.8 35420.6 1170.7 1181.9 1213.2 1217.0 1237.5 18134.6 18502.2 18524.4 18583.6 18715.8 616394.0 620510.0 620740.0 621838.0 623451.0 64572.0 65496.3 65574.7 65598.3 65621.7 1335.0 1378.6 1383.4 1392.3 1451.3 34080.5 36215.2 36448.3 36882.4 37320.4 56726.4 57013.7 57135.1 57311.7 57343.1 479912.0 480977.0 483975.0 485847.0 528687.0 31628.8 32303.4 32349.2 33827.3 33865.2 54528.0 54605.0 54719.5 54745.5 54865.5 44821.3 46687.8 47104.5 47883.0 47958.8 46267.3 46398.5 46674.3 47188.0 47454.1 44895.3 44934.3 45194.8 45480.5 45522.0 39349.2 40248.4 40633.6 41023.6 41075.6 29723.7 30201.4 30207.8 30287.4 30344.7 14:13:22 INFO - PROCESS | 2461 | 14:13:22 INFO - PROCESS | 2461 | [#2] jquery.html Cycles:180 Average:33611.61 Median:410.87 stddev:100466.25 (24452.0%) stddev-sans-first:100717.15 14:13:22 INFO - PROCESS | 2461 | Values: 410.5 411.2 411.3 411.4 434.2 19604.2 21139.9 21213.9 21388.3 21972.1 19776.9 20232.9 20437.7 20514.6 20563.8 5627.5 5808.2 5810.7 5812.7 5837.2 5439.5 5669.4 5700.3 5792.8 5834.6 5693.3 5723.9 5741.3 5759.7 5767.5 5671.3 5715.7 5840.6 5977.7 5983.5 5660.2 5812.8 5813.4 5859.8 5868.4 13526.3 13814.9 13819.7 22579.4 24606.1 2300.2 2357.4 2527.4 2672.6 2898.9 9897.0 10282.1 10308.8 10745.4 16145.5 2048.2 2140.6 2171.5 2176.1 2244.0 64882.0 90992.5 91299.5 93425.0 100585.5 18449.0 18920.4 19089.4 19241.4 20001.6 33794.5 34054.5 34173.0 34224.5 34426.1 9602.0 9970.0 9973.4 10217.9 10504.0 8270.5 8282.1 8354.7 8520.8 8642.5 8923.9 9218.2 9411.4 9550.7 9623.8 3327.8 3575.9 3624.3 3678.3 3739.9 445.1 454.1 469.1 469.3 983.9 12252.9 12394.2 12406.8 12454.3 12483.9 455.8 461.1 486.7 493.4 495.8 6344.0 6494.8 6528.5 6529.7 6584.1 593428.0 596720.0 606195.0 628515.0 633208.0 89902.0 91689.5 93560.0 100807.0 101705.0 1023.9 1068.5 1075.5 1076.1 1078.2 18352.5 18592.7 18849.5 18906.3 18922.6 31719.8 36044.5 43194.1 45254.5 45285.5 60344.0 63931.7 64425.0 65301.7 65841.3 7895.4 8090.0 9079.7 9169.4 9482.9 12529.7 13283.0 14082.4 14802.6 14850.9 6071.9 11104.7 12620.4 12942.8 13010.5 12208.6 12627.1 12878.5 13101.1 13340.9 12404.8 13116.4 13277.5 13394.9 13518.7 7601.4 7987.7 9001.6 9292.7 9564.3 12881.7 12913.2 12913.7 13013.5 13352.4 14:13:22 INFO - PROCESS | 2461 | 14:13:22 INFO - PROCESS | 2461 | [#3] mootools.html Cycles:180 Average:6133.01 Median:2646.77 stddev:3487.94 (131.8%) stddev-sans-first:3479.75 14:13:22 INFO - PROCESS | 2461 | Values: 1421.0 1513.9 1536.1 1682.4 1841.9 8655.4 9038.2 9146.2 9392.7 9405.3 9133.6 9218.5 9244.0 9248.9 9257.1 2486.9 2530.5 2531.4 2533.0 2551.0 2437.5 2500.9 2508.2 2508.4 2508.7 2467.4 2476.6 2537.3 2539.6 2542.2 2389.1 2397.5 2408.8 2456.0 2463.8 2418.8 2421.1 2455.9 2457.9 2458.3 9082.8 9149.6 9272.3 9277.9 9278.2 5443.6 5579.6 5600.1 5613.6 5623.3 3676.6 3740.7 3778.2 3805.8 3809.5 1153.1 1153.7 1163.1 1178.1 2175.8 12991.6 13027.7 13119.7 13138.7 13154.9 4713.2 4727.0 4801.3 4989.7 5046.9 10564.6 10582.8 10600.7 10603.3 10619.5 5760.7 5762.0 5764.7 5768.6 5768.6 4594.7 4605.9 5107.1 5170.3 5404.7 4080.2 4092.6 4113.4 4151.6 4186.7 1224.8 1251.7 1252.5 1270.4 1276.2 896.7 975.8 990.1 1028.1 1040.5 6030.7 6048.7 6196.4 6222.1 6283.9 492.2 514.3 514.7 517.5 518.3 4215.5 4293.2 4349.0 4359.5 4400.4 11029.1 11383.5 11504.2 11528.7 11547.0 10977.2 11466.4 11500.4 11502.1 11502.7 8623.3 9149.6 9156.3 9163.6 9168.3 6090.8 6160.8 6295.6 6479.5 6554.0 10614.7 10684.9 10909.9 10923.2 10950.0 9722.9 10046.3 10878.0 11137.5 11205.4 8100.4 8219.3 8273.1 8329.8 8390.0 9006.7 9019.5 9053.3 9058.3 9072.0 7938.6 8037.2 8040.6 8210.6 8246.3 7815.6 8178.0 8201.8 8289.4 8391.2 7638.5 7660.2 7676.4 7748.5 7764.7 5402.5 5499.1 5537.3 5551.4 5563.4 5423.9 5519.4 5544.3 5574.4 5654.8 14:13:22 INFO - PROCESS | 2461 | 14:13:22 INFO - PROCESS | 2461 | [#4] prototype.html Cycles:180 Average:4825.62 Median:5579.21 stddev:1420.76 (25.5%) stddev-sans-first:1384.29 14:13:22 INFO - PROCESS | 2461 | Values: 340.8 344.2 346.8 348.8 355.9 5431.4 5543.2 5545.2 5572.2 5583.9 5389.3 5422.6 5445.6 5450.4 5472.3 4063.6 4342.3 4354.2 4367.8 4394.1 4107.0 4197.2 4287.7 4294.6 4319.2 4255.6 4262.9 4269.1 4271.7 4279.7 4255.0 4281.7 4287.8 4307.3 4309.4 3583.2 3750.5 3751.8 3759.4 3804.6 5317.5 5341.8 5349.4 5375.0 5390.7 4849.7 4886.8 4925.8 4928.6 4935.8 5489.1 5652.7 5660.8 5703.4 5723.8 4121.8 4139.4 4147.1 4147.5 4165.3 5715.5 5922.9 5940.1 5960.2 5994.6 4171.4 4175.8 4176.1 4176.3 4188.9 4224.4 4230.2 4251.3 4270.7 4280.6 4842.5 4848.2 4865.2 4882.8 4897.7 5661.9 5683.9 5699.5 5701.3 5771.8 1439.8 1450.0 1453.2 1473.9 1478.9 895.9 927.7 928.2 934.0 949.5 4930.7 4952.9 5390.4 5512.2 5608.0 5819.4 5824.7 5836.8 5853.3 5880.6 5590.9 5594.7 5598.2 5604.0 5616.2 2965.0 3104.0 3112.5 3116.6 3119.6 6062.6 6066.7 6068.9 6086.8 6102.0 5839.0 5853.4 5868.8 5962.9 6015.4 6020.6 6044.8 6063.4 6077.7 6096.5 5591.8 5803.0 5843.8 5855.9 5884.8 5802.4 5809.4 5816.2 5817.5 5887.5 6070.8 6078.6 6081.2 6121.9 6162.2 4145.7 4239.2 4240.9 4275.6 4281.6 5890.6 5894.6 5898.5 5900.2 5904.1 5622.5 5790.4 5801.8 5833.0 5845.6 5715.3 5750.0 5750.8 5751.3 5776.6 5705.9 5716.3 5722.5 5730.8 5749.9 5421.9 5582.8 5690.5 5788.3 5846.5 5575.6 5583.3 5596.7 5616.7 5622.2 14:13:22 INFO - PROCESS | 2461 | 14:13:22 INFO - PROCESS | 2461 | [#5] yui.html Cycles:180 Average:22824.96 Median:4795.86 stddev:33764.83 (704.0%) stddev-sans-first:33818.74 14:13:22 INFO - PROCESS | 2461 | Values: 716.1 721.1 776.8 802.8 804.6 16696.3 18254.9 18281.9 18296.4 18297.4 16496.0 16649.7 16666.0 16668.8 16682.8 738.4 859.0 865.0 878.8 879.8 867.5 874.9 876.6 880.7 882.2 936.5 938.6 940.3 940.6 940.8 932.2 935.3 936.2 936.4 936.6 686.4 687.7 687.8 687.8 688.0 15924.9 15937.8 16020.4 16054.2 16067.2 14475.0 14492.7 14712.4 14900.7 14973.3 14412.6 14413.8 14549.0 14676.6 14741.1 4765.8 4775.9 4815.8 4869.7 4917.6 85076.0 86089.6 88052.6 89623.6 89676.0 11673.2 11870.3 11939.0 12720.6 13032.6 22629.5 22631.1 22667.0 22673.9 22705.4 8758.5 8910.7 8951.1 8988.4 9289.7 4985.4 5137.4 5208.6 5264.9 5308.5 3782.6 3887.7 3889.9 3890.4 3891.1 649.2 654.1 661.1 667.2 669.1 626.3 722.1 725.4 726.0 727.5 9254.0 9632.0 9756.5 9902.3 10060.3 607.2 607.4 692.0 708.1 759.3 4663.6 4828.3 4871.1 4926.9 4980.0 74904.6 111463.4 136141.0 137181.0 140533.0 70276.3 86879.6 112253.0 112961.4 119465.4 103808.9 104867.0 105166.0 107104.5 108862.5 9483.4 9717.5 9723.0 9751.1 9798.6 59888.1 60916.0 61645.4 62066.4 62558.4 85429.0 89389.6 89931.0 90430.5 91811.0 11605.4 11946.3 12159.5 12287.5 12494.9 14016.5 14035.2 14045.9 14141.4 14602.0 11945.8 11978.2 12241.3 12880.7 13002.2 12859.2 13168.6 13305.4 13587.0 13588.7 13111.5 13330.5 13571.9 13688.1 13871.7 10802.8 10836.7 10843.5 10887.6 11433.8 10943.9 10948.6 11604.4 11635.4 11681.0 14:13:22 INFO - PROCESS | 2461 | -------- Summary: end -------- 14:13:22 INFO - PROCESS | 2461 | 14:13:23 INFO - TEST-INFO | 2461: exit 0 14:13:23 INFO - TEST-OK | dromaeo_css | took 1112319ms 14:13:23 INFO - TEST-START | kraken 14:13:23 INFO - operating with platform_type : linux_ 14:13:23 INFO - Initialising browser for kraken test... 14:13:23 INFO - TEST-INFO | started process 2670 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppn3a7S/profile http://localhost:60002/getInfo.html) 14:13:27 INFO - PROCESS | 2670 | __metrics Screen width/height:1600/1200 14:13:27 INFO - PROCESS | 2670 | colorDepth:24 14:13:27 INFO - PROCESS | 2670 | Browser inner width/height: 1024/697 14:13:27 INFO - PROCESS | 2670 | __metrics 14:13:28 INFO - PROCESS | 2670 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:13:28 INFO - PROCESS | 2670 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:13:28 INFO - PROCESS | 2670 | console.error: PushService: 14:13:28 INFO - PROCESS | 2670 | stateChangeProcessEnqueue: Error transitioning state 14:13:28 INFO - PROCESS | 2670 | UnknownError 14:13:29 INFO - TEST-INFO | 2670: exit 0 14:13:29 INFO - Browser initialized. 14:13:29 INFO - Running cycle 1/1 for kraken test... 14:13:29 INFO - TEST-INFO | started process 2843 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppn3a7S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:13:40 INFO - PROCESS | 2843 | RSS: Main: 143585280 14:13:40 INFO - PROCESS | 2843 | 14:13:43 INFO - PROCESS | 2843 | Cycle 1(1): loaded http://localhost:60002/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:60002/tests/kraken/driver.html?testName=audio-beat-detection) 14:13:44 INFO - PROCESS | 2843 | RSS: Main: 199073792 14:13:44 INFO - PROCESS | 2843 | 14:13:46 INFO - PROCESS | 2843 | Cycle 1(1): loaded http://localhost:60002/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:60002/tests/kraken/driver.html?testName=audio-dft) 14:13:47 INFO - PROCESS | 2843 | RSS: Main: 183336960 14:13:47 INFO - PROCESS | 2843 | 14:13:50 INFO - PROCESS | 2843 | Cycle 1(1): loaded http://localhost:60002/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:60002/tests/kraken/driver.html?testName=audio-fft) 14:13:50 INFO - PROCESS | 2843 | RSS: Main: 273813504 14:13:50 INFO - PROCESS | 2843 | 14:13:52 INFO - PROCESS | 2843 | Cycle 1(1): loaded http://localhost:60002/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:60002/tests/kraken/driver.html?testName=audio-oscillator) 14:13:53 INFO - PROCESS | 2843 | RSS: Main: 180273152 14:13:53 INFO - PROCESS | 2843 | 14:13:55 INFO - PROCESS | 2843 | Cycle 1(1): loaded http://localhost:60002/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:60002/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:13:56 INFO - PROCESS | 2843 | RSS: Main: 178884608 14:13:56 INFO - PROCESS | 2843 | 14:13:59 INFO - PROCESS | 2843 | Cycle 1(1): loaded http://localhost:60002/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:60002/tests/kraken/driver.html?testName=imaging-darkroom) 14:13:59 INFO - PROCESS | 2843 | RSS: Main: 214773760 14:13:59 INFO - PROCESS | 2843 | 14:14:03 INFO - PROCESS | 2843 | Cycle 1(1): loaded http://localhost:60002/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:60002/tests/kraken/driver.html?testName=imaging-desaturate) 14:14:03 INFO - PROCESS | 2843 | RSS: Main: 213245952 14:14:03 INFO - PROCESS | 2843 | 14:14:06 INFO - PROCESS | 2843 | Cycle 1(1): loaded http://localhost:60002/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:60002/tests/kraken/driver.html?testName=json-parse-financial) 14:14:06 INFO - PROCESS | 2843 | RSS: Main: 213671936 14:14:06 INFO - PROCESS | 2843 | 14:14:08 INFO - PROCESS | 2843 | Cycle 1(1): loaded http://localhost:60002/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:60002/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:14:09 INFO - PROCESS | 2843 | RSS: Main: 175820800 14:14:09 INFO - PROCESS | 2843 | 14:14:10 INFO - PROCESS | 2843 | Cycle 1(1): loaded http://localhost:60002/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:60002/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:14:11 INFO - PROCESS | 2843 | RSS: Main: 183619584 14:14:11 INFO - PROCESS | 2843 | 14:14:13 INFO - PROCESS | 2843 | Cycle 1(1): loaded http://localhost:60002/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:60002/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:14:13 INFO - PROCESS | 2843 | RSS: Main: 185434112 14:14:13 INFO - PROCESS | 2843 | 14:14:16 INFO - PROCESS | 2843 | Cycle 1(1): loaded http://localhost:60002/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:60002/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:14:17 INFO - PROCESS | 2843 | RSS: Main: 185286656 14:14:17 INFO - PROCESS | 2843 | 14:14:19 INFO - PROCESS | 2843 | Cycle 1(1): loaded http://localhost:60002/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:60002/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:14:20 INFO - PROCESS | 2843 | RSS: Main: 183468032 14:14:20 INFO - PROCESS | 2843 | 14:14:21 INFO - PROCESS | 2843 | Cycle 1(1): loaded http://localhost:60002/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:60002/tests/kraken/driver.html?testName=ai-astar) 14:14:22 INFO - PROCESS | 2843 | RSS: Main: 234016768 14:14:22 INFO - PROCESS | 2843 | 14:14:22 INFO - PROCESS | 2843 | __start_tp_report 14:14:22 INFO - PROCESS | 2843 | _x_x_mozilla_page_load 14:14:22 INFO - PROCESS | 2843 | _x_x_mozilla_page_load_details 14:14:22 INFO - PROCESS | 2843 | |i|pagename|runs| 14:14:22 INFO - PROCESS | 2843 | |0;ai-astar;110;109;122;110;110;110;110;111;109;109 14:14:22 INFO - PROCESS | 2843 | |1;audio-beat-detection;143;116;147;170;117;139;138;116;178;116 14:14:22 INFO - PROCESS | 2843 | |2;audio-dft;160;161;160;160;162;163;161;162;160;162 14:14:22 INFO - PROCESS | 2843 | |3;audio-fft;75;76;75;183;75;76;74;76;162;74 14:14:22 INFO - PROCESS | 2843 | |4;audio-oscillator;81;82;157;82;82;82;137;84;83;146 14:14:22 INFO - PROCESS | 2843 | |5;imaging-gaussian-blur;116;117;116;117;117;116;117;117;116;116 14:14:22 INFO - PROCESS | 2843 | |6;imaging-darkroom;145;146;145;145;146;145;146;147;146;146 14:14:22 INFO - PROCESS | 2843 | |7;imaging-desaturate;94;94;95;95;94;95;94;94;94;94 14:14:22 INFO - PROCESS | 2843 | |8;json-parse-financial;49;49;49;50;50;50;50;49;51;50 14:14:22 INFO - PROCESS | 2843 | |9;json-stringify-tinderbox;59;59;58;59;59;59;59;60;59;59 14:14:22 INFO - PROCESS | 2843 | |10;stanford-crypto-aes;73;75;72;75;76;72;72;83;78;73 14:14:22 INFO - PROCESS | 2843 | |11;stanford-crypto-ccm;120;126;127;119;116;116;117;119;153;116 14:14:22 INFO - PROCESS | 2843 | |12;stanford-crypto-pbkdf2;152;154;149;149;152;157;149;152;148;152 14:14:22 INFO - PROCESS | 2843 | |13;stanford-crypto-sha256-iterative;62;66;61;63;62;60;60;60;61;64 14:14:22 INFO - PROCESS | 2843 | __end_tp_report 14:14:22 INFO - PROCESS | 2843 | __start_cc_report 14:14:22 INFO - PROCESS | 2843 | _x_x_mozilla_cycle_collect,1648 14:14:22 INFO - PROCESS | 2843 | __end_cc_report 14:14:22 INFO - PROCESS | 2843 | __startTimestamp1474492462123__endTimestamp 14:14:22 INFO - PROCESS | 2843 | 14:14:22 INFO - PROCESS | 2843 | ------- Summary: start ------- 14:14:22 INFO - PROCESS | 2843 | Number of tests: 14 14:14:22 INFO - PROCESS | 2843 | 14:14:22 INFO - PROCESS | 2843 | [#0] ai-astar Cycles:10 Average:111.00 Median:110.00 stddev:3.92 (3.6%) stddev-sans-first:4.14 14:14:22 INFO - PROCESS | 2843 | Values: 110.0 109.0 122.0 110.0 110.0 110.0 110.0 111.0 109.0 109.0 14:14:22 INFO - PROCESS | 2843 | 14:14:22 INFO - PROCESS | 2843 | [#1] audio-beat-detection Cycles:10 Average:138.00 Median:141.00 stddev:22.67 (16.1%) stddev-sans-first:23.97 14:14:22 INFO - PROCESS | 2843 | Values: 143.0 116.0 147.0 170.0 117.0 139.0 138.0 116.0 178.0 116.0 14:14:22 INFO - PROCESS | 2843 | 14:14:22 INFO - PROCESS | 2843 | [#2] audio-dft Cycles:10 Average:161.10 Median:161.50 stddev:1.10 (0.7%) stddev-sans-first:1.09 14:14:22 INFO - PROCESS | 2843 | Values: 160.0 161.0 160.0 160.0 162.0 163.0 161.0 162.0 160.0 162.0 14:14:22 INFO - PROCESS | 2843 | 14:14:22 INFO - PROCESS | 2843 | [#3] audio-fft Cycles:10 Average:94.60 Median:75.00 stddev:41.36 (55.1%) stddev-sans-first:43.26 14:14:22 INFO - PROCESS | 2843 | Values: 75.0 76.0 75.0 183.0 75.0 76.0 74.0 76.0 162.0 74.0 14:14:22 INFO - PROCESS | 2843 | 14:14:22 INFO - PROCESS | 2843 | [#4] audio-oscillator Cycles:10 Average:101.60 Median:82.00 stddev:31.46 (38.4%) stddev-sans-first:32.48 14:14:22 INFO - PROCESS | 2843 | Values: 81.0 82.0 157.0 82.0 82.0 82.0 137.0 84.0 83.0 146.0 14:14:22 INFO - PROCESS | 2843 | 14:14:22 INFO - PROCESS | 2843 | [#5] imaging-gaussian-blur Cycles:10 Average:116.50 Median:117.00 stddev:0.53 (0.5%) stddev-sans-first:0.53 14:14:22 INFO - PROCESS | 2843 | Values: 116.0 117.0 116.0 117.0 117.0 116.0 117.0 117.0 116.0 116.0 14:14:22 INFO - PROCESS | 2843 | 14:14:22 INFO - PROCESS | 2843 | [#6] imaging-darkroom Cycles:10 Average:145.70 Median:146.00 stddev:0.67 (0.5%) stddev-sans-first:0.67 14:14:22 INFO - PROCESS | 2843 | Values: 145.0 146.0 145.0 145.0 146.0 145.0 146.0 147.0 146.0 146.0 14:14:22 INFO - PROCESS | 2843 | 14:14:22 INFO - PROCESS | 2843 | [#7] imaging-desaturate Cycles:10 Average:94.30 Median:94.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 14:14:22 INFO - PROCESS | 2843 | Values: 94.0 94.0 95.0 95.0 94.0 95.0 94.0 94.0 94.0 94.0 14:14:22 INFO - PROCESS | 2843 | 14:14:22 INFO - PROCESS | 2843 | [#8] json-parse-financial Cycles:10 Average:49.70 Median:50.00 stddev:0.67 (1.3%) stddev-sans-first:0.67 14:14:22 INFO - PROCESS | 2843 | Values: 49.0 49.0 49.0 50.0 50.0 50.0 50.0 49.0 51.0 50.0 14:14:22 INFO - PROCESS | 2843 | 14:14:22 INFO - PROCESS | 2843 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:59.00 stddev:0.47 (0.8%) stddev-sans-first:0.50 14:14:22 INFO - PROCESS | 2843 | Values: 59.0 59.0 58.0 59.0 59.0 59.0 59.0 60.0 59.0 59.0 14:14:22 INFO - PROCESS | 2843 | 14:14:22 INFO - PROCESS | 2843 | [#10] stanford-crypto-aes Cycles:10 Average:74.90 Median:75.00 stddev:3.48 (4.6%) stddev-sans-first:3.62 14:14:22 INFO - PROCESS | 2843 | Values: 73.0 75.0 72.0 75.0 76.0 72.0 72.0 83.0 78.0 73.0 14:14:22 INFO - PROCESS | 2843 | 14:14:22 INFO - PROCESS | 2843 | [#11] stanford-crypto-ccm Cycles:10 Average:122.90 Median:119.50 stddev:11.30 (9.5%) stddev-sans-first:11.94 14:14:22 INFO - PROCESS | 2843 | Values: 120.0 126.0 127.0 119.0 116.0 116.0 117.0 119.0 153.0 116.0 14:14:22 INFO - PROCESS | 2843 | 14:14:22 INFO - PROCESS | 2843 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:151.40 Median:152.00 stddev:2.76 (1.8%) stddev-sans-first:2.92 14:14:22 INFO - PROCESS | 2843 | Values: 152.0 154.0 149.0 149.0 152.0 157.0 149.0 152.0 148.0 152.0 14:14:22 INFO - PROCESS | 2843 | 14:14:22 INFO - PROCESS | 2843 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:61.90 Median:62.00 stddev:1.97 (3.2%) stddev-sans-first:2.09 14:14:22 INFO - PROCESS | 2843 | Values: 62.0 66.0 61.0 63.0 62.0 60.0 60.0 60.0 61.0 64.0 14:14:22 INFO - PROCESS | 2843 | -------- Summary: end -------- 14:14:22 INFO - PROCESS | 2843 | 14:14:23 INFO - TEST-INFO | 2843: exit 0 14:14:23 INFO - TEST-OK | kraken | took 59442ms 14:14:23 INFO - SUITE-END | took 1171s 14:14:23 INFO - Completed test suite (00:19:31) 14:14:23 INFO - javascript benchmark 14:14:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3182.602174728159, 3187.633422156451, 3189.441847676656, 3229.5963004624423, 3236.158598764322, 5523.447655234475, 5575.330960284761, 5630.296212973379, 5630.324361076667, 5634.3802181759975, 5625.128105143699, 5696.25948626679, 5701.2018317435595, 5711.857203569913, 5750.712464376786, 4976.651634385595, 4991.076650819592, 5068.239764035387, 5147.510986456281, 5203.063448579254, 5028.974855125737, 5058.898822023557, 5082.491750824925, 5114.820981265657, 5121.66709163905, 5010.423801262865, 5051.090803655341, 5091.032703786287, 5107.514786095334, 5112.948870511303, 5175.767090480918, 5184.351956005499, 5189.42916279209, 5192.870178245574, 5223.425423203445, 4904.5831104356175, 4985.5513003829465, 5013.89972200554, 5087.058894104565, 5090.643654944155, 5514.393416724157, 5515.09001014832, 5536.889262214773, 5540.861478463071, 5541.307336582927, 6023.036314189752, 6045.818625441244, 6056.091586262052, 6057.878842423171, 6058.0, 5827.883442331172, 5960.314563825173, 6024.277086749575, 6046.455818976269, 6080.513558915276, 5675.659460432387, 5693.3168019837885, 5708.857278568069, 5719.771209151629, 5734.6845923473775, 6152.538559608047, 6154.076888466766, 6154.599951003171, 6156.784512542129, 6157.907631385443, 5730.455606717439, 5779.971100144472, 5831.125331200354, 5834.358220595731, 5962.850928726816, 5978.970105149447, 5979.372165922602, 5980.282366116007, 5982.581219314607, 6017.969910150421, 5942.346341902387, 5953.077273022688, 5954.821355359346, 5958.821235362946, 5964.075568286922, 5744.770209191669, 5895.793647222326, 5923.911141332884, 5927.4665280125, 5947.107933809876, 4593.19619066662, 4619.23782575878, 4625.074985003012, 4632.69887457321, 4654.534546545387, 4112.629863312316, 4115.5678653741525, 4117.155983023473, 4123.463949686521, 4149.481314835645, 5540.972295138579, 5658.943410565924, 5699.287589051369, 5768.192453056576, 5773.624714393551, 5964.075568286922, 6009.06859436788, 6022.427869352405, 6022.5784195105925, 6055.182550355734, 5738.885222295531, 5749.655020698771, 5762.827115186551, 5769.105788602773, 5769.134629805562, 4996.075725990703, 5101.0, 5138.794448222029, 5192.688438693691, 5203.8178663746585, 6210.037444196157, 6246.593971391845, 6266.342034086355, 6270.749170033238, 6279.874402511924, 6006.0, 6016.87966240664, 6030.4572588467245, 6043.395660433922, 6051.394860513913, 6009.90985135232, 6123.571350005457, 6140.166841500682, 6193.828153491885, 6223.471004964629, 5946.583739138392, 5956.374580668968, 5962.284525856925, 5980.282366116094, 6001.4298641628975, 5989.460948514655, 6015.308239552507, 6046.183765191643, 6046.820869930321, 6052.818415447544, 5731.656100633975, 5772.307323121252, 5792.884142317214, 5902.852428689317, 5911.881762364815, 5849.444302791228, 5855.560832937632, 5858.0, 5883.176355310175, 5894.2926848778425, 5806.883862322815, 5807.76768929255, 5812.041013232769, 5814.069748840166, 5815.505682017076, 5728.627639203439, 5815.5929084963645, 5839.240898683144, 5861.560382971209, 5890.764369425175, 5760.049591817303, 5766.942330576809, 5767.596268261349, 5770.971145144247, 5796.333421656394], "value": 5529.007840444315, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [590.3506143242433, 640.5772190354367, 656.9638669873156, 662.2251965200714, 667.5427332277388, 34946.0, 38215.426768598496, 38242.04394890126, 46236.76881615587, 47373.526264737346, 49400.75299623506, 49474.50525494744, 49862.50137498624, 49953.0, 49973.0, 9259.490728009974, 9335.626574937029, 9412.62349506019, 9421.199198068172, 9423.057694230556, 8512.14878512147, 10856.511456984455, 11166.832497512503, 11172.217944743872, 11500.022498087672, 11364.886351136465, 11430.142739294544, 11445.885541144566, 11447.942760286229, 11468.19722619417, 11172.944135279313, 11183.776324473547, 11209.439528023566, 11250.774984500305, 11254.324740515594, 11022.889771102347, 11094.057005154571, 11096.500657470433, 11098.167637427145, 11102.278351907182, 46768.532314676755, 47046.2943055851, 47151.29273060907, 47178.0, 47198.05603887903, 3759.1166075972124, 3802.543694756619, 3878.340682084024, 4100.17996400721, 4545.977270113662, 36713.265734685425, 37184.0, 37219.0, 38690.806545967374, 38953.80523097395, 4315.503717072514, 4363.0, 4388.912221755547, 4523.185826551219, 4578.633709303248, 359382.0, 360800.0, 361336.0, 361684.0, 363076.0, 65646.0, 66029.0, 66078.66960665166, 66097.33902660912, 66106.0, 39760.20479590392, 40717.796411017756, 40723.0, 42968.570314296456, 42998.78500607539, 17029.659406811796, 17188.140592970303, 17203.483895483154, 17206.913965430344, 17208.31166753341, 11146.386948717736, 11681.357525336189, 12495.562655307021, 12562.183458075197, 12568.245905245714, 20238.494037649176, 21126.0, 21258.0, 21288.254911078348, 21377.251796187058, 7406.55560666351, 7468.701251949861, 7590.468667193243, 7611.961940190264, 7677.9232207677205, 1023.9692809215735, 1070.550368845087, 1145.0438883532177, 1150.0908491903567, 1165.516310731054, 35030.824845875606, 35037.0, 35318.117047074025, 35368.82315588457, 35420.645793541735, 1170.6605084525563, 1181.911356648255, 1213.245006704249, 1216.9634910952686, 1237.4740735187509, 18134.637307253783, 18502.167402466923, 18524.351647692267, 18583.628327433376, 18715.81284187168, 616394.0, 620510.0, 620740.0, 621838.0, 623451.0, 64572.0, 65496.34503654998, 65574.67212663907, 65598.34401656018, 65621.67189164023, 1334.9799753003713, 1378.5657517881834, 1383.4189640351076, 1392.2899321346185, 1451.267110109388, 34080.48879266763, 36215.21298979722, 36448.27103457969, 36882.44676329804, 37320.44019339766, 56726.43273567211, 57013.71493142508, 57135.142972854606, 57311.71344143253, 57343.13985290308, 479912.0, 480977.0, 483975.0, 485847.0, 528687.0, 31628.841855790575, 32303.35393292074, 32349.191270218435, 33827.32345353128, 33865.15337116592, 54528.0, 54605.0, 54719.452805473025, 54745.452545474036, 54865.45134548763, 44821.32768008548, 46687.76656116698, 47104.528954711386, 47883.0, 47958.76020619875, 46267.305990410845, 46398.53601463942, 46674.29988550243, 47188.0, 47454.05091898212, 44895.32657010213, 44934.32598510959, 45194.77402612966, 45480.54519454763, 45522.0, 39349.2130157401, 40248.3962740565, 40633.59366406298, 41023.58976410198, 41075.58924410718, 29723.702762972094, 30201.395972080874, 30207.848960755055, 30287.394252115275, 30344.696553034188], "value": 21989.02475938308, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [410.49714100227226, 411.24536475567334, 411.32542630086664, 411.38908720549983, 434.2227412930854, 19604.215831366746, 21139.894300528515, 21213.893930530347, 21388.25141120061, 21972.121115155416, 19776.901115494402, 20232.898835505803, 20437.693434598492, 20514.589708205825, 20563.794362056375, 5627.493525582707, 5808.2449281593335, 5810.651360918338, 5812.680302583356, 5837.182794408786, 5439.48324909133, 5669.433056694318, 5700.315962084555, 5792.7972520961775, 5834.649921004732, 5693.259876216107, 5723.885522289552, 5741.311042674884, 5759.68321742304, 5767.45209205127, 5671.262735844334, 5715.71421428929, 5840.649561026352, 5977.731002104916, 5983.521318294526, 5660.179274005287, 5812.767489300423, 5813.418658134153, 5859.7949071782705, 5868.383819698913, 13526.256055916921, 13814.861851381458, 13819.654508637264, 22579.435514112116, 24606.138785142433, 2300.229423143249, 2357.3752955466816, 2527.3934255778663, 2672.599110133476, 2898.9130326090253, 9897.0, 10282.12601928837, 10308.845367319496, 10745.355278683226, 16145.515634530866, 2048.2114385961513, 2140.625390556662, 2171.457135716071, 2176.1077958037285, 2243.9887800561055, 64882.0, 90992.54503727505, 91299.54350228273, 93425.0, 100585.4970725149, 18449.0, 18920.43238702841, 19089.427317180507, 19241.4227573173, 20001.59996800056, 33794.49308260378, 34054.48918266228, 34173.0, 34224.48663270053, 34426.13934651654, 9602.0, 9970.0, 9973.401595904268, 10217.897821021843, 10504.0, 8270.503769773833, 8282.088970213272, 8354.707585234488, 8520.829583408296, 8642.481451112953, 8923.866142007746, 9218.21645160169, 9411.43531388119, 9550.713478595653, 9623.759406014906, 3327.7670563060356, 3575.94636080459, 3624.3113808376324, 3678.319510890494, 3739.8691045813266, 445.09867518275104, 454.0714239380475, 469.12976428724227, 469.2562288772266, 983.8524221366853, 12252.938735306445, 12394.194377365442, 12406.75186496265, 12454.31501267421, 12483.876451119442, 455.8427144442638, 461.1238646571541, 486.6642017008253, 493.4054464370425, 495.8363739965803, 6344.0, 6494.772682956168, 6528.543001989815, 6529.7388104475285, 6584.11114499546, 593428.0, 596720.0, 606195.0, 628515.0, 633208.0, 89902.0, 91689.5415522918, 93560.0, 100807.0, 101705.0, 1023.8515415264745, 1068.4711068021224, 1075.499892549968, 1076.0530732954949, 1078.2075174746556, 18352.541186470444, 18592.721109183643, 18849.528761780515, 18906.338278160198, 18922.621547569248, 31719.841400792848, 36044.4593331095, 43194.1361172781, 45254.54745452635, 45285.54714452814, 60344.0, 63931.680341597996, 64425.0, 65301.67349163224, 65841.34158658353, 7895.447318687637, 8090.0, 9079.727608171765, 9169.449833009774, 9482.85775713351, 12529.749405012031, 13283.0, 14082.436702532194, 14802.62993425173, 14850.925745371203, 6071.908921366271, 11104.666859994213, 12620.36898155107, 12942.805857912328, 13010.544630937871, 12208.572699955459, 12627.116101873147, 12878.484860605471, 13101.148425352321, 13340.866591333963, 12404.81392779091, 13116.409761560944, 13277.535286264934, 13394.9330253352, 13518.662033448849, 7601.3918886490055, 7987.720429784929, 9001.639934402812, 9292.72121836346, 9564.282678798978, 12881.677958051123, 12913.225206487641, 12913.677158071123, 13013.544525941546, 13352.399142038437], "value": 9714.500407182952, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1420.9644758881034, 1513.9243037848105, 1536.1013806922952, 1682.3775203174828, 1841.9355322563713, 8655.35084868635, 9038.18656320931, 9146.222571081458, 9392.71821845344, 9405.341626086176, 9133.634654613825, 9218.492982885939, 9244.02937691541, 9248.861267080982, 9257.07429257074, 2486.925392238236, 2530.544501989641, 2531.4304281536693, 2533.024785457603, 2550.987245063772, 2437.463757973252, 2500.949981000379, 2508.2224510401743, 2508.3854455658297, 2508.7240403555593, 2467.383154211447, 2476.6037434010564, 2537.264193383926, 2539.6317533957567, 2542.2373288013614, 2389.1160270699866, 2397.472556037678, 2408.819338549607, 2455.9631605525933, 2463.7905778008712, 2418.7702168293986, 2421.128393778238, 2455.938601534976, 2457.913973010936, 2458.299384675359, 9082.77293067672, 9149.634014639407, 9272.304577156672, 9277.860832087525, 9278.164965153168, 5443.564514838803, 5579.609427340088, 5600.131979543177, 5613.578981576357, 5623.297087864017, 3676.5955744868043, 3740.663340299386, 3778.1876896467115, 3805.7906815125157, 3809.5047643806406, 1153.0832987774656, 1153.6712037069478, 1163.0521125282867, 1178.116412690482, 2175.7715439878743, 12991.61025169237, 13027.739445211137, 13119.737605247938, 13138.737225255441, 13154.934225328907, 4713.2458806590785, 4727.0, 4801.303810947393, 4989.725565093955, 5046.949530504648, 10564.630237941636, 10582.841257381146, 10600.734981625521, 10603.257771956043, 10619.522121504551, 5760.683162426107, 5762.049261871828, 5764.7029418380525, 5768.625039372428, 5768.625039372428, 4594.701344412602, 4605.907881842411, 5107.131787596118, 5170.276161337416, 5404.702741349185, 4080.204360149742, 4092.570280120604, 4113.382992551082, 4151.626353628188, 4186.706930514834, 1224.7611715715527, 1251.7058491254547, 1252.4927404401233, 1270.358468973181, 1276.24063682109, 896.7264984179757, 975.7609385700549, 990.1138481059412, 1028.141501845952, 1040.5265604150093, 6030.698465076729, 6048.727807248596, 6196.3803619638575, 6222.097795819581, 6283.937160628427, 492.2370325994694, 514.26460161968, 514.7234857553289, 517.4851023231834, 518.3158231134865, 4215.536291007987, 4293.205756934977, 4349.043210493686, 4359.520452750195, 4400.44994375703, 11029.062529685067, 11383.54465821358, 11504.194706370641, 11528.711782205344, 11547.0, 10977.231593788518, 11466.369349685847, 11500.424978751196, 11502.07983361329, 11502.654920352235, 8623.267022303175, 9149.5882685278, 9156.267498600097, 9163.58763855628, 9168.312376571785, 6090.786822461193, 6160.784372546939, 6295.622262664255, 6479.481641468766, 6553.967230163818, 10614.73463163427, 10684.89315106839, 10909.945450272699, 10923.180761442765, 10950.0, 9722.854157187789, 10046.346987445793, 10878.020978112008, 11137.498812553617, 11205.43972801373, 8100.392470564849, 8219.342452603896, 8273.089960104504, 8329.83340333202, 8390.035145958049, 9006.684766033157, 9019.458832470073, 9053.321000924825, 9058.275337973078, 9072.047435019227, 7938.563379013978, 8037.196280371916, 8040.557769322743, 8210.63052162642, 8246.299064579578, 7815.6092195388865, 8177.959110204649, 8201.79495512617, 8289.4197406181, 8391.16088391156, 7638.541687498768, 7660.157382687788, 7676.424268179797, 7748.496347737379, 7764.728234511765, 5402.540784033401, 5499.147632116868, 5537.335519737658, 5551.389347171727, 5563.360213575491, 5423.864403389947, 5519.448055194448, 5544.3346798384455, 5574.358948720949, 5654.802081927113], "value": 4837.397213618645, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.787008119925, 344.2220581485842, 346.83525325470396, 348.82907375386037, 355.9288142371525, 5431.375391829941, 5543.205127564658, 5545.1682247662875, 5572.219889215513, 5583.86040348991, 5389.326334208224, 5422.620416570841, 5445.591580631449, 5450.400455949843, 5472.26124473197, 4063.5936406359274, 4342.261815491358, 4354.216241076605, 4367.794263896736, 4394.055278115205, 4106.958930410688, 4197.18154959783, 4287.678424118188, 4294.592013758688, 4319.2441322768545, 4255.63827074699, 4262.9147417051645, 4269.0789144884775, 4271.722338048033, 4279.679024073192, 4254.957535403815, 4281.678874084456, 4287.849925252601, 4307.289297265947, 4309.35359696045, 3583.211693427442, 3750.493683352767, 3751.8124093795473, 3759.3797023491093, 3804.619538046201, 5317.46825317469, 5341.813036543741, 5349.384820745586, 5375.0, 5390.652336915771, 4849.660523763338, 4886.828960986384, 4925.7783399747095, 4928.556429921325, 4935.777889994959, 5489.149181876815, 5652.72813616937, 5660.801871934504, 5703.429657034305, 5723.77104915807, 4121.753644263883, 4139.3583994480905, 4147.087640719037, 4147.4815648044, 4165.312723400635, 5715.542756579505, 5922.911156332615, 5940.079287710412, 5960.195373624549, 5994.55040871932, 4171.395147703627, 4175.770332631734, 4176.081262122329, 4176.2691528982305, 4188.895277618084, 4224.366345048206, 4230.196262710137, 4251.341042138473, 4270.743755374688, 4280.5505421930875, 4842.539958703917, 4848.200046992278, 4865.245886887538, 4882.780274887638, 4897.681650692765, 5661.886762264731, 5683.943160568341, 5699.487046165865, 5701.315842098975, 5771.826845194651, 1439.7768345906402, 1449.9782503262459, 1453.1644304524941, 1473.8820894328428, 1478.9277773614258, 895.8611415230649, 927.7263207353847, 928.2342067794096, 934.0332755597924, 949.5110018340561, 4930.6795058321095, 4952.851414457572, 5390.380106287748, 5512.173174023928, 5608.0, 5819.359870414251, 5824.6796426196115, 5836.795712150054, 5853.2976042875125, 5880.558958078162, 5590.888182236414, 5594.748236329375, 5598.188262701967, 5603.971980140073, 5616.241807356037, 2964.985175074111, 3104.0, 3112.486439737463, 3116.6415862175686, 3119.5632611434417, 6062.6352374431735, 6066.66633335162, 6068.878622427526, 6086.786962456293, 6102.0, 5838.970805146032, 5853.358894717772, 5868.823935281948, 5962.940370596239, 6015.428534289235, 6020.608660437058, 6044.848878777978, 6063.423974722395, 6077.696115194222, 6096.451319381277, 5591.804286849941, 5803.0, 5843.766249349978, 5855.858107669134, 5884.823035393024, 5802.448767367094, 5809.390014048464, 5816.214811000461, 5817.534597232296, 5887.52899768009, 6070.7875224366935, 6078.635281883101, 6081.239845019372, 6121.877562448637, 6162.22972128484, 4145.730527515701, 4239.15216956616, 4240.872773816791, 4275.551067137906, 4281.614654681093, 5890.558208134493, 5894.587378883437, 5898.498627616701, 5900.173975643327, 5904.0553511437965, 5622.465865742748, 5790.420957904176, 5801.839632073517, 5832.9708351457975, 5845.561582881216, 5715.2855893013375, 5750.0, 5750.763585829067, 5751.252337196138, 5776.56675749312, 5705.857353566194, 5716.28546431696, 5722.513586345207, 5730.799422020209, 5749.885002300014, 5421.864453388697, 5582.804601838916, 5690.544756419559, 5788.27646544182, 5846.532277417881, 5575.606098475381, 5583.302087239095, 5596.720163991866, 5616.69108199053, 5622.172793842099], "value": 4369.774170251046, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.0834132310641, 721.0986267166043, 776.8019155115445, 802.807326241702, 804.585638396226, 16696.332146714132, 18254.908725456353, 18281.908590457064, 18296.359627413043, 18297.35959241427, 16496.0, 16649.66700665986, 16666.0, 16668.83331166688, 16682.83317166828, 738.4424759306715, 858.9527575983318, 864.9610767515477, 878.8154487557621, 879.7624641346832, 867.5185272173941, 874.8643960186149, 876.6318146378536, 880.6653471680752, 882.2324577617482, 936.4662142578733, 938.5776400619715, 940.3229674634252, 940.5673390240497, 940.7977284883749, 932.2355668351954, 935.256471105466, 936.1574582875398, 936.3539157980971, 936.559816886059, 686.4439803758971, 687.6596084937938, 687.7730348984823, 687.8349196192878, 687.9862402751917, 15924.920375398166, 15937.760933586005, 16020.4392846251, 16054.197290135446, 16067.196640168062, 14475.0, 14492.710145797131, 14712.411503539952, 14900.70198596022, 14973.326200320902, 14412.567622971328, 14413.78379324311, 14549.0, 14676.559703208814, 14741.11553306805, 4765.785539650724, 4775.904481910342, 4815.807367705288, 4869.732164730938, 4917.631177661689, 85076.0, 86089.56955215184, 88052.5597372009, 89623.55188224082, 89676.0, 11673.182877198515, 11870.347130907883, 11939.0, 12720.618381448572, 13032.609021729364, 22629.547409051727, 22631.094756209895, 22667.0, 22673.88663056674, 22705.432364190696, 8758.47449153053, 8910.732678019669, 8951.104889510996, 8988.370814043084, 9289.721308360626, 4985.376827896513, 5137.357830271216, 5208.609354298367, 5264.92102618461, 5308.46915308466, 3782.5650050244026, 3887.6501114899793, 3889.922201555953, 3890.358090915024, 3891.0855948851995, 649.1852724830308, 654.1398061549048, 661.0777964739162, 667.209356912051, 669.0733334331985, 626.3329554024949, 722.0973782771536, 725.419664268583, 725.9818504537429, 727.519836907649, 9254.0, 9632.0, 9756.512174391251, 9902.30683852138, 10060.346077504939, 607.1742430294884, 607.3774381259244, 692.0000599134303, 708.143146792387, 759.2559291893975, 4663.556962088596, 4828.251620998751, 4871.098846713369, 4926.9014619707405, 4979.950200498021, 74904.62547687227, 111463.44268278607, 136141.0, 137181.0, 140533.0, 70276.29723702697, 86879.56560217158, 112253.0, 112961.43519282351, 119465.40267298782, 103808.92382152466, 104867.0, 105165.99999999847, 107104.46447767712, 108862.45568772106, 9483.430994140374, 9717.465539395402, 9722.951385243028, 9751.122398984126, 9798.608055677978, 59888.101678473984, 60916.0, 61645.38354616396, 62066.379336207865, 62558.374416255254, 85429.0, 89389.55305223432, 89931.0, 90430.54784726034, 91811.0, 11605.419729013684, 11946.34295113777, 12159.513619455123, 12287.508499659914, 12494.937525312314, 14016.509422170175, 14035.228062456663, 14045.859541404456, 14141.36363863608, 14602.0, 11945.82081268799, 11978.221415607957, 12241.265524068584, 12880.677983050498, 13002.219866807643, 12859.16415432994, 13168.60494185176, 13305.401256943267, 13587.0, 13588.660283492993, 13111.475540978254, 13330.466781328638, 13571.86428135706, 13688.110272832235, 13871.722565548835, 10802.783944321227, 10836.729081773019, 10843.512041958289, 10887.564497420015, 11433.771324573296, 10943.890561094287, 10948.616798412017, 11604.419779011185, 11635.360055197045, 11681.0], "value": 8022.111347336322, "unit": "score"}], "name": "dromaeo_css", "value": 7649.148950743038, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 109.0, 122.0, 110.0, 110.0, 110.0, 110.0, 111.0, 109.0, 109.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 116.0, 147.0, 170.0, 117.0, 139.0, 138.0, 116.0, 178.0, 116.0], "value": 138.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 160.0, 160.0, 162.0, 163.0, 161.0, 162.0, 160.0, 162.0], "value": 161.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 75.0, 183.0, 75.0, 76.0, 74.0, 76.0, 162.0, 74.0], "value": 94.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 157.0, 82.0, 82.0, 82.0, 137.0, 84.0, 83.0, 146.0], "value": 101.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 117.0, 116.0, 117.0, 117.0, 116.0, 116.0], "value": 116.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 145.0, 146.0, 145.0, 146.0, 147.0, 146.0, 146.0], "value": 145.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0], "value": 94.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 49.0, 50.0, 50.0, 50.0, 50.0, 49.0, 51.0, 50.0], "value": 49.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 58.0, 59.0, 59.0, 59.0, 59.0, 60.0, 59.0, 59.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 75.0, 72.0, 75.0, 76.0, 72.0, 72.0, 83.0, 78.0, 73.0], "value": 74.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 126.0, 127.0, 119.0, 116.0, 116.0, 117.0, 119.0, 153.0, 116.0], "value": 122.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 154.0, 149.0, 149.0, 152.0, 157.0, 149.0, 152.0, 148.0, 152.0], "value": 151.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 66.0, 61.0, 63.0, 62.0, 60.0, 60.0, 60.0, 61.0, 64.0], "value": 61.9, "unit": "score"}], "name": "kraken", "value": 1482.6000000000004, "alertThreshold": 2.0}]} 14:14:23 INFO - Return code: 0 14:14:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:14:23 INFO - # TBPL SUCCESS # 14:14:23 INFO - Running post-action listener: _resource_record_post_action 14:14:23 INFO - [mozharness: 2016-09-21 21:14:23.432196Z] Finished run-tests step (success) 14:14:23 INFO - Running post-run listener: _resource_record_post_run 14:14:23 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 8192; Write bytes: 291172352; Read time: 24; Write time: 138340 14:14:23 INFO - TinderboxPrint: CPU usage
12.9% 14:14:23 INFO - TinderboxPrint: I/O read bytes / time
8,192 / 24 14:14:23 INFO - TinderboxPrint: I/O write bytes / time
291,172,352 / 138,340 14:14:23 INFO - TinderboxPrint: CPU idle
8,247.5 (87.1%) 14:14:23 INFO - TinderboxPrint: CPU user
1,177.6 (12.4%) 14:14:23 INFO - TinderboxPrint: Swap in / out
0 / 0 14:14:23 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 70070272; Read time: 0; Write time: 30968 14:14:23 INFO - run-tests - Wall time: 1172s; CPU: 13.0%; Read bytes: 4096; Write bytes: 220524544; Read time: 12; Write time: 107320 14:14:24 INFO - Running post-run listener: _upload_blobber_files 14:14:24 INFO - Blob upload gear active. 14:14:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:14:24 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:14:24 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'] 14:14:24 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 14:14:24 INFO - (blobuploader) - INFO - Open directory for files ... 14:14:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:14:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:14:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:14:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:14:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:14:25 INFO - (blobuploader) - INFO - Done attempting. 14:14:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:14:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:14:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:14:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:14:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:14:25 INFO - (blobuploader) - INFO - Done attempting. 14:14:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:14:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:14:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:14:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:14:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:14:29 INFO - (blobuploader) - INFO - Done attempting. 14:14:29 INFO - (blobuploader) - INFO - Iteration through files over. 14:14:29 INFO - Return code: 0 14:14:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:14:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:14:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c485b49e87c97f39c22744c7ceafc146f32383d2714e26f544fda33fd7ac2387bdc81e1c861c78ec2f866c2979f2a1d5e6ccd12e1bef6da5f235b5db50c7b7ac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0e1da83d66f1e3f865fee7f6a49a99e8f6292eff2f56a9d1931ec339c1f7bed66069ca90caf03eee6171f056cc0c8a92dfe24244caa6d6f79fc863899a35633e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/27c82fceb6f1ea4841108419ae14d778b64f27bc60a481fd54ed920b130b494c3de74c5a5acc2f5a078e943515549a1ff5fea0dac46be4bbd2fe38894494de92"} 14:14:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:14:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:14:29 INFO - Contents: 14:14:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c485b49e87c97f39c22744c7ceafc146f32383d2714e26f544fda33fd7ac2387bdc81e1c861c78ec2f866c2979f2a1d5e6ccd12e1bef6da5f235b5db50c7b7ac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0e1da83d66f1e3f865fee7f6a49a99e8f6292eff2f56a9d1931ec339c1f7bed66069ca90caf03eee6171f056cc0c8a92dfe24244caa6d6f79fc863899a35633e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/27c82fceb6f1ea4841108419ae14d778b64f27bc60a481fd54ed920b130b494c3de74c5a5acc2f5a078e943515549a1ff5fea0dac46be4bbd2fe38894494de92"} 14:14:29 INFO - Running post-run listener: copy_logs_to_upload_dir 14:14:29 INFO - Copying logs to upload dir... 14:14:29 INFO - mkdir: /builds/slave/test/build/upload/logs 14:14:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1674.289883 ========= master_lag: 0.28 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 27 mins, 54 secs) (at 2016-09-21 14:14:29.952238) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 14:14:29.957830) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509 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/c485b49e87c97f39c22744c7ceafc146f32383d2714e26f544fda33fd7ac2387bdc81e1c861c78ec2f866c2979f2a1d5e6ccd12e1bef6da5f235b5db50c7b7ac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0e1da83d66f1e3f865fee7f6a49a99e8f6292eff2f56a9d1931ec339c1f7bed66069ca90caf03eee6171f056cc0c8a92dfe24244caa6d6f79fc863899a35633e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/27c82fceb6f1ea4841108419ae14d778b64f27bc60a481fd54ed920b130b494c3de74c5a5acc2f5a078e943515549a1ff5fea0dac46be4bbd2fe38894494de92"} build_url:https://queue.taskcluster.net/v1/task/I64Vk5nZRMij8TXY9r__2g/artifacts/public/build/firefox-50.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011277 build_url: 'https://queue.taskcluster.net/v1/task/I64Vk5nZRMij8TXY9r__2g/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/c485b49e87c97f39c22744c7ceafc146f32383d2714e26f544fda33fd7ac2387bdc81e1c861c78ec2f866c2979f2a1d5e6ccd12e1bef6da5f235b5db50c7b7ac", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0e1da83d66f1e3f865fee7f6a49a99e8f6292eff2f56a9d1931ec339c1f7bed66069ca90caf03eee6171f056cc0c8a92dfe24244caa6d6f79fc863899a35633e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/27c82fceb6f1ea4841108419ae14d778b64f27bc60a481fd54ed920b130b494c3de74c5a5acc2f5a078e943515549a1ff5fea0dac46be4bbd2fe38894494de92"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 14:14:29.982690) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 14:14:29.982996) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1474490792.968062-1340385509 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004536 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 14:14:30.036143) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 14:14:30.036430) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 14:14:30.038708) ========= ========= Total master_lag: 0.40 =========