builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0223 starttime: 1487880510.72 results: success (0) buildid: 20170223110523 builduid: a8f7f98ff1da4abf89d802ad7fa14909 revision: 5069348353f8fc1121e632e3208da33900627214 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-23 12:08:30.723193) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-23 12:08:30.723627) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-23 12:08:30.723899) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-23 12:08:30.752119) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 12:08:30.752450) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wDBtNUXBo1/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-02-23 12:08:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 164M=0s 2017-02-23 12:08:30 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121266 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 12:08:30.896274) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 12:08:30.896596) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wDBtNUXBo1/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.020767 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 12:08:30.945522) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-23 12:08:30.945928) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5069348353f8fc1121e632e3208da33900627214 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5069348353f8fc1121e632e3208da33900627214 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wDBtNUXBo1/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-02-23 12:08:31,053 truncating revision to first 12 chars 2017-02-23 12:08:31,054 Setting DEBUG logging. 2017-02-23 12:08:31,054 attempt 1/10 2017-02-23 12:08:31,054 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5069348353f8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-23 12:08:32,169 unpacking tar archive at: mozilla-central-5069348353f8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.377872 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-23 12:08:32.346611) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-23 12:08:32.346958) ========= script_repo_revision: 5069348353f8fc1121e632e3208da33900627214 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-23 12:08:32.347390) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-23 12:08:32.347672) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-23 12:08:32.366528) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-02-23 12:08:32.366926) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wDBtNUXBo1/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 12:08:32 INFO - MultiFileLogger online at 20170223 12:08:32 in /builds/slave/test 12:08:32 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:08:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:08:32 INFO - {'append_to_log': False, 12:08:32 INFO - 'base_work_dir': '/builds/slave/test', 12:08:32 INFO - 'blob_upload_branch': 'Firefox', 12:08:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:08:32 INFO - 'branch': 'Firefox', 12:08:32 INFO - 'buildbot_json_path': 'buildprops.json', 12:08:32 INFO - 'config_files': ('talos/mac_config.py',), 12:08:32 INFO - 'default_actions': ('clobber', 12:08:32 INFO - 'read-buildbot-config', 12:08:32 INFO - 'download-and-extract', 12:08:32 INFO - 'populate-webroot', 12:08:32 INFO - 'create-virtualenv', 12:08:32 INFO - 'install', 12:08:32 INFO - 'run-tests'), 12:08:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:08:32 INFO - 'download_minidump_stackwalk': True, 12:08:32 INFO - 'download_symbols': 'ondemand', 12:08:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:08:32 INFO - 'tooltool.py': '/tools/tooltool.py', 12:08:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:08:32 INFO - '/tools/misc-python/virtualenv.py')}, 12:08:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:08:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:08:32 INFO - 'gecko_profile': False, 12:08:32 INFO - 'gecko_profile_interval': 0, 12:08:32 INFO - 'installer_path': 'installer.exe', 12:08:32 INFO - 'log_level': 'info', 12:08:32 INFO - 'log_name': 'talos', 12:08:32 INFO - 'log_to_console': True, 12:08:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:08:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:08:32 INFO - 'opt_config_files': (), 12:08:32 INFO - 'pip_index': False, 12:08:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:08:32 INFO - 'cmd': ('bash', 12:08:32 INFO - '-c', 12:08:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:08:32 INFO - 'enabled': True, 12:08:32 INFO - 'halt_on_failure': False, 12:08:32 INFO - 'name': 'check_screen_resolution'},), 12:08:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:08:32 INFO - 'cmd': ('bash', 12:08:32 INFO - '-c', 12:08:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:08:32 INFO - 'enabled': True, 12:08:32 INFO - 'halt_on_failure': False, 12:08:32 INFO - 'name': 'check_screen_resolution'},), 12:08:32 INFO - 'run_cmd_checks_enabled': True, 12:08:32 INFO - 'suite': 'dromaeojs', 12:08:32 INFO - 'system_bits': '32', 12:08:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:08:32 INFO - 'title': 't-yosemite-r7-0223', 12:08:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:08:32 INFO - 'use_talos_json': True, 12:08:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:08:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:08:32 INFO - 'work_dir': 'build'} 12:08:32 INFO - [mozharness: 2017-02-23 20:08:32.596789Z] Running clobber step. 12:08:32 INFO - Running pre-action listener: _resource_record_pre_action 12:08:32 INFO - Running main action method: clobber 12:08:32 INFO - rmtree: /builds/slave/test/build 12:08:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:08:34 INFO - Running post-action listener: _resource_record_post_action 12:08:34 INFO - [mozharness: 2017-02-23 20:08:34.410565Z] Finished clobber step (success) 12:08:34 INFO - [mozharness: 2017-02-23 20:08:34.410685Z] Running read-buildbot-config step. 12:08:34 INFO - Running pre-action listener: _resource_record_pre_action 12:08:34 INFO - Running main action method: read_buildbot_config 12:08:34 INFO - Using buildbot properties: 12:08:34 INFO - { 12:08:34 INFO - "project": "", 12:08:34 INFO - "product": "firefox", 12:08:34 INFO - "script_repo_revision": "production", 12:08:34 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 12:08:34 INFO - "repository": "", 12:08:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 12:08:34 INFO - "buildid": "20170223110523", 12:08:34 INFO - "pgo_build": "False", 12:08:34 INFO - "basedir": "/builds/slave/test", 12:08:34 INFO - "buildnumber": 4, 12:08:34 INFO - "slavename": "t-yosemite-r7-0223", 12:08:34 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 12:08:34 INFO - "platform": "yosemite_r7", 12:08:34 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 12:08:34 INFO - "branch": "mozilla-central", 12:08:34 INFO - "repo_path": "mozilla-central", 12:08:34 INFO - "revision": "5069348353f8fc1121e632e3208da33900627214", 12:08:34 INFO - "stage_platform": "macosx64", 12:08:34 INFO - "builduid": "a8f7f98ff1da4abf89d802ad7fa14909", 12:08:34 INFO - "slavebuilddir": "test" 12:08:34 INFO - } 12:08:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/Nv66kwh3RCSSFpP3d3NMsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 12:08:34 INFO - Running post-action listener: _resource_record_post_action 12:08:34 INFO - [mozharness: 2017-02-23 20:08:34.413972Z] Finished read-buildbot-config step (success) 12:08:34 INFO - [mozharness: 2017-02-23 20:08:34.414113Z] Running download-and-extract step. 12:08:34 INFO - Running pre-action listener: _resource_record_pre_action 12:08:34 INFO - Running main action method: download_and_extract 12:08:34 INFO - mkdir: /builds/slave/test/build/tests 12:08:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:34 INFO - trying https://queue.taskcluster.net/v1/task/Nv66kwh3RCSSFpP3d3NMsw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 12:08:34 INFO - Downloading https://queue.taskcluster.net/v1/task/Nv66kwh3RCSSFpP3d3NMsw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 12:08:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nv66kwh3RCSSFpP3d3NMsw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 12:08:35 INFO - Downloaded 1199 bytes. 12:08:35 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 12:08:35 INFO - Using the following test package requirements: 12:08:35 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 12:08:35 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 12:08:35 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 12:08:35 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 12:08:35 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 12:08:35 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 12:08:35 INFO - u'jsshell-mac.zip'], 12:08:35 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 12:08:35 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 12:08:35 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 12:08:35 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 12:08:35 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 12:08:35 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 12:08:35 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 12:08:35 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 12:08:35 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 12:08:35 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 12:08:35 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 12:08:35 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:08:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nv66kwh3RCSSFpP3d3NMsw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 12:08:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nv66kwh3RCSSFpP3d3NMsw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:08:35 INFO - Fetch https://queue.taskcluster.net/v1/task/Nv66kwh3RCSSFpP3d3NMsw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 12:08:37 INFO - Expected file size: 51450560 12:08:37 INFO - Obtained file size: 51450560 12:08:53 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:08:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nv66kwh3RCSSFpP3d3NMsw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 12:08:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nv66kwh3RCSSFpP3d3NMsw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:08:53 INFO - Fetch https://queue.taskcluster.net/v1/task/Nv66kwh3RCSSFpP3d3NMsw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 12:08:54 INFO - Expected file size: 51450560 12:08:54 INFO - Obtained file size: 51450560 12:09:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Nv66kwh3RCSSFpP3d3NMsw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 12:09:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Nv66kwh3RCSSFpP3d3NMsw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:09:10 INFO - Fetch https://queue.taskcluster.net/v1/task/Nv66kwh3RCSSFpP3d3NMsw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 12:09:11 INFO - Expected file size: 14404306 12:09:11 INFO - Obtained file size: 14404306 12:09: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:11 INFO - trying https://queue.taskcluster.net/v1/task/Nv66kwh3RCSSFpP3d3NMsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 12:09:11 INFO - Downloading https://queue.taskcluster.net/v1/task/Nv66kwh3RCSSFpP3d3NMsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 12:09:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Nv66kwh3RCSSFpP3d3NMsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 12:09:13 INFO - Downloaded 74934982 bytes. 12:09:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Nv66kwh3RCSSFpP3d3NMsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 12:09:13 INFO - mkdir: /builds/slave/test/properties 12:09:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:09:13 INFO - Writing to file /builds/slave/test/properties/build_url 12:09:13 INFO - Contents: 12:09:13 INFO - build_url:https://queue.taskcluster.net/v1/task/Nv66kwh3RCSSFpP3d3NMsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 12:09:14 INFO - Running post-action listener: _resource_record_post_action 12:09:14 INFO - Running post-action listener: set_extra_try_arguments 12:09:14 INFO - [mozharness: 2017-02-23 20:09:14.581609Z] Finished download-and-extract step (success) 12:09:14 INFO - [mozharness: 2017-02-23 20:09:14.581745Z] Running populate-webroot step. 12:09:14 INFO - Running pre-action listener: _resource_record_pre_action 12:09:14 INFO - Running main action method: populate_webroot 12:09:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:09:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:09:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:09:14 INFO - '--tppagecycles', 12:09:14 INFO - '7'], 12:09:14 INFO - 'tests': ['tsvgm']}}, 12:09:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:09:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:09:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:09:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:09:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:09:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:09:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:09:14 INFO - 'talos_options': ['--disable-e10s'], 12:09:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:09:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:09:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:09:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:09:14 INFO - 'talos_options': ['--disable-e10s'], 12:09:14 INFO - 'tests': ['damp', 'tps']}, 12:09:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:09:14 INFO - 'tests': ['damp', 'tps']}, 12:09:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:09:14 INFO - 'tests': ['dromaeo_dom']}, 12:09:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:09:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:09:14 INFO - 'tests': ['basic_compositor_video']}, 12:09:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:09:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:09:14 INFO - 'tests': ['a11yr', 12:09:14 INFO - 'ts_paint', 12:09:14 INFO - 'tpaint', 12:09:14 INFO - 'sessionrestore', 12:09:14 INFO - 'sessionrestore_no_auto_restore', 12:09:14 INFO - 'tabpaint']}, 12:09:14 INFO - 'other-e10s': {'tests': ['a11yr', 12:09:14 INFO - 'ts_paint', 12:09:14 INFO - 'tpaint', 12:09:14 INFO - 'sessionrestore', 12:09:14 INFO - 'sessionrestore_no_auto_restore', 12:09:14 INFO - 'tabpaint']}, 12:09:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:09:14 INFO - 'ts_paint', 12:09:14 INFO - 'tpaint', 12:09:14 INFO - 'sessionrestore', 12:09:14 INFO - 'sessionrestore_no_auto_restore', 12:09:14 INFO - 'tabpaint']}, 12:09:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:09:14 INFO - 'ts_paint', 12:09:14 INFO - 'tpaint', 12:09:14 INFO - 'sessionrestore', 12:09:14 INFO - 'sessionrestore_no_auto_restore', 12:09:14 INFO - 'tabpaint']}, 12:09:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:09:14 INFO - 'tests': ['a11yr', 12:09:14 INFO - 'ts_paint', 12:09:14 INFO - 'tpaint', 12:09:14 INFO - 'sessionrestore', 12:09:14 INFO - 'sessionrestore_no_auto_restore', 12:09:14 INFO - 'tabpaint']}, 12:09:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:09:14 INFO - 'tests': ['a11yr', 12:09:14 INFO - 'ts_paint', 12:09:14 INFO - 'tpaint', 12:09:14 INFO - 'sessionrestore', 12:09:14 INFO - 'sessionrestore_no_auto_restore', 12:09:14 INFO - 'tabpaint']}, 12:09:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:09:14 INFO - 'tests': ['tsvgx', 12:09:14 INFO - 'tsvgr_opacity', 12:09:14 INFO - 'tart', 12:09:14 INFO - 'tscrollx', 12:09:14 INFO - 'cart', 12:09:14 INFO - 'tsvg_static']}, 12:09:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:09:14 INFO - 'tsvgr_opacity', 12:09:14 INFO - 'tart', 12:09:14 INFO - 'tscrollx', 12:09:14 INFO - 'cart', 12:09:14 INFO - 'tsvg_static']}, 12:09:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:09:14 INFO - 'talos_options': ['--disable-e10s'], 12:09:14 INFO - 'tests': ['tp5o']}, 12:09:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:09:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:09:14 INFO - 'talos_options': ['--disable-e10s', 12:09:14 INFO - '--xperf_path', 12:09:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:09:14 INFO - 'tests': ['tp5n']}, 12:09:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:09:14 INFO - 'talos_options': ['--xperf_path', 12:09:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:09:14 INFO - 'tests': ['tp5n']}}, 12:09:14 INFO - 'talos.zip': {'path': '', 12:09:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:09:14 INFO - Running post-action listener: _resource_record_post_action 12:09:14 INFO - [mozharness: 2017-02-23 20:09:14.600340Z] Finished populate-webroot step (success) 12:09:14 INFO - [mozharness: 2017-02-23 20:09:14.600491Z] Running create-virtualenv step. 12:09:14 INFO - Running pre-action listener: _resource_record_pre_action 12:09:14 INFO - Running main action method: create_virtualenv 12:09:14 INFO - Creating virtualenv /builds/slave/test/build/venv 12:09:14 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 12:09:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:09:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:09:14 INFO - Using real prefix '/tools/python27' 12:09:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:09:15 INFO - Installing distribute.............................................................................................................................................................................................done. 12:09:18 INFO - Installing pip.................done. 12:09:18 INFO - Return code: 0 12:09:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:09:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:09:18 INFO - Reading from file tmpfile_stdout 12:09:18 INFO - Output received: 12:09:18 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:09:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:09: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108dd1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dd4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe62b584460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bafd30>, '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 0x108ad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ba4be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDBtNUXBo1/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09: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', 'pip>=1.5'] in /builds/slave/test/build 12:09: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 pip>=1.5 12:09:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDBtNUXBo1/Render', 12:09:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:18 INFO - 'HOME': '/Users/cltbld', 12:09:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:18 INFO - 'LOGNAME': 'cltbld', 12:09:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:18 INFO - 'MOZ_NO_REMOTE': '1', 12:09:18 INFO - 'NO_EM_RESTART': '1', 12:09:18 INFO - 'PAGER': '/bin/cat', 12:09:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:18 INFO - 'PWD': '/builds/slave/test', 12:09:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:18 INFO - 'SHELL': '/bin/bash', 12:09:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners', 12:09:18 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 12:09:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:18 INFO - 'USER': 'cltbld', 12:09:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:18 INFO - 'XPC_FLAGS': '0x0', 12:09:18 INFO - 'XPC_SERVICE_NAME': '0', 12:09:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09: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 12:09:18 INFO - Cleaning up... 12:09:18 INFO - Return code: 0 12:09:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:09: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09: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 0x108dd1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dd4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe62b584460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bafd30>, '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 0x108ad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ba4be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDBtNUXBo1/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09: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 12:09: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 12:09:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDBtNUXBo1/Render', 12:09:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:18 INFO - 'HOME': '/Users/cltbld', 12:09:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:18 INFO - 'LOGNAME': 'cltbld', 12:09:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:18 INFO - 'MOZ_NO_REMOTE': '1', 12:09:18 INFO - 'NO_EM_RESTART': '1', 12:09:18 INFO - 'PAGER': '/bin/cat', 12:09:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:18 INFO - 'PWD': '/builds/slave/test', 12:09:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:18 INFO - 'SHELL': '/bin/bash', 12:09:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners', 12:09:18 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 12:09:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:18 INFO - 'USER': 'cltbld', 12:09:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:18 INFO - 'XPC_FLAGS': '0x0', 12:09:18 INFO - 'XPC_SERVICE_NAME': '0', 12:09:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:19 INFO - Downloading/unpacking psutil>=3.1.1 12:09:19 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 12:09:19 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 12:09:19 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 12:09:19 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 12:09:19 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 12:09:19 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 12:09:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:09:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:09:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:09:23 INFO - Installing collected packages: psutil 12:09:23 INFO - Running setup.py install for psutil 12:09:23 INFO - building 'psutil._psutil_osx' extension 12:09:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:09:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:09:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:09:24 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:09:24 INFO - building 'psutil._psutil_posix' extension 12:09:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:09:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:09:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:09:24 INFO - ^ 12:09:24 INFO - 1 warning generated. 12:09:24 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:09:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:09:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:09:24 INFO - Successfully installed psutil 12:09:24 INFO - Cleaning up... 12:09:24 INFO - Return code: 0 12:09:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:09:24 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:24 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108dd1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dd4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe62b584460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bafd30>, '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 0x108ad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ba4be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDBtNUXBo1/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:09: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 mozsystemmonitor==0.3 12:09:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDBtNUXBo1/Render', 12:09:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:25 INFO - 'HOME': '/Users/cltbld', 12:09:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:25 INFO - 'LOGNAME': 'cltbld', 12:09:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:25 INFO - 'MOZ_NO_REMOTE': '1', 12:09:25 INFO - 'NO_EM_RESTART': '1', 12:09:25 INFO - 'PAGER': '/bin/cat', 12:09:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:25 INFO - 'PWD': '/builds/slave/test', 12:09:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:25 INFO - 'SHELL': '/bin/bash', 12:09:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners', 12:09:25 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 12:09:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:25 INFO - 'USER': 'cltbld', 12:09:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:25 INFO - 'XPC_FLAGS': '0x0', 12:09:25 INFO - 'XPC_SERVICE_NAME': '0', 12:09:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:09: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 12:09: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 12:09: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 12:09: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 12:09: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 12:09: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 12:09:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:09:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:09:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:09:28 INFO - Installing collected packages: mozsystemmonitor 12:09:28 INFO - Running setup.py install for mozsystemmonitor 12:09:28 INFO - Successfully installed mozsystemmonitor 12:09:28 INFO - Cleaning up... 12:09:28 INFO - Return code: 0 12:09:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:09:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:28 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 0x108dd1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dd4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe62b584460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bafd30>, '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 0x108ad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ba4be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDBtNUXBo1/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09:28 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 12:09:28 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 12:09:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDBtNUXBo1/Render', 12:09:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:28 INFO - 'HOME': '/Users/cltbld', 12:09:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:28 INFO - 'LOGNAME': 'cltbld', 12:09:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:28 INFO - 'MOZ_NO_REMOTE': '1', 12:09:28 INFO - 'NO_EM_RESTART': '1', 12:09:28 INFO - 'PAGER': '/bin/cat', 12:09:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:28 INFO - 'PWD': '/builds/slave/test', 12:09:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:28 INFO - 'SHELL': '/bin/bash', 12:09:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners', 12:09:28 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 12:09:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:28 INFO - 'USER': 'cltbld', 12:09:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:28 INFO - 'XPC_FLAGS': '0x0', 12:09:28 INFO - 'XPC_SERVICE_NAME': '0', 12:09:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:28 INFO - Downloading/unpacking jsonschema==2.5.1 12:09: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 12:09: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 12:09: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 12:09: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 12:09: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 12:09: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 12:09:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:09:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:31 INFO - Downloading functools32-3.2.3-2.tar.gz 12:09:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:09:31 INFO - warning: no files found matching '*.txt' 12:09:31 INFO - no previously-included directories found matching 'build' 12:09:31 INFO - no previously-included directories found matching 'dist' 12:09:31 INFO - no previously-included directories found matching '.git*' 12:09:31 INFO - Installing collected packages: jsonschema, functools32 12:09:31 INFO - Running setup.py install for functools32 12:09:32 INFO - warning: no files found matching '*.txt' 12:09:32 INFO - no previously-included directories found matching 'build' 12:09:32 INFO - no previously-included directories found matching 'dist' 12:09:32 INFO - no previously-included directories found matching '.git*' 12:09:32 INFO - Successfully installed jsonschema functools32 12:09:32 INFO - Cleaning up... 12:09:32 INFO - Return code: 0 12:09:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:09: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:32 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 0x108dd1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dd4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe62b584460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bafd30>, '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 0x108ad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ba4be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDBtNUXBo1/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09:32 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 12:09:32 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 12:09:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDBtNUXBo1/Render', 12:09:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:32 INFO - 'HOME': '/Users/cltbld', 12:09:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:32 INFO - 'LOGNAME': 'cltbld', 12:09:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:32 INFO - 'MOZ_NO_REMOTE': '1', 12:09:32 INFO - 'NO_EM_RESTART': '1', 12:09:32 INFO - 'PAGER': '/bin/cat', 12:09:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:32 INFO - 'PWD': '/builds/slave/test', 12:09:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:32 INFO - 'SHELL': '/bin/bash', 12:09:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners', 12:09:32 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 12:09:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:32 INFO - 'USER': 'cltbld', 12:09:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:32 INFO - 'XPC_FLAGS': '0x0', 12:09:32 INFO - 'XPC_SERVICE_NAME': '0', 12:09:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:09:32 INFO - Cleaning up... 12:09:32 INFO - Return code: 0 12:09:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:09: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:32 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 0x108dd1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dd4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe62b584460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bafd30>, '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 0x108ad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ba4be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDBtNUXBo1/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09:32 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 12:09:32 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 12:09:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDBtNUXBo1/Render', 12:09:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:32 INFO - 'HOME': '/Users/cltbld', 12:09:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:32 INFO - 'LOGNAME': 'cltbld', 12:09:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:32 INFO - 'MOZ_NO_REMOTE': '1', 12:09:32 INFO - 'NO_EM_RESTART': '1', 12:09:32 INFO - 'PAGER': '/bin/cat', 12:09:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:32 INFO - 'PWD': '/builds/slave/test', 12:09:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:32 INFO - 'SHELL': '/bin/bash', 12:09:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners', 12:09:32 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 12:09:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:32 INFO - 'USER': 'cltbld', 12:09:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:32 INFO - 'XPC_FLAGS': '0x0', 12:09:32 INFO - 'XPC_SERVICE_NAME': '0', 12:09:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:32 INFO - Downloading/unpacking blobuploader==1.2.4 12:09:32 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 12:09:32 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 12:09:32 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 12:09:32 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 12:09:32 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 12:09:32 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 12:09:35 INFO - Downloading blobuploader-1.2.4.tar.gz 12:09:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:09:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:09:35 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 12:09:35 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 12:09:35 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 12:09:35 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 12:09:35 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 12:09:35 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 12:09:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:09:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:09:35 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 12:09:35 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 12:09:35 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 12:09:35 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 12:09:35 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 12:09:35 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 12:09:36 INFO - Downloading docopt-0.6.1.tar.gz 12:09:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:09:36 INFO - Installing collected packages: blobuploader, requests, docopt 12:09:36 INFO - Running setup.py install for blobuploader 12:09:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:09:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:09:36 INFO - Running setup.py install for requests 12:09:37 INFO - Running setup.py install for docopt 12:09:37 INFO - Successfully installed blobuploader requests docopt 12:09:37 INFO - Cleaning up... 12:09:37 INFO - Return code: 0 12:09:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09:37 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:37 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:37 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 0x108dd1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dd4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe62b584460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bafd30>, '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 0x108ad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ba4be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDBtNUXBo1/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09:37 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 12:09:37 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 12:09:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDBtNUXBo1/Render', 12:09:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:37 INFO - 'HOME': '/Users/cltbld', 12:09:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:37 INFO - 'LOGNAME': 'cltbld', 12:09:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:37 INFO - 'MOZ_NO_REMOTE': '1', 12:09:37 INFO - 'NO_EM_RESTART': '1', 12:09:37 INFO - 'PAGER': '/bin/cat', 12:09:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:37 INFO - 'PWD': '/builds/slave/test', 12:09:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:37 INFO - 'SHELL': '/bin/bash', 12:09:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners', 12:09:37 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 12:09:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:37 INFO - 'USER': 'cltbld', 12:09:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:37 INFO - 'XPC_FLAGS': '0x0', 12:09:37 INFO - 'XPC_SERVICE_NAME': '0', 12:09:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:09:37 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-isU6dY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:09:37 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-AeeMOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:09:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:09:37 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-uwRJcX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:09:38 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-0qFVhl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:09:38 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-KBCpIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:09:38 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-9NOUP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:09:38 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-7Ws4B1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:09:38 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-ypAW8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:09:38 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-nFchY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:09:38 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-o04tZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:09:38 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-FqUul0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:09:38 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-hoEpyl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:09:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:09:39 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-gAP3sM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:09:39 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-XJrUIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:39 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-0VRY4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:09:39 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-Oe_5Xg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:09:39 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-p0zpFb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:09:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:09:39 INFO - Running setup.py install for manifestparser 12:09:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:09:39 INFO - Running setup.py install for mozcrash 12:09:39 INFO - Running setup.py install for mozdebug 12:09:39 INFO - Running setup.py install for mozdevice 12:09:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:09:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:09:40 INFO - Running setup.py install for mozfile 12:09:40 INFO - Running setup.py install for mozhttpd 12:09:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:09:40 INFO - Running setup.py install for mozinfo 12:09:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:09:40 INFO - Running setup.py install for mozInstall 12:09:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:09:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:09:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:09:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:09:40 INFO - Running setup.py install for mozleak 12:09:40 INFO - Running setup.py install for mozlog 12:09:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:09:41 INFO - Running setup.py install for moznetwork 12:09:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:09:41 INFO - Running setup.py install for mozprocess 12:09:41 INFO - Running setup.py install for mozprofile 12:09:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:09:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:09:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:09:41 INFO - Running setup.py install for mozrunner 12:09:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:09:41 INFO - Running setup.py install for mozscreenshot 12:09:41 INFO - Running setup.py install for moztest 12:09:41 INFO - Running setup.py install for mozversion 12:09:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:09:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:09:42 INFO - Cleaning up... 12:09:42 INFO - Return code: 0 12:09:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09:42 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:42 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:42 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 0x108dd1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dd4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe62b584460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bafd30>, '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 0x108ad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ba4be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDBtNUXBo1/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09:42 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 12:09:42 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 12:09:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDBtNUXBo1/Render', 12:09:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:42 INFO - 'HOME': '/Users/cltbld', 12:09:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:42 INFO - 'LOGNAME': 'cltbld', 12:09:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:42 INFO - 'MOZ_NO_REMOTE': '1', 12:09:42 INFO - 'NO_EM_RESTART': '1', 12:09:42 INFO - 'PAGER': '/bin/cat', 12:09:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:42 INFO - 'PWD': '/builds/slave/test', 12:09:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:42 INFO - 'SHELL': '/bin/bash', 12:09:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners', 12:09:42 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 12:09:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:42 INFO - 'USER': 'cltbld', 12:09:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:42 INFO - 'XPC_FLAGS': '0x0', 12:09:42 INFO - 'XPC_SERVICE_NAME': '0', 12:09:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:09:42 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-Yox6kc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:09:42 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)) 12:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:09:42 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-s6VexY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:09:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 12:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:09:42 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-3BI95s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:09:42 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)) 12:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:09:42 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-_OhYzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:09:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 12:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:09:42 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-RIHL45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:09:42 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)) 12:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:09:42 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-gb4W8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:09:43 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)) 12:09:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:09:43 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-6b_Nhd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:09:43 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)) 12:09:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:09:43 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-KnK0Sy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:09:43 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)) 12:09:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:09:43 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-Ttbspu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:09:43 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)) 12:09:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:09:43 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-8CLcw1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:09:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 12:09:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:09:43 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-dwoKn0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:09:43 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)) 12:09:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:09:43 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-fdqDwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:09:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 12:09:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:09:43 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-081xib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:09:43 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)) 12:09:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:09:43 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-SUxgI3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:09:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 12:09:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:43 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-N9AFC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:44 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)) 12:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:09:44 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-KEtBfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 12:09:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:09:44 INFO - Running setup.py (path:/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/pip-Umrsun-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:09:44 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)) 12:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:09:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:09:44 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)) 12:09:44 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:09:44 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:09:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:09:44 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 12:09:44 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 12:09:44 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 12:09:44 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 12:09:44 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 12:09:44 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 12:09:46 INFO - Downloading blessings-1.6.tar.gz 12:09:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:09:46 INFO - Installing collected packages: blessings 12:09:46 INFO - Running setup.py install for blessings 12:09:47 INFO - Successfully installed blessings 12:09:47 INFO - Cleaning up... 12:09:47 INFO - Return code: 0 12:09:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:09:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:47 INFO - Reading from file tmpfile_stdout 12:09:47 INFO - Current package versions: 12:09:47 INFO - blessings == 1.6 12:09:47 INFO - blobuploader == 1.2.4 12:09:47 INFO - docopt == 0.6.1 12:09:47 INFO - functools32 == 3.2.3-2 12:09:47 INFO - jsonschema == 2.5.1 12:09:47 INFO - manifestparser == 1.1 12:09:47 INFO - mozInstall == 1.12 12:09:47 INFO - mozcrash == 1.0 12:09:47 INFO - mozdebug == 0.1 12:09:47 INFO - mozdevice == 0.49 12:09:47 INFO - mozfile == 1.2 12:09:47 INFO - mozhttpd == 0.7 12:09:47 INFO - mozinfo == 0.9 12:09:47 INFO - mozleak == 0.1 12:09:47 INFO - mozlog == 3.4 12:09:47 INFO - moznetwork == 0.27 12:09:47 INFO - mozprocess == 0.25 12:09:47 INFO - mozprofile == 0.28 12:09:47 INFO - mozrunner == 6.13 12:09:47 INFO - mozscreenshot == 0.1 12:09:47 INFO - mozsystemmonitor == 0.3 12:09:47 INFO - moztest == 0.8 12:09:47 INFO - mozversion == 1.4 12:09:47 INFO - psutil == 3.1.1 12:09:47 INFO - requests == 1.2.3 12:09:47 INFO - wsgiref == 0.1.2 12:09:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:47 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 0x108dd1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dd4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe62b584460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bafd30>, '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 0x108ad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ba4be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDBtNUXBo1/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09:47 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 12:09:47 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 12:09:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDBtNUXBo1/Render', 12:09:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:47 INFO - 'HOME': '/Users/cltbld', 12:09:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:47 INFO - 'LOGNAME': 'cltbld', 12:09:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:47 INFO - 'MOZ_NO_REMOTE': '1', 12:09:47 INFO - 'NO_EM_RESTART': '1', 12:09:47 INFO - 'PAGER': '/bin/cat', 12:09:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:47 INFO - 'PWD': '/builds/slave/test', 12:09:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:47 INFO - 'SHELL': '/bin/bash', 12:09:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners', 12:09:47 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 12:09:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:47 INFO - 'USER': 'cltbld', 12:09:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:47 INFO - 'XPC_FLAGS': '0x0', 12:09:47 INFO - 'XPC_SERVICE_NAME': '0', 12:09:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:47 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)) 12:09:47 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)) 12:09:47 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)) 12:09:47 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)) 12:09:47 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)) 12:09:47 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)) 12:09:47 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)) 12:09:47 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)) 12:09:47 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)) 12:09:47 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)) 12:09:47 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)) 12:09:47 INFO - Cleaning up... 12:09:47 INFO - Return code: 0 12:09:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:09:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:09:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108dd1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108dd4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe62b584460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108bafd30>, '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 0x108ad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ba4be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDBtNUXBo1/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:09:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:09:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:09:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDBtNUXBo1/Render', 12:09:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:47 INFO - 'HOME': '/Users/cltbld', 12:09:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:47 INFO - 'LOGNAME': 'cltbld', 12:09:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:47 INFO - 'MOZ_NO_REMOTE': '1', 12:09:47 INFO - 'NO_EM_RESTART': '1', 12:09:47 INFO - 'PAGER': '/bin/cat', 12:09:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:47 INFO - 'PWD': '/builds/slave/test', 12:09:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:47 INFO - 'SHELL': '/bin/bash', 12:09:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners', 12:09:47 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 12:09:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:47 INFO - 'USER': 'cltbld', 12:09:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:47 INFO - 'XPC_FLAGS': '0x0', 12:09:47 INFO - 'XPC_SERVICE_NAME': '0', 12:09:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:09:48 INFO - Cleaning up... 12:09:48 INFO - Return code: 0 12:09:48 INFO - Running post-action listener: _resource_record_post_action 12:09:48 INFO - Running post-action listener: _start_resource_monitoring 12:09:48 INFO - Starting resource monitoring. 12:09:48 INFO - [mozharness: 2017-02-23 20:09:48.047746Z] Finished create-virtualenv step (success) 12:09:48 INFO - [mozharness: 2017-02-23 20:09:48.048760Z] Running install step. 12:09:48 INFO - Running pre-action listener: _resource_record_pre_action 12:09:48 INFO - Running main action method: install 12:09:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:48 INFO - Reading from file tmpfile_stdout 12:09:48 INFO - Detecting whether we're running mozinstall >=1.0... 12:09:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:09:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:09:48 INFO - Reading from file tmpfile_stdout 12:09:48 INFO - Output received: 12:09:48 INFO - Usage: mozinstall [options] installer 12:09:48 INFO - Options: 12:09:48 INFO - -h, --help show this help message and exit 12:09:48 INFO - -d DEST, --destination=DEST 12:09:48 INFO - Directory to install application into. [default: 12:09:48 INFO - "/builds/slave/test"] 12:09:48 INFO - --app=APP Application being installed. [default: firefox] 12:09:48 INFO - mkdir: /builds/slave/test/build/application 12:09:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:09:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:10:08 INFO - Reading from file tmpfile_stdout 12:10:08 INFO - Output received: 12:10:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:10:08 INFO - Running post-action listener: _resource_record_post_action 12:10:08 INFO - [mozharness: 2017-02-23 20:10:08.461502Z] Finished install step (success) 12:10:08 INFO - [mozharness: 2017-02-23 20:10:08.461633Z] Running run-tests step. 12:10:08 INFO - Running pre-action listener: _resource_record_pre_action 12:10:08 INFO - Running main action method: run_tests 12:10:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:10:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:10:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:10:08 INFO - 2017-02-23 12:10:08.476 screenresolution[1915:21081] starting screenresolution argv=screenresolution get 12:10:08 INFO - 2017-02-23 12:10:08.479 screenresolution[1915:21081] Display 0: 1600x1200x32@60 12:10:08 INFO - 2017-02-23 12:10:08.485 screenresolution[1916:21084] starting screenresolution argv=screenresolution list 12:10:08 INFO - Available Modes on Display 0 12:10:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:10:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:10:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:10:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:10:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:10:08 INFO - Intel Iris: 12:10:08 INFO - Chipset Model: Intel Iris 12:10:08 INFO - Type: GPU 12:10:08 INFO - Bus: Built-In 12:10:08 INFO - VRAM (Dynamic, Max): 1536 MB 12:10:08 INFO - Vendor: Intel (0x8086) 12:10:08 INFO - Device ID: 0x0a2e 12:10:08 INFO - Revision ID: 0x0009 12:10:08 INFO - Displays: 12:10:08 INFO - Display: 12:10:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:10:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:10:08 INFO - Display Serial Number: 1600x1200 60 12:10:08 INFO - Main Display: Yes 12:10:08 INFO - Mirror: Off 12:10:08 INFO - Online: Yes 12:10:08 INFO - Rotation: Supported 12:10:08 INFO - Return code: 0 12:10:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:10:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:10:08 INFO - Python 2.7.3 12:10:08 INFO - Return code: 0 12:10:08 INFO - grabbing minidump binary from tooltool 12:10:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:08 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/macosx64/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 0x108bafd30>, '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 0x108ad6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ba4be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:10:08 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:10:08 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/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:10:08 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:10:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:10:08 INFO - Return code: 0 12:10:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:10:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:10:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:10:08 INFO - ENV: RUST_BACKTRACE is now 1 12:10:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:10:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:10:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nv66kwh3RCSSFpP3d3NMsw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0223', '--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 12:10:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Nv66kwh3RCSSFpP3d3NMsw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0223 --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 12:10:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDBtNUXBo1/Render', 12:10:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:10:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:10:08 INFO - 'HOME': '/Users/cltbld', 12:10:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:10:08 INFO - 'LOGNAME': 'cltbld', 12:10:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:10:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:10:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:08 INFO - 'MOZ_NO_REMOTE': '1', 12:10:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:10:08 INFO - 'NO_EM_RESTART': '1', 12:10:08 INFO - 'PAGER': '/bin/cat', 12:10:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:10:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:08 INFO - 'PWD': '/builds/slave/test', 12:10:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:10:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:10:08 INFO - 'RUST_BACKTRACE': '1', 12:10:08 INFO - 'SHELL': '/bin/bash', 12:10:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners', 12:10:08 INFO - 'TMPDIR': '/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/', 12:10:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:10:08 INFO - 'USER': 'cltbld', 12:10:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:10:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:10:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:08 INFO - 'XPC_FLAGS': '0x0', 12:10:08 INFO - 'XPC_SERVICE_NAME': '0', 12:10:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:10:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Nv66kwh3RCSSFpP3d3NMsw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0223', '--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 12:10:09 INFO - mozversion application_buildid: 20170223110523 12:10:09 INFO - mozversion application_changeset: 5069348353f8fc1121e632e3208da33900627214 12:10:09 INFO - mozversion application_display_name: Nightly 12:10:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:10:09 INFO - mozversion application_name: Firefox 12:10:09 INFO - mozversion application_remotingname: firefox 12:10:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:10:09 INFO - mozversion application_vendor: Mozilla 12:10:09 INFO - mozversion application_version: 54.0a1 12:10:09 INFO - mozversion platform_buildid: 20170223110523 12:10:09 INFO - mozversion platform_changeset: 5069348353f8fc1121e632e3208da33900627214 12:10:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:10:09 INFO - mozversion platform_version: 54.0a1 12:10:09 INFO - using testdate: 1487880609 12:10:09 INFO - actual date: 1487880609 12:10:09 INFO - starting webserver on 'localhost:56744' 12:10:09 INFO - SUITE-START | Running 2 tests 12:10:09 INFO - TEST-START | dromaeo_css 12:10:09 INFO - operating with platform_type : mac_ 12:10:09 INFO - Initialising browser for dromaeo_css test... 12:10:09 INFO - TEST-INFO | started process 1926 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/tmpeKx_sL/profile http://localhost:56744/getInfo.html) 12:10:09 INFO - PROCESS | 1926 | 1487880609620 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 12:10:09 INFO - PROCESS | 1926 | 1487880609621 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method install 12:10:09 INFO - PROCESS | 1926 | 1487880609674 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 12:10:11 INFO - PROCESS | 1926 | __metrics Screen width/height:1600/1200 12:10:11 INFO - PROCESS | 1926 | colorDepth:24 12:10:11 INFO - PROCESS | 1926 | Browser inner width/height: 1024/689 12:10:11 INFO - PROCESS | 1926 | __metrics 12:10:11 INFO - PROCESS | 1926 | 1487880611561 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 12:10:11 INFO - PROCESS | 1926 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:10:11 INFO - PROCESS | 1926 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:10:12 INFO - TEST-INFO | 1926: exit 0 12:10:12 INFO - Browser initialized. 12:10:12 INFO - Running cycle 1/1 for dromaeo_css test... 12:10:12 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/tmpeKx_sL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:10:12 INFO - PROCESS | 1928 | 1487880612716 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 12:10:12 INFO - PROCESS | 1928 | 1487880612717 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 12:10:24 INFO - PROCESS | 1928 | RSS: Main: 230907904 12:10:24 INFO - PROCESS | 1928 | 12:13:28 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56744/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56744/tests/dromaeo/cssquery-ext.html) 12:13:30 INFO - PROCESS | 1928 | RSS: Main: 374153216 12:13:30 INFO - PROCESS | 1928 | 12:15:14 INFO - PROCESS | 1928 | ************************* 12:15:14 INFO - PROCESS | 1928 | A coding exception was thrown and uncaught in a Task. 12:15:14 INFO - PROCESS | 1928 | 12:15:14 ERROR - PROCESS | 1928 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:15:14 INFO - PROCESS | 1928 | Full stack: 12:15:14 INFO - PROCESS | 1928 | ************************* 12:16:33 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56744/tests/dromaeo/cssquery-ext.html (next: http://localhost:56744/tests/dromaeo/cssquery-jquery.html) 12:16:34 INFO - PROCESS | 1928 | RSS: Main: 474062848 12:16:34 INFO - PROCESS | 1928 | 12:19:36 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56744/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56744/tests/dromaeo/cssquery-mootools.html) 12:19:37 INFO - PROCESS | 1928 | RSS: Main: 346284032 12:19:37 INFO - PROCESS | 1928 | 12:22:39 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56744/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56744/tests/dromaeo/cssquery-prototype.html) 12:22:40 INFO - PROCESS | 1928 | RSS: Main: 329584640 12:22:40 INFO - PROCESS | 1928 | 12:25:44 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56744/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56744/tests/dromaeo/cssquery-yui.html) 12:25:45 INFO - PROCESS | 1928 | RSS: Main: 335953920 12:25:45 INFO - PROCESS | 1928 | 12:28:48 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:56744/tests/dromaeo/cssquery-yui.html (next: http://localhost:56744/tests/dromaeo/cssquery-dojo.html) 12:28:48 INFO - PROCESS | 1928 | RSS: Main: 466915328 12:28:48 INFO - PROCESS | 1928 | 12:28:48 INFO - PROCESS | 1928 | __start_tp_report 12:28:48 INFO - PROCESS | 1928 | _x_x_mozilla_page_load 12:28:48 INFO - PROCESS | 1928 | _x_x_mozilla_page_load_details 12:28:48 INFO - PROCESS | 1928 | |i|pagename|runs| 12:28:48 INFO - PROCESS | 1928 | |0;dojo.html;3248.9025329240126;3252.6259480159783;3259.478483442648;3262.8368581570917;3309.172706823294;5340.412554618989;5479.123340265558;5528.889422211559;5584.413636568158;5600.243967064445;5440.129579267318;5513.4210907854595;5519;5521.834344969648;5534.501894829465;4966.15575352191;4996.975015124919;5003.374578177728;5060.797568097271;5076.619253555979;5056;5124.692518448905;5131.255967884655;5146.871328216787;5154.74226288686;5114.488551144875;5114.590832733373;5117.334746482952;5123.436421993578;5130.897382052375;5095.668781529226;5130.050940576006;5131.153359695646;5138;5146.305248791403;4220.767857767822;4943.876403089916;5065.138926382524;5087.923681144785;5090.134677104901;5546.112621980504;5680.233168522238;5687.232223649796;5698.715064246812;5700.287464066992;6137.41694539018;6142.754289828402;6163.907541386883;6173.166622505949;6179.3202747697715;5965.4929331006915;6035.698215089228;6047.606905551125;6071.939280607182;6141.631502109888;4145.564715704868;5643.8589035274035;5725.341585717654;5733.713314334266;5797.159411885253;6186.381361863778;6196.47329976948;6217.160683307786;6220.129181914535;6225.533085018642;6089.512838972872;6102.389761023951;6117.051856962178;6117.449429551361;6125.142480052797;6019.578629495981;6067.7269522871575;6081.3614570470345;6120.693965301717;6137.478314343241;6108.736706092886;6132.263729246456;6139.19845745766;6143.849652621636;6156.076588511759;6125.816225513153;6176.382361763787;6178.289496707846;6181.134641150243;6190;4753.405824271966;4827.444843843003;4830.396200474941;4903.754812259373;4942.629302802305;4286.121345124242;4293.613574778285;4305.526392096866;4311.568843115726;4320.481542214891;4684.461286951976;5575.358833734092;5585.469380408855;5592.86017849557;5619.269494965628;6111.54163437744;6155.907661385083;6157.322694503601;6159;6159.1069294951985;4528.932066019012;5368.1679339702405;5425.104857698515;5429.104197807396;5484.643498172606;5240.868978275574;5246.606504512177;5247.10799164143;5249.212618107238;5269.130593452114;5140;6130.153553065287;6183.165272688219;6188.845278867974;6192.845178870564;4705.55297246761;5105.540501354896;6001.609895356788;6022.006368949074;6053.727582258721;6189.3501182377;6197.349278325711;6212.254529456494;6212.378762123752;6215.595986260879;5900.675462849585;5914.024186009432;5923.911141332797;5934.821955341346;5942.554308426799;5069.442361340176;5973.372795856546;5980.43185897339;5998.550108741774;6095.939040609537;4926.679765815211;5955.731992060281;6000.369961154016;6008.24896887889;6026.608270462406;5564.38791732901;5794.304683438015;5987.072003839412;5995.46040856325;5997.130416089555;5665.915011274752;5716.828495145152;5770.769169233352;5785.595008349375;5865.530757539472;5980.401959803985;5985.132155837467;5987.221661184192;5990.460858522754;5994.340622531431;4701.294805779161;5589.965856316594;5782.045962416154;5792.160136780228;5805.245318108618 12:28:48 INFO - PROCESS | 1928 | |1;ext.html;456.8332558616106;483.07973310842846;488.1238177471439;495.7645118568678;501.76667849450024;27350.31624209396;28678.28304292391;28794.568081478796;28826.711732882664;28910.855445722744;40501.39247911284;42390.15219695604;42396.364054539205;45044.774776126156;45064.77467612665;9402.623895044208;9604.327697061208;9614.855777163348;9620.42277463351;9629.229661627052;7405.259474052578;9384.577694003752;9493.145616894479;9494;9516.19112375449;7514.39884809214;11400.942995285011;11692.006179474754;11707.941460292686;11823.054155667525;11575.189736718434;11641.35972521511;11673.94163029188;11676.591319303869;11684.00685941696;7464.477486575942;11410.42947852604;11443.771124577463;11478.426078696031;11483.540658373739;27416.725832741817;39230.21539569193;39960.20079598392;40214.59785402138;42261;3790.886273411802;3793.203427280247;3813;3850.6919446444217;3972.0268534209013;29911.551326730118;30054.24864378408;30693.539596906063;31010.224744381347;31557.684423155704;3143.685631436861;3156.675354763965;3163.9209019774457;3174.68253174683;3278.360719659667;436691.81654091843;436802;437885;440141;448799.99999999674;60210.698946505865;60535;60641;60729.69635151796;60750;50415.74792126016;51414.74292628588;62714.74570508564;62780;62819.42951426251;13377.996650251076;15041.774373384407;15162.924185379003;15335.539933802003;15821.446249381217;11371.033462155809;11857.762844743229;11949.522019119311;11968.341741204318;11995.520179192908;14321.283935803169;16011.27949242287;16014.279357428944;16031.274683303865;16066.75899861503;5774.852705275634;6810.9318906810295;6820.624865632438;6853.4859885507785;6894.413974812197;725.6190499987549;740.8740514112613;745.6495447139836;755.7732680196029;756.5649751392971;28807.855960720062;28877.278068048046;29108.854455727585;29715.851420742758;29754.553681694793;1005.2209537608412;1023.4064242739194;1030.2067408095725;1048.119579553179;1048.5962904281907;14543.418263269563;15034;15120.697586048438;15388.692226155637;15494.07035577869;495853;497954;499578;500668;500715;56785.71607141938;56954.7152264236;57055.42944570501;57137.42862571404;57167.71416142893;1327.634900402397;1389;1392.6309527975095;1392.7144935288445;1395.1280449718927;25804;27618.309542261602;28352.14943551614;29028.7097129026;29166.416671666873;48006;48547.27179092246;48628.75685621549;48644.270335945694;48649;488073;489567;492181;492396;493936;28579.714202858537;31367;37915.431268531545;39218.80390598029;41420.964475888344;44859.65421037374;46014.539854601026;46171.53828461673;46555;46674.53325466838;34627.826860865534;35370.29259414849;37260.81369593243;38750;38814.22371552497;28571.571426428203;29396.412071758874;35452;35601.64398356087;37116.81441592775;36547.26905461929;37062.62937370592;37288;37411.43882841814;37427.06432339214;28228.012019579215;32050.679493205702;32847.83576082104;32932.34135317232;33206.83396583002;22845.88577057104;23029.40914445268;24058.87970560136;24627.261182164562;24682.259532214062 12:28:48 INFO - PROCESS | 1928 | |2;jquery.html;359.1326945426794;362.11824208053395;388.795882161865;392.4584073977911;397.95423526294485;13413.530526431578;13888.861111388884;16909.154542272867;17212.827871721278;21019.894900525513;21158.57682846346;21502.354929352106;21606.675899861515;21660.8916955415;21889.890550547265;5794.478496935264;6154.815355539341;6163.414475624809;6165.722542485577;6167.62994220346;3832.540095188567;4042.9595704042877;5970.582059255854;6284.434400903918;6584.6707664616815;6412.64730439828;6623.933760662404;6647.20233571972;6647.20233571972;6658.800235992928;5683.744231509592;6616.27221005689;6636.336366363374;6674.132362792854;6698.698558564876;5580.888382232373;5688.772449102031;5773.719380844543;5852.326982397036;5856.063029915235;23514.294571162893;24441.633375499383;24496.265112046665;25990.61014084771;26126.085587004553;2957.8055699844117;2974.9851250743823;3019.743321817648;3038.7113224243662;3120.890768823103;10047.145992590638;10156.644517441853;10178.982101789836;10181.236407269484;10508.316959397614;1422.786582012696;1744.3371518822894;1789.6152327249704;1807.5940813466934;1821.3716267887557;127098;132717;134255;135215;136662;11229.66311010671;11736;11747.941260293816;11805.704857378634;11964.521419143137;13140.737185256432;13747.931260343634;13748.243846588533;14080.577582672537;14105.224212668196;5914.142449344907;6051.878962420727;8270.958645206736;8349.415540912078;8392.958035209786;7985.920140798634;8078.83842323162;8215.87796637286;8230.012398512217;8283.384739975647;4590.632749380042;4688.507706690748;4691.859244222679;4779.9924031906685;4799.224287013827;1503;1612.9032258064553;1887.4526387347516;1950.4246247357064;1983.7718662353725;879.3097418526426;990.821652102614;1025.3847691385258;1033.49358814182;1076.020821052838;20182.798172018385;21357.572848542943;22269.331920042423;22285.888570557043;22334.329970100924;1036.9014943580348;1037.621268237088;1066.4241309692834;1072.7371793910543;1073.7745073534488;10473.371597704163;11496.482658280398;11579.363135027486;11592.88407115935;11595.246308989888;516721;623163;623841.8542331212;626677;636870;101151;133773.33113334567;135170.3241483806;135487.32256338655;139125.3043734795;887.3566664168555;893.6827426263712;917.4128557723062;919.5172534419484;922;19103.904480477508;19272.8072719271;19321.4203573893;19329.903350483157;19367.128479218187;52570.73714631402;52879;52889.735551321995;53176.89638968583;54313;77607.61196193982;77734.2226577727;77936.2206377929;79899.60259359893;81100;6645.269020407654;7687.2697093776005;8042.42642354441;8047.51714897108;8135.959320203361;8688.739337819876;9867.013298670076;9919;10065;10162;11160.386178759922;11339.262947908359;11356.602518911799;11377.374244416636;11402;10010.899891000996;10980.615678451215;11204.047655949336;11239.157063220127;11240.447618714106;11154.274972126786;11235.269707469315;11322.433878306216;11497.48261328259;11529.250598711056;7996.280334769898;8178.7955301117945;8205.220504052106;8469.61886715087;8470.110638382881;9923.454210018519;10834.620788272372;10934.343939363664;11027.503762330874;11125.22123451383 12:28:48 INFO - PROCESS | 1928 | |3;mootools.html;1328.8206092177554;1402.807613528501;1449.405743645105;1451.6951440197558;1452.317410816916;8084.514929104258;8516.190961858614;8519.020312664046;8520.446170998888;8526.317894568427;7916.208379162095;8351;8422.28410585101;8450.619722112504;8547.14528547145;2190.4742861713194;2229.2643427668913;2231.129859354853;2233.7766223377694;2235.5975924333616;1925.2876435718804;2187.5515519318583;2292.2550171194343;2298.4139044543617;2302.033146078651;1905.8189472000206;1993.2824183294003;1996.880187188766;2033.1460786469645;2114.2071723103836;2227.521082967162;2252;2252.68462415262;2257.7177852768405;2263.943401414978;2204.0963205085836;2213.302809614966;2213.7454192767877;2219.8557093789013;2272.9772702273094;7108.644567771591;8348;8423.620937057785;8432.114627964036;8435.704750333709;5009.774560144766;5175;5177.741112944338;5178;5178.741062946875;3237.190702324419;3422.281320922609;3429.4855771634457;3437.9828100859586;3490.4938783876196;1142.4344949250092;1196.6948428150854;1213.8482689663792;1244.0420875925577;1244.5333000124954;10689.358638481639;10791;10923.39921304328;10936.125109991182;10937.234393592451;3616.9638303617153;4215.936760948588;4284.186004659105;4337.891552711208;4341.739495630272;8637.093105223987;8652.437591556529;8654.394192406597;8656.004559475616;8665.696700615448;4986.32684587576;4992.900141997213;4998.2502624606595;5011.649184557119;5016.974915125474;4505.234110201273;4549.818007279671;4641.95358046422;4696.929546056812;4709.3642358281195;3519.436890097572;3520.964790352115;3580.5524309461316;3606.0804494853646;3606.549181352331;1101.8677758669012;1106.5739690219164;1107.7562936154034;1125.6285425809463;1127.4813585750646;626.8871603111484;740.0859937976595;748.2181120728851;775.3835700618022;807.0557447786105;4152.750834949913;5236.816711415082;5371.973140134352;5471.343438787371;5680.375158732536;475.47935011162724;543.1391244876835;543.5135553679435;564.664024905189;571.3286887906776;2931.2232258451527;3293.374258890803;3385.1029477188563;3456.5333679952882;3515.367233897923;8114.918850811534;9233.076692330847;10082.04220599042;10090.09189172978;10104.646337378303;9918.752031199278;10046.497675116361;10064.396136231702;10076.49617519121;10094.949525252327;7642.541447513167;7710.383169346439;7749.418793590503;7754.147043825287;7908.841823163504;5408.648437851527;5501.642393244426;5541.972290138524;5597.244372009889;5744.195812586157;9025.458472491671;9131.634734610541;9156.542172891195;9228.261739060725;9359.766005849908;9142.45145291258;9382.29632777558;9460.763480913032;9567.37812042215;9580.281478888972;6581.3747693969;6742;6753.864922701617;6756.155480564929;6771.898421523582;7840.921590784019;7844.450888437755;7857.842843143219;7899.486533375312;7944.880826787718;5454.863628409322;6219.502439804895;7212.386947109345;7454.142773580891;7521.360684341892;6185.381461853958;7191.352778249982;7281.890771638536;7304.306090921354;7391.556506609621;6251.71867265965;6299.874002519832;6440.355964403522;6441.742330306923;6448.806535803933;4791.904161916812;4844.539768721966;4902.362692850049;4935.284383764406;5080.542751152414;4538.841140560065;4890;4930.926036109389;5089.949100508948;5095.796168153381 12:28:48 INFO - PROCESS | 1928 | |4;prototype.html;314.7183270972481;326.6602733157516;337.2142907026628;357.27116681968795;359.8686479435007;4884.5359690829355;4955.207166853304;4972.900541989163;4988.925166122502;5035.194368900977;4911.035585765696;4934.901301973968;4944.5797107245835;5010.924836127461;5064.24036394541;3791.947482276766;3815.389537673973;3856.537215534133;4062.53280872699;4096.918061638766;3538.44386391676;3748.9690335157775;3938.369860822269;4020.1959608078355;4121.113960498492;3779.4897688811934;3989.803059082278;4058.776767277813;4066.6136717011836;4068.9796551017353;3282.2450836307785;3384.526166336715;3901.5903330150495;3912.139329347539;4076.408920706481;3392.1689186149306;3548.574171099459;3566.5185199997927;3598.334308153;3602.837872295753;4388.758618275993;4818;4860.489648586883;5063.8480845574695;5078.822241221539;3505.7370697197557;4051.534073581546;4437.2900335946415;4542.182407166709;4561.126490311116;4823.493533179036;5016.545201891484;5035.269885866565;5307.575393968474;5418.349798024262;3163.414768267855;3811.542614886204;3929.292727309083;3941.4876066111224;3948.9268134229474;4762.78567464465;5269.919666468365;5607.915881261784;5750.8146904176565;5840.182374467578;3400.9117082533353;3601.5678118625933;4056.9797151014645;4096.897577560525;4118.835246590163;3500.562429696288;3505.386557352453;4050.1292222171787;4185.790710464465;4193.433886425354;4586.8132853237075;4709.764511774398;4719.95280047202;4740.66815322931;4755.286706993909;5279.313689220454;5432.483914028161;5441.863953401117;5504.559635229172;5516.200150978086;1266.0818859427975;1306.158316773345;1309.796981467874;1348.9392977316045;1367.9452821887212;889.7375274294099;907.8138981508774;912.0560220172223;914.2731528434911;914.5472990869562;4524.34397012438;4561.954380456219;4762.357081793983;5005.299258103868;5137.563307118937;4724.4094488188975;5012.323336349619;5084.61872854555;5285.207218917193;5378.1663842104535;4632.113120657738;4876.804927802848;5037.974810125926;5075.365579302587;5094.089716356367;2360.4688944987583;2525.747425257496;2602.5315443220024;2789.567617019365;2841.559558268472;4582.9541704582525;5202.81790137351;5301.761420736076;5585.944140558624;5596.313769390634;5129.255643380353;5143.5113664201845;5256.26412302278;5312.150055991023;5411.724737457364;4582.816687332536;4932.926006109839;5108.873121994797;5282.7622756976025;5519.420460851553;4475.149721552894;5323.467653234646;5342.519173274424;5371.516563509303;5389.7035663038905;4389.978050109729;4533.501314855429;4857.927131092966;4893.629783660581;5228.63399562027;4644.049499210272;5305.32882142124;5347.310355854448;5451.863703407446;5617.32592088952;3552.3073000764603;3757.6242375762204;3812.618738126165;3856.3444214483043;3895.844166233319;4707.199776038014;4912.5753531475475;5248.527632513092;5248.895022099613;5276.393214780196;4372.497162826316;4535.160995215896;4981.352424184833;5197.324347834758;5271.736413179402;4538.798682638983;4837.201861692782;4956.430010548833;5168.966206758588;5270.525652691276;5036;5091.312672789274;5098;5242.670903975926;5250.369955605352;5098.796048158032;5168.896622067462;5236.476352364733;5291.947080529146;5354.384245811626;4432.800523976364;4853.757312134308;5238.2975532952705;5301.3373328333955;5381.596380271417 12:28:48 INFO - PROCESS | 1928 | |5;yui.html;728.8105092675903;758.901342825433;807.8626633472309;809.2352726673294;825.8472182646211;14894.329755161016;15261.69476610467;15263.084214947097;15277.770833437507;15287;13866.168029918212;13960.58118256454;13987.090839095452;14000.0899941504;14017.719645607081;884.3986089459181;892.393172642606;903.9276857851356;910.4901255297021;925.8981512033705;782.2255966593088;923.5936188077225;932.7621456528576;934.8223837470892;934.9158575728183;964.5707660091263;986.9753256168582;987.4914419074147;989.7080361293399;989.8812142542869;978.3102912446708;981.9852702209473;982.513655740406;983.1201075037836;984.5913945712521;728.0571659700647;728.2026181331433;728.9562626242389;728.9599072051035;729.2889432803028;14389.77686896615;14942.850571494255;15102.848971510253;15144.621384465367;15457.768133478006;13661.316934153252;13953.581392558239;13986.580402587837;14044.92977535116;14120;10904;11316.943415282953;11424.200305978586;11916.46375913086;11947.880521194851;4384.035512187345;4405.933910991338;4452.732836029849;4467.151241264182;4479.960292840303;86852.13147868567;87106;89317.55341223252;89434.5528272361;90304;9405.717828465156;10294.794104118026;10412.947935260276;10428.530716117793;10702.785944281071;19978.600427991358;20260.797392026187;20391.4902127448;20448.079836407396;20481.79518204799;7223.855522889513;7261.89107163393;7319.41444684424;7352.411807055422;7497;4022.35642297231;4070.5929407059057;4118.91762164761;4139.586041395836;4222.071144348239;3455.2571197192724;3533.452314891196;3539.380608393521;3548.8580456781956;3564.6078931317534;648.2674577727232;650.5348675696899;658.2495954611746;678.7115475922714;679.6737565968363;516.8888688931822;520.276815226835;528.5084871069942;546.2461802712231;562.2746656812748;9192.632294708137;9475.979712779872;9672.419654820733;9713.431348118615;9725.854112188323;545.5281181777734;570.6889745088937;611.3641812515024;633.1547384241996;647.1263793878227;4678.976605116953;4778.283257511332;5062.620303477254;5316.441773613792;5380.892382152335;84634;98570.70724744872;99617.50191248997;99929.50035249777;101002;53669;61236;67402.66298668475;84002.57998709967;86193.56903215569;61214;67742;72554.27445725475;75259.62370188114;79109.94685876765;10570.630027948984;10583.31208471447;10662.73343166427;10793.460326983777;10915.454227288445;47010.29484557666;47384;47599.762001189774;47855;48025.519744802106;59509;62700.68649656723;63101.684491577245;63867;64212.67893660502;12986.935065324613;13094.541691040768;13461.259630720404;13729.719497676371;14311.427542898167;11633.53465861356;13915.443382264595;14109.365078571695;14747.967642265368;14763.557093287218;12968.610941671765;13132.934335328262;13240.933795331348;13252.668683282995;13683.931580342034;12970.092093553361;13081.934590326988;13255.734885302432;13586.456541738615;13759.793603096161;13008.544700935421;13303;13449.260290684106;13812;13932;8020.398470114648;10851.782964340828;10931.344119352865;11050.668479945614;11658.53365865356;8350.87473687883;9140.22308103792;9831.852522212315;10462;10740.96346621216 12:28:48 INFO - PROCESS | 1928 | __end_tp_report 12:28:48 INFO - PROCESS | 1928 | __start_cc_report 12:28:48 INFO - PROCESS | 1928 | _x_x_mozilla_cycle_collect,1657 12:28:48 INFO - PROCESS | 1928 | __end_cc_report 12:28:48 INFO - PROCESS | 1928 | __startTimestamp1487881728672__endTimestamp 12:28:48 INFO - PROCESS | 1928 | 12:28:48 INFO - PROCESS | 1928 | ------- Summary: start ------- 12:28:48 INFO - PROCESS | 1928 | Number of tests: 6 12:28:48 INFO - PROCESS | 1928 | 12:28:48 INFO - PROCESS | 1928 | [#0] dojo.html Cycles:165 Average:5551.23 Median:5725.34 stddev:666.15 (11.6%) stddev-sans-first:643.24 12:28:48 INFO - PROCESS | 1928 | Values: 3248.9 3252.6 3259.5 3262.8 3309.2 5340.4 5479.1 5528.9 5584.4 5600.2 5440.1 5513.4 5519.0 5521.8 5534.5 4966.2 4997.0 5003.4 5060.8 5076.6 5056.0 5124.7 5131.3 5146.9 5154.7 5114.5 5114.6 5117.3 5123.4 5130.9 5095.7 5130.1 5131.2 5138.0 5146.3 4220.8 4943.9 5065.1 5087.9 5090.1 5546.1 5680.2 5687.2 5698.7 5700.3 6137.4 6142.8 6163.9 6173.2 6179.3 5965.5 6035.7 6047.6 6071.9 6141.6 4145.6 5643.9 5725.3 5733.7 5797.2 6186.4 6196.5 6217.2 6220.1 6225.5 6089.5 6102.4 6117.1 6117.4 6125.1 6019.6 6067.7 6081.4 6120.7 6137.5 6108.7 6132.3 6139.2 6143.8 6156.1 6125.8 6176.4 6178.3 6181.1 6190.0 4753.4 4827.4 4830.4 4903.8 4942.6 4286.1 4293.6 4305.5 4311.6 4320.5 4684.5 5575.4 5585.5 5592.9 5619.3 6111.5 6155.9 6157.3 6159.0 6159.1 4528.9 5368.2 5425.1 5429.1 5484.6 5240.9 5246.6 5247.1 5249.2 5269.1 5140.0 6130.2 6183.2 6188.8 6192.8 4705.6 5105.5 6001.6 6022.0 6053.7 6189.4 6197.3 6212.3 6212.4 6215.6 5900.7 5914.0 5923.9 5934.8 5942.6 5069.4 5973.4 5980.4 5998.6 6095.9 4926.7 5955.7 6000.4 6008.2 6026.6 5564.4 5794.3 5987.1 5995.5 5997.1 5665.9 5716.8 5770.8 5785.6 5865.5 5980.4 5985.1 5987.2 5990.5 5994.3 4701.3 5590.0 5782.0 5792.2 5805.2 12:28:48 INFO - PROCESS | 1928 | 12:28:48 INFO - PROCESS | 1928 | [#1] ext.html Cycles:180 Average:61753.06 Median:37274.41 stddev:126885.68 (340.4%) stddev-sans-first:127158.16 12:28:48 INFO - PROCESS | 1928 | Values: 456.8 483.1 488.1 495.8 501.8 27350.3 28678.3 28794.6 28826.7 28910.9 40501.4 42390.2 42396.4 45044.8 45064.8 9402.6 9604.3 9614.9 9620.4 9629.2 7405.3 9384.6 9493.1 9494.0 9516.2 7514.4 11400.9 11692.0 11707.9 11823.1 11575.2 11641.4 11673.9 11676.6 11684.0 7464.5 11410.4 11443.8 11478.4 11483.5 27416.7 39230.2 39960.2 40214.6 42261.0 3790.9 3793.2 3813.0 3850.7 3972.0 29911.6 30054.2 30693.5 31010.2 31557.7 3143.7 3156.7 3163.9 3174.7 3278.4 436691.8 436802.0 437885.0 440141.0 448800.0 60210.7 60535.0 60641.0 60729.7 60750.0 50415.7 51414.7 62714.7 62780.0 62819.4 13378.0 15041.8 15162.9 15335.5 15821.4 11371.0 11857.8 11949.5 11968.3 11995.5 14321.3 16011.3 16014.3 16031.3 16066.8 5774.9 6810.9 6820.6 6853.5 6894.4 725.6 740.9 745.6 755.8 756.6 28807.9 28877.3 29108.9 29715.9 29754.6 1005.2 1023.4 1030.2 1048.1 1048.6 14543.4 15034.0 15120.7 15388.7 15494.1 495853.0 497954.0 499578.0 500668.0 500715.0 56785.7 56954.7 57055.4 57137.4 57167.7 1327.6 1389.0 1392.6 1392.7 1395.1 25804.0 27618.3 28352.1 29028.7 29166.4 48006.0 48547.3 48628.8 48644.3 48649.0 488073.0 489567.0 492181.0 492396.0 493936.0 28579.7 31367.0 37915.4 39218.8 41421.0 44859.7 46014.5 46171.5 46555.0 46674.5 34627.8 35370.3 37260.8 38750.0 38814.2 28571.6 29396.4 35452.0 35601.6 37116.8 36547.3 37062.6 37288.0 37411.4 37427.1 28228.0 32050.7 32847.8 32932.3 33206.8 22845.9 23029.4 24058.9 24627.3 24682.3 12:28:48 INFO - PROCESS | 1928 | 12:28:48 INFO - PROCESS | 1928 | [#2] jquery.html Cycles:180 Average:35584.37 Median:24468.95 stddev:101722.06 (415.7%) stddev-sans-first:101973.03 12:28:48 INFO - PROCESS | 1928 | Values: 359.1 362.1 388.8 392.5 398.0 13413.5 13888.9 16909.2 17212.8 21019.9 21158.6 21502.4 21606.7 21660.9 21889.9 5794.5 6154.8 6163.4 6165.7 6167.6 3832.5 4043.0 5970.6 6284.4 6584.7 6412.6 6623.9 6647.2 6647.2 6658.8 5683.7 6616.3 6636.3 6674.1 6698.7 5580.9 5688.8 5773.7 5852.3 5856.1 23514.3 24441.6 24496.3 25990.6 26126.1 2957.8 2975.0 3019.7 3038.7 3120.9 10047.1 10156.6 10179.0 10181.2 10508.3 1422.8 1744.3 1789.6 1807.6 1821.4 127098.0 132717.0 134255.0 135215.0 136662.0 11229.7 11736.0 11747.9 11805.7 11964.5 13140.7 13747.9 13748.2 14080.6 14105.2 5914.1 6051.9 8271.0 8349.4 8393.0 7985.9 8078.8 8215.9 8230.0 8283.4 4590.6 4688.5 4691.9 4780.0 4799.2 1503.0 1612.9 1887.5 1950.4 1983.8 879.3 990.8 1025.4 1033.5 1076.0 20182.8 21357.6 22269.3 22285.9 22334.3 1036.9 1037.6 1066.4 1072.7 1073.8 10473.4 11496.5 11579.4 11592.9 11595.2 516721.0 623163.0 623841.9 626677.0 636870.0 101151.0 133773.3 135170.3 135487.3 139125.3 887.4 893.7 917.4 919.5 922.0 19103.9 19272.8 19321.4 19329.9 19367.1 52570.7 52879.0 52889.7 53176.9 54313.0 77607.6 77734.2 77936.2 79899.6 81100.0 6645.3 7687.3 8042.4 8047.5 8136.0 8688.7 9867.0 9919.0 10065.0 10162.0 11160.4 11339.3 11356.6 11377.4 11402.0 10010.9 10980.6 11204.0 11239.2 11240.4 11154.3 11235.3 11322.4 11497.5 11529.3 7996.3 8178.8 8205.2 8469.6 8470.1 9923.5 10834.6 10934.3 11027.5 11125.2 12:28:48 INFO - PROCESS | 1928 | 12:28:48 INFO - PROCESS | 1928 | [#3] mootools.html Cycles:180 Average:5302.04 Median:5014.31 stddev:2987.30 (59.6%) stddev-sans-first:2980.76 12:28:48 INFO - PROCESS | 1928 | Values: 1328.8 1402.8 1449.4 1451.7 1452.3 8084.5 8516.2 8519.0 8520.4 8526.3 7916.2 8351.0 8422.3 8450.6 8547.1 2190.5 2229.3 2231.1 2233.8 2235.6 1925.3 2187.6 2292.3 2298.4 2302.0 1905.8 1993.3 1996.9 2033.1 2114.2 2227.5 2252.0 2252.7 2257.7 2263.9 2204.1 2213.3 2213.7 2219.9 2273.0 7108.6 8348.0 8423.6 8432.1 8435.7 5009.8 5175.0 5177.7 5178.0 5178.7 3237.2 3422.3 3429.5 3438.0 3490.5 1142.4 1196.7 1213.8 1244.0 1244.5 10689.4 10791.0 10923.4 10936.1 10937.2 3617.0 4215.9 4284.2 4337.9 4341.7 8637.1 8652.4 8654.4 8656.0 8665.7 4986.3 4992.9 4998.3 5011.6 5017.0 4505.2 4549.8 4642.0 4696.9 4709.4 3519.4 3521.0 3580.6 3606.1 3606.5 1101.9 1106.6 1107.8 1125.6 1127.5 626.9 740.1 748.2 775.4 807.1 4152.8 5236.8 5372.0 5471.3 5680.4 475.5 543.1 543.5 564.7 571.3 2931.2 3293.4 3385.1 3456.5 3515.4 8114.9 9233.1 10082.0 10090.1 10104.6 9918.8 10046.5 10064.4 10076.5 10094.9 7642.5 7710.4 7749.4 7754.1 7908.8 5408.6 5501.6 5542.0 5597.2 5744.2 9025.5 9131.6 9156.5 9228.3 9359.8 9142.5 9382.3 9460.8 9567.4 9580.3 6581.4 6742.0 6753.9 6756.2 6771.9 7840.9 7844.5 7857.8 7899.5 7944.9 5454.9 6219.5 7212.4 7454.1 7521.4 6185.4 7191.4 7281.9 7304.3 7391.6 6251.7 6299.9 6440.4 6441.7 6448.8 4791.9 4844.5 4902.4 4935.3 5080.5 4538.8 4890.0 4930.9 5089.9 5095.8 12:28:48 INFO - PROCESS | 1928 | 12:28:48 INFO - PROCESS | 1928 | [#4] prototype.html Cycles:180 Average:4311.09 Median:4845.48 stddev:1261.89 (26.0%) stddev-sans-first:1229.27 12:28:48 INFO - PROCESS | 1928 | Values: 314.7 326.7 337.2 357.3 359.9 4884.5 4955.2 4972.9 4988.9 5035.2 4911.0 4934.9 4944.6 5010.9 5064.2 3791.9 3815.4 3856.5 4062.5 4096.9 3538.4 3749.0 3938.4 4020.2 4121.1 3779.5 3989.8 4058.8 4066.6 4069.0 3282.2 3384.5 3901.6 3912.1 4076.4 3392.2 3548.6 3566.5 3598.3 3602.8 4388.8 4818.0 4860.5 5063.8 5078.8 3505.7 4051.5 4437.3 4542.2 4561.1 4823.5 5016.5 5035.3 5307.6 5418.3 3163.4 3811.5 3929.3 3941.5 3948.9 4762.8 5269.9 5607.9 5750.8 5840.2 3400.9 3601.6 4057.0 4096.9 4118.8 3500.6 3505.4 4050.1 4185.8 4193.4 4586.8 4709.8 4720.0 4740.7 4755.3 5279.3 5432.5 5441.9 5504.6 5516.2 1266.1 1306.2 1309.8 1348.9 1367.9 889.7 907.8 912.1 914.3 914.5 4524.3 4562.0 4762.4 5005.3 5137.6 4724.4 5012.3 5084.6 5285.2 5378.2 4632.1 4876.8 5038.0 5075.4 5094.1 2360.5 2525.7 2602.5 2789.6 2841.6 4583.0 5202.8 5301.8 5585.9 5596.3 5129.3 5143.5 5256.3 5312.2 5411.7 4582.8 4932.9 5108.9 5282.8 5519.4 4475.1 5323.5 5342.5 5371.5 5389.7 4390.0 4533.5 4857.9 4893.6 5228.6 4644.0 5305.3 5347.3 5451.9 5617.3 3552.3 3757.6 3812.6 3856.3 3895.8 4707.2 4912.6 5248.5 5248.9 5276.4 4372.5 4535.2 4981.4 5197.3 5271.7 4538.8 4837.2 4956.4 5169.0 5270.5 5036.0 5091.3 5098.0 5242.7 5250.4 5098.8 5168.9 5236.5 5291.9 5354.4 4432.8 4853.8 5238.3 5301.3 5381.6 12:28:48 INFO - PROCESS | 1928 | 12:28:48 INFO - PROCESS | 1928 | [#5] yui.html Cycles:180 Average:18803.28 Median:26316.64 stddev:25842.39 (98.2%) stddev-sans-first:25879.24 12:28:48 INFO - PROCESS | 1928 | Values: 728.8 758.9 807.9 809.2 825.8 14894.3 15261.7 15263.1 15277.8 15287.0 13866.2 13960.6 13987.1 14000.1 14017.7 884.4 892.4 903.9 910.5 925.9 782.2 923.6 932.8 934.8 934.9 964.6 987.0 987.5 989.7 989.9 978.3 982.0 982.5 983.1 984.6 728.1 728.2 729.0 729.0 729.3 14389.8 14942.9 15102.8 15144.6 15457.8 13661.3 13953.6 13986.6 14044.9 14120.0 10904.0 11316.9 11424.2 11916.5 11947.9 4384.0 4405.9 4452.7 4467.2 4480.0 86852.1 87106.0 89317.6 89434.6 90304.0 9405.7 10294.8 10412.9 10428.5 10702.8 19978.6 20260.8 20391.5 20448.1 20481.8 7223.9 7261.9 7319.4 7352.4 7497.0 4022.4 4070.6 4118.9 4139.6 4222.1 3455.3 3533.5 3539.4 3548.9 3564.6 648.3 650.5 658.2 678.7 679.7 516.9 520.3 528.5 546.2 562.3 9192.6 9476.0 9672.4 9713.4 9725.9 545.5 570.7 611.4 633.2 647.1 4679.0 4778.3 5062.6 5316.4 5380.9 84634.0 98570.7 99617.5 99929.5 101002.0 53669.0 61236.0 67402.7 84002.6 86193.6 61214.0 67742.0 72554.3 75259.6 79109.9 10570.6 10583.3 10662.7 10793.5 10915.5 47010.3 47384.0 47599.8 47855.0 48025.5 59509.0 62700.7 63101.7 63867.0 64212.7 12986.9 13094.5 13461.3 13729.7 14311.4 11633.5 13915.4 14109.4 14748.0 14763.6 12968.6 13132.9 13240.9 13252.7 13683.9 12970.1 13081.9 13255.7 13586.5 13759.8 13008.5 13303.0 13449.3 13812.0 13932.0 8020.4 10851.8 10931.3 11050.7 11658.5 8350.9 9140.2 9831.9 10462.0 10741.0 12:28:48 INFO - PROCESS | 1928 | -------- Summary: end -------- 12:28:48 INFO - PROCESS | 1928 | 12:28:48 INFO - PROCESS | 1928 | 1487881728695 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 12:28:49 INFO - TEST-INFO | 1928: exit 0 12:28:49 INFO - TEST-OK | dromaeo_css | took 1120267ms 12:28:49 INFO - TEST-START | kraken 12:28:49 INFO - operating with platform_type : mac_ 12:28:49 INFO - Initialising browser for kraken test... 12:28:49 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/tmpHzFoaP/profile http://localhost:56744/getInfo.html) 12:28:49 INFO - PROCESS | 1932 | 1487881729857 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 12:28:49 INFO - PROCESS | 1932 | 1487881729858 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method install 12:28:49 INFO - PROCESS | 1932 | 1487881729901 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 12:28:51 INFO - PROCESS | 1932 | __metrics Screen width/height:1600/1200 12:28:51 INFO - PROCESS | 1932 | colorDepth:24 12:28:51 INFO - PROCESS | 1932 | Browser inner width/height: 1024/689 12:28:51 INFO - PROCESS | 1932 | __metrics 12:28:51 INFO - PROCESS | 1932 | 1487881731881 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 12:28:51 INFO - PROCESS | 1932 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:28:51 INFO - PROCESS | 1932 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:28:52 INFO - TEST-INFO | 1932: exit 0 12:28:52 INFO - Browser initialized. 12:28:52 INFO - Running cycle 1/1 for kraken test... 12:28:52 INFO - TEST-INFO | started process 1933 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/tmpHzFoaP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:28:53 INFO - PROCESS | 1933 | 1487881733116 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 12:28:53 INFO - PROCESS | 1933 | 1487881733117 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 12:29:05 INFO - PROCESS | 1933 | RSS: Main: 230998016 12:29:05 INFO - PROCESS | 1933 | 12:29:07 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:56744/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56744/tests/kraken/driver.html?testName=audio-beat-detection) 12:29:08 INFO - PROCESS | 1933 | RSS: Main: 283398144 12:29:08 INFO - PROCESS | 1933 | 12:29:11 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:56744/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56744/tests/kraken/driver.html?testName=audio-dft) 12:29:12 INFO - PROCESS | 1933 | RSS: Main: 285351936 12:29:12 INFO - PROCESS | 1933 | 12:29:15 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:56744/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56744/tests/kraken/driver.html?testName=audio-fft) 12:29:16 INFO - PROCESS | 1933 | RSS: Main: 377393152 12:29:16 INFO - PROCESS | 1933 | 12:29:18 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:56744/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56744/tests/kraken/driver.html?testName=audio-oscillator) 12:29:18 INFO - PROCESS | 1933 | RSS: Main: 280313856 12:29:18 INFO - PROCESS | 1933 | 12:29:20 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:56744/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56744/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:29:21 INFO - PROCESS | 1933 | RSS: Main: 283607040 12:29:21 INFO - PROCESS | 1933 | 12:29:24 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:56744/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56744/tests/kraken/driver.html?testName=imaging-darkroom) 12:29:24 INFO - PROCESS | 1933 | RSS: Main: 324751360 12:29:24 INFO - PROCESS | 1933 | 12:29:27 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:56744/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56744/tests/kraken/driver.html?testName=imaging-desaturate) 12:29:28 INFO - PROCESS | 1933 | RSS: Main: 327958528 12:29:28 INFO - PROCESS | 1933 | 12:29:31 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:56744/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56744/tests/kraken/driver.html?testName=json-parse-financial) 12:29:31 INFO - PROCESS | 1933 | RSS: Main: 325804032 12:29:31 INFO - PROCESS | 1933 | 12:29:33 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:56744/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56744/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:29:34 INFO - PROCESS | 1933 | RSS: Main: 300232704 12:29:34 INFO - PROCESS | 1933 | 12:29:35 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:56744/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56744/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:29:36 INFO - PROCESS | 1933 | RSS: Main: 302350336 12:29:36 INFO - PROCESS | 1933 | 12:29:38 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:56744/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56744/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:29:39 INFO - PROCESS | 1933 | RSS: Main: 303886336 12:29:39 INFO - PROCESS | 1933 | 12:29:42 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:56744/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56744/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:29:43 INFO - PROCESS | 1933 | RSS: Main: 317468672 12:29:43 INFO - PROCESS | 1933 | 12:29:46 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:56744/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56744/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:29:46 INFO - PROCESS | 1933 | RSS: Main: 315969536 12:29:46 INFO - PROCESS | 1933 | 12:29:48 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:56744/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56744/tests/kraken/driver.html?testName=ai-astar) 12:29:48 INFO - PROCESS | 1933 | RSS: Main: 361213952 12:29:48 INFO - PROCESS | 1933 | 12:29:48 INFO - PROCESS | 1933 | __start_tp_report 12:29:48 INFO - PROCESS | 1933 | _x_x_mozilla_page_load 12:29:48 INFO - PROCESS | 1933 | _x_x_mozilla_page_load_details 12:29:48 INFO - PROCESS | 1933 | |i|pagename|runs| 12:29:48 INFO - PROCESS | 1933 | |0;ai-astar;113;113;113;112;113;114;111;111;112;130 12:29:48 INFO - PROCESS | 1933 | |1;audio-beat-detection;135;108;112;135;144;139;139;108;132;111 12:29:48 INFO - PROCESS | 1933 | |2;audio-dft;250;254;186;181;183;184;182;184;183;182 12:29:48 INFO - PROCESS | 1933 | |3;audio-fft;80;61;61;89;62;61;60;62;77;60 12:29:48 INFO - PROCESS | 1933 | |4;audio-oscillator;92;73;86;67;70;70;86;67;68;86 12:29:48 INFO - PROCESS | 1933 | |5;imaging-gaussian-blur;87;90;88;87;87;86;87;89;125;88 12:29:48 INFO - PROCESS | 1933 | |6;imaging-darkroom;95;95;95;95;96;95;100;94;94;95 12:29:48 INFO - PROCESS | 1933 | |7;imaging-desaturate;87;87;84;87;86;87;89;85;88;85 12:29:48 INFO - PROCESS | 1933 | |8;json-parse-financial;61;59;58;58;57;57;59;58;59;58 12:29:48 INFO - PROCESS | 1933 | |9;json-stringify-tinderbox;66;73;77;79;73;70;71;70;71;73 12:29:48 INFO - PROCESS | 1933 | |10;stanford-crypto-aes;89;88;88;87;85;87;87;88;88;86 12:29:48 INFO - PROCESS | 1933 | |11;stanford-crypto-ccm;146;143;143;143;160;175;159;165;251;164 12:29:48 INFO - PROCESS | 1933 | |12;stanford-crypto-pbkdf2;173;183;153;215;188;172;175;174;171;173 12:29:48 INFO - PROCESS | 1933 | |13;stanford-crypto-sha256-iterative;68;66;68;67;67;68;68;67;65;66 12:29:48 INFO - PROCESS | 1933 | __end_tp_report 12:29:48 INFO - PROCESS | 1933 | __start_cc_report 12:29:48 INFO - PROCESS | 1933 | _x_x_mozilla_cycle_collect,2097 12:29:48 INFO - PROCESS | 1933 | __end_cc_report 12:29:48 INFO - PROCESS | 1933 | __startTimestamp1487881788985__endTimestamp 12:29:48 INFO - PROCESS | 1933 | 12:29:48 INFO - PROCESS | 1933 | ------- Summary: start ------- 12:29:48 INFO - PROCESS | 1933 | Number of tests: 14 12:29:48 INFO - PROCESS | 1933 | 12:29:48 INFO - PROCESS | 1933 | [#0] ai-astar Cycles:10 Average:114.20 Median:113.00 stddev:5.63 (5.0%) stddev-sans-first:5.96 12:29:48 INFO - PROCESS | 1933 | Values: 113.0 113.0 113.0 112.0 113.0 114.0 111.0 111.0 112.0 130.0 12:29:48 INFO - PROCESS | 1933 | 12:29:48 INFO - PROCESS | 1933 | [#1] audio-beat-detection Cycles:10 Average:126.30 Median:135.00 stddev:14.64 (10.8%) stddev-sans-first:15.18 12:29:48 INFO - PROCESS | 1933 | Values: 135.0 108.0 112.0 135.0 144.0 139.0 139.0 108.0 132.0 111.0 12:29:48 INFO - PROCESS | 1933 | 12:29:48 INFO - PROCESS | 1933 | [#2] audio-dft Cycles:10 Average:196.90 Median:184.00 stddev:29.09 (15.8%) stddev-sans-first:23.67 12:29:48 INFO - PROCESS | 1933 | Values: 250.0 254.0 186.0 181.0 183.0 184.0 182.0 184.0 183.0 182.0 12:29:48 INFO - PROCESS | 1933 | 12:29:48 INFO - PROCESS | 1933 | [#3] audio-fft Cycles:10 Average:67.30 Median:62.00 stddev:10.58 (17.1%) stddev-sans-first:10.18 12:29:48 INFO - PROCESS | 1933 | Values: 80.0 61.0 61.0 89.0 62.0 61.0 60.0 62.0 77.0 60.0 12:29:48 INFO - PROCESS | 1933 | 12:29:48 INFO - PROCESS | 1933 | [#4] audio-oscillator Cycles:10 Average:76.50 Median:79.50 stddev:9.78 (12.3%) stddev-sans-first:8.61 12:29:48 INFO - PROCESS | 1933 | Values: 92.0 73.0 86.0 67.0 70.0 70.0 86.0 67.0 68.0 86.0 12:29:48 INFO - PROCESS | 1933 | 12:29:48 INFO - PROCESS | 1933 | [#5] imaging-gaussian-blur Cycles:10 Average:91.40 Median:87.50 stddev:11.86 (13.6%) stddev-sans-first:12.47 12:29:48 INFO - PROCESS | 1933 | Values: 87.0 90.0 88.0 87.0 87.0 86.0 87.0 89.0 125.0 88.0 12:29:48 INFO - PROCESS | 1933 | 12:29:48 INFO - PROCESS | 1933 | [#6] imaging-darkroom Cycles:10 Average:95.40 Median:95.00 stddev:1.71 (1.8%) stddev-sans-first:1.81 12:29:48 INFO - PROCESS | 1933 | Values: 95.0 95.0 95.0 95.0 96.0 95.0 100.0 94.0 94.0 95.0 12:29:48 INFO - PROCESS | 1933 | 12:29:48 INFO - PROCESS | 1933 | [#7] imaging-desaturate Cycles:10 Average:86.50 Median:87.00 stddev:1.51 (1.7%) stddev-sans-first:1.59 12:29:48 INFO - PROCESS | 1933 | Values: 87.0 87.0 84.0 87.0 86.0 87.0 89.0 85.0 88.0 85.0 12:29:48 INFO - PROCESS | 1933 | 12:29:48 INFO - PROCESS | 1933 | [#8] json-parse-financial Cycles:10 Average:58.40 Median:58.50 stddev:1.17 (2.0%) stddev-sans-first:0.78 12:29:48 INFO - PROCESS | 1933 | Values: 61.0 59.0 58.0 58.0 57.0 57.0 59.0 58.0 59.0 58.0 12:29:48 INFO - PROCESS | 1933 | 12:29:48 INFO - PROCESS | 1933 | [#9] json-stringify-tinderbox Cycles:10 Average:72.30 Median:73.00 stddev:3.68 (5.0%) stddev-sans-first:3.12 12:29:48 INFO - PROCESS | 1933 | Values: 66.0 73.0 77.0 79.0 73.0 70.0 71.0 70.0 71.0 73.0 12:29:48 INFO - PROCESS | 1933 | 12:29:48 INFO - PROCESS | 1933 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:88.00 stddev:1.16 (1.3%) stddev-sans-first:1.05 12:29:48 INFO - PROCESS | 1933 | Values: 89.0 88.0 88.0 87.0 85.0 87.0 87.0 88.0 88.0 86.0 12:29:48 INFO - PROCESS | 1933 | 12:29:48 INFO - PROCESS | 1933 | [#11] stanford-crypto-ccm Cycles:10 Average:164.90 Median:162.00 stddev:32.27 (19.9%) stddev-sans-first:33.49 12:29:48 INFO - PROCESS | 1933 | Values: 146.0 143.0 143.0 143.0 160.0 175.0 159.0 165.0 251.0 164.0 12:29:48 INFO - PROCESS | 1933 | 12:29:48 INFO - PROCESS | 1933 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.70 Median:174.50 stddev:15.91 (9.1%) stddev-sans-first:16.78 12:29:48 INFO - PROCESS | 1933 | Values: 173.0 183.0 153.0 215.0 188.0 172.0 175.0 174.0 171.0 173.0 12:29:48 INFO - PROCESS | 1933 | 12:29:48 INFO - PROCESS | 1933 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.50 stddev:1.05 (1.6%) stddev-sans-first:1.05 12:29:48 INFO - PROCESS | 1933 | Values: 68.0 66.0 68.0 67.0 67.0 68.0 68.0 67.0 65.0 66.0 12:29:48 INFO - PROCESS | 1933 | -------- Summary: end -------- 12:29:48 INFO - PROCESS | 1933 | 12:29:49 INFO - PROCESS | 1933 | 1487881789007 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 12:29:49 INFO - TEST-INFO | 1933: exit 0 12:29:49 INFO - TEST-OK | kraken | took 60604ms 12:29:49 INFO - SUITE-END | took 1180s 12:29:50 INFO - Completed test suite (00:19:41) 12:29:50 INFO - javascript benchmark 12:29:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7048.558502275508, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3248.9025329240126, 3252.6259480159783, 3259.478483442648, 3262.8368581570917, 3309.172706823294, 5340.412554618989, 5479.123340265558, 5528.889422211559, 5584.413636568158, 5600.243967064445, 5440.129579267318, 5513.4210907854595, 5519.0, 5521.834344969648, 5534.501894829465, 4966.15575352191, 4996.975015124919, 5003.374578177728, 5060.797568097271, 5076.619253555979, 5056.0, 5124.692518448905, 5131.255967884655, 5146.871328216787, 5154.74226288686, 5114.488551144875, 5114.590832733373, 5117.334746482952, 5123.436421993578, 5130.897382052375, 5095.668781529226, 5130.050940576006, 5131.153359695646, 5138.0, 5146.305248791403, 4220.767857767822, 4943.876403089916, 5065.138926382524, 5087.923681144785, 5090.134677104901, 5546.112621980504, 5680.233168522238, 5687.232223649796, 5698.715064246812, 5700.287464066992, 6137.41694539018, 6142.754289828402, 6163.907541386883, 6173.166622505949, 6179.3202747697715, 5965.4929331006915, 6035.698215089228, 6047.606905551125, 6071.939280607182, 6141.631502109888, 4145.564715704868, 5643.8589035274035, 5725.341585717654, 5733.713314334266, 5797.159411885253, 6186.381361863778, 6196.47329976948, 6217.160683307786, 6220.129181914535, 6225.533085018642, 6089.512838972872, 6102.389761023951, 6117.051856962178, 6117.449429551361, 6125.142480052797, 6019.578629495981, 6067.7269522871575, 6081.3614570470345, 6120.693965301717, 6137.478314343241, 6108.736706092886, 6132.263729246456, 6139.19845745766, 6143.849652621636, 6156.076588511759, 6125.816225513153, 6176.382361763787, 6178.289496707846, 6181.134641150243, 6190.0, 4753.405824271966, 4827.444843843003, 4830.396200474941, 4903.754812259373, 4942.629302802305, 4286.121345124242, 4293.613574778285, 4305.526392096866, 4311.568843115726, 4320.481542214891, 4684.461286951976, 5575.358833734092, 5585.469380408855, 5592.86017849557, 5619.269494965628, 6111.54163437744, 6155.907661385083, 6157.322694503601, 6159.0, 6159.1069294951985, 4528.932066019012, 5368.1679339702405, 5425.104857698515, 5429.104197807396, 5484.643498172606, 5240.868978275574, 5246.606504512177, 5247.10799164143, 5249.212618107238, 5269.130593452114, 5140.0, 6130.153553065287, 6183.165272688219, 6188.845278867974, 6192.845178870564, 4705.55297246761, 5105.540501354896, 6001.609895356788, 6022.006368949074, 6053.727582258721, 6189.3501182377, 6197.349278325711, 6212.254529456494, 6212.378762123752, 6215.595986260879, 5900.675462849585, 5914.024186009432, 5923.911141332797, 5934.821955341346, 5942.554308426799, 5069.442361340176, 5973.372795856546, 5980.43185897339, 5998.550108741774, 6095.939040609537, 4926.679765815211, 5955.731992060281, 6000.369961154016, 6008.24896887889, 6026.608270462406, 5564.38791732901, 5794.304683438015, 5987.072003839412, 5995.46040856325, 5997.130416089555, 5665.915011274752, 5716.828495145152, 5770.769169233352, 5785.595008349375, 5865.530757539472, 5980.401959803985, 5985.132155837467, 5987.221661184192, 5990.460858522754, 5994.340622531431, 4701.294805779161, 5589.965856316594, 5782.045962416154, 5792.160136780228, 5805.245318108618], "value": 5510.450825541894, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.8332558616106, 483.07973310842846, 488.1238177471439, 495.7645118568678, 501.76667849450024, 27350.31624209396, 28678.28304292391, 28794.568081478796, 28826.711732882664, 28910.855445722744, 40501.39247911284, 42390.15219695604, 42396.364054539205, 45044.774776126156, 45064.77467612665, 9402.623895044208, 9604.327697061208, 9614.855777163348, 9620.42277463351, 9629.229661627052, 7405.259474052578, 9384.577694003752, 9493.145616894479, 9494.0, 9516.19112375449, 7514.39884809214, 11400.942995285011, 11692.006179474754, 11707.941460292686, 11823.054155667525, 11575.189736718434, 11641.35972521511, 11673.94163029188, 11676.591319303869, 11684.00685941696, 7464.477486575942, 11410.42947852604, 11443.771124577463, 11478.426078696031, 11483.540658373739, 27416.725832741817, 39230.21539569193, 39960.20079598392, 40214.59785402138, 42261.0, 3790.886273411802, 3793.203427280247, 3813.0, 3850.6919446444217, 3972.0268534209013, 29911.551326730118, 30054.24864378408, 30693.539596906063, 31010.224744381347, 31557.684423155704, 3143.685631436861, 3156.675354763965, 3163.9209019774457, 3174.68253174683, 3278.360719659667, 436691.81654091843, 436802.0, 437885.0, 440141.0, 448799.99999999674, 60210.698946505865, 60535.0, 60641.0, 60729.69635151796, 60750.0, 50415.74792126016, 51414.74292628588, 62714.74570508564, 62780.0, 62819.42951426251, 13377.996650251076, 15041.774373384407, 15162.924185379003, 15335.539933802003, 15821.446249381217, 11371.033462155809, 11857.762844743229, 11949.522019119311, 11968.341741204318, 11995.520179192908, 14321.283935803169, 16011.27949242287, 16014.279357428944, 16031.274683303865, 16066.75899861503, 5774.852705275634, 6810.9318906810295, 6820.624865632438, 6853.4859885507785, 6894.413974812197, 725.6190499987549, 740.8740514112613, 745.6495447139836, 755.7732680196029, 756.5649751392971, 28807.855960720062, 28877.278068048046, 29108.854455727585, 29715.851420742758, 29754.553681694793, 1005.2209537608412, 1023.4064242739194, 1030.2067408095725, 1048.119579553179, 1048.5962904281907, 14543.418263269563, 15034.0, 15120.697586048438, 15388.692226155637, 15494.07035577869, 495853.0, 497954.0, 499578.0, 500668.0, 500715.0, 56785.71607141938, 56954.7152264236, 57055.42944570501, 57137.42862571404, 57167.71416142893, 1327.634900402397, 1389.0, 1392.6309527975095, 1392.7144935288445, 1395.1280449718927, 25804.0, 27618.309542261602, 28352.14943551614, 29028.7097129026, 29166.416671666873, 48006.0, 48547.27179092246, 48628.75685621549, 48644.270335945694, 48649.0, 488073.0, 489567.0, 492181.0, 492396.0, 493936.0, 28579.714202858537, 31367.0, 37915.431268531545, 39218.80390598029, 41420.964475888344, 44859.65421037374, 46014.539854601026, 46171.53828461673, 46555.0, 46674.53325466838, 34627.826860865534, 35370.29259414849, 37260.81369593243, 38750.0, 38814.22371552497, 28571.571426428203, 29396.412071758874, 35452.0, 35601.64398356087, 37116.81441592775, 36547.26905461929, 37062.62937370592, 37288.0, 37411.43882841814, 37427.06432339214, 28228.012019579215, 32050.679493205702, 32847.83576082104, 32932.34135317232, 33206.83396583002, 22845.88577057104, 23029.40914445268, 24058.87970560136, 24627.261182164562, 24682.259532214062], "value": 19145.618497199892, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.1326945426794, 362.11824208053395, 388.795882161865, 392.4584073977911, 397.95423526294485, 13413.530526431578, 13888.861111388884, 16909.154542272867, 17212.827871721278, 21019.894900525513, 21158.57682846346, 21502.354929352106, 21606.675899861515, 21660.8916955415, 21889.890550547265, 5794.478496935264, 6154.815355539341, 6163.414475624809, 6165.722542485577, 6167.62994220346, 3832.540095188567, 4042.9595704042877, 5970.582059255854, 6284.434400903918, 6584.6707664616815, 6412.64730439828, 6623.933760662404, 6647.20233571972, 6647.20233571972, 6658.800235992928, 5683.744231509592, 6616.27221005689, 6636.336366363374, 6674.132362792854, 6698.698558564876, 5580.888382232373, 5688.772449102031, 5773.719380844543, 5852.326982397036, 5856.063029915235, 23514.294571162893, 24441.633375499383, 24496.265112046665, 25990.61014084771, 26126.085587004553, 2957.8055699844117, 2974.9851250743823, 3019.743321817648, 3038.7113224243662, 3120.890768823103, 10047.145992590638, 10156.644517441853, 10178.982101789836, 10181.236407269484, 10508.316959397614, 1422.786582012696, 1744.3371518822894, 1789.6152327249704, 1807.5940813466934, 1821.3716267887557, 127098.0, 132717.0, 134255.0, 135215.0, 136662.0, 11229.66311010671, 11736.0, 11747.941260293816, 11805.704857378634, 11964.521419143137, 13140.737185256432, 13747.931260343634, 13748.243846588533, 14080.577582672537, 14105.224212668196, 5914.142449344907, 6051.878962420727, 8270.958645206736, 8349.415540912078, 8392.958035209786, 7985.920140798634, 8078.83842323162, 8215.87796637286, 8230.012398512217, 8283.384739975647, 4590.632749380042, 4688.507706690748, 4691.859244222679, 4779.9924031906685, 4799.224287013827, 1503.0, 1612.9032258064553, 1887.4526387347516, 1950.4246247357064, 1983.7718662353725, 879.3097418526426, 990.821652102614, 1025.3847691385258, 1033.49358814182, 1076.020821052838, 20182.798172018385, 21357.572848542943, 22269.331920042423, 22285.888570557043, 22334.329970100924, 1036.9014943580348, 1037.621268237088, 1066.4241309692834, 1072.7371793910543, 1073.7745073534488, 10473.371597704163, 11496.482658280398, 11579.363135027486, 11592.88407115935, 11595.246308989888, 516721.0, 623163.0, 623841.8542331212, 626677.0, 636870.0, 101151.0, 133773.33113334567, 135170.3241483806, 135487.32256338655, 139125.3043734795, 887.3566664168555, 893.6827426263712, 917.4128557723062, 919.5172534419484, 922.0, 19103.904480477508, 19272.8072719271, 19321.4203573893, 19329.903350483157, 19367.128479218187, 52570.73714631402, 52879.0, 52889.735551321995, 53176.89638968583, 54313.0, 77607.61196193982, 77734.2226577727, 77936.2206377929, 79899.60259359893, 81100.0, 6645.269020407654, 7687.2697093776005, 8042.42642354441, 8047.51714897108, 8135.959320203361, 8688.739337819876, 9867.013298670076, 9919.0, 10065.0, 10162.0, 11160.386178759922, 11339.262947908359, 11356.602518911799, 11377.374244416636, 11402.0, 10010.899891000996, 10980.615678451215, 11204.047655949336, 11239.157063220127, 11240.447618714106, 11154.274972126786, 11235.269707469315, 11322.433878306216, 11497.48261328259, 11529.250598711056, 7996.280334769898, 8178.7955301117945, 8205.220504052106, 8469.61886715087, 8470.110638382881, 9923.454210018519, 10834.620788272372, 10934.343939363664, 11027.503762330874, 11125.22123451383], "value": 9586.693897110765, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1328.8206092177554, 1402.807613528501, 1449.405743645105, 1451.6951440197558, 1452.317410816916, 8084.514929104258, 8516.190961858614, 8519.020312664046, 8520.446170998888, 8526.317894568427, 7916.208379162095, 8351.0, 8422.28410585101, 8450.619722112504, 8547.14528547145, 2190.4742861713194, 2229.2643427668913, 2231.129859354853, 2233.7766223377694, 2235.5975924333616, 1925.2876435718804, 2187.5515519318583, 2292.2550171194343, 2298.4139044543617, 2302.033146078651, 1905.8189472000206, 1993.2824183294003, 1996.880187188766, 2033.1460786469645, 2114.2071723103836, 2227.521082967162, 2252.0, 2252.68462415262, 2257.7177852768405, 2263.943401414978, 2204.0963205085836, 2213.302809614966, 2213.7454192767877, 2219.8557093789013, 2272.9772702273094, 7108.644567771591, 8348.0, 8423.620937057785, 8432.114627964036, 8435.704750333709, 5009.774560144766, 5175.0, 5177.741112944338, 5178.0, 5178.741062946875, 3237.190702324419, 3422.281320922609, 3429.4855771634457, 3437.9828100859586, 3490.4938783876196, 1142.4344949250092, 1196.6948428150854, 1213.8482689663792, 1244.0420875925577, 1244.5333000124954, 10689.358638481639, 10791.0, 10923.39921304328, 10936.125109991182, 10937.234393592451, 3616.9638303617153, 4215.936760948588, 4284.186004659105, 4337.891552711208, 4341.739495630272, 8637.093105223987, 8652.437591556529, 8654.394192406597, 8656.004559475616, 8665.696700615448, 4986.32684587576, 4992.900141997213, 4998.2502624606595, 5011.649184557119, 5016.974915125474, 4505.234110201273, 4549.818007279671, 4641.95358046422, 4696.929546056812, 4709.3642358281195, 3519.436890097572, 3520.964790352115, 3580.5524309461316, 3606.0804494853646, 3606.549181352331, 1101.8677758669012, 1106.5739690219164, 1107.7562936154034, 1125.6285425809463, 1127.4813585750646, 626.8871603111484, 740.0859937976595, 748.2181120728851, 775.3835700618022, 807.0557447786105, 4152.750834949913, 5236.816711415082, 5371.973140134352, 5471.343438787371, 5680.375158732536, 475.47935011162724, 543.1391244876835, 543.5135553679435, 564.664024905189, 571.3286887906776, 2931.2232258451527, 3293.374258890803, 3385.1029477188563, 3456.5333679952882, 3515.367233897923, 8114.918850811534, 9233.076692330847, 10082.04220599042, 10090.09189172978, 10104.646337378303, 9918.752031199278, 10046.497675116361, 10064.396136231702, 10076.49617519121, 10094.949525252327, 7642.541447513167, 7710.383169346439, 7749.418793590503, 7754.147043825287, 7908.841823163504, 5408.648437851527, 5501.642393244426, 5541.972290138524, 5597.244372009889, 5744.195812586157, 9025.458472491671, 9131.634734610541, 9156.542172891195, 9228.261739060725, 9359.766005849908, 9142.45145291258, 9382.29632777558, 9460.763480913032, 9567.37812042215, 9580.281478888972, 6581.3747693969, 6742.0, 6753.864922701617, 6756.155480564929, 6771.898421523582, 7840.921590784019, 7844.450888437755, 7857.842843143219, 7899.486533375312, 7944.880826787718, 5454.863628409322, 6219.502439804895, 7212.386947109345, 7454.142773580891, 7521.360684341892, 6185.381461853958, 7191.352778249982, 7281.890771638536, 7304.306090921354, 7391.556506609621, 6251.71867265965, 6299.874002519832, 6440.355964403522, 6441.742330306923, 6448.806535803933, 4791.904161916812, 4844.539768721966, 4902.362692850049, 4935.284383764406, 5080.542751152414, 4538.841140560065, 4890.0, 4930.926036109389, 5089.949100508948, 5095.796168153381], "value": 4206.972039194984, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.7183270972481, 326.6602733157516, 337.2142907026628, 357.27116681968795, 359.8686479435007, 4884.5359690829355, 4955.207166853304, 4972.900541989163, 4988.925166122502, 5035.194368900977, 4911.035585765696, 4934.901301973968, 4944.5797107245835, 5010.924836127461, 5064.24036394541, 3791.947482276766, 3815.389537673973, 3856.537215534133, 4062.53280872699, 4096.918061638766, 3538.44386391676, 3748.9690335157775, 3938.369860822269, 4020.1959608078355, 4121.113960498492, 3779.4897688811934, 3989.803059082278, 4058.776767277813, 4066.6136717011836, 4068.9796551017353, 3282.2450836307785, 3384.526166336715, 3901.5903330150495, 3912.139329347539, 4076.408920706481, 3392.1689186149306, 3548.574171099459, 3566.5185199997927, 3598.334308153, 3602.837872295753, 4388.758618275993, 4818.0, 4860.489648586883, 5063.8480845574695, 5078.822241221539, 3505.7370697197557, 4051.534073581546, 4437.2900335946415, 4542.182407166709, 4561.126490311116, 4823.493533179036, 5016.545201891484, 5035.269885866565, 5307.575393968474, 5418.349798024262, 3163.414768267855, 3811.542614886204, 3929.292727309083, 3941.4876066111224, 3948.9268134229474, 4762.78567464465, 5269.919666468365, 5607.915881261784, 5750.8146904176565, 5840.182374467578, 3400.9117082533353, 3601.5678118625933, 4056.9797151014645, 4096.897577560525, 4118.835246590163, 3500.562429696288, 3505.386557352453, 4050.1292222171787, 4185.790710464465, 4193.433886425354, 4586.8132853237075, 4709.764511774398, 4719.95280047202, 4740.66815322931, 4755.286706993909, 5279.313689220454, 5432.483914028161, 5441.863953401117, 5504.559635229172, 5516.200150978086, 1266.0818859427975, 1306.158316773345, 1309.796981467874, 1348.9392977316045, 1367.9452821887212, 889.7375274294099, 907.8138981508774, 912.0560220172223, 914.2731528434911, 914.5472990869562, 4524.34397012438, 4561.954380456219, 4762.357081793983, 5005.299258103868, 5137.563307118937, 4724.4094488188975, 5012.323336349619, 5084.61872854555, 5285.207218917193, 5378.1663842104535, 4632.113120657738, 4876.804927802848, 5037.974810125926, 5075.365579302587, 5094.089716356367, 2360.4688944987583, 2525.747425257496, 2602.5315443220024, 2789.567617019365, 2841.559558268472, 4582.9541704582525, 5202.81790137351, 5301.761420736076, 5585.944140558624, 5596.313769390634, 5129.255643380353, 5143.5113664201845, 5256.26412302278, 5312.150055991023, 5411.724737457364, 4582.816687332536, 4932.926006109839, 5108.873121994797, 5282.7622756976025, 5519.420460851553, 4475.149721552894, 5323.467653234646, 5342.519173274424, 5371.516563509303, 5389.7035663038905, 4389.978050109729, 4533.501314855429, 4857.927131092966, 4893.629783660581, 5228.63399562027, 4644.049499210272, 5305.32882142124, 5347.310355854448, 5451.863703407446, 5617.32592088952, 3552.3073000764603, 3757.6242375762204, 3812.618738126165, 3856.3444214483043, 3895.844166233319, 4707.199776038014, 4912.5753531475475, 5248.527632513092, 5248.895022099613, 5276.393214780196, 4372.497162826316, 4535.160995215896, 4981.352424184833, 5197.324347834758, 5271.736413179402, 4538.798682638983, 4837.201861692782, 4956.430010548833, 5168.966206758588, 5270.525652691276, 5036.0, 5091.312672789274, 5098.0, 5242.670903975926, 5250.369955605352, 5098.796048158032, 5168.896622067462, 5236.476352364733, 5291.947080529146, 5354.384245811626, 4432.800523976364, 4853.757312134308, 5238.2975532952705, 5301.3373328333955, 5381.596380271417], "value": 3925.6205201004486, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.8105092675903, 758.901342825433, 807.8626633472309, 809.2352726673294, 825.8472182646211, 14894.329755161016, 15261.69476610467, 15263.084214947097, 15277.770833437507, 15287.0, 13866.168029918212, 13960.58118256454, 13987.090839095452, 14000.0899941504, 14017.719645607081, 884.3986089459181, 892.393172642606, 903.9276857851356, 910.4901255297021, 925.8981512033705, 782.2255966593088, 923.5936188077225, 932.7621456528576, 934.8223837470892, 934.9158575728183, 964.5707660091263, 986.9753256168582, 987.4914419074147, 989.7080361293399, 989.8812142542869, 978.3102912446708, 981.9852702209473, 982.513655740406, 983.1201075037836, 984.5913945712521, 728.0571659700647, 728.2026181331433, 728.9562626242389, 728.9599072051035, 729.2889432803028, 14389.77686896615, 14942.850571494255, 15102.848971510253, 15144.621384465367, 15457.768133478006, 13661.316934153252, 13953.581392558239, 13986.580402587837, 14044.92977535116, 14120.0, 10904.0, 11316.943415282953, 11424.200305978586, 11916.46375913086, 11947.880521194851, 4384.035512187345, 4405.933910991338, 4452.732836029849, 4467.151241264182, 4479.960292840303, 86852.13147868567, 87106.0, 89317.55341223252, 89434.5528272361, 90304.0, 9405.717828465156, 10294.794104118026, 10412.947935260276, 10428.530716117793, 10702.785944281071, 19978.600427991358, 20260.797392026187, 20391.4902127448, 20448.079836407396, 20481.79518204799, 7223.855522889513, 7261.89107163393, 7319.41444684424, 7352.411807055422, 7497.0, 4022.35642297231, 4070.5929407059057, 4118.91762164761, 4139.586041395836, 4222.071144348239, 3455.2571197192724, 3533.452314891196, 3539.380608393521, 3548.8580456781956, 3564.6078931317534, 648.2674577727232, 650.5348675696899, 658.2495954611746, 678.7115475922714, 679.6737565968363, 516.8888688931822, 520.276815226835, 528.5084871069942, 546.2461802712231, 562.2746656812748, 9192.632294708137, 9475.979712779872, 9672.419654820733, 9713.431348118615, 9725.854112188323, 545.5281181777734, 570.6889745088937, 611.3641812515024, 633.1547384241996, 647.1263793878227, 4678.976605116953, 4778.283257511332, 5062.620303477254, 5316.441773613792, 5380.892382152335, 84634.0, 98570.70724744872, 99617.50191248997, 99929.50035249777, 101002.0, 53669.0, 61236.0, 67402.66298668475, 84002.57998709967, 86193.56903215569, 61214.0, 67742.0, 72554.27445725475, 75259.62370188114, 79109.94685876765, 10570.630027948984, 10583.31208471447, 10662.73343166427, 10793.460326983777, 10915.454227288445, 47010.29484557666, 47384.0, 47599.762001189774, 47855.0, 48025.519744802106, 59509.0, 62700.68649656723, 63101.684491577245, 63867.0, 64212.67893660502, 12986.935065324613, 13094.541691040768, 13461.259630720404, 13729.719497676371, 14311.427542898167, 11633.53465861356, 13915.443382264595, 14109.365078571695, 14747.967642265368, 14763.557093287218, 12968.610941671765, 13132.934335328262, 13240.933795331348, 13252.668683282995, 13683.931580342034, 12970.092093553361, 13081.934590326988, 13255.734885302432, 13586.456541738615, 13759.793603096161, 13008.544700935421, 13303.0, 13449.260290684106, 13812.0, 13932.0, 8020.398470114648, 10851.782964340828, 10931.344119352865, 11050.668479945614, 11658.53365865356, 8350.87473687883, 9140.22308103792, 9831.852522212315, 10462.0, 10740.96346621216], "value": 7340.884631049266, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1482.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 112.0, 113.0, 114.0, 111.0, 111.0, 112.0, 130.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 108.0, 112.0, 135.0, 144.0, 139.0, 139.0, 108.0, 132.0, 111.0], "value": 126.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [250.0, 254.0, 186.0, 181.0, 183.0, 184.0, 182.0, 184.0, 183.0, 182.0], "value": 196.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 61.0, 61.0, 89.0, 62.0, 61.0, 60.0, 62.0, 77.0, 60.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 73.0, 86.0, 67.0, 70.0, 70.0, 86.0, 67.0, 68.0, 86.0], "value": 76.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 90.0, 88.0, 87.0, 87.0, 86.0, 87.0, 89.0, 125.0, 88.0], "value": 91.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 100.0, 94.0, 94.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 84.0, 87.0, 86.0, 87.0, 89.0, 85.0, 88.0, 85.0], "value": 86.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 59.0, 58.0, 58.0, 57.0, 57.0, 59.0, 58.0, 59.0, 58.0], "value": 58.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 73.0, 77.0, 79.0, 73.0, 70.0, 71.0, 70.0, 71.0, 73.0], "value": 72.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 88.0, 87.0, 85.0, 87.0, 87.0, 88.0, 88.0, 86.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 143.0, 143.0, 143.0, 160.0, 175.0, 159.0, 165.0, 251.0, 164.0], "value": 164.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 183.0, 153.0, 215.0, 188.0, 172.0, 175.0, 174.0, 171.0, 173.0], "value": 177.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 68.0, 67.0, 67.0, 68.0, 68.0, 67.0, 65.0, 66.0], "value": 67.0, "unit": "score"}]}]} 12:29:50 INFO - Return code: 0 12:29:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:29:50 ERROR - # TBPL SUCCESS # 12:29:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:29:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:29:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:29:50 INFO - 2017-02-23 12:29:50.322 screenresolution[1937:23523] starting screenresolution argv=screenresolution get 12:29:50 INFO - 2017-02-23 12:29:50.326 screenresolution[1937:23523] Display 0: 1600x1200x32@60 12:29:50 INFO - 2017-02-23 12:29:50.333 screenresolution[1938:23526] starting screenresolution argv=screenresolution list 12:29:50 INFO - Available Modes on Display 0 12:29:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:29:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:29:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:29:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:29:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:29:50 INFO - Intel Iris: 12:29:50 INFO - Chipset Model: Intel Iris 12:29:50 INFO - Type: GPU 12:29:50 INFO - Bus: Built-In 12:29:50 INFO - VRAM (Dynamic, Max): 1536 MB 12:29:50 INFO - Vendor: Intel (0x8086) 12:29:50 INFO - Device ID: 0x0a2e 12:29:50 INFO - Revision ID: 0x0009 12:29:50 INFO - Displays: 12:29:50 INFO - Display: 12:29:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:29:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:29:50 INFO - Display Serial Number: 1600x1200 60 12:29:50 INFO - Main Display: Yes 12:29:50 INFO - Mirror: Off 12:29:50 INFO - Online: Yes 12:29:50 INFO - Rotation: Supported 12:29:50 INFO - Return code: 0 12:29:50 INFO - Running post-action listener: _resource_record_post_action 12:29:50 INFO - [mozharness: 2017-02-23 20:29:50.569088Z] Finished run-tests step (success) 12:29:50 INFO - Running post-run listener: _resource_record_post_run 12:29:50 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 606208; Write bytes: 511603200; Read time: 12; Write time: 1592 12:29:50 INFO - TinderboxPrint: CPU usage
53.9% 12:29:50 INFO - TinderboxPrint: I/O read bytes / time
606,208 / 12 12:29:50 INFO - TinderboxPrint: I/O write bytes / time
511,603,200 / 1,592 12:29:50 INFO - TinderboxPrint: CPU idle
2,215.1 (46.1%) 12:29:50 INFO - TinderboxPrint: CPU system
964.0 (20.0%) 12:29:50 INFO - TinderboxPrint: CPU user
1,630.5 (33.9%) 12:29:50 INFO - TinderboxPrint: Swap in / out
211,308,544 / 0 12:29:50 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 206000640; Write bytes: 203675136; Read time: 15572; Write time: 442 12:29:50 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 606208; Write bytes: 297114624; Read time: 12; Write time: 1115 12:29:51 INFO - Running post-run listener: _upload_blobber_files 12:29:51 INFO - Blob upload gear active. 12:29:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:29:51 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 12:29:51 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:29:51 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:29:51 INFO - (blobuploader) - INFO - Open directory for files ... 12:29:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:29:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:29:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:52 INFO - (blobuploader) - INFO - Done attempting. 12:29:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:29:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:29:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:53 INFO - (blobuploader) - INFO - Done attempting. 12:29:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:29:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:53 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:29:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:53 INFO - (blobuploader) - INFO - Done attempting. 12:29:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:29:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:29:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:56 INFO - (blobuploader) - INFO - Done attempting. 12:29:56 INFO - (blobuploader) - INFO - Iteration through files over. 12:29:56 INFO - Return code: 0 12:29:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:29:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:29:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44c6a4c0bde3febf0c7155b58effd28451835cdc82c95f0c0036c154d5381bed4a2118b32b13c3f6d6028ed46302ecc70952a71c651adcb4c5a217c4ee10aa8e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ecc8c38a444d67c8381e2b5aa985cde4b6e0b1659c0b80d39590f670ab7e4895d1c70e1cecb28fe69dbec857b0dff7a1605954aff8595dc6d6202c46f83245d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b576594e0a1a526eda2708f58b9fa8c3191d663eab2958a65ed1b8f739b4dd6528e25316db2a8f8748c6cb61e772ad91c0c9a7d1fe0d7c3181ee68209833225", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9970057aa5ef8f551adf013885fcf37a20015889659e6452fcbd52eaad5d94aaf180121d365eb27e2b153315bb601907ebdb83e7c8a6c7d9f92cd07f494bf08"} 12:29:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:29:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:29:56 INFO - Contents: 12:29:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44c6a4c0bde3febf0c7155b58effd28451835cdc82c95f0c0036c154d5381bed4a2118b32b13c3f6d6028ed46302ecc70952a71c651adcb4c5a217c4ee10aa8e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ecc8c38a444d67c8381e2b5aa985cde4b6e0b1659c0b80d39590f670ab7e4895d1c70e1cecb28fe69dbec857b0dff7a1605954aff8595dc6d6202c46f83245d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b576594e0a1a526eda2708f58b9fa8c3191d663eab2958a65ed1b8f739b4dd6528e25316db2a8f8748c6cb61e772ad91c0c9a7d1fe0d7c3181ee68209833225", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9970057aa5ef8f551adf013885fcf37a20015889659e6452fcbd52eaad5d94aaf180121d365eb27e2b153315bb601907ebdb83e7c8a6c7d9f92cd07f494bf08"} 12:29:56 INFO - Running post-run listener: copy_logs_to_upload_dir 12:29:56 INFO - Copying logs to upload dir... 12:29:56 INFO - mkdir: /builds/slave/test/build/upload/logs 12:29:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.810748 ========= master_lag: 0.29 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-02-23 12:29:56.464118) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-23 12:29:56.468013) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wDBtNUXBo1/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44c6a4c0bde3febf0c7155b58effd28451835cdc82c95f0c0036c154d5381bed4a2118b32b13c3f6d6028ed46302ecc70952a71c651adcb4c5a217c4ee10aa8e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ecc8c38a444d67c8381e2b5aa985cde4b6e0b1659c0b80d39590f670ab7e4895d1c70e1cecb28fe69dbec857b0dff7a1605954aff8595dc6d6202c46f83245d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b576594e0a1a526eda2708f58b9fa8c3191d663eab2958a65ed1b8f739b4dd6528e25316db2a8f8748c6cb61e772ad91c0c9a7d1fe0d7c3181ee68209833225", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9970057aa5ef8f551adf013885fcf37a20015889659e6452fcbd52eaad5d94aaf180121d365eb27e2b153315bb601907ebdb83e7c8a6c7d9f92cd07f494bf08"} build_url:https://queue.taskcluster.net/v1/task/Nv66kwh3RCSSFpP3d3NMsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011385 build_url: 'https://queue.taskcluster.net/v1/task/Nv66kwh3RCSSFpP3d3NMsw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44c6a4c0bde3febf0c7155b58effd28451835cdc82c95f0c0036c154d5381bed4a2118b32b13c3f6d6028ed46302ecc70952a71c651adcb4c5a217c4ee10aa8e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ecc8c38a444d67c8381e2b5aa985cde4b6e0b1659c0b80d39590f670ab7e4895d1c70e1cecb28fe69dbec857b0dff7a1605954aff8595dc6d6202c46f83245d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b576594e0a1a526eda2708f58b9fa8c3191d663eab2958a65ed1b8f739b4dd6528e25316db2a8f8748c6cb61e772ad91c0c9a7d1fe0d7c3181ee68209833225", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9970057aa5ef8f551adf013885fcf37a20015889659e6452fcbd52eaad5d94aaf180121d365eb27e2b153315bb601907ebdb83e7c8a6c7d9f92cd07f494bf08"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-23 12:29:56.502170) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 12:29:56.502502) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wDBtNUXBo1/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.bdbpUL8nT8/Listeners TMPDIR=/var/folders/k2/9jjq6xks3qn82q_w7h2c5zq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005168 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-23 12:29:56.551559) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-23 12:29:56.551951) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-23 12:29:56.552267) ========= ========= Total master_lag: 0.43 =========