builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0092 starttime: 1489923929.8 results: success (0) buildid: 20170319030203 builduid: ae8c8cd79f3b4753a6b07e2c5aacff15 revision: e1576dd8bd9d3a4ca418cf347133b8a4957ddeca ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-19 04:45:29.795789) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-19 04:45:29.796235) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-19 04:45:29.796511) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-19 04:45:29.821488) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-19 04:45:29.821863) ========= 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.IRavaIZz3q/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.NmRXu2wQXf/Listeners TMPDIR=/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/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-03-19 04:45:29-- 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% 10.1M=0.001s 2017-03-19 04:45:30 (10.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.608179 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-19 04:45:30.453405) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-19 04:45:30.453719) ========= 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.IRavaIZz3q/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.NmRXu2wQXf/Listeners TMPDIR=/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/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.020415 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-19 04:45:30.502847) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-19 04:45:30.503211) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev e1576dd8bd9d3a4ca418cf347133b8a4957ddeca --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 e1576dd8bd9d3a4ca418cf347133b8a4957ddeca --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.IRavaIZz3q/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.NmRXu2wQXf/Listeners TMPDIR=/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/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-03-19 04:45:30,598 truncating revision to first 12 chars 2017-03-19 04:45:30,598 Setting DEBUG logging. 2017-03-19 04:45:30,598 attempt 1/10 2017-03-19 04:45:30,598 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/e1576dd8bd9d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-19 04:45:31,288 unpacking tar archive at: mozilla-central-e1576dd8bd9d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.960172 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-19 04:45:31.486986) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-19 04:45:31.487297) ========= script_repo_revision: e1576dd8bd9d3a4ca418cf347133b8a4957ddeca ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-19 04:45:31.487678) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-19 04:45:31.487956) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-19 04:45:31.508347) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-19 04:45:31.508843) ========= /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.IRavaIZz3q/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.NmRXu2wQXf/Listeners TMPDIR=/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/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 04:45:31 INFO - MultiFileLogger online at 20170319 04:45:31 in /builds/slave/test 04:45:31 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 04:45:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:45:31 INFO - {'append_to_log': False, 04:45:31 INFO - 'base_work_dir': '/builds/slave/test', 04:45:31 INFO - 'blob_upload_branch': 'Firefox', 04:45:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:45:31 INFO - 'branch': 'Firefox', 04:45:31 INFO - 'buildbot_json_path': 'buildprops.json', 04:45:31 INFO - 'config_files': ('talos/mac_config.py',), 04:45:31 INFO - 'default_actions': ('clobber', 04:45:31 INFO - 'read-buildbot-config', 04:45:31 INFO - 'download-and-extract', 04:45:31 INFO - 'populate-webroot', 04:45:31 INFO - 'create-virtualenv', 04:45:31 INFO - 'install', 04:45:31 INFO - 'run-tests'), 04:45:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:45:31 INFO - 'download_minidump_stackwalk': True, 04:45:31 INFO - 'download_symbols': 'ondemand', 04:45:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:45:31 INFO - 'tooltool.py': '/tools/tooltool.py', 04:45:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:45:31 INFO - '/tools/misc-python/virtualenv.py')}, 04:45:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:45:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:45:31 INFO - 'gecko_profile': False, 04:45:31 INFO - 'gecko_profile_interval': 0, 04:45:31 INFO - 'installer_path': 'installer.exe', 04:45:31 INFO - 'log_level': 'info', 04:45:31 INFO - 'log_name': 'talos', 04:45:31 INFO - 'log_to_console': True, 04:45:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:45:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:45:31 INFO - 'opt_config_files': (), 04:45:31 INFO - 'pip_index': False, 04:45:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:45:31 INFO - 'cmd': ('bash', 04:45:31 INFO - '-c', 04:45:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:45:31 INFO - 'enabled': True, 04:45:31 INFO - 'halt_on_failure': False, 04:45:31 INFO - 'name': 'check_screen_resolution'},), 04:45:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:45:31 INFO - 'cmd': ('bash', 04:45:31 INFO - '-c', 04:45:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:45:31 INFO - 'enabled': True, 04:45:31 INFO - 'halt_on_failure': False, 04:45:31 INFO - 'name': 'check_screen_resolution'},), 04:45:31 INFO - 'run_cmd_checks_enabled': True, 04:45:31 INFO - 'suite': 'dromaeojs', 04:45:31 INFO - 'system_bits': '32', 04:45:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:45:31 INFO - 'title': 't-yosemite-r7-0092', 04:45:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:45:31 INFO - 'use_talos_json': True, 04:45:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:45:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:45:31 INFO - 'work_dir': 'build'} 04:45:31 INFO - [mozharness: 2017-03-19 11:45:31.733570Z] Running clobber step. 04:45:31 INFO - Running pre-action listener: _resource_record_pre_action 04:45:31 INFO - Running main action method: clobber 04:45:31 INFO - rmtree: /builds/slave/test/build 04:45:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:45:31 INFO - Running post-action listener: _resource_record_post_action 04:45:31 INFO - [mozharness: 2017-03-19 11:45:31.882677Z] Finished clobber step (success) 04:45:31 INFO - [mozharness: 2017-03-19 11:45:31.882920Z] Running read-buildbot-config step. 04:45:31 INFO - Running pre-action listener: _resource_record_pre_action 04:45:31 INFO - Running main action method: read_buildbot_config 04:45:31 INFO - Using buildbot properties: 04:45:31 INFO - { 04:45:31 INFO - "project": "", 04:45:31 INFO - "product": "firefox", 04:45:31 INFO - "script_repo_revision": "production", 04:45:31 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:45:31 INFO - "repository": "", 04:45:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:45:31 INFO - "buildid": "20170319030203", 04:45:31 INFO - "stage_platform": "macosx64", 04:45:31 INFO - "basedir": "/builds/slave/test", 04:45:31 INFO - "nightly_build": "True", 04:45:31 INFO - "buildnumber": 79, 04:45:31 INFO - "slavename": "t-yosemite-r7-0092", 04:45:31 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 04:45:31 INFO - "platform": "yosemite_r7", 04:45:31 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:45:31 INFO - "branch": "mozilla-central", 04:45:31 INFO - "repo_path": "mozilla-central", 04:45:31 INFO - "revision": "e1576dd8bd9d3a4ca418cf347133b8a4957ddeca", 04:45:31 INFO - "pgo_build": "False", 04:45:31 INFO - "builduid": "ae8c8cd79f3b4753a6b07e2c5aacff15", 04:45:31 INFO - "slavebuilddir": "test" 04:45:31 INFO - } 04:45:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q6TF7l8MS-igeqBcd2Z99Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 04:45:31 INFO - Running post-action listener: _resource_record_post_action 04:45:31 INFO - [mozharness: 2017-03-19 11:45:31.887738Z] Finished read-buildbot-config step (success) 04:45:31 INFO - [mozharness: 2017-03-19 11:45:31.887880Z] Running download-and-extract step. 04:45:31 INFO - Running pre-action listener: _resource_record_pre_action 04:45:31 INFO - Running main action method: download_and_extract 04:45:31 INFO - mkdir: /builds/slave/test/build/tests 04:45:31 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')]} 04:45:31 INFO - trying https://queue.taskcluster.net/v1/task/Q6TF7l8MS-igeqBcd2Z99Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 04:45:31 INFO - Downloading https://queue.taskcluster.net/v1/task/Q6TF7l8MS-igeqBcd2Z99Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:45:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q6TF7l8MS-igeqBcd2Z99Q/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 04:45:33 INFO - Downloaded 1325 bytes. 04:45:33 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 04:45:33 INFO - Using the following test package requirements: 04:45:33 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:45:33 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 04:45:33 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:45:33 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:45:33 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 04:45:33 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:45:33 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 04:45:33 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:45:33 INFO - u'jsshell-mac.zip'], 04:45:33 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:45:33 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 04:45:33 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 04:45:33 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:45:33 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 04:45:33 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:45:33 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 04:45:33 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:45:33 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 04:45:33 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 04:45:33 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 04:45:33 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:45:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q6TF7l8MS-igeqBcd2Z99Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:45:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q6TF7l8MS-igeqBcd2Z99Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:45:33 INFO - Fetch https://queue.taskcluster.net/v1/task/Q6TF7l8MS-igeqBcd2Z99Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:45:37 INFO - Expected file size: 52475671 04:45:37 INFO - Obtained file size: 52475671 04:45:53 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:45:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q6TF7l8MS-igeqBcd2Z99Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 04:45:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q6TF7l8MS-igeqBcd2Z99Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:45:53 INFO - Fetch https://queue.taskcluster.net/v1/task/Q6TF7l8MS-igeqBcd2Z99Q/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 04:45:55 INFO - Expected file size: 52475671 04:45:55 INFO - Obtained file size: 52475671 04:46:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q6TF7l8MS-igeqBcd2Z99Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 04:46:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q6TF7l8MS-igeqBcd2Z99Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:46:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Q6TF7l8MS-igeqBcd2Z99Q/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 04:46:13 INFO - Expected file size: 14404171 04:46:13 INFO - Obtained file size: 14404171 04:46:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:13 INFO - trying https://queue.taskcluster.net/v1/task/Q6TF7l8MS-igeqBcd2Z99Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:46:13 INFO - Downloading https://queue.taskcluster.net/v1/task/Q6TF7l8MS-igeqBcd2Z99Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 04:46:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q6TF7l8MS-igeqBcd2Z99Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 04:46:15 INFO - Downloaded 75231136 bytes. 04:46:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q6TF7l8MS-igeqBcd2Z99Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:46:15 INFO - mkdir: /builds/slave/test/properties 04:46:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:46:15 INFO - Writing to file /builds/slave/test/properties/build_url 04:46:15 INFO - Contents: 04:46:15 INFO - build_url:https://queue.taskcluster.net/v1/task/Q6TF7l8MS-igeqBcd2Z99Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 04:46:16 INFO - Running post-action listener: _resource_record_post_action 04:46:16 INFO - Running post-action listener: set_extra_try_arguments 04:46:16 INFO - [mozharness: 2017-03-19 11:46:16.100539Z] Finished download-and-extract step (success) 04:46:16 INFO - [mozharness: 2017-03-19 11:46:16.100673Z] Running populate-webroot step. 04:46:16 INFO - Running pre-action listener: _resource_record_pre_action 04:46:16 INFO - Running main action method: populate_webroot 04:46:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:46:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:46:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:46:16 INFO - '--tppagecycles', 04:46:16 INFO - '7'], 04:46:16 INFO - 'tests': ['tsvgm']}}, 04:46:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:46:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:46:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:46:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:46:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:46:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:46:16 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:46:16 INFO - 'talos_options': ['--disable-e10s'], 04:46:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:46:16 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:46:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:46:16 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:46:16 INFO - 'talos_options': ['--disable-e10s'], 04:46:16 INFO - 'tests': ['damp', 'tps']}, 04:46:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:46:16 INFO - 'tests': ['damp', 'tps']}, 04:46:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:46:16 INFO - 'tests': ['dromaeo_dom']}, 04:46:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:46:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:46:16 INFO - 'tests': ['basic_compositor_video']}, 04:46:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:46:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:46:16 INFO - 'tests': ['a11yr', 04:46:16 INFO - 'ts_paint', 04:46:16 INFO - 'tpaint', 04:46:16 INFO - 'sessionrestore', 04:46:16 INFO - 'sessionrestore_no_auto_restore', 04:46:16 INFO - 'tabpaint']}, 04:46:16 INFO - 'other-e10s': {'tests': ['a11yr', 04:46:16 INFO - 'ts_paint', 04:46:16 INFO - 'tpaint', 04:46:16 INFO - 'sessionrestore', 04:46:16 INFO - 'sessionrestore_no_auto_restore', 04:46:16 INFO - 'tabpaint']}, 04:46:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:46:16 INFO - 'ts_paint', 04:46:16 INFO - 'tpaint', 04:46:16 INFO - 'sessionrestore', 04:46:16 INFO - 'sessionrestore_no_auto_restore', 04:46:16 INFO - 'tabpaint']}, 04:46:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:46:16 INFO - 'ts_paint', 04:46:16 INFO - 'tpaint', 04:46:16 INFO - 'sessionrestore', 04:46:16 INFO - 'sessionrestore_no_auto_restore', 04:46:16 INFO - 'tabpaint']}, 04:46:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:46:16 INFO - 'tests': ['a11yr', 04:46:16 INFO - 'ts_paint', 04:46:16 INFO - 'tpaint', 04:46:16 INFO - 'sessionrestore', 04:46:16 INFO - 'sessionrestore_no_auto_restore', 04:46:16 INFO - 'tabpaint']}, 04:46:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:46:16 INFO - 'tests': ['a11yr', 04:46:16 INFO - 'ts_paint', 04:46:16 INFO - 'tpaint', 04:46:16 INFO - 'sessionrestore', 04:46:16 INFO - 'sessionrestore_no_auto_restore', 04:46:16 INFO - 'tabpaint']}, 04:46:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:46:16 INFO - 'tests': ['tsvgx', 04:46:16 INFO - 'tsvgr_opacity', 04:46:16 INFO - 'tart', 04:46:16 INFO - 'tscrollx', 04:46:16 INFO - 'cart', 04:46:16 INFO - 'tsvg_static']}, 04:46:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:46:16 INFO - 'tsvgr_opacity', 04:46:16 INFO - 'tart', 04:46:16 INFO - 'tscrollx', 04:46:16 INFO - 'cart', 04:46:16 INFO - 'tsvg_static']}, 04:46:16 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:46:16 INFO - 'talos_options': ['--disable-e10s'], 04:46:16 INFO - 'tests': ['tp5o']}, 04:46:16 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:46:16 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:46:16 INFO - 'talos_options': ['--disable-e10s', 04:46:16 INFO - '--xperf_path', 04:46:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:46:16 INFO - 'tests': ['tp5n']}, 04:46:16 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:46:16 INFO - 'talos_options': ['--xperf_path', 04:46:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:46:16 INFO - 'tests': ['tp5n']}}, 04:46:16 INFO - 'talos.zip': {'path': '', 04:46:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:46:16 INFO - Running post-action listener: _resource_record_post_action 04:46:16 INFO - [mozharness: 2017-03-19 11:46:16.119266Z] Finished populate-webroot step (success) 04:46:16 INFO - [mozharness: 2017-03-19 11:46:16.119377Z] Running create-virtualenv step. 04:46:16 INFO - Running pre-action listener: _resource_record_pre_action 04:46:16 INFO - Running main action method: create_virtualenv 04:46:16 INFO - Creating virtualenv /builds/slave/test/build/venv 04:46:16 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 04:46:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:46:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:46:16 INFO - Using real prefix '/tools/python27' 04:46:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:46:16 INFO - Installing distribute.............................................................................................................................................................................................done. 04:46:19 INFO - Installing pip.................done. 04:46:19 INFO - Return code: 0 04:46:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:46:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:46:20 INFO - Reading from file tmpfile_stdout 04:46:20 INFO - Output received: 04:46:20 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) 04:46:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:46:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x11025cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11025f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee32d4f620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11003ad30>, '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 0x10ff61db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11002fbe8>, '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.IRavaIZz3q/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.NmRXu2wQXf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/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 04:46:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:46:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:46:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRavaIZz3q/Render', 04:46:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:20 INFO - 'HOME': '/Users/cltbld', 04:46:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:20 INFO - 'LOGNAME': 'cltbld', 04:46:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:20 INFO - 'MOZ_NO_REMOTE': '1', 04:46:20 INFO - 'NO_EM_RESTART': '1', 04:46:20 INFO - 'PAGER': '/bin/cat', 04:46:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:20 INFO - 'PWD': '/builds/slave/test', 04:46:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:20 INFO - 'SHELL': '/bin/bash', 04:46:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NmRXu2wQXf/Listeners', 04:46:20 INFO - 'TMPDIR': '/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/', 04:46:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:20 INFO - 'USER': 'cltbld', 04:46:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:20 INFO - 'XPC_FLAGS': '0x0', 04:46:20 INFO - 'XPC_SERVICE_NAME': '0', 04:46:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:20 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 04:46:20 INFO - Cleaning up... 04:46:20 INFO - Return code: 0 04:46:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:46:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x11025cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11025f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee32d4f620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11003ad30>, '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 0x10ff61db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11002fbe8>, '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.IRavaIZz3q/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.NmRXu2wQXf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/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 04:46:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:46:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:46:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRavaIZz3q/Render', 04:46:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:20 INFO - 'HOME': '/Users/cltbld', 04:46:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:20 INFO - 'LOGNAME': 'cltbld', 04:46:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:20 INFO - 'MOZ_NO_REMOTE': '1', 04:46:20 INFO - 'NO_EM_RESTART': '1', 04:46:20 INFO - 'PAGER': '/bin/cat', 04:46:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:20 INFO - 'PWD': '/builds/slave/test', 04:46:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:20 INFO - 'SHELL': '/bin/bash', 04:46:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NmRXu2wQXf/Listeners', 04:46:20 INFO - 'TMPDIR': '/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/', 04:46:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:20 INFO - 'USER': 'cltbld', 04:46:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:20 INFO - 'XPC_FLAGS': '0x0', 04:46:20 INFO - 'XPC_SERVICE_NAME': '0', 04:46:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:20 INFO - Downloading/unpacking psutil>=3.1.1 04:46:20 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 04:46:20 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 04:46:20 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 04:46:20 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 04:46:20 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 04:46:20 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 04:46:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:46:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:46:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:46:24 INFO - Installing collected packages: psutil 04:46:24 INFO - Running setup.py install for psutil 04:46:24 INFO - building 'psutil._psutil_osx' extension 04:46: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/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:46: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/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:46: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 04:46:25 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 04:46:25 INFO - building 'psutil._psutil_posix' extension 04:46:25 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 04:46:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:46:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:46:25 INFO - ^ 04:46:25 INFO - 1 warning generated. 04:46:25 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 04:46:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:46:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:46:25 INFO - Successfully installed psutil 04:46:25 INFO - Cleaning up... 04:46:25 INFO - Return code: 0 04:46:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:46:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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 0x11025cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11025f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee32d4f620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11003ad30>, '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 0x10ff61db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11002fbe8>, '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.IRavaIZz3q/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.NmRXu2wQXf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/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 04:46: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 04:46: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 04:46:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRavaIZz3q/Render', 04:46:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:25 INFO - 'HOME': '/Users/cltbld', 04:46:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:25 INFO - 'LOGNAME': 'cltbld', 04:46:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:25 INFO - 'MOZ_NO_REMOTE': '1', 04:46:25 INFO - 'NO_EM_RESTART': '1', 04:46:25 INFO - 'PAGER': '/bin/cat', 04:46:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:25 INFO - 'PWD': '/builds/slave/test', 04:46:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:25 INFO - 'SHELL': '/bin/bash', 04:46:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NmRXu2wQXf/Listeners', 04:46:25 INFO - 'TMPDIR': '/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/', 04:46:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:25 INFO - 'USER': 'cltbld', 04:46:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:25 INFO - 'XPC_FLAGS': '0x0', 04:46:25 INFO - 'XPC_SERVICE_NAME': '0', 04:46:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:46:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:46:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:46:28 INFO - Installing collected packages: mozsystemmonitor 04:46:28 INFO - Running setup.py install for mozsystemmonitor 04:46:28 INFO - Successfully installed mozsystemmonitor 04:46:28 INFO - Cleaning up... 04:46:28 INFO - Return code: 0 04:46:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:46: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')]} 04:46:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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')]} 04:46:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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 0x11025cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11025f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee32d4f620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11003ad30>, '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 0x10ff61db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11002fbe8>, '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.IRavaIZz3q/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.NmRXu2wQXf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/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 04:46: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 04:46: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 04:46:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRavaIZz3q/Render', 04:46:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:28 INFO - 'HOME': '/Users/cltbld', 04:46:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:28 INFO - 'LOGNAME': 'cltbld', 04:46:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:28 INFO - 'MOZ_NO_REMOTE': '1', 04:46:28 INFO - 'NO_EM_RESTART': '1', 04:46:28 INFO - 'PAGER': '/bin/cat', 04:46:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:28 INFO - 'PWD': '/builds/slave/test', 04:46:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:28 INFO - 'SHELL': '/bin/bash', 04:46:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NmRXu2wQXf/Listeners', 04:46:28 INFO - 'TMPDIR': '/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/', 04:46:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:28 INFO - 'USER': 'cltbld', 04:46:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:28 INFO - 'XPC_FLAGS': '0x0', 04:46:28 INFO - 'XPC_SERVICE_NAME': '0', 04:46:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:28 INFO - Downloading/unpacking jsonschema==2.5.1 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:46:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46:31 INFO - Downloading functools32-3.2.3-2.tar.gz 04:46:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:46:31 INFO - warning: no files found matching '*.txt' 04:46:31 INFO - no previously-included directories found matching 'build' 04:46:31 INFO - no previously-included directories found matching 'dist' 04:46:31 INFO - no previously-included directories found matching '.git*' 04:46:31 INFO - Installing collected packages: jsonschema, functools32 04:46:32 INFO - Running setup.py install for functools32 04:46:32 INFO - warning: no files found matching '*.txt' 04:46:32 INFO - no previously-included directories found matching 'build' 04:46:32 INFO - no previously-included directories found matching 'dist' 04:46:32 INFO - no previously-included directories found matching '.git*' 04:46:32 INFO - Successfully installed jsonschema functools32 04:46:32 INFO - Cleaning up... 04:46:32 INFO - Return code: 0 04:46:32 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:46: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')]} 04:46:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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')]} 04:46:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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 0x11025cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11025f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee32d4f620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11003ad30>, '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 0x10ff61db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11002fbe8>, '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.IRavaIZz3q/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.NmRXu2wQXf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/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 04:46: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 04:46: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 04:46:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRavaIZz3q/Render', 04:46:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:32 INFO - 'HOME': '/Users/cltbld', 04:46:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:32 INFO - 'LOGNAME': 'cltbld', 04:46:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:32 INFO - 'MOZ_NO_REMOTE': '1', 04:46:32 INFO - 'NO_EM_RESTART': '1', 04:46:32 INFO - 'PAGER': '/bin/cat', 04:46:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:32 INFO - 'PWD': '/builds/slave/test', 04:46:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:32 INFO - 'SHELL': '/bin/bash', 04:46:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NmRXu2wQXf/Listeners', 04:46:32 INFO - 'TMPDIR': '/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/', 04:46:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:32 INFO - 'USER': 'cltbld', 04:46:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:32 INFO - 'XPC_FLAGS': '0x0', 04:46:32 INFO - 'XPC_SERVICE_NAME': '0', 04:46:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:46:32 INFO - Cleaning up... 04:46:32 INFO - Return code: 0 04:46:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:46: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')]} 04:46:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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')]} 04:46:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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 0x11025cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11025f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee32d4f620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11003ad30>, '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 0x10ff61db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11002fbe8>, '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.IRavaIZz3q/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.NmRXu2wQXf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/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 04:46: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 04:46: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 04:46:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRavaIZz3q/Render', 04:46:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:32 INFO - 'HOME': '/Users/cltbld', 04:46:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:32 INFO - 'LOGNAME': 'cltbld', 04:46:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:32 INFO - 'MOZ_NO_REMOTE': '1', 04:46:32 INFO - 'NO_EM_RESTART': '1', 04:46:32 INFO - 'PAGER': '/bin/cat', 04:46:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:32 INFO - 'PWD': '/builds/slave/test', 04:46:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:32 INFO - 'SHELL': '/bin/bash', 04:46:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NmRXu2wQXf/Listeners', 04:46:32 INFO - 'TMPDIR': '/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/', 04:46:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:32 INFO - 'USER': 'cltbld', 04:46:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:32 INFO - 'XPC_FLAGS': '0x0', 04:46:32 INFO - 'XPC_SERVICE_NAME': '0', 04:46:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:32 INFO - Downloading/unpacking blobuploader==1.2.4 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46:35 INFO - Downloading blobuploader-1.2.4.tar.gz 04:46:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:46:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:46:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:46:36 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 04:46:36 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 04:46:36 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 04:46:36 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 04:46:36 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 04:46:36 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 04:46:36 INFO - Downloading docopt-0.6.1.tar.gz 04:46:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:46:36 INFO - Installing collected packages: blobuploader, requests, docopt 04:46:36 INFO - Running setup.py install for blobuploader 04:46:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:46:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:46:36 INFO - Running setup.py install for requests 04:46:37 INFO - Running setup.py install for docopt 04:46:37 INFO - Successfully installed blobuploader requests docopt 04:46:37 INFO - Cleaning up... 04:46:37 INFO - Return code: 0 04:46:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:46: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')]} 04:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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')]} 04:46:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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 0x11025cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11025f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee32d4f620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11003ad30>, '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 0x10ff61db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11002fbe8>, '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.IRavaIZz3q/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.NmRXu2wQXf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/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 04:46: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 04:46: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 04:46:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRavaIZz3q/Render', 04:46:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:37 INFO - 'HOME': '/Users/cltbld', 04:46:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:37 INFO - 'LOGNAME': 'cltbld', 04:46:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:37 INFO - 'MOZ_NO_REMOTE': '1', 04:46:37 INFO - 'NO_EM_RESTART': '1', 04:46:37 INFO - 'PAGER': '/bin/cat', 04:46:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:37 INFO - 'PWD': '/builds/slave/test', 04:46:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:37 INFO - 'SHELL': '/bin/bash', 04:46:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NmRXu2wQXf/Listeners', 04:46:37 INFO - 'TMPDIR': '/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/', 04:46:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:37 INFO - 'USER': 'cltbld', 04:46:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:37 INFO - 'XPC_FLAGS': '0x0', 04:46:37 INFO - 'XPC_SERVICE_NAME': '0', 04:46:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:46:37 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-NhB_w5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:46:38 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-LROzNz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:46:38 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-aJbDRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:46:38 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-88U2Hr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:46:38 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-WC55vv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:46:38 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-tlOZHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:46:38 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-XUEm5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:46:38 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-LauUUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:46:38 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-XdmOxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:46:38 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-0kyxMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:46:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:46:38 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-z2ZX5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:46:39 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-IhEIaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:46:39 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-CdbAOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:46:39 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-0mEacN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:46:39 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-cVY4u7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:46:39 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-B0wv_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:46:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:46:39 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-sAxtDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:46:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:46:39 INFO - Running setup.py install for manifestparser 04:46:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:46:39 INFO - Running setup.py install for mozcrash 04:46:40 INFO - Running setup.py install for mozdebug 04:46:40 INFO - Running setup.py install for mozdevice 04:46:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:46:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:46:40 INFO - Running setup.py install for mozfile 04:46:40 INFO - Running setup.py install for mozhttpd 04:46:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:46:40 INFO - Running setup.py install for mozinfo 04:46:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:46:40 INFO - Running setup.py install for mozInstall 04:46:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:46:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:46:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:46:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:46:40 INFO - Running setup.py install for mozleak 04:46:41 INFO - Running setup.py install for mozlog 04:46:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:46:41 INFO - Running setup.py install for moznetwork 04:46:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:46:41 INFO - Running setup.py install for mozprocess 04:46:41 INFO - Running setup.py install for mozprofile 04:46:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:46:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:46:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:46:41 INFO - Running setup.py install for mozrunner 04:46:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:46:41 INFO - Running setup.py install for mozscreenshot 04:46:42 INFO - Running setup.py install for moztest 04:46:42 INFO - Running setup.py install for mozversion 04:46:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:46:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:46:42 INFO - Cleaning up... 04:46:42 INFO - Return code: 0 04:46:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:46: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')]} 04:46:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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')]} 04:46:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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 0x11025cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11025f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee32d4f620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11003ad30>, '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 0x10ff61db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11002fbe8>, '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.IRavaIZz3q/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.NmRXu2wQXf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/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 04:46: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 04:46: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 04:46:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRavaIZz3q/Render', 04:46:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:42 INFO - 'HOME': '/Users/cltbld', 04:46:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:42 INFO - 'LOGNAME': 'cltbld', 04:46:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:42 INFO - 'MOZ_NO_REMOTE': '1', 04:46:42 INFO - 'NO_EM_RESTART': '1', 04:46:42 INFO - 'PAGER': '/bin/cat', 04:46:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:42 INFO - 'PWD': '/builds/slave/test', 04:46:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:42 INFO - 'SHELL': '/bin/bash', 04:46:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NmRXu2wQXf/Listeners', 04:46:42 INFO - 'TMPDIR': '/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/', 04:46:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:42 INFO - 'USER': 'cltbld', 04:46:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:42 INFO - 'XPC_FLAGS': '0x0', 04:46:42 INFO - 'XPC_SERVICE_NAME': '0', 04:46:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:46:42 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-QfeetC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:46: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)) 04:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:46:42 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-zn2TNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:46: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)) 04:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:46:42 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-fBjoDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:46: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)) 04:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:46:42 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-jQcy9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:46:43 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)) 04:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:46:43 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-f5jqwT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:46:43 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)) 04:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:46:43 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-7srW4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:46: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)) 04:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:46:43 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-PP_Nvm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:46: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)) 04:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:46:43 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-4JvudK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:46: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)) 04:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:46:43 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-lsIsgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:46: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)) 04:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:46:43 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-ZFhp8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:46: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)) 04:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:46:43 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-xii7sq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:46: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)) 04:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:46:43 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-ACRjuF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:46: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)) 04:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:46:43 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-ZJrVK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:46:44 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)) 04:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:46:44 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-SUEN7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:46:44 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)) 04:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:46:44 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-JaKz6s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:46: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)) 04:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:46:44 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-Hvb8Lf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:46: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)) 04:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:46:44 INFO - Running setup.py (path:/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/pip-aZOcCp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:46: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)) 04:46: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)) 04:46: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)) 04:46: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)) 04:46: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)) 04:46: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)) 04:46:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46: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 04:46:47 INFO - Downloading blessings-1.6.tar.gz 04:46:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:46:47 INFO - Installing collected packages: blessings 04:46:47 INFO - Running setup.py install for blessings 04:46:47 INFO - Successfully installed blessings 04:46:47 INFO - Cleaning up... 04:46:47 INFO - Return code: 0 04:46:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:46:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:46:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:46:47 INFO - Reading from file tmpfile_stdout 04:46:47 INFO - Current package versions: 04:46:47 INFO - blessings == 1.6 04:46:47 INFO - blobuploader == 1.2.4 04:46:47 INFO - docopt == 0.6.1 04:46:47 INFO - functools32 == 3.2.3-2 04:46:47 INFO - jsonschema == 2.5.1 04:46:47 INFO - manifestparser == 1.1 04:46:47 INFO - mozInstall == 1.12 04:46:47 INFO - mozcrash == 1.0 04:46:47 INFO - mozdebug == 0.1 04:46:47 INFO - mozdevice == 0.49 04:46:47 INFO - mozfile == 1.2 04:46:47 INFO - mozhttpd == 0.7 04:46:47 INFO - mozinfo == 0.9 04:46:47 INFO - mozleak == 0.1 04:46:47 INFO - mozlog == 3.4 04:46:47 INFO - moznetwork == 0.27 04:46:47 INFO - mozprocess == 0.25 04:46:47 INFO - mozprofile == 0.28 04:46:47 INFO - mozrunner == 6.13 04:46:47 INFO - mozscreenshot == 0.1 04:46:47 INFO - mozsystemmonitor == 0.3 04:46:47 INFO - moztest == 0.8 04:46:47 INFO - mozversion == 1.4 04:46:47 INFO - psutil == 3.1.1 04:46:47 INFO - requests == 1.2.3 04:46:47 INFO - wsgiref == 0.1.2 04:46:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:46: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')]} 04:46:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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')]} 04:46:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46: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 0x11025cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11025f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee32d4f620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11003ad30>, '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 0x10ff61db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11002fbe8>, '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.IRavaIZz3q/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.NmRXu2wQXf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/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 04:46: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 04:46: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 04:46:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRavaIZz3q/Render', 04:46:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:47 INFO - 'HOME': '/Users/cltbld', 04:46:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:47 INFO - 'LOGNAME': 'cltbld', 04:46:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:47 INFO - 'MOZ_NO_REMOTE': '1', 04:46:47 INFO - 'NO_EM_RESTART': '1', 04:46:47 INFO - 'PAGER': '/bin/cat', 04:46:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:47 INFO - 'PWD': '/builds/slave/test', 04:46:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:47 INFO - 'SHELL': '/bin/bash', 04:46:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NmRXu2wQXf/Listeners', 04:46:47 INFO - 'TMPDIR': '/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/', 04:46:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:47 INFO - 'USER': 'cltbld', 04:46:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:47 INFO - 'XPC_FLAGS': '0x0', 04:46:47 INFO - 'XPC_SERVICE_NAME': '0', 04:46:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46: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)) 04:46: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)) 04:46: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)) 04:46: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)) 04:46: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)) 04:46: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)) 04:46: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)) 04:46: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)) 04:46: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)) 04:46: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)) 04:46: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)) 04:46:47 INFO - Cleaning up... 04:46:48 INFO - Return code: 0 04:46:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:46:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:46:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:46:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:46:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:46:48 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 0x11025cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11025f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee32d4f620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11003ad30>, '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 0x10ff61db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11002fbe8>, '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.IRavaIZz3q/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.NmRXu2wQXf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/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 04:46:48 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 04:46:48 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 04:46:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRavaIZz3q/Render', 04:46:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:46:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:46:48 INFO - 'HOME': '/Users/cltbld', 04:46:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:46:48 INFO - 'LOGNAME': 'cltbld', 04:46:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:46:48 INFO - 'MOZ_NO_REMOTE': '1', 04:46:48 INFO - 'NO_EM_RESTART': '1', 04:46:48 INFO - 'PAGER': '/bin/cat', 04:46:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:46:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:46:48 INFO - 'PWD': '/builds/slave/test', 04:46:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:46:48 INFO - 'SHELL': '/bin/bash', 04:46:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NmRXu2wQXf/Listeners', 04:46:48 INFO - 'TMPDIR': '/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/', 04:46:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:46:48 INFO - 'USER': 'cltbld', 04:46:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:46:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:46:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:46:48 INFO - 'XPC_FLAGS': '0x0', 04:46:48 INFO - 'XPC_SERVICE_NAME': '0', 04:46:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:46:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:46:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:46:48 INFO - Cleaning up... 04:46:48 INFO - Return code: 0 04:46:48 INFO - Running post-action listener: _resource_record_post_action 04:46:48 INFO - Running post-action listener: _start_resource_monitoring 04:46:48 INFO - Starting resource monitoring. 04:46:48 INFO - [mozharness: 2017-03-19 11:46:48.291804Z] Finished create-virtualenv step (success) 04:46:48 INFO - [mozharness: 2017-03-19 11:46:48.292816Z] Running install step. 04:46:48 INFO - Running pre-action listener: _resource_record_pre_action 04:46:48 INFO - Running main action method: install 04:46:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:46:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:46:48 INFO - Reading from file tmpfile_stdout 04:46:48 INFO - Detecting whether we're running mozinstall >=1.0... 04:46:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:46:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:46:48 INFO - Reading from file tmpfile_stdout 04:46:48 INFO - Output received: 04:46:48 INFO - Usage: mozinstall [options] installer 04:46:48 INFO - Options: 04:46:48 INFO - -h, --help show this help message and exit 04:46:48 INFO - -d DEST, --destination=DEST 04:46:48 INFO - Directory to install application into. [default: 04:46:48 INFO - "/builds/slave/test"] 04:46:48 INFO - --app=APP Application being installed. [default: firefox] 04:46:48 INFO - mkdir: /builds/slave/test/build/application 04:46:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:46:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:47:09 INFO - Reading from file tmpfile_stdout 04:47:09 INFO - Output received: 04:47:09 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 04:47:09 INFO - Running post-action listener: _resource_record_post_action 04:47:09 INFO - [mozharness: 2017-03-19 11:47:09.144597Z] Finished install step (success) 04:47:09 INFO - [mozharness: 2017-03-19 11:47:09.144749Z] Running run-tests step. 04:47:09 INFO - Running pre-action listener: _resource_record_pre_action 04:47:09 INFO - Running main action method: run_tests 04:47:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:47:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:47:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:47:09 INFO - 2017-03-19 04:47:09.159 screenresolution[1999:21326] starting screenresolution argv=screenresolution get 04:47:09 INFO - 2017-03-19 04:47:09.162 screenresolution[1999:21326] Display 0: 1600x1200x32@60 04:47:09 INFO - 2017-03-19 04:47:09.168 screenresolution[2000:21328] starting screenresolution argv=screenresolution list 04:47:09 INFO - Available Modes on Display 0 04:47:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:47:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:47:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:47:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:47:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:47:09 INFO - Intel Iris: 04:47:09 INFO - Chipset Model: Intel Iris 04:47:09 INFO - Type: GPU 04:47:09 INFO - Bus: Built-In 04:47:09 INFO - VRAM (Dynamic, Max): 1536 MB 04:47:09 INFO - Vendor: Intel (0x8086) 04:47:09 INFO - Device ID: 0x0a2e 04:47:09 INFO - Revision ID: 0x0009 04:47:09 INFO - Displays: 04:47:09 INFO - Display: 04:47:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:47:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:47:09 INFO - Display Serial Number: 1600x1200 60 04:47:09 INFO - Main Display: Yes 04:47:09 INFO - Mirror: Off 04:47:09 INFO - Online: Yes 04:47:09 INFO - Rotation: Supported 04:47:09 INFO - Return code: 0 04:47:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:47:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:47:09 INFO - Python 2.7.3 04:47:09 INFO - Return code: 0 04:47:09 INFO - grabbing minidump binary from tooltool 04:47:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:47:09 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 0x11003ad30>, '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 0x10ff61db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11002fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:47:09 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 04:47:09 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 04:47:09 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 04:47:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:47:09 INFO - Return code: 0 04:47:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:47:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:47:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:47:09 INFO - ENV: RUST_BACKTRACE is now 1 04:47:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:47:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:47:09 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q6TF7l8MS-igeqBcd2Z99Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0092', '--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 04:47:09 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Q6TF7l8MS-igeqBcd2Z99Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0092 --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 04:47:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.IRavaIZz3q/Render', 04:47:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:47:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:47:09 INFO - 'HOME': '/Users/cltbld', 04:47:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:47:09 INFO - 'LOGNAME': 'cltbld', 04:47:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:47:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:47:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:47:09 INFO - 'MOZ_NO_REMOTE': '1', 04:47:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:47:09 INFO - 'NO_EM_RESTART': '1', 04:47:09 INFO - 'PAGER': '/bin/cat', 04:47:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:47:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:47:09 INFO - 'PWD': '/builds/slave/test', 04:47:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:47:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:47:09 INFO - 'RUST_BACKTRACE': '1', 04:47:09 INFO - 'SHELL': '/bin/bash', 04:47:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NmRXu2wQXf/Listeners', 04:47:09 INFO - 'TMPDIR': '/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/', 04:47:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:47:09 INFO - 'USER': 'cltbld', 04:47:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:47:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:47:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:47:09 INFO - 'XPC_FLAGS': '0x0', 04:47:09 INFO - 'XPC_SERVICE_NAME': '0', 04:47:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:47:09 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q6TF7l8MS-igeqBcd2Z99Q/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0092', '--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 04:47:09 INFO - mozversion application_buildid: 20170319030203 04:47:09 INFO - mozversion application_changeset: e1576dd8bd9d3a4ca418cf347133b8a4957ddeca 04:47:09 INFO - mozversion application_display_name: Nightly 04:47:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:47:09 INFO - mozversion application_name: Firefox 04:47:09 INFO - mozversion application_remotingname: firefox 04:47:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:47:09 INFO - mozversion application_vendor: Mozilla 04:47:09 INFO - mozversion application_version: 55.0a1 04:47:09 INFO - mozversion platform_buildid: 20170319030203 04:47:09 INFO - mozversion platform_changeset: e1576dd8bd9d3a4ca418cf347133b8a4957ddeca 04:47:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:47:09 INFO - mozversion platform_version: 55.0a1 04:47:09 INFO - using testdate: 1489924029 04:47:09 INFO - actual date: 1489924029 04:47:09 INFO - starting webserver on 'localhost:49400' 04:47:09 INFO - SUITE-START | Running 2 tests 04:47:09 INFO - TEST-START | dromaeo_css 04:47:09 INFO - operating with platform_type : mac_ 04:47:09 INFO - Initialising browser for dromaeo_css test... 04:47:09 INFO - TEST-INFO | started process 2010 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/tmpr1Lep_/profile http://localhost:49400/getInfo.html) 04:47:12 INFO - PID 2010 | __metrics Screen width/height:1600/1200 04:47:12 INFO - PID 2010 | colorDepth:24 04:47:12 INFO - PID 2010 | Browser inner width/height: 1024/689 04:47:12 INFO - PID 2010 | __metrics 04:47:12 INFO - PID 2010 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:47:12 INFO - PID 2010 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:47:13 INFO - TEST-INFO | 2010: exit 0 04:47:13 INFO - Browser initialized. 04:47:13 INFO - Running cycle 1/1 for dromaeo_css test... 04:47:13 INFO - TEST-INFO | started process 2013 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/tmpr1Lep_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:47:25 INFO - PID 2013 | RSS: Main: 233492480 04:47:25 INFO - PID 2013 | 04:50:29 INFO - PID 2013 | Cycle 1(1): loaded http://localhost:49400/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49400/tests/dromaeo/cssquery-ext.html) 04:50:32 INFO - PID 2013 | RSS: Main: 453312512 04:50:32 INFO - PID 2013 | 04:52:14 INFO - PID 2013 | ************************* 04:52:14 INFO - PID 2013 | A coding exception was thrown and uncaught in a Task. 04:52:14 INFO - PID 2013 | 04:52:14 ERROR - PID 2013 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:52:14 INFO - PID 2013 | Full stack: 04:52:14 INFO - PID 2013 | ************************* 04:53:35 INFO - PID 2013 | Cycle 1(1): loaded http://localhost:49400/tests/dromaeo/cssquery-ext.html (next: http://localhost:49400/tests/dromaeo/cssquery-jquery.html) 04:53:36 INFO - PID 2013 | RSS: Main: 466915328 04:53:36 INFO - PID 2013 | 04:56:38 INFO - PID 2013 | Cycle 1(1): loaded http://localhost:49400/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49400/tests/dromaeo/cssquery-mootools.html) 04:56:39 INFO - PID 2013 | RSS: Main: 354152448 04:56:39 INFO - PID 2013 | 04:59:41 INFO - PID 2013 | Cycle 1(1): loaded http://localhost:49400/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49400/tests/dromaeo/cssquery-prototype.html) 04:59:42 INFO - PID 2013 | RSS: Main: 339615744 04:59:42 INFO - PID 2013 | 05:02:46 INFO - PID 2013 | Cycle 1(1): loaded http://localhost:49400/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49400/tests/dromaeo/cssquery-yui.html) 05:02:46 INFO - PID 2013 | RSS: Main: 340955136 05:02:46 INFO - PID 2013 | 05:05:50 INFO - PID 2013 | Cycle 1(1): loaded http://localhost:49400/tests/dromaeo/cssquery-yui.html (next: http://localhost:49400/tests/dromaeo/cssquery-dojo.html) 05:05:50 INFO - PID 2013 | RSS: Main: 474927104 05:05:50 INFO - PID 2013 | 05:05:50 INFO - PID 2013 | __start_tp_report 05:05:50 INFO - PID 2013 | _x_x_mozilla_page_load 05:05:50 INFO - PID 2013 | _x_x_mozilla_page_load_details 05:05:50 INFO - PID 2013 | |i|pagename|runs| 05:05:50 INFO - PID 2013 | |0;dojo.html;3368.090615533806;3373.2916087621597;3376.4597664373705;3412.3175364927015;3439.2777516721485;5676.772929082832;5825.796097136601;5878.676672783006;5917.644941303525;5927.110933359998;5836.212111364965;5875.353711091787;5950.970245148779;5952.970235148829;5966.612170208933;5181.6113791465605;5243.895122097556;5261.500157485033;5263.86840328991;5268;5342.198670199482;5373.731313434332;5378;5382.6232163748555;5418.783248670068;5190.792368305263;5221.791128354861;5241.764120614582;5247.947520524784;5301.0723123453245;5254.921176182322;5258.3689957205;5262.947370526284;5264.578833693296;5269.6311258211945;5140.177571588528;5206.895862082737;5222.895542089175;5232.73836308183;5233.136532472137;5753.165790960329;5756;5759.107338362519;5763.654180749127;5776.306843178845;6295.181626388586;6362.0456931460185;6364.204474440695;6366.777162799326;6370.5222108341595;5800.187973683687;6311.7159727811895;6389.4568961638315;6394.1687580614225;6414.679266036679;5948.821535353946;5956.52347812174;5972.343042265347;6006.939930600682;6023.457888789986;6318.684065796691;6374.649394283266;6387.648679322589;6389.808305750835;6398.2641996170105;6142.600730952563;6176.258848938156;6186.226721659793;6320.652364119926;6356.491480681534;6288.937110628835;6289.779857704958;6291.993281075006;6292.842678933063;6295.276043254993;6217.906731398942;6225.066240063936;6228.003519436868;6243.282022567372;6243.43809057187;6332.303446620868;6354.17395738551;6389.936100639027;6415.967920160369;6423.5503514754455;4939.185034469272;5041.521055499722;5063.518965698253;5067.898642027212;5084.0340335336905;4460.197164510354;4517.457905051415;4535.274356103007;4553.681242313072;4632.536746325341;4774.809007639725;5787.045137552341;5791.739371728282;5814.3604203538425;5882.117682347596;5770.567207459458;5962.522998160137;6339.873202535923;6353.237611486651;6364.522660800459;5883.882322353615;5888.087346461305;5904.852378690567;5916.7337469813965;5950.821475355746;5456.372517160577;5605.747741351649;5606.074997625427;5610.607257491936;5614.85962850932;6316.526260530387;6336.651484168415;6375.904361434489;6395.936040639534;6435.2599451063725;6328.525360598066;6340.904886426615;6367.522435817425;6370.362963703593;6382.680865956777;6395.328490508616;6413.230412350548;6433.742650293936;6449.419552240321;6453.225612926479;6202.2867370253;6241.095041218907;6319.715612797527;6380.649064301322;6382.266039405527;6138.263408391022;6340.1123842661145;6348.904766428415;6351.110844481868;6376.362363763772;6203.751849925952;6249;6255.624662520263;6264.780732674334;6270.184875966278;6071.666058366833;6102.913431175913;6205.751769929152;6263.843403914939;6277.027060805583;5670.574706897081;6005.249343832021;6119.641076769691;6120.466805134251;6122.154209354628;5682.488576028178;6118.418750218722;6121.910283036266;6156.445919867234;6286.874262514815;5884.470397664059;5949.19685842423;5966.49284810796;5970.522358211246;5974.641521508724 05:05:50 INFO - PID 2013 | |1;ext.html;685.1914740606085;772.8106613879601;835.2482765511043;842.8988521377436;845.9407841451101;36494.27011459769;36913.077173070655;37128.443073353854;37130.25739485209;37176.814115929454;38289.425658615146;38670;38678.61321386785;38717.8064109679;38757;6244.56288059836;7364.5949472778975;8995.595198216097;9210.171084602385;9229.492377919212;10634.734131646694;10878;10911.963363480456;10918.836217456745;10921.781564368708;10981.231313808037;11010.834837477405;11031.503582338813;11048.447577621127;11059.281146725478;7165.283471652845;7338.486305958585;10904.073153781937;11002.834957475645;11025.007749302562;10411.635592754294;10621.84067238992;10645.57417703291;10661.253712240146;10669.253152279343;36573.451398229045;36974;37001.444978325075;37003;37072.629273707455;3739.981300093509;3777.471154038437;3808.6572208501093;3830.578636349999;3932.351162058257;26913.730862691515;27086.1874143776;28364;28392.716072839215;28547.714522854712;3127.358891427252;3141.089084165589;3174.2857856982223;3245.0589329094532;3254.739620830328;275029;275383.3309169431;275391;275498;275520;38237;38839;39008.60991390106;50651.49348506542;50796;51741;52535;52579;52584;52613.47386526162;14022.088564243291;14169.716605667829;14180.85819141816;14181.574552763434;14185.219812910183;9470.52647367629;11357.261777984404;11420.88579114215;11425.200235983568;11451.942740286411;13592;13689.110207836458;13705;13934.790978135336;13993.930030349784;5036.212144688656;5559.138333558305;5871.4715675589405;5980.252468441445;6002.849928751816;810.2626609785185;886.0829041941688;925.472480686016;960.283702072982;997.1723469520281;19666.410007699793;26131.467133216694;28450.85774571114;28855.134345969655;29093.981710640026;886.6497733395402;958.1185309515263;1014.8021135878433;1024.2011231239644;1042.6246551241547;13243;13807.792883106762;14331.498397556035;14834.332455039548;15198.544043678707;359245;366364;366596;367319;451316;54368;54871.72564137234;54984;55058.44941550613;55106.44893551093;1264.525802823941;1309.9803502947464;1407.971840563183;1408.4507042253733;1439.5609339151658;23760.762392375855;29333.12000639984;29347.85326073356;29413.85293073521;30187.849060754557;46121.53878461307;46389.129141441015;46391.304130438744;46434;46507.53492465032;473490;473814;475177;475620;476270.6186469184;32770.016934254636;33469.66530334666;33617.66382336243;33774.49338259977;33916;42447;42940;43162.2521522626;43218;43308;33588.496172556945;35037.299254014266;35344.46983295201;35651.28697425985;36121.0969725759;34090.488642670876;34397.484037738956;34519;35432.64567354293;35672.92981210568;34868.128296792784;35090.824545877105;35538.46692299669;35666.64333356737;35687.82156089203;25388.365290867878;27608.033718819745;30506.389872202875;30697.53953690741;30785.511998807393;20473.48816279605;21287.254946076813;21420.92895355195;23027.76972230256;23095.307140785804 05:05:50 INFO - PID 2013 | |2;jquery.html;360.67178867230825;373.156665934987;384.147193231027;415.7380850064459;422.93867389228564;12012.519499220032;15927;18835.058247087625;19925;22546.21088261911;21981.526281064434;22639.207627733034;22880;23026.309210723663;23066.423339416524;5306.726385667432;6564.409203171714;6932.0641713368805;6936.0636314097455;6948.756793512228;6761.256261811197;6806.047153398528;6855.828604284883;6860.5197636165485;6873.587584744906;6746.359095885883;6747.561408508432;6837.760678376258;6839.760608378708;6887.758928437505;6506.3818937200895;6537.836554086139;6783.694733736945;6801.319868013184;6806.6256355900405;6493.350664933469;6577.177852768404;6586.077949087132;6586.682663467278;6587.93412065878;26524;27129.186124416297;27156.864215678794;27255.727442725518;27447.86276068627;3357.513160591725;3363.1928337198897;3363.2432702641954;3371.190914180579;3391.491276308549;16198.676026479523;16824.663506729798;16854;16946.576335591584;17812.821871781245;1990.4227773945538;2065.6901464780253;2084.3017589107517;2084.5101401170714;2087.77034526202;133213.3339333297;155735.22132389378;156567.99999999886;158841.70424661835;161525;14305.270696215084;21046.473838153863;21272.574548509252;21387;21610;32773;33875.491867622004;33960;34038.829805850815;34199.65800342015;8378.958105209556;8392.916070839336;8726.756791862865;8841.336899732418;10393.792124157626;8307.667693292322;8479.236868681724;8510.36172287081;8587.441816281922;8612.78468038304;9305.091861418221;9581.712548623553;9944.552495137592;10420.32267902584;10422.322549034288;3482.6169121396624;4048.595140485928;4133.359329303963;4168.520620128724;4204.51648060477;840.815020695446;990.4404011733347;995.0994350113159;1009.2089072361358;1030.90206430389;15925.840741592436;19560.315388961317;20383.286584969454;22972.4256893579;23235.002812050603;979.8187335342984;988.2902578539167;997.850322451638;1014.9898501014895;1027.0961553832578;10596;11187.608433704943;11736.061115110771;11987.52049918011;12159.331236781885;670017;672701;680683.3692483751;700737;702483.1647727492;143811;144958;151294;152848;156948.21525892298;1205.3551350027692;1244.7199380139577;1305.654001689553;1305.6670549010132;1306.5165888621082;16247.59381015484;19471.92904390272;20028.799712003278;20193.59612807765;21320.04059817281;60769.392306078145;61349.07976380447;61906;61954;63813.680931595045;66123;90612;90698.09301906897;91330.54334728284;91975.44442000237;11413.657590272305;11567.942160289145;12151.149419540547;12410.193337433038;12564.87435125637;13611.795823062464;13704.451821927012;13716.108452950526;14163.221022843842;14175.149491030572;11193.27243729155;11457.255278406878;11669.066474682175;11877.22798018126;12043.75912481763;11628.94185529067;11790.410479476164;12005.339706315886;12015.45930433115;12066.75866482683;10277.331973421704;10887.618933337295;11824.23142495735;11858.644240672793;12171.878281217074;11849.466773995018;12225.229454004235;12422.875771242172;12475.875241247835;12590.685232869251;11628.360440175871;11712.063034957013;12038.217515861792;12089.51641934348;12171.330576818005 05:05:50 INFO - PID 2013 | |3;mootools.html;1508.479574546781;1510.5166346769035;1529.4876216467483;1549.5041586692264;1555.449063535801;10046.54790534426;10383.688489345332;10387.636432724856;10484.213683973703;10504.212184086206;10246.282760206788;10554.78890422191;10671.306365086284;10681.893181068186;10706.839397409045;2340.6137987232087;2383.07060246504;2383.7735415135535;2384.2966324934187;2393.365758074112;2329.4292898239955;2342.61346877765;2346.753590872959;2362.61016932206;2370.288913326004;1888.4523488188408;2227.042371780134;2234.352037909013;2235.441139715071;2239.988800055997;2285.337252196861;2299.4711216420233;2309.8614083155067;2312.907483700667;2317.188983855637;2272.54549090181;2351.9412014699597;2360.929172124839;2362.255889394852;2365.727941286757;9207.723768286962;10820.837687434694;10916.727081822939;10940;10941.179411544166;5617.775288988436;5842.649441033552;5844.094165404369;5848.707564621752;5862;3335.816530090844;3430.2967891582416;3638.508801311842;3642.763220390666;3657.6708096271464;1116.4808364110727;1132.326265871805;1133.1274918312854;1201.4173126033866;1240.138104017708;13447.394867230998;13899.305034748222;13900;14944.178070206135;14978.925105374512;4557.931631025537;4725.8025018366625;4738.07607516531;4741.881452963635;4759.857204283877;11355.261907975955;11369.488373023234;11369.602063927723;11369.829452558219;11393;4459.2865141577195;4845.3216549683075;5024.497550244946;5650.4349565043985;5701.600887937888;4622.791974361162;5015.197568389113;5200.401953775364;5203.713795741271;5207.453217412193;3766.6798322142927;3766.9058273543383;3832.405977073558;3839.616038396138;3840.711946604016;1015.6343716262139;1039.2673165418362;1047.910927571165;1055.2296823318866;1055.8099542082498;774.7520793346188;779.1701837542998;798.9201079891965;806.2058872011064;809.8542262392826;5333.893322133536;5820.447057529528;6292.905606415908;6472.190976127984;6593.5384523083885;966.2510855351836;967.5549247346158;979.2900147393083;988.328780730072;1035.321864178949;3738.420544815558;4046.7976601169826;4053.2298863215897;4120.361343991686;4157.168566286755;12854.164479308814;13128.671783205305;13254.53609123676;13271.668208294677;13343.199408035549;11756.882431175749;13159.144655597356;13161.73676526464;13232.73534529304;13289.867101329057;9520;9809.656662016796;9828;9845.360051596623;9852.556634951445;5701.4298570142655;6447.161868957193;6470.708818103102;6676.198856137295;6688.197416310074;11767.529298828293;12061.276323420623;12184.268943863397;12206.511739530675;12229.082818788449;10908.61819836302;11024.779504409706;11433;11438.313701177956;12065.939670301592;8897.510636915033;8960.596773145095;9056.864147037932;9059.864102038606;9139.994600593796;9868.654597089355;10155.39067655943;10171.542280597243;10187.643432479828;10201.591936322464;8518.74443766688;8718.694845680371;8809.647614095364;8863.778405539655;8865.556722163738;8675.739727808175;8686.78283042929;8784;8790;8945.23965462943;7413.703451862081;7627.5042122261875;7658.885116723365;7668.271514206142;7693.6537855795505;5472.50747432733;5624.915626265528;5698.145278208136;5747.7413516391025;5781.4218578141845;5794.797976768801;5797.4782269595935;5805.564582656233;5813.186153938367;5830.5627077968475 05:05:50 INFO - PID 2013 | |4;prototype.html;321.67671490152395;368.3222869919348;368.7713617557115;377.3867465368776;381.24132975378996;5866.560007999396;5921.904447677182;5939.762409503625;5953.40465953405;6020.428059334362;4982.377202849644;5525.475079867406;5668.263125793641;5748.252727145476;5777.266819954018;4416.492103408117;4472.5974662280305;4484.59638632523;4506.707064040843;4516.503184649686;3891.571927088018;4498;4524.6380289576755;4528.660350473711;4543.909121817562;3315.834208289576;4380.948572342647;4408;4481.655503348982;4503.144402563519;4100.466939297902;4252.298370768804;4344.978275108636;4484.372187893698;4514.841980530666;3290.851911663981;3887.319719049155;3911.706622003361;3932.7050471214775;3935.9372969298415;5684.9198652256355;5700.37295897451;5715.571332150105;5729.9713501432225;5758.222639943638;5114.616403769732;5241.475852414766;5256.211568264791;5293.6559123657225;5294.11470748443;6207.616848817405;6226.501879849601;6240.188775459206;6251.906221406683;6258.655773932432;4146.958530414688;4374.256376416017;4386.078923426085;4388.912221755579;4414.690971631987;6245.875742366372;6399.776007839703;6495.8700825983215;6497.415232629086;6511.28375878653;5413.972930135403;5500.862478438071;5554.472325129106;5556.444355564492;5595.468430499096;5493.7802487900035;5628.748500599793;5701.660109874177;5715.714214289352;5745.299709136699;5193.428722840485;5561.388247292794;5869.88472190291;5896.498797602166;5918.526517878559;5815.73829177688;6271.404216599416;6287.779927702509;6323.715432805443;6323.7786677466065;1671.1811212505784;1756.6750151222066;1772.822717728217;1962.7546556680416;1997.1811557261453;1099.9275706186404;1252.8286052540902;1269.3526301586257;1272.7136394311206;1308.5943357558997;5131.25596788471;5232.953409318152;5560.471755183251;5655.462731040545;5883.558733094949;5677.347105082886;6504.934950650433;6519.5436319457185;6523.64119973406;6581.3418658133805;5232.502912223333;5575.916361254584;5704.458076482728;5824.330202026821;5859.1797148399255;2928.546075358323;2976.062540299842;3362.0922350965593;3394.9151271218416;3411.624721280657;6513.902291465632;6578.111954886125;6719.932800672029;6723.462123030145;6727.865442691216;6066.393360663898;6319.115323854664;6579.828656543329;6583.210014798255;6605.372489613479;6398;6403.839904002344;6441.452476539546;6639.767608133789;6721.361470660378;6529.118568993315;6551.246606640298;6566.671666416564;6578.934210657832;6578.934210657832;4740.862193073706;4950.059488697136;5870;6138;6263.592866463665;6674.998750187511;6690.799276021728;6717;6718.8320291993095;6756.223034351113;4838.395200599925;4990.251462280687;5061.898762024813;5114.309568208393;5115.7953681852305;6428.967855160694;6430.967845160744;6446.8388290293115;6515.576487528296;6530.379613936669;6102.877942441215;6248.781292654735;6290.182276304051;6302.369763023661;6305.95951667988;5215.947840521546;5522.86192845166;6176.702892392648;6276.413991547435;6284.780032698834;6100.23747031621;6102.328743838086;6141.969290153521;6149.538784591084;6169.50643948474;5873.794417195378;6076.787312444043;6251;6313.873722525432;6322.620642761449;5597.076482380362;6126.969365153146;6147.231596050494;6148.723307451086;6150.077488376601 05:05:50 INFO - PID 2013 | |5;yui.html;741.3772431157827;748.2293237964894;834.8914641096657;839.7312859884837;842.9452085614438;15335;16053.277602507886;16089.034657920532;16094.597635059115;16109.677806443864;14803.555893323191;14884.181370024644;14997.400103995828;15032.849671503309;15042.24788760563;887.5118684723392;907.5326207003366;909.8589718593629;910.8406028944942;912.5938957164065;764.3197554176787;930.3208657679897;937.1565590968115;948.5778828421355;952.6570434643522;965.9951700241489;979.7403688022644;981.9787421082102;982.1749730226608;984.4093543873654;959.3620242538739;969.5782334684435;970.0202795176851;970.335320305593;972.6595691507915;726.920038795732;728.5883475836135;728.6975904999418;729.2015243308568;729.5258082246529;15782.36870525189;16014.19929003545;16049.197540122947;16734;16834.49496515094;14558.92720536401;14640.194789286585;14666.413343466249;14666.56000319992;14736.410543578244;11652.592159274385;11681.766364672743;11774.529018839236;11952.462139203757;12198.329091899943;3652.726045546595;3739.1960728443514;3773.811309434545;3815.8092095395396;3920.980395098035;78475.21524784736;100681.49659251657;110153.89846101517;111822.44088779585;112701.30948035786;10644.308119972331;11468.31190128595;11507.539698412138;11690.298582085103;11775.941120294343;20889;24940.875295623406;24968.250952471455;24974.125905593217;25002.4999500009;7528.698852045966;7946.165652606509;8117.95941020303;8200.466969646954;8203.179682031869;4039.4344791728595;4178.39413285072;4186.3092589722355;4269.167512335065;4326.050303141863;3427.057559171198;3513.5608048993877;3515.5605549306338;3517.62812503124;3521.102118959649;573.7676241122352;591.5267785771451;605.6880706436189;612.4671535763914;626.0734113512028;628.3371043549008;660.8917553005991;686.9969844027776;697.2817997462622;699.6816448515913;8914.465132092095;10061.849072263922;10086.546105425274;10156.441395723159;10376.273660843668;649.7362629685312;658.83386406061;691.1326285511751;701.6737217194029;718.6047673779505;4584.426946631671;5003.49965003504;5034.84895453137;5037.2874614696375;5053.9494605054215;101553.49223253837;102556;103188.48405757923;103685;106131.46934265434;68880.93357199292;68991;80459.59770201112;85783;88110;79499.20500794917;81326.59336703278;81464;81746.77379839188;82421.93945151419;8140.918590814016;9558.235341172829;9648.614055437703;9692.856608199032;9698.408029157037;50653;51015.97968040544;51625.4167895573;52337.47662523326;53138.937221254586;61517;63316;63504;63998.7200255983;64537;11732.77661795414;11738.94130529342;11839.881601183877;12455.190412623151;12463.5014599415;12708.745825083632;12851.807222891477;12886.742265154831;13037.543685970946;13310.733785324433;11448.313101213955;11527.308361498337;11764.529418823493;12080.690952454594;12269;10660.880607536406;11709.765804683688;11812.401271720208;12225.755484890075;12229;10365.844512332473;11725.239775628497;11856.17006809515;11914.821277681016;12138.92880146665;9822.508874556386;9824.239309928542;9939.353941993748;9950.10449059588;10582.206334524788;8726.628655581748;8728.301735861243;9623.326367154512;9644;9687.41875487473 05:05:50 INFO - PID 2013 | __end_tp_report 05:05:50 INFO - PID 2013 | __start_cc_report 05:05:50 INFO - PID 2013 | _x_x_mozilla_cycle_collect,2568 05:05:50 INFO - PID 2013 | __end_cc_report 05:05:50 INFO - PID 2013 | __startTimestamp1489925150898__endTimestamp 05:05:50 INFO - PID 2013 | 05:05:50 INFO - PID 2013 | ------- Summary: start ------- 05:05:50 INFO - PID 2013 | Number of tests: 6 05:05:50 INFO - PID 2013 | 05:05:50 INFO - PID 2013 | [#0] dojo.html Cycles:165 Average:5831.36 Median:5974.64 stddev:651.19 (10.9%) stddev-sans-first:623.86 05:05:50 INFO - PID 2013 | Values: 3368.1 3373.3 3376.5 3412.3 3439.3 5676.8 5825.8 5878.7 5917.6 5927.1 5836.2 5875.4 5951.0 5953.0 5966.6 5181.6 5243.9 5261.5 5263.9 5268.0 5342.2 5373.7 5378.0 5382.6 5418.8 5190.8 5221.8 5241.8 5247.9 5301.1 5254.9 5258.4 5262.9 5264.6 5269.6 5140.2 5206.9 5222.9 5232.7 5233.1 5753.2 5756.0 5759.1 5763.7 5776.3 6295.2 6362.0 6364.2 6366.8 6370.5 5800.2 6311.7 6389.5 6394.2 6414.7 5948.8 5956.5 5972.3 6006.9 6023.5 6318.7 6374.6 6387.6 6389.8 6398.3 6142.6 6176.3 6186.2 6320.7 6356.5 6288.9 6289.8 6292.0 6292.8 6295.3 6217.9 6225.1 6228.0 6243.3 6243.4 6332.3 6354.2 6389.9 6416.0 6423.6 4939.2 5041.5 5063.5 5067.9 5084.0 4460.2 4517.5 4535.3 4553.7 4632.5 4774.8 5787.0 5791.7 5814.4 5882.1 5770.6 5962.5 6339.9 6353.2 6364.5 5883.9 5888.1 5904.9 5916.7 5950.8 5456.4 5605.7 5606.1 5610.6 5614.9 6316.5 6336.7 6375.9 6395.9 6435.3 6328.5 6340.9 6367.5 6370.4 6382.7 6395.3 6413.2 6433.7 6449.4 6453.2 6202.3 6241.1 6319.7 6380.6 6382.3 6138.3 6340.1 6348.9 6351.1 6376.4 6203.8 6249.0 6255.6 6264.8 6270.2 6071.7 6102.9 6205.8 6263.8 6277.0 5670.6 6005.2 6119.6 6120.5 6122.2 5682.5 6118.4 6121.9 6156.4 6286.9 5884.5 5949.2 5966.5 5970.5 5974.6 05:05:50 INFO - PID 2013 | 05:05:50 INFO - PID 2013 | [#1] ext.html Cycles:180 Average:52158.21 Median:35064.06 stddev:102524.16 (292.4%) stddev-sans-first:102738.93 05:05:50 INFO - PID 2013 | Values: 685.2 772.8 835.2 842.9 845.9 36494.3 36913.1 37128.4 37130.3 37176.8 38289.4 38670.0 38678.6 38717.8 38757.0 6244.6 7364.6 8995.6 9210.2 9229.5 10634.7 10878.0 10912.0 10918.8 10921.8 10981.2 11010.8 11031.5 11048.4 11059.3 7165.3 7338.5 10904.1 11002.8 11025.0 10411.6 10621.8 10645.6 10661.3 10669.3 36573.5 36974.0 37001.4 37003.0 37072.6 3740.0 3777.5 3808.7 3830.6 3932.4 26913.7 27086.2 28364.0 28392.7 28547.7 3127.4 3141.1 3174.3 3245.1 3254.7 275029.0 275383.3 275391.0 275498.0 275520.0 38237.0 38839.0 39008.6 50651.5 50796.0 51741.0 52535.0 52579.0 52584.0 52613.5 14022.1 14169.7 14180.9 14181.6 14185.2 9470.5 11357.3 11420.9 11425.2 11451.9 13592.0 13689.1 13705.0 13934.8 13993.9 5036.2 5559.1 5871.5 5980.3 6002.8 810.3 886.1 925.5 960.3 997.2 19666.4 26131.5 28450.9 28855.1 29094.0 886.6 958.1 1014.8 1024.2 1042.6 13243.0 13807.8 14331.5 14834.3 15198.5 359245.0 366364.0 366596.0 367319.0 451316.0 54368.0 54871.7 54984.0 55058.4 55106.4 1264.5 1310.0 1408.0 1408.5 1439.6 23760.8 29333.1 29347.9 29413.9 30187.8 46121.5 46389.1 46391.3 46434.0 46507.5 473490.0 473814.0 475177.0 475620.0 476270.6 32770.0 33469.7 33617.7 33774.5 33916.0 42447.0 42940.0 43162.3 43218.0 43308.0 33588.5 35037.3 35344.5 35651.3 36121.1 34090.5 34397.5 34519.0 35432.6 35672.9 34868.1 35090.8 35538.5 35666.6 35687.8 25388.4 27608.0 30506.4 30697.5 30785.5 20473.5 21287.3 21420.9 23027.8 23095.3 05:05:50 INFO - PID 2013 | 05:05:50 INFO - PID 2013 | [#2] jquery.html Cycles:180 Average:41257.11 Median:22592.71 stddev:114814.64 (508.2%) stddev-sans-first:115095.66 05:05:50 INFO - PID 2013 | Values: 360.7 373.2 384.1 415.7 422.9 12012.5 15927.0 18835.1 19925.0 22546.2 21981.5 22639.2 22880.0 23026.3 23066.4 5306.7 6564.4 6932.1 6936.1 6948.8 6761.3 6806.0 6855.8 6860.5 6873.6 6746.4 6747.6 6837.8 6839.8 6887.8 6506.4 6537.8 6783.7 6801.3 6806.6 6493.4 6577.2 6586.1 6586.7 6587.9 26524.0 27129.2 27156.9 27255.7 27447.9 3357.5 3363.2 3363.2 3371.2 3391.5 16198.7 16824.7 16854.0 16946.6 17812.8 1990.4 2065.7 2084.3 2084.5 2087.8 133213.3 155735.2 156568.0 158841.7 161525.0 14305.3 21046.5 21272.6 21387.0 21610.0 32773.0 33875.5 33960.0 34038.8 34199.7 8379.0 8392.9 8726.8 8841.3 10393.8 8307.7 8479.2 8510.4 8587.4 8612.8 9305.1 9581.7 9944.6 10420.3 10422.3 3482.6 4048.6 4133.4 4168.5 4204.5 840.8 990.4 995.1 1009.2 1030.9 15925.8 19560.3 20383.3 22972.4 23235.0 979.8 988.3 997.9 1015.0 1027.1 10596.0 11187.6 11736.1 11987.5 12159.3 670017.0 672701.0 680683.4 700737.0 702483.2 143811.0 144958.0 151294.0 152848.0 156948.2 1205.4 1244.7 1305.7 1305.7 1306.5 16247.6 19471.9 20028.8 20193.6 21320.0 60769.4 61349.1 61906.0 61954.0 63813.7 66123.0 90612.0 90698.1 91330.5 91975.4 11413.7 11567.9 12151.1 12410.2 12564.9 13611.8 13704.5 13716.1 14163.2 14175.1 11193.3 11457.3 11669.1 11877.2 12043.8 11628.9 11790.4 12005.3 12015.5 12066.8 10277.3 10887.6 11824.2 11858.6 12171.9 11849.5 12225.2 12422.9 12475.9 12590.7 11628.4 11712.1 12038.2 12089.5 12171.3 05:05:50 INFO - PID 2013 | 05:05:50 INFO - PID 2013 | [#3] mootools.html Cycles:180 Average:6494.80 Median:3534.40 stddev:4028.94 (114.0%) stddev-sans-first:4022.82 05:05:50 INFO - PID 2013 | Values: 1508.5 1510.5 1529.5 1549.5 1555.4 10046.5 10383.7 10387.6 10484.2 10504.2 10246.3 10554.8 10671.3 10681.9 10706.8 2340.6 2383.1 2383.8 2384.3 2393.4 2329.4 2342.6 2346.8 2362.6 2370.3 1888.5 2227.0 2234.4 2235.4 2240.0 2285.3 2299.5 2309.9 2312.9 2317.2 2272.5 2351.9 2360.9 2362.3 2365.7 9207.7 10820.8 10916.7 10940.0 10941.2 5617.8 5842.6 5844.1 5848.7 5862.0 3335.8 3430.3 3638.5 3642.8 3657.7 1116.5 1132.3 1133.1 1201.4 1240.1 13447.4 13899.3 13900.0 14944.2 14978.9 4557.9 4725.8 4738.1 4741.9 4759.9 11355.3 11369.5 11369.6 11369.8 11393.0 4459.3 4845.3 5024.5 5650.4 5701.6 4622.8 5015.2 5200.4 5203.7 5207.5 3766.7 3766.9 3832.4 3839.6 3840.7 1015.6 1039.3 1047.9 1055.2 1055.8 774.8 779.2 798.9 806.2 809.9 5333.9 5820.4 6292.9 6472.2 6593.5 966.3 967.6 979.3 988.3 1035.3 3738.4 4046.8 4053.2 4120.4 4157.2 12854.2 13128.7 13254.5 13271.7 13343.2 11756.9 13159.1 13161.7 13232.7 13289.9 9520.0 9809.7 9828.0 9845.4 9852.6 5701.4 6447.2 6470.7 6676.2 6688.2 11767.5 12061.3 12184.3 12206.5 12229.1 10908.6 11024.8 11433.0 11438.3 12065.9 8897.5 8960.6 9056.9 9059.9 9140.0 9868.7 10155.4 10171.5 10187.6 10201.6 8518.7 8718.7 8809.6 8863.8 8865.6 8675.7 8686.8 8784.0 8790.0 8945.2 7413.7 7627.5 7658.9 7668.3 7693.7 5472.5 5624.9 5698.1 5747.7 5781.4 5794.8 5797.5 5805.6 5813.2 5830.6 05:05:50 INFO - PID 2013 | 05:05:50 INFO - PID 2013 | [#4] prototype.html Cycles:180 Average:5231.54 Median:5737.64 stddev:1528.83 (26.6%) stddev-sans-first:1488.04 05:05:50 INFO - PID 2013 | Values: 321.7 368.3 368.8 377.4 381.2 5866.6 5921.9 5939.8 5953.4 6020.4 4982.4 5525.5 5668.3 5748.3 5777.3 4416.5 4472.6 4484.6 4506.7 4516.5 3891.6 4498.0 4524.6 4528.7 4543.9 3315.8 4380.9 4408.0 4481.7 4503.1 4100.5 4252.3 4345.0 4484.4 4514.8 3290.9 3887.3 3911.7 3932.7 3935.9 5684.9 5700.4 5715.6 5730.0 5758.2 5114.6 5241.5 5256.2 5293.7 5294.1 6207.6 6226.5 6240.2 6251.9 6258.7 4147.0 4374.3 4386.1 4388.9 4414.7 6245.9 6399.8 6495.9 6497.4 6511.3 5414.0 5500.9 5554.5 5556.4 5595.5 5493.8 5628.7 5701.7 5715.7 5745.3 5193.4 5561.4 5869.9 5896.5 5918.5 5815.7 6271.4 6287.8 6323.7 6323.8 1671.2 1756.7 1772.8 1962.8 1997.2 1099.9 1252.8 1269.4 1272.7 1308.6 5131.3 5233.0 5560.5 5655.5 5883.6 5677.3 6504.9 6519.5 6523.6 6581.3 5232.5 5575.9 5704.5 5824.3 5859.2 2928.5 2976.1 3362.1 3394.9 3411.6 6513.9 6578.1 6719.9 6723.5 6727.9 6066.4 6319.1 6579.8 6583.2 6605.4 6398.0 6403.8 6441.5 6639.8 6721.4 6529.1 6551.2 6566.7 6578.9 6578.9 4740.9 4950.1 5870.0 6138.0 6263.6 6675.0 6690.8 6717.0 6718.8 6756.2 4838.4 4990.3 5061.9 5114.3 5115.8 6429.0 6431.0 6446.8 6515.6 6530.4 6102.9 6248.8 6290.2 6302.4 6306.0 5215.9 5522.9 6176.7 6276.4 6284.8 6100.2 6102.3 6142.0 6149.5 6169.5 5873.8 6076.8 6251.0 6313.9 6322.6 5597.1 6127.0 6147.2 6148.7 6150.1 05:05:50 INFO - PID 2013 | 05:05:50 INFO - PID 2013 | [#5] yui.html Cycles:180 Average:20019.42 Median:5019.17 stddev:28742.88 (572.7%) stddev-sans-first:28787.06 05:05:50 INFO - PID 2013 | Values: 741.4 748.2 834.9 839.7 842.9 15335.0 16053.3 16089.0 16094.6 16109.7 14803.6 14884.2 14997.4 15032.8 15042.2 887.5 907.5 909.9 910.8 912.6 764.3 930.3 937.2 948.6 952.7 966.0 979.7 982.0 982.2 984.4 959.4 969.6 970.0 970.3 972.7 726.9 728.6 728.7 729.2 729.5 15782.4 16014.2 16049.2 16734.0 16834.5 14558.9 14640.2 14666.4 14666.6 14736.4 11652.6 11681.8 11774.5 11952.5 12198.3 3652.7 3739.2 3773.8 3815.8 3921.0 78475.2 100681.5 110153.9 111822.4 112701.3 10644.3 11468.3 11507.5 11690.3 11775.9 20889.0 24940.9 24968.3 24974.1 25002.5 7528.7 7946.2 8118.0 8200.5 8203.2 4039.4 4178.4 4186.3 4269.2 4326.1 3427.1 3513.6 3515.6 3517.6 3521.1 573.8 591.5 605.7 612.5 626.1 628.3 660.9 687.0 697.3 699.7 8914.5 10061.8 10086.5 10156.4 10376.3 649.7 658.8 691.1 701.7 718.6 4584.4 5003.5 5034.8 5037.3 5053.9 101553.5 102556.0 103188.5 103685.0 106131.5 68880.9 68991.0 80459.6 85783.0 88110.0 79499.2 81326.6 81464.0 81746.8 82421.9 8140.9 9558.2 9648.6 9692.9 9698.4 50653.0 51016.0 51625.4 52337.5 53138.9 61517.0 63316.0 63504.0 63998.7 64537.0 11732.8 11738.9 11839.9 12455.2 12463.5 12708.7 12851.8 12886.7 13037.5 13310.7 11448.3 11527.3 11764.5 12080.7 12269.0 10660.9 11709.8 11812.4 12225.8 12229.0 10365.8 11725.2 11856.2 11914.8 12138.9 9822.5 9824.2 9939.4 9950.1 10582.2 8726.6 8728.3 9623.3 9644.0 9687.4 05:05:50 INFO - PID 2013 | -------- Summary: end -------- 05:05:50 INFO - PID 2013 | 05:05:51 INFO - TEST-INFO | 2013: exit 0 05:05:51 INFO - TEST-OK | dromaeo_css | took 1121829ms 05:05:51 INFO - TEST-START | kraken 05:05:51 INFO - operating with platform_type : mac_ 05:05:51 INFO - Initialising browser for kraken test... 05:05:51 INFO - TEST-INFO | started process 2017 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/tmp9TzRFU/profile http://localhost:49400/getInfo.html) 05:05:54 INFO - PID 2017 | __metrics Screen width/height:1600/1200 05:05:54 INFO - PID 2017 | colorDepth:24 05:05:54 INFO - PID 2017 | Browser inner width/height: 1024/689 05:05:54 INFO - PID 2017 | __metrics 05:05:54 INFO - PID 2017 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:05:54 INFO - PID 2017 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:05:55 INFO - TEST-INFO | 2017: exit 0 05:05:55 INFO - Browser initialized. 05:05:55 INFO - Running cycle 1/1 for kraken test... 05:05:55 INFO - TEST-INFO | started process 2019 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/T/tmp9TzRFU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:06:07 INFO - PID 2019 | RSS: Main: 233500672 05:06:07 INFO - PID 2019 | 05:06:10 INFO - PID 2019 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49400/tests/kraken/driver.html?testName=audio-beat-detection) 05:06:11 INFO - PID 2019 | RSS: Main: 284577792 05:06:11 INFO - PID 2019 | 05:06:13 INFO - PID 2019 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49400/tests/kraken/driver.html?testName=audio-dft) 05:06:14 INFO - PID 2019 | RSS: Main: 285409280 05:06:14 INFO - PID 2019 | 05:06:17 INFO - PID 2019 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49400/tests/kraken/driver.html?testName=audio-fft) 05:06:18 INFO - PID 2019 | RSS: Main: 378781696 05:06:18 INFO - PID 2019 | 05:06:20 INFO - PID 2019 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49400/tests/kraken/driver.html?testName=audio-oscillator) 05:06:21 INFO - PID 2019 | RSS: Main: 279867392 05:06:21 INFO - PID 2019 | 05:06:23 INFO - PID 2019 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49400/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:06:23 INFO - PID 2019 | RSS: Main: 284221440 05:06:23 INFO - PID 2019 | 05:06:26 INFO - PID 2019 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49400/tests/kraken/driver.html?testName=imaging-darkroom) 05:06:27 INFO - PID 2019 | RSS: Main: 326529024 05:06:27 INFO - PID 2019 | 05:06:30 INFO - PID 2019 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49400/tests/kraken/driver.html?testName=imaging-desaturate) 05:06:31 INFO - PID 2019 | RSS: Main: 341450752 05:06:31 INFO - PID 2019 | 05:06:33 INFO - PID 2019 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49400/tests/kraken/driver.html?testName=json-parse-financial) 05:06:34 INFO - PID 2019 | RSS: Main: 350957568 05:06:34 INFO - PID 2019 | 05:06:36 INFO - PID 2019 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49400/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:06:36 INFO - PID 2019 | RSS: Main: 301473792 05:06:36 INFO - PID 2019 | 05:06:38 INFO - PID 2019 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49400/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:06:39 INFO - PID 2019 | RSS: Main: 306319360 05:06:39 INFO - PID 2019 | 05:06:41 INFO - PID 2019 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49400/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:06:42 INFO - PID 2019 | RSS: Main: 307875840 05:06:42 INFO - PID 2019 | 05:06:44 INFO - PID 2019 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49400/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:06:45 INFO - PID 2019 | RSS: Main: 325419008 05:06:45 INFO - PID 2019 | 05:06:48 INFO - PID 2019 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49400/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:06:49 INFO - PID 2019 | RSS: Main: 318910464 05:06:49 INFO - PID 2019 | 05:06:51 INFO - PID 2019 | Cycle 1(1): loaded http://localhost:49400/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49400/tests/kraken/driver.html?testName=ai-astar) 05:06:51 INFO - PID 2019 | RSS: Main: 380346368 05:06:51 INFO - PID 2019 | 05:06:51 INFO - PID 2019 | __start_tp_report 05:06:51 INFO - PID 2019 | _x_x_mozilla_page_load 05:06:51 INFO - PID 2019 | _x_x_mozilla_page_load_details 05:06:51 INFO - PID 2019 | |i|pagename|runs| 05:06:51 INFO - PID 2019 | |0;ai-astar;115;114;116;115;116;116;115;116;116;121 05:06:51 INFO - PID 2019 | |1;audio-beat-detection;147;113;114;156;115;110;112;113;136;113 05:06:51 INFO - PID 2019 | |2;audio-dft;184;184;185;188;185;185;235;255;187;185 05:06:51 INFO - PID 2019 | |3;audio-fft;80;59;61;91;61;61;62;59;63;59 05:06:51 INFO - PID 2019 | |4;audio-oscillator;88;71;88;69;70;70;83;70;71;82 05:06:51 INFO - PID 2019 | |5;imaging-gaussian-blur;90;90;90;89;91;90;78;122;135;90 05:06:51 INFO - PID 2019 | |6;imaging-darkroom;96;95;96;95;96;96;97;96;95;96 05:06:51 INFO - PID 2019 | |7;imaging-desaturate;87;84;95;83;86;87;86;85;85;84 05:06:51 INFO - PID 2019 | |8;json-parse-financial;63;60;60;61;62;62;62;62;61;60 05:06:51 INFO - PID 2019 | |9;json-stringify-tinderbox;53;56;61;64;64;60;57;58;58;58 05:06:51 INFO - PID 2019 | |10;stanford-crypto-aes;84;78;81;80;83;81;83;83;83;80 05:06:51 INFO - PID 2019 | |11;stanford-crypto-ccm;139;144;132;136;133;135;164;144;190;157 05:06:51 INFO - PID 2019 | |12;stanford-crypto-pbkdf2;197;231;185;184;182;182;180;184;216;182 05:06:51 INFO - PID 2019 | |13;stanford-crypto-sha256-iterative;68;68;68;69;68;67;70;69;69;69 05:06:51 INFO - PID 2019 | __end_tp_report 05:06:51 INFO - PID 2019 | __start_cc_report 05:06:51 INFO - PID 2019 | _x_x_mozilla_cycle_collect,2186 05:06:51 INFO - PID 2019 | __end_cc_report 05:06:51 INFO - PID 2019 | __startTimestamp1489925211597__endTimestamp 05:06:51 INFO - PID 2019 | 05:06:51 INFO - PID 2019 | ------- Summary: start ------- 05:06:51 INFO - PID 2019 | Number of tests: 14 05:06:51 INFO - PID 2019 | 05:06:51 INFO - PID 2019 | [#0] ai-astar Cycles:10 Average:116.00 Median:116.00 stddev:1.89 (1.6%) stddev-sans-first:1.96 05:06:51 INFO - PID 2019 | Values: 115.0 114.0 116.0 115.0 116.0 116.0 115.0 116.0 116.0 121.0 05:06:51 INFO - PID 2019 | 05:06:51 INFO - PID 2019 | [#1] audio-beat-detection Cycles:10 Average:122.90 Median:114.50 stddev:16.89 (14.8%) stddev-sans-first:15.51 05:06:51 INFO - PID 2019 | Values: 147.0 113.0 114.0 156.0 115.0 110.0 112.0 113.0 136.0 113.0 05:06:51 INFO - PID 2019 | 05:06:51 INFO - PID 2019 | [#2] audio-dft Cycles:10 Average:197.30 Median:186.00 stddev:25.61 (13.8%) stddev-sans-first:26.71 05:06:51 INFO - PID 2019 | Values: 184.0 184.0 185.0 188.0 185.0 185.0 235.0 255.0 187.0 185.0 05:06:51 INFO - PID 2019 | 05:06:51 INFO - PID 2019 | [#3] audio-fft Cycles:10 Average:65.60 Median:61.50 stddev:10.89 (17.7%) stddev-sans-first:10.22 05:06:51 INFO - PID 2019 | Values: 80.0 59.0 61.0 91.0 61.0 61.0 62.0 59.0 63.0 59.0 05:06:51 INFO - PID 2019 | 05:06:51 INFO - PID 2019 | [#4] audio-oscillator Cycles:10 Average:76.20 Median:76.50 stddev:8.02 (10.5%) stddev-sans-first:7.29 05:06:51 INFO - PID 2019 | Values: 88.0 71.0 88.0 69.0 70.0 70.0 83.0 70.0 71.0 82.0 05:06:51 INFO - PID 2019 | 05:06:51 INFO - PID 2019 | [#5] imaging-gaussian-blur Cycles:10 Average:96.50 Median:90.00 stddev:17.55 (19.5%) stddev-sans-first:18.46 05:06:51 INFO - PID 2019 | Values: 90.0 90.0 90.0 89.0 91.0 90.0 78.0 122.0 135.0 90.0 05:06:51 INFO - PID 2019 | 05:06:51 INFO - PID 2019 | [#6] imaging-darkroom Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 05:06:51 INFO - PID 2019 | Values: 96.0 95.0 96.0 95.0 96.0 96.0 97.0 96.0 95.0 96.0 05:06:51 INFO - PID 2019 | 05:06:51 INFO - PID 2019 | [#7] imaging-desaturate Cycles:10 Average:86.20 Median:86.00 stddev:3.36 (3.9%) stddev-sans-first:3.55 05:06:51 INFO - PID 2019 | Values: 87.0 84.0 95.0 83.0 86.0 87.0 86.0 85.0 85.0 84.0 05:06:51 INFO - PID 2019 | 05:06:51 INFO - PID 2019 | [#8] json-parse-financial Cycles:10 Average:61.30 Median:62.00 stddev:1.06 (1.7%) stddev-sans-first:0.93 05:06:51 INFO - PID 2019 | Values: 63.0 60.0 60.0 61.0 62.0 62.0 62.0 62.0 61.0 60.0 05:06:51 INFO - PID 2019 | 05:06:51 INFO - PID 2019 | [#9] json-stringify-tinderbox Cycles:10 Average:58.90 Median:59.00 stddev:3.45 (5.8%) stddev-sans-first:2.92 05:06:51 INFO - PID 2019 | Values: 53.0 56.0 61.0 64.0 64.0 60.0 57.0 58.0 58.0 58.0 05:06:51 INFO - PID 2019 | 05:06:51 INFO - PID 2019 | [#10] stanford-crypto-aes Cycles:10 Average:81.60 Median:83.00 stddev:1.90 (2.3%) stddev-sans-first:1.80 05:06:51 INFO - PID 2019 | Values: 84.0 78.0 81.0 80.0 83.0 81.0 83.0 83.0 83.0 80.0 05:06:51 INFO - PID 2019 | 05:06:51 INFO - PID 2019 | [#11] stanford-crypto-ccm Cycles:10 Average:147.40 Median:144.00 stddev:18.27 (12.7%) stddev-sans-first:19.12 05:06:51 INFO - PID 2019 | Values: 139.0 144.0 132.0 136.0 133.0 135.0 164.0 144.0 190.0 157.0 05:06:51 INFO - PID 2019 | 05:06:51 INFO - PID 2019 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.30 Median:184.50 stddev:17.46 (9.5%) stddev-sans-first:18.43 05:06:51 INFO - PID 2019 | Values: 197.0 231.0 185.0 184.0 182.0 182.0 180.0 184.0 216.0 182.0 05:06:51 INFO - PID 2019 | 05:06:51 INFO - PID 2019 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:69.00 stddev:0.85 (1.2%) stddev-sans-first:0.88 05:06:51 INFO - PID 2019 | Values: 68.0 68.0 68.0 69.0 68.0 67.0 70.0 69.0 69.0 69.0 05:06:51 INFO - PID 2019 | -------- Summary: end -------- 05:06:51 INFO - PID 2019 | 05:06:52 INFO - TEST-INFO | 2019: exit 0 05:06:52 INFO - TEST-OK | kraken | took 60946ms 05:06:52 INFO - SUITE-END | took 1182s 05:06:52 INFO - Completed test suite (00:19:43) 05:06:52 INFO - javascript benchmark 05:06:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7752.62385638536, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3368.090615533806, 3373.2916087621597, 3376.4597664373705, 3412.3175364927015, 3439.2777516721485, 5676.772929082832, 5825.796097136601, 5878.676672783006, 5917.644941303525, 5927.110933359998, 5836.212111364965, 5875.353711091787, 5950.970245148779, 5952.970235148829, 5966.612170208933, 5181.6113791465605, 5243.895122097556, 5261.500157485033, 5263.86840328991, 5268.0, 5342.198670199482, 5373.731313434332, 5378.0, 5382.6232163748555, 5418.783248670068, 5190.792368305263, 5221.791128354861, 5241.764120614582, 5247.947520524784, 5301.0723123453245, 5254.921176182322, 5258.3689957205, 5262.947370526284, 5264.578833693296, 5269.6311258211945, 5140.177571588528, 5206.895862082737, 5222.895542089175, 5232.73836308183, 5233.136532472137, 5753.165790960329, 5756.0, 5759.107338362519, 5763.654180749127, 5776.306843178845, 6295.181626388586, 6362.0456931460185, 6364.204474440695, 6366.777162799326, 6370.5222108341595, 5800.187973683687, 6311.7159727811895, 6389.4568961638315, 6394.1687580614225, 6414.679266036679, 5948.821535353946, 5956.52347812174, 5972.343042265347, 6006.939930600682, 6023.457888789986, 6318.684065796691, 6374.649394283266, 6387.648679322589, 6389.808305750835, 6398.2641996170105, 6142.600730952563, 6176.258848938156, 6186.226721659793, 6320.652364119926, 6356.491480681534, 6288.937110628835, 6289.779857704958, 6291.993281075006, 6292.842678933063, 6295.276043254993, 6217.906731398942, 6225.066240063936, 6228.003519436868, 6243.282022567372, 6243.43809057187, 6332.303446620868, 6354.17395738551, 6389.936100639027, 6415.967920160369, 6423.5503514754455, 4939.185034469272, 5041.521055499722, 5063.518965698253, 5067.898642027212, 5084.0340335336905, 4460.197164510354, 4517.457905051415, 4535.274356103007, 4553.681242313072, 4632.536746325341, 4774.809007639725, 5787.045137552341, 5791.739371728282, 5814.3604203538425, 5882.117682347596, 5770.567207459458, 5962.522998160137, 6339.873202535923, 6353.237611486651, 6364.522660800459, 5883.882322353615, 5888.087346461305, 5904.852378690567, 5916.7337469813965, 5950.821475355746, 5456.372517160577, 5605.747741351649, 5606.074997625427, 5610.607257491936, 5614.85962850932, 6316.526260530387, 6336.651484168415, 6375.904361434489, 6395.936040639534, 6435.2599451063725, 6328.525360598066, 6340.904886426615, 6367.522435817425, 6370.362963703593, 6382.680865956777, 6395.328490508616, 6413.230412350548, 6433.742650293936, 6449.419552240321, 6453.225612926479, 6202.2867370253, 6241.095041218907, 6319.715612797527, 6380.649064301322, 6382.266039405527, 6138.263408391022, 6340.1123842661145, 6348.904766428415, 6351.110844481868, 6376.362363763772, 6203.751849925952, 6249.0, 6255.624662520263, 6264.780732674334, 6270.184875966278, 6071.666058366833, 6102.913431175913, 6205.751769929152, 6263.843403914939, 6277.027060805583, 5670.574706897081, 6005.249343832021, 6119.641076769691, 6120.466805134251, 6122.154209354628, 5682.488576028178, 6118.418750218722, 6121.910283036266, 6156.445919867234, 6286.874262514815, 5884.470397664059, 5949.19685842423, 5966.49284810796, 5970.522358211246, 5974.641521508724], "value": 5789.369284252074, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.1914740606085, 772.8106613879601, 835.2482765511043, 842.8988521377436, 845.9407841451101, 36494.27011459769, 36913.077173070655, 37128.443073353854, 37130.25739485209, 37176.814115929454, 38289.425658615146, 38670.0, 38678.61321386785, 38717.8064109679, 38757.0, 6244.56288059836, 7364.5949472778975, 8995.595198216097, 9210.171084602385, 9229.492377919212, 10634.734131646694, 10878.0, 10911.963363480456, 10918.836217456745, 10921.781564368708, 10981.231313808037, 11010.834837477405, 11031.503582338813, 11048.447577621127, 11059.281146725478, 7165.283471652845, 7338.486305958585, 10904.073153781937, 11002.834957475645, 11025.007749302562, 10411.635592754294, 10621.84067238992, 10645.57417703291, 10661.253712240146, 10669.253152279343, 36573.451398229045, 36974.0, 37001.444978325075, 37003.0, 37072.629273707455, 3739.981300093509, 3777.471154038437, 3808.6572208501093, 3830.578636349999, 3932.351162058257, 26913.730862691515, 27086.1874143776, 28364.0, 28392.716072839215, 28547.714522854712, 3127.358891427252, 3141.089084165589, 3174.2857856982223, 3245.0589329094532, 3254.739620830328, 275029.0, 275383.3309169431, 275391.0, 275498.0, 275520.0, 38237.0, 38839.0, 39008.60991390106, 50651.49348506542, 50796.0, 51741.0, 52535.0, 52579.0, 52584.0, 52613.47386526162, 14022.088564243291, 14169.716605667829, 14180.85819141816, 14181.574552763434, 14185.219812910183, 9470.52647367629, 11357.261777984404, 11420.88579114215, 11425.200235983568, 11451.942740286411, 13592.0, 13689.110207836458, 13705.0, 13934.790978135336, 13993.930030349784, 5036.212144688656, 5559.138333558305, 5871.4715675589405, 5980.252468441445, 6002.849928751816, 810.2626609785185, 886.0829041941688, 925.472480686016, 960.283702072982, 997.1723469520281, 19666.410007699793, 26131.467133216694, 28450.85774571114, 28855.134345969655, 29093.981710640026, 886.6497733395402, 958.1185309515263, 1014.8021135878433, 1024.2011231239644, 1042.6246551241547, 13243.0, 13807.792883106762, 14331.498397556035, 14834.332455039548, 15198.544043678707, 359245.0, 366364.0, 366596.0, 367319.0, 451316.0, 54368.0, 54871.72564137234, 54984.0, 55058.44941550613, 55106.44893551093, 1264.525802823941, 1309.9803502947464, 1407.971840563183, 1408.4507042253733, 1439.5609339151658, 23760.762392375855, 29333.12000639984, 29347.85326073356, 29413.85293073521, 30187.849060754557, 46121.53878461307, 46389.129141441015, 46391.304130438744, 46434.0, 46507.53492465032, 473490.0, 473814.0, 475177.0, 475620.0, 476270.6186469184, 32770.016934254636, 33469.66530334666, 33617.66382336243, 33774.49338259977, 33916.0, 42447.0, 42940.0, 43162.2521522626, 43218.0, 43308.0, 33588.496172556945, 35037.299254014266, 35344.46983295201, 35651.28697425985, 36121.0969725759, 34090.488642670876, 34397.484037738956, 34519.0, 35432.64567354293, 35672.92981210568, 34868.128296792784, 35090.824545877105, 35538.46692299669, 35666.64333356737, 35687.82156089203, 25388.365290867878, 27608.033718819745, 30506.389872202875, 30697.53953690741, 30785.511998807393, 20473.48816279605, 21287.254946076813, 21420.92895355195, 23027.76972230256, 23095.307140785804], "value": 18265.349537641905, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.67178867230825, 373.156665934987, 384.147193231027, 415.7380850064459, 422.93867389228564, 12012.519499220032, 15927.0, 18835.058247087625, 19925.0, 22546.21088261911, 21981.526281064434, 22639.207627733034, 22880.0, 23026.309210723663, 23066.423339416524, 5306.726385667432, 6564.409203171714, 6932.0641713368805, 6936.0636314097455, 6948.756793512228, 6761.256261811197, 6806.047153398528, 6855.828604284883, 6860.5197636165485, 6873.587584744906, 6746.359095885883, 6747.561408508432, 6837.760678376258, 6839.760608378708, 6887.758928437505, 6506.3818937200895, 6537.836554086139, 6783.694733736945, 6801.319868013184, 6806.6256355900405, 6493.350664933469, 6577.177852768404, 6586.077949087132, 6586.682663467278, 6587.93412065878, 26524.0, 27129.186124416297, 27156.864215678794, 27255.727442725518, 27447.86276068627, 3357.513160591725, 3363.1928337198897, 3363.2432702641954, 3371.190914180579, 3391.491276308549, 16198.676026479523, 16824.663506729798, 16854.0, 16946.576335591584, 17812.821871781245, 1990.4227773945538, 2065.6901464780253, 2084.3017589107517, 2084.5101401170714, 2087.77034526202, 133213.3339333297, 155735.22132389378, 156567.99999999886, 158841.70424661835, 161525.0, 14305.270696215084, 21046.473838153863, 21272.574548509252, 21387.0, 21610.0, 32773.0, 33875.491867622004, 33960.0, 34038.829805850815, 34199.65800342015, 8378.958105209556, 8392.916070839336, 8726.756791862865, 8841.336899732418, 10393.792124157626, 8307.667693292322, 8479.236868681724, 8510.36172287081, 8587.441816281922, 8612.78468038304, 9305.091861418221, 9581.712548623553, 9944.552495137592, 10420.32267902584, 10422.322549034288, 3482.6169121396624, 4048.595140485928, 4133.359329303963, 4168.520620128724, 4204.51648060477, 840.815020695446, 990.4404011733347, 995.0994350113159, 1009.2089072361358, 1030.90206430389, 15925.840741592436, 19560.315388961317, 20383.286584969454, 22972.4256893579, 23235.002812050603, 979.8187335342984, 988.2902578539167, 997.850322451638, 1014.9898501014895, 1027.0961553832578, 10596.0, 11187.608433704943, 11736.061115110771, 11987.52049918011, 12159.331236781885, 670017.0, 672701.0, 680683.3692483751, 700737.0, 702483.1647727492, 143811.0, 144958.0, 151294.0, 152848.0, 156948.21525892298, 1205.3551350027692, 1244.7199380139577, 1305.654001689553, 1305.6670549010132, 1306.5165888621082, 16247.59381015484, 19471.92904390272, 20028.799712003278, 20193.59612807765, 21320.04059817281, 60769.392306078145, 61349.07976380447, 61906.0, 61954.0, 63813.680931595045, 66123.0, 90612.0, 90698.09301906897, 91330.54334728284, 91975.44442000237, 11413.657590272305, 11567.942160289145, 12151.149419540547, 12410.193337433038, 12564.87435125637, 13611.795823062464, 13704.451821927012, 13716.108452950526, 14163.221022843842, 14175.149491030572, 11193.27243729155, 11457.255278406878, 11669.066474682175, 11877.22798018126, 12043.75912481763, 11628.94185529067, 11790.410479476164, 12005.339706315886, 12015.45930433115, 12066.75866482683, 10277.331973421704, 10887.618933337295, 11824.23142495735, 11858.644240672793, 12171.878281217074, 11849.466773995018, 12225.229454004235, 12422.875771242172, 12475.875241247835, 12590.685232869251, 11628.360440175871, 11712.063034957013, 12038.217515861792, 12089.51641934348, 12171.330576818005], "value": 11665.495208124325, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1508.479574546781, 1510.5166346769035, 1529.4876216467483, 1549.5041586692264, 1555.449063535801, 10046.54790534426, 10383.688489345332, 10387.636432724856, 10484.213683973703, 10504.212184086206, 10246.282760206788, 10554.78890422191, 10671.306365086284, 10681.893181068186, 10706.839397409045, 2340.6137987232087, 2383.07060246504, 2383.7735415135535, 2384.2966324934187, 2393.365758074112, 2329.4292898239955, 2342.61346877765, 2346.753590872959, 2362.61016932206, 2370.288913326004, 1888.4523488188408, 2227.042371780134, 2234.352037909013, 2235.441139715071, 2239.988800055997, 2285.337252196861, 2299.4711216420233, 2309.8614083155067, 2312.907483700667, 2317.188983855637, 2272.54549090181, 2351.9412014699597, 2360.929172124839, 2362.255889394852, 2365.727941286757, 9207.723768286962, 10820.837687434694, 10916.727081822939, 10940.0, 10941.179411544166, 5617.775288988436, 5842.649441033552, 5844.094165404369, 5848.707564621752, 5862.0, 3335.816530090844, 3430.2967891582416, 3638.508801311842, 3642.763220390666, 3657.6708096271464, 1116.4808364110727, 1132.326265871805, 1133.1274918312854, 1201.4173126033866, 1240.138104017708, 13447.394867230998, 13899.305034748222, 13900.0, 14944.178070206135, 14978.925105374512, 4557.931631025537, 4725.8025018366625, 4738.07607516531, 4741.881452963635, 4759.857204283877, 11355.261907975955, 11369.488373023234, 11369.602063927723, 11369.829452558219, 11393.0, 4459.2865141577195, 4845.3216549683075, 5024.497550244946, 5650.4349565043985, 5701.600887937888, 4622.791974361162, 5015.197568389113, 5200.401953775364, 5203.713795741271, 5207.453217412193, 3766.6798322142927, 3766.9058273543383, 3832.405977073558, 3839.616038396138, 3840.711946604016, 1015.6343716262139, 1039.2673165418362, 1047.910927571165, 1055.2296823318866, 1055.8099542082498, 774.7520793346188, 779.1701837542998, 798.9201079891965, 806.2058872011064, 809.8542262392826, 5333.893322133536, 5820.447057529528, 6292.905606415908, 6472.190976127984, 6593.5384523083885, 966.2510855351836, 967.5549247346158, 979.2900147393083, 988.328780730072, 1035.321864178949, 3738.420544815558, 4046.7976601169826, 4053.2298863215897, 4120.361343991686, 4157.168566286755, 12854.164479308814, 13128.671783205305, 13254.53609123676, 13271.668208294677, 13343.199408035549, 11756.882431175749, 13159.144655597356, 13161.73676526464, 13232.73534529304, 13289.867101329057, 9520.0, 9809.656662016796, 9828.0, 9845.360051596623, 9852.556634951445, 5701.4298570142655, 6447.161868957193, 6470.708818103102, 6676.198856137295, 6688.197416310074, 11767.529298828293, 12061.276323420623, 12184.268943863397, 12206.511739530675, 12229.082818788449, 10908.61819836302, 11024.779504409706, 11433.0, 11438.313701177956, 12065.939670301592, 8897.510636915033, 8960.596773145095, 9056.864147037932, 9059.864102038606, 9139.994600593796, 9868.654597089355, 10155.39067655943, 10171.542280597243, 10187.643432479828, 10201.591936322464, 8518.74443766688, 8718.694845680371, 8809.647614095364, 8863.778405539655, 8865.556722163738, 8675.739727808175, 8686.78283042929, 8784.0, 8790.0, 8945.23965462943, 7413.703451862081, 7627.5042122261875, 7658.885116723365, 7668.271514206142, 7693.6537855795505, 5472.50747432733, 5624.915626265528, 5698.145278208136, 5747.7413516391025, 5781.4218578141845, 5794.797976768801, 5797.4782269595935, 5805.564582656233, 5813.186153938367, 5830.5627077968475], "value": 4958.477756093885, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.67671490152395, 368.3222869919348, 368.7713617557115, 377.3867465368776, 381.24132975378996, 5866.560007999396, 5921.904447677182, 5939.762409503625, 5953.40465953405, 6020.428059334362, 4982.377202849644, 5525.475079867406, 5668.263125793641, 5748.252727145476, 5777.266819954018, 4416.492103408117, 4472.5974662280305, 4484.59638632523, 4506.707064040843, 4516.503184649686, 3891.571927088018, 4498.0, 4524.6380289576755, 4528.660350473711, 4543.909121817562, 3315.834208289576, 4380.948572342647, 4408.0, 4481.655503348982, 4503.144402563519, 4100.466939297902, 4252.298370768804, 4344.978275108636, 4484.372187893698, 4514.841980530666, 3290.851911663981, 3887.319719049155, 3911.706622003361, 3932.7050471214775, 3935.9372969298415, 5684.9198652256355, 5700.37295897451, 5715.571332150105, 5729.9713501432225, 5758.222639943638, 5114.616403769732, 5241.475852414766, 5256.211568264791, 5293.6559123657225, 5294.11470748443, 6207.616848817405, 6226.501879849601, 6240.188775459206, 6251.906221406683, 6258.655773932432, 4146.958530414688, 4374.256376416017, 4386.078923426085, 4388.912221755579, 4414.690971631987, 6245.875742366372, 6399.776007839703, 6495.8700825983215, 6497.415232629086, 6511.28375878653, 5413.972930135403, 5500.862478438071, 5554.472325129106, 5556.444355564492, 5595.468430499096, 5493.7802487900035, 5628.748500599793, 5701.660109874177, 5715.714214289352, 5745.299709136699, 5193.428722840485, 5561.388247292794, 5869.88472190291, 5896.498797602166, 5918.526517878559, 5815.73829177688, 6271.404216599416, 6287.779927702509, 6323.715432805443, 6323.7786677466065, 1671.1811212505784, 1756.6750151222066, 1772.822717728217, 1962.7546556680416, 1997.1811557261453, 1099.9275706186404, 1252.8286052540902, 1269.3526301586257, 1272.7136394311206, 1308.5943357558997, 5131.25596788471, 5232.953409318152, 5560.471755183251, 5655.462731040545, 5883.558733094949, 5677.347105082886, 6504.934950650433, 6519.5436319457185, 6523.64119973406, 6581.3418658133805, 5232.502912223333, 5575.916361254584, 5704.458076482728, 5824.330202026821, 5859.1797148399255, 2928.546075358323, 2976.062540299842, 3362.0922350965593, 3394.9151271218416, 3411.624721280657, 6513.902291465632, 6578.111954886125, 6719.932800672029, 6723.462123030145, 6727.865442691216, 6066.393360663898, 6319.115323854664, 6579.828656543329, 6583.210014798255, 6605.372489613479, 6398.0, 6403.839904002344, 6441.452476539546, 6639.767608133789, 6721.361470660378, 6529.118568993315, 6551.246606640298, 6566.671666416564, 6578.934210657832, 6578.934210657832, 4740.862193073706, 4950.059488697136, 5870.0, 6138.0, 6263.592866463665, 6674.998750187511, 6690.799276021728, 6717.0, 6718.8320291993095, 6756.223034351113, 4838.395200599925, 4990.251462280687, 5061.898762024813, 5114.309568208393, 5115.7953681852305, 6428.967855160694, 6430.967845160744, 6446.8388290293115, 6515.576487528296, 6530.379613936669, 6102.877942441215, 6248.781292654735, 6290.182276304051, 6302.369763023661, 6305.95951667988, 5215.947840521546, 5522.86192845166, 6176.702892392648, 6276.413991547435, 6284.780032698834, 6100.23747031621, 6102.328743838086, 6141.969290153521, 6149.538784591084, 6169.50643948474, 5873.794417195378, 6076.787312444043, 6251.0, 6313.873722525432, 6322.620642761449, 5597.076482380362, 6126.969365153146, 6147.231596050494, 6148.723307451086, 6150.077488376601], "value": 4763.934507830814, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [741.3772431157827, 748.2293237964894, 834.8914641096657, 839.7312859884837, 842.9452085614438, 15335.0, 16053.277602507886, 16089.034657920532, 16094.597635059115, 16109.677806443864, 14803.555893323191, 14884.181370024644, 14997.400103995828, 15032.849671503309, 15042.24788760563, 887.5118684723392, 907.5326207003366, 909.8589718593629, 910.8406028944942, 912.5938957164065, 764.3197554176787, 930.3208657679897, 937.1565590968115, 948.5778828421355, 952.6570434643522, 965.9951700241489, 979.7403688022644, 981.9787421082102, 982.1749730226608, 984.4093543873654, 959.3620242538739, 969.5782334684435, 970.0202795176851, 970.335320305593, 972.6595691507915, 726.920038795732, 728.5883475836135, 728.6975904999418, 729.2015243308568, 729.5258082246529, 15782.36870525189, 16014.19929003545, 16049.197540122947, 16734.0, 16834.49496515094, 14558.92720536401, 14640.194789286585, 14666.413343466249, 14666.56000319992, 14736.410543578244, 11652.592159274385, 11681.766364672743, 11774.529018839236, 11952.462139203757, 12198.329091899943, 3652.726045546595, 3739.1960728443514, 3773.811309434545, 3815.8092095395396, 3920.980395098035, 78475.21524784736, 100681.49659251657, 110153.89846101517, 111822.44088779585, 112701.30948035786, 10644.308119972331, 11468.31190128595, 11507.539698412138, 11690.298582085103, 11775.941120294343, 20889.0, 24940.875295623406, 24968.250952471455, 24974.125905593217, 25002.4999500009, 7528.698852045966, 7946.165652606509, 8117.95941020303, 8200.466969646954, 8203.179682031869, 4039.4344791728595, 4178.39413285072, 4186.3092589722355, 4269.167512335065, 4326.050303141863, 3427.057559171198, 3513.5608048993877, 3515.5605549306338, 3517.62812503124, 3521.102118959649, 573.7676241122352, 591.5267785771451, 605.6880706436189, 612.4671535763914, 626.0734113512028, 628.3371043549008, 660.8917553005991, 686.9969844027776, 697.2817997462622, 699.6816448515913, 8914.465132092095, 10061.849072263922, 10086.546105425274, 10156.441395723159, 10376.273660843668, 649.7362629685312, 658.83386406061, 691.1326285511751, 701.6737217194029, 718.6047673779505, 4584.426946631671, 5003.49965003504, 5034.84895453137, 5037.2874614696375, 5053.9494605054215, 101553.49223253837, 102556.0, 103188.48405757923, 103685.0, 106131.46934265434, 68880.93357199292, 68991.0, 80459.59770201112, 85783.0, 88110.0, 79499.20500794917, 81326.59336703278, 81464.0, 81746.77379839188, 82421.93945151419, 8140.918590814016, 9558.235341172829, 9648.614055437703, 9692.856608199032, 9698.408029157037, 50653.0, 51015.97968040544, 51625.4167895573, 52337.47662523326, 53138.937221254586, 61517.0, 63316.0, 63504.0, 63998.7200255983, 64537.0, 11732.77661795414, 11738.94130529342, 11839.881601183877, 12455.190412623151, 12463.5014599415, 12708.745825083632, 12851.807222891477, 12886.742265154831, 13037.543685970946, 13310.733785324433, 11448.313101213955, 11527.308361498337, 11764.529418823493, 12080.690952454594, 12269.0, 10660.880607536406, 11709.765804683688, 11812.401271720208, 12225.755484890075, 12229.0, 10365.844512332473, 11725.239775628497, 11856.17006809515, 11914.821277681016, 12138.92880146665, 9822.508874556386, 9824.239309928542, 9939.353941993748, 9950.10449059588, 10582.206334524788, 8726.628655581748, 8728.301735861243, 9623.326367154512, 9644.0, 9687.41875487473], "value": 7450.385918986886, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1466.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 116.0, 115.0, 116.0, 116.0, 115.0, 116.0, 116.0, 121.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 113.0, 114.0, 156.0, 115.0, 110.0, 112.0, 113.0, 136.0, 113.0], "value": 122.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 185.0, 188.0, 185.0, 185.0, 235.0, 255.0, 187.0, 185.0], "value": 197.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 61.0, 91.0, 61.0, 61.0, 62.0, 59.0, 63.0, 59.0], "value": 65.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 88.0, 69.0, 70.0, 70.0, 83.0, 70.0, 71.0, 82.0], "value": 76.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 90.0, 89.0, 91.0, 90.0, 78.0, 122.0, 135.0, 90.0], "value": 96.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 95.0, 96.0, 96.0, 97.0, 96.0, 95.0, 96.0], "value": 95.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 95.0, 83.0, 86.0, 87.0, 86.0, 85.0, 85.0, 84.0], "value": 86.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 60.0, 61.0, 62.0, 62.0, 62.0, 62.0, 61.0, 60.0], "value": 61.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 56.0, 61.0, 64.0, 64.0, 60.0, 57.0, 58.0, 58.0, 58.0], "value": 58.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 78.0, 81.0, 80.0, 83.0, 81.0, 83.0, 83.0, 83.0, 80.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 144.0, 132.0, 136.0, 133.0, 135.0, 164.0, 144.0, 190.0, 157.0], "value": 147.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 231.0, 185.0, 184.0, 182.0, 182.0, 180.0, 184.0, 216.0, 182.0], "value": 192.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 69.0, 68.0, 67.0, 70.0, 69.0, 69.0, 69.0], "value": 68.5, "unit": "score"}]}]} 05:06:52 INFO - Return code: 0 05:06:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:06:52 ERROR - # TBPL SUCCESS # 05:06:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:06:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:06:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:06:52 INFO - 2017-03-19 05:06:52.944 screenresolution[2023:23503] starting screenresolution argv=screenresolution get 05:06:52 INFO - 2017-03-19 05:06:52.947 screenresolution[2023:23503] Display 0: 1600x1200x32@60 05:06:52 INFO - 2017-03-19 05:06:52.953 screenresolution[2024:23506] starting screenresolution argv=screenresolution list 05:06:52 INFO - Available Modes on Display 0 05:06:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:06:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:06:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:06:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:06:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:06:53 INFO - Intel Iris: 05:06:53 INFO - Chipset Model: Intel Iris 05:06:53 INFO - Type: GPU 05:06:53 INFO - Bus: Built-In 05:06:53 INFO - VRAM (Dynamic, Max): 1536 MB 05:06:53 INFO - Vendor: Intel (0x8086) 05:06:53 INFO - Device ID: 0x0a2e 05:06:53 INFO - Revision ID: 0x0009 05:06:53 INFO - Displays: 05:06:53 INFO - Display: 05:06:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:06:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:06:53 INFO - Display Serial Number: 1600x1200 60 05:06:53 INFO - Main Display: Yes 05:06:53 INFO - Mirror: Off 05:06:53 INFO - Online: Yes 05:06:53 INFO - Rotation: Supported 05:06:53 INFO - Return code: 0 05:06:53 INFO - Running post-action listener: _resource_record_post_action 05:06:53 INFO - [mozharness: 2017-03-19 12:06:53.190713Z] Finished run-tests step (success) 05:06:53 INFO - Running post-run listener: _resource_record_post_run 05:06:53 INFO - Total resource usage - Wall time: 1204s; CPU: 53.0%; Read bytes: 2093056; Write bytes: 513329152; Read time: 19; Write time: 1720 05:06:53 INFO - TinderboxPrint: CPU usage
53.4% 05:06:53 INFO - TinderboxPrint: I/O read bytes / time
2,093,056 / 19 05:06:53 INFO - TinderboxPrint: I/O write bytes / time
513,329,152 / 1,720 05:06:53 INFO - TinderboxPrint: CPU idle
2,244.0 (46.6%) 05:06:53 INFO - TinderboxPrint: CPU system
960.1 (19.9%) 05:06:53 INFO - TinderboxPrint: CPU user
1,613.6 (33.5%) 05:06:53 INFO - TinderboxPrint: Swap in / out
212,611,072 / 0 05:06:53 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201955840; Write bytes: 203458048; Read time: 15685; Write time: 442 05:06:53 INFO - run-tests - Wall time: 1184s; CPU: 53.0%; Read bytes: 2093056; Write bytes: 299926016; Read time: 19; Write time: 1245 05:06:53 INFO - Running post-run listener: _upload_blobber_files 05:06:53 INFO - Blob upload gear active. 05:06:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:06:53 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 05:06:53 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'] 05:06:53 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 05:06:54 INFO - (blobuploader) - INFO - Open directory for files ... 05:06:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:06:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:06:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:06:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:06:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:06:54 INFO - (blobuploader) - INFO - Done attempting. 05:06:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:06:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:06:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:06:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:06:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:06:55 INFO - (blobuploader) - INFO - Done attempting. 05:06:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:06:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:06:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:06:56 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:06:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:06:56 INFO - (blobuploader) - INFO - Done attempting. 05:06:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:06:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:06:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:06:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:06:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:06:58 INFO - (blobuploader) - INFO - Done attempting. 05:06:58 INFO - (blobuploader) - INFO - Iteration through files over. 05:06:58 INFO - Return code: 0 05:06:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:06:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:06:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8230e2094acea99871ee67ce7e9791fdc0f44d7ae70148d295f25d605cd166e8fac0fb5e89d3fc2d2292baed36bb9189bcf0353ace27464b76cd13ce09fb0435", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/461e2817602ca55e3b9e3af47d8d0c05943945859f3bf48f0cc8ec84b146a61f8c100846664221634ad6df0f7e3917ccba89de0cb09fdf68796983a3bc41df20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8a9134a856383e4043b0f5da59849f58934bdbf5159a9cc257b05caf16b1590ec6580b7b2ea6ac44b85a4d77a2a4db7ae43814d55c67533291dd49ff879c91c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c1cfa9e59b2a05044cf9d7129a1956ea1077068d426bfd9f48e056a4ddf1fc5ad518939b9b0fc139016b3b1603f3864f5c5e8a6300791fbb9d2c7f2d14271d94"} 05:06:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:06:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:06:58 INFO - Contents: 05:06:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8230e2094acea99871ee67ce7e9791fdc0f44d7ae70148d295f25d605cd166e8fac0fb5e89d3fc2d2292baed36bb9189bcf0353ace27464b76cd13ce09fb0435", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/461e2817602ca55e3b9e3af47d8d0c05943945859f3bf48f0cc8ec84b146a61f8c100846664221634ad6df0f7e3917ccba89de0cb09fdf68796983a3bc41df20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8a9134a856383e4043b0f5da59849f58934bdbf5159a9cc257b05caf16b1590ec6580b7b2ea6ac44b85a4d77a2a4db7ae43814d55c67533291dd49ff879c91c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c1cfa9e59b2a05044cf9d7129a1956ea1077068d426bfd9f48e056a4ddf1fc5ad518939b9b0fc139016b3b1603f3864f5c5e8a6300791fbb9d2c7f2d14271d94"} 05:06:58 INFO - Running post-run listener: copy_logs_to_upload_dir 05:06:58 INFO - Copying logs to upload dir... 05:06:58 INFO - mkdir: /builds/slave/test/build/upload/logs 05:06:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1287.280813 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 27 secs) (at 2017-03-19 05:06:58.826688) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-19 05:06:58.830288) ========= 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.IRavaIZz3q/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.NmRXu2wQXf/Listeners TMPDIR=/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/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/8230e2094acea99871ee67ce7e9791fdc0f44d7ae70148d295f25d605cd166e8fac0fb5e89d3fc2d2292baed36bb9189bcf0353ace27464b76cd13ce09fb0435", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/461e2817602ca55e3b9e3af47d8d0c05943945859f3bf48f0cc8ec84b146a61f8c100846664221634ad6df0f7e3917ccba89de0cb09fdf68796983a3bc41df20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8a9134a856383e4043b0f5da59849f58934bdbf5159a9cc257b05caf16b1590ec6580b7b2ea6ac44b85a4d77a2a4db7ae43814d55c67533291dd49ff879c91c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c1cfa9e59b2a05044cf9d7129a1956ea1077068d426bfd9f48e056a4ddf1fc5ad518939b9b0fc139016b3b1603f3864f5c5e8a6300791fbb9d2c7f2d14271d94"} build_url:https://queue.taskcluster.net/v1/task/Q6TF7l8MS-igeqBcd2Z99Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010987 build_url: 'https://queue.taskcluster.net/v1/task/Q6TF7l8MS-igeqBcd2Z99Q/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8230e2094acea99871ee67ce7e9791fdc0f44d7ae70148d295f25d605cd166e8fac0fb5e89d3fc2d2292baed36bb9189bcf0353ace27464b76cd13ce09fb0435", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/461e2817602ca55e3b9e3af47d8d0c05943945859f3bf48f0cc8ec84b146a61f8c100846664221634ad6df0f7e3917ccba89de0cb09fdf68796983a3bc41df20", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e8a9134a856383e4043b0f5da59849f58934bdbf5159a9cc257b05caf16b1590ec6580b7b2ea6ac44b85a4d77a2a4db7ae43814d55c67533291dd49ff879c91c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c1cfa9e59b2a05044cf9d7129a1956ea1077068d426bfd9f48e056a4ddf1fc5ad518939b9b0fc139016b3b1603f3864f5c5e8a6300791fbb9d2c7f2d14271d94"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-19 05:06:58.864171) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-19 05:06:58.864455) ========= 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.IRavaIZz3q/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.NmRXu2wQXf/Listeners TMPDIR=/var/folders/x3/802dh5md7_73wtzq6q6p6b0m00000w/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.005276 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-19 05:06:58.912432) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-19 05:06:58.912732) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-19 05:06:58.913025) ========= ========= Total master_lag: 0.18 =========