builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0240 starttime: 1488907088.78 results: success (0) buildid: 20170307073232 builduid: d050742f9b8d4b09a953feba3afef250 revision: 575e82f15c295cd56746fe26847ba95eae197954 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-07 09:18:08.780138) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-07 09:18:08.780573) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-07 09:18:08.780908) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-07 09:18:08.807850) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 09:18:08.808229) ========= 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.nvbCUjpVfs/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.rWlV3GCbjn/Listeners TMPDIR=/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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-07 09:18:08-- 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% 153M=0s 2017-03-07 09:18:08 (153 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130384 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 09:18:08.964269) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 09:18:08.964798) ========= 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.nvbCUjpVfs/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.rWlV3GCbjn/Listeners TMPDIR=/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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.026129 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 09:18:09.015180) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-07 09:18:09.015572) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 575e82f15c295cd56746fe26847ba95eae197954 --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 575e82f15c295cd56746fe26847ba95eae197954 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nvbCUjpVfs/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.rWlV3GCbjn/Listeners TMPDIR=/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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-07 09:18:09,127 truncating revision to first 12 chars 2017-03-07 09:18:09,127 Setting DEBUG logging. 2017-03-07 09:18:09,127 attempt 1/10 2017-03-07 09:18:09,127 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/575e82f15c29?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-07 09:18:10,256 unpacking tar archive at: mozilla-central-575e82f15c29/testing/mozharness/ program finished with exit code 0 elapsedTime=1.399051 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-07 09:18:10.442845) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-07 09:18:10.443169) ========= script_repo_revision: 575e82f15c295cd56746fe26847ba95eae197954 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-07 09:18:10.443572) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-07 09:18:10.443857) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-07 09:18:10.464315) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-07 09:18:10.464833) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.nvbCUjpVfs/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.rWlV3GCbjn/Listeners TMPDIR=/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 09:18:10 INFO - MultiFileLogger online at 20170307 09:18:10 in /builds/slave/test 09:18:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:18:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:18:10 INFO - {'append_to_log': False, 09:18:10 INFO - 'base_work_dir': '/builds/slave/test', 09:18:10 INFO - 'blob_upload_branch': 'Firefox', 09:18:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:18:10 INFO - 'branch': 'Firefox', 09:18:10 INFO - 'buildbot_json_path': 'buildprops.json', 09:18:10 INFO - 'config_files': ('talos/mac_config.py',), 09:18:10 INFO - 'default_actions': ('clobber', 09:18:10 INFO - 'read-buildbot-config', 09:18:10 INFO - 'download-and-extract', 09:18:10 INFO - 'populate-webroot', 09:18:10 INFO - 'create-virtualenv', 09:18:10 INFO - 'install', 09:18:10 INFO - 'run-tests'), 09:18:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:18:10 INFO - 'download_minidump_stackwalk': True, 09:18:10 INFO - 'download_symbols': 'ondemand', 09:18:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:18:10 INFO - 'tooltool.py': '/tools/tooltool.py', 09:18:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:18:10 INFO - '/tools/misc-python/virtualenv.py')}, 09:18:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:18:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:18:10 INFO - 'gecko_profile': False, 09:18:10 INFO - 'gecko_profile_interval': 0, 09:18:10 INFO - 'installer_path': 'installer.exe', 09:18:10 INFO - 'log_level': 'info', 09:18:10 INFO - 'log_name': 'talos', 09:18:10 INFO - 'log_to_console': True, 09:18:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:18:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:18:10 INFO - 'opt_config_files': (), 09:18:10 INFO - 'pip_index': False, 09:18:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:18:10 INFO - 'cmd': ('bash', 09:18:10 INFO - '-c', 09:18:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:18:10 INFO - 'enabled': True, 09:18:10 INFO - 'halt_on_failure': False, 09:18:10 INFO - 'name': 'check_screen_resolution'},), 09:18:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:18:10 INFO - 'cmd': ('bash', 09:18:10 INFO - '-c', 09:18:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:18:10 INFO - 'enabled': True, 09:18:10 INFO - 'halt_on_failure': False, 09:18:10 INFO - 'name': 'check_screen_resolution'},), 09:18:10 INFO - 'run_cmd_checks_enabled': True, 09:18:10 INFO - 'suite': 'dromaeojs-e10s', 09:18:10 INFO - 'system_bits': '32', 09:18:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:18:10 INFO - 'title': 't-yosemite-r7-0240', 09:18:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:18:10 INFO - 'use_talos_json': True, 09:18:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:18:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:18:10 INFO - 'work_dir': 'build'} 09:18:10 INFO - [mozharness: 2017-03-07 17:18:10.676734Z] Running clobber step. 09:18:10 INFO - Running pre-action listener: _resource_record_pre_action 09:18:10 INFO - Running main action method: clobber 09:18:10 INFO - rmtree: /builds/slave/test/build 09:18:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:18:13 INFO - Running post-action listener: _resource_record_post_action 09:18:13 INFO - [mozharness: 2017-03-07 17:18:13.053998Z] Finished clobber step (success) 09:18:13 INFO - [mozharness: 2017-03-07 17:18:13.054127Z] Running read-buildbot-config step. 09:18:13 INFO - Running pre-action listener: _resource_record_pre_action 09:18:13 INFO - Running main action method: read_buildbot_config 09:18:13 INFO - Using buildbot properties: 09:18:13 INFO - { 09:18:13 INFO - "project": "", 09:18:13 INFO - "product": "firefox", 09:18:13 INFO - "script_repo_revision": "production", 09:18:13 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:18:13 INFO - "repository": "", 09:18:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:18:13 INFO - "buildid": "20170307073232", 09:18:13 INFO - "pgo_build": "False", 09:18:13 INFO - "basedir": "/builds/slave/test", 09:18:13 INFO - "buildnumber": 165, 09:18:13 INFO - "slavename": "t-yosemite-r7-0240", 09:18:13 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 09:18:13 INFO - "platform": "yosemite_r7", 09:18:13 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:18:13 INFO - "branch": "mozilla-central", 09:18:13 INFO - "repo_path": "mozilla-central", 09:18:13 INFO - "revision": "575e82f15c295cd56746fe26847ba95eae197954", 09:18:13 INFO - "stage_platform": "macosx64", 09:18:13 INFO - "builduid": "d050742f9b8d4b09a953feba3afef250", 09:18:13 INFO - "slavebuilddir": "test" 09:18:13 INFO - } 09:18:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/eYuBMkf_S6G_o_oLtNs0Sw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 09:18:13 INFO - Running post-action listener: _resource_record_post_action 09:18:13 INFO - [mozharness: 2017-03-07 17:18:13.057605Z] Finished read-buildbot-config step (success) 09:18:13 INFO - [mozharness: 2017-03-07 17:18:13.057751Z] Running download-and-extract step. 09:18:13 INFO - Running pre-action listener: _resource_record_pre_action 09:18:13 INFO - Running main action method: download_and_extract 09:18:13 INFO - mkdir: /builds/slave/test/build/tests 09:18: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')]} 09:18:13 INFO - trying https://queue.taskcluster.net/v1/task/eYuBMkf_S6G_o_oLtNs0Sw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:18:13 INFO - Downloading https://queue.taskcluster.net/v1/task/eYuBMkf_S6G_o_oLtNs0Sw/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 09:18:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eYuBMkf_S6G_o_oLtNs0Sw/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 09:18:13 INFO - Downloaded 1199 bytes. 09:18:13 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:18:13 INFO - Using the following test package requirements: 09:18:13 INFO - {u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:18:13 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:18:13 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:18:13 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:18:13 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:18:13 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:18:13 INFO - u'jsshell-mac.zip'], 09:18:13 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:18:13 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:18:13 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:18:13 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:18:13 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:18:13 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:18:13 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:18:13 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:18:13 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 09:18:13 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:18:13 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:18:13 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:18:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eYuBMkf_S6G_o_oLtNs0Sw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:18:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eYuBMkf_S6G_o_oLtNs0Sw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:18:13 INFO - Fetch https://queue.taskcluster.net/v1/task/eYuBMkf_S6G_o_oLtNs0Sw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:18:15 INFO - Expected file size: 51785029 09:18:15 INFO - Obtained file size: 51785029 09:18:31 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 09:18:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eYuBMkf_S6G_o_oLtNs0Sw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:18:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eYuBMkf_S6G_o_oLtNs0Sw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:18:31 INFO - Fetch https://queue.taskcluster.net/v1/task/eYuBMkf_S6G_o_oLtNs0Sw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:18:33 INFO - Expected file size: 51785029 09:18:33 INFO - Obtained file size: 51785029 09:18:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/eYuBMkf_S6G_o_oLtNs0Sw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:18:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eYuBMkf_S6G_o_oLtNs0Sw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:18:48 INFO - Fetch https://queue.taskcluster.net/v1/task/eYuBMkf_S6G_o_oLtNs0Sw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:18:49 INFO - Expected file size: 14404424 09:18:49 INFO - Obtained file size: 14404424 09:18:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:18:50 INFO - trying https://queue.taskcluster.net/v1/task/eYuBMkf_S6G_o_oLtNs0Sw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:18:50 INFO - Downloading https://queue.taskcluster.net/v1/task/eYuBMkf_S6G_o_oLtNs0Sw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:18:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eYuBMkf_S6G_o_oLtNs0Sw/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 09:18:52 INFO - Downloaded 74920747 bytes. 09:18:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eYuBMkf_S6G_o_oLtNs0Sw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:18:52 INFO - mkdir: /builds/slave/test/properties 09:18:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:18:52 INFO - Writing to file /builds/slave/test/properties/build_url 09:18:52 INFO - Contents: 09:18:52 INFO - build_url:https://queue.taskcluster.net/v1/task/eYuBMkf_S6G_o_oLtNs0Sw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:18:53 INFO - Running post-action listener: _resource_record_post_action 09:18:53 INFO - Running post-action listener: set_extra_try_arguments 09:18:53 INFO - [mozharness: 2017-03-07 17:18:53.326162Z] Finished download-and-extract step (success) 09:18:53 INFO - [mozharness: 2017-03-07 17:18:53.326303Z] Running populate-webroot step. 09:18:53 INFO - Running pre-action listener: _resource_record_pre_action 09:18:53 INFO - Running main action method: populate_webroot 09:18:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:18:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:18:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:18:53 INFO - '--tppagecycles', 09:18:53 INFO - '7'], 09:18:53 INFO - 'tests': ['tsvgm']}}, 09:18:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:18:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:18:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:18:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:18:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:18:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:18:53 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:18:53 INFO - 'talos_options': ['--disable-e10s'], 09:18:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:18:53 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:18:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:18:53 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:18:53 INFO - 'talos_options': ['--disable-e10s'], 09:18:53 INFO - 'tests': ['damp', 'tps']}, 09:18:53 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:18:53 INFO - 'tests': ['damp', 'tps']}, 09:18:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:18:53 INFO - 'tests': ['dromaeo_dom']}, 09:18:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:18:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:18:53 INFO - 'tests': ['basic_compositor_video']}, 09:18:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:18:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:18:53 INFO - 'tests': ['a11yr', 09:18:53 INFO - 'ts_paint', 09:18:53 INFO - 'tpaint', 09:18:53 INFO - 'sessionrestore', 09:18:53 INFO - 'sessionrestore_no_auto_restore', 09:18:53 INFO - 'tabpaint']}, 09:18:53 INFO - 'other-e10s': {'tests': ['a11yr', 09:18:53 INFO - 'ts_paint', 09:18:53 INFO - 'tpaint', 09:18:53 INFO - 'sessionrestore', 09:18:53 INFO - 'sessionrestore_no_auto_restore', 09:18:53 INFO - 'tabpaint']}, 09:18:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:18:53 INFO - 'ts_paint', 09:18:53 INFO - 'tpaint', 09:18:53 INFO - 'sessionrestore', 09:18:53 INFO - 'sessionrestore_no_auto_restore', 09:18:53 INFO - 'tabpaint']}, 09:18:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:18:53 INFO - 'ts_paint', 09:18:53 INFO - 'tpaint', 09:18:53 INFO - 'sessionrestore', 09:18:53 INFO - 'sessionrestore_no_auto_restore', 09:18:53 INFO - 'tabpaint']}, 09:18:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:18:53 INFO - 'tests': ['a11yr', 09:18:53 INFO - 'ts_paint', 09:18:53 INFO - 'tpaint', 09:18:53 INFO - 'sessionrestore', 09:18:53 INFO - 'sessionrestore_no_auto_restore', 09:18:53 INFO - 'tabpaint']}, 09:18:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:18:53 INFO - 'tests': ['a11yr', 09:18:53 INFO - 'ts_paint', 09:18:53 INFO - 'tpaint', 09:18:53 INFO - 'sessionrestore', 09:18:53 INFO - 'sessionrestore_no_auto_restore', 09:18:53 INFO - 'tabpaint']}, 09:18:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:18:53 INFO - 'tests': ['tsvgx', 09:18:53 INFO - 'tsvgr_opacity', 09:18:53 INFO - 'tart', 09:18:53 INFO - 'tscrollx', 09:18:53 INFO - 'cart', 09:18:53 INFO - 'tsvg_static']}, 09:18:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:18:53 INFO - 'tsvgr_opacity', 09:18:53 INFO - 'tart', 09:18:53 INFO - 'tscrollx', 09:18:53 INFO - 'cart', 09:18:53 INFO - 'tsvg_static']}, 09:18:53 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:18:53 INFO - 'talos_options': ['--disable-e10s'], 09:18:53 INFO - 'tests': ['tp5o']}, 09:18:53 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:18:53 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:18:53 INFO - 'talos_options': ['--disable-e10s', 09:18:53 INFO - '--xperf_path', 09:18:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:18:53 INFO - 'tests': ['tp5n']}, 09:18:53 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:18:53 INFO - 'talos_options': ['--xperf_path', 09:18:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:18:53 INFO - 'tests': ['tp5n']}}, 09:18:53 INFO - 'talos.zip': {'path': '', 09:18:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:18:53 INFO - Running post-action listener: _resource_record_post_action 09:18:53 INFO - [mozharness: 2017-03-07 17:18:53.344086Z] Finished populate-webroot step (success) 09:18:53 INFO - [mozharness: 2017-03-07 17:18:53.344203Z] Running create-virtualenv step. 09:18:53 INFO - Running pre-action listener: _resource_record_pre_action 09:18:53 INFO - Running main action method: create_virtualenv 09:18:53 INFO - Creating virtualenv /builds/slave/test/build/venv 09:18:53 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 09:18:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:18:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:18:53 INFO - Using real prefix '/tools/python27' 09:18:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:18:54 INFO - Installing distribute.............................................................................................................................................................................................done. 09:18:57 INFO - Installing pip.................done. 09:18:57 INFO - Return code: 0 09:18:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:18:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:18:57 INFO - Reading from file tmpfile_stdout 09:18:57 INFO - Output received: 09:18:57 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) 09:18:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:18:57 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')]} 09:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:57 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')]} 09:18:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:57 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 0x10e27cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e27f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa585b7a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e05ad30>, '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 0x10df81db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e04fbe8>, '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.nvbCUjpVfs/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.rWlV3GCbjn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 09:18:57 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 09:18:57 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 09:18:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvbCUjpVfs/Render', 09:18:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:57 INFO - 'HOME': '/Users/cltbld', 09:18:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:57 INFO - 'LOGNAME': 'cltbld', 09:18:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:57 INFO - 'MOZ_NO_REMOTE': '1', 09:18:57 INFO - 'NO_EM_RESTART': '1', 09:18:57 INFO - 'PAGER': '/bin/cat', 09:18:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:57 INFO - 'PWD': '/builds/slave/test', 09:18:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:57 INFO - 'SHELL': '/bin/bash', 09:18:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rWlV3GCbjn/Listeners', 09:18:57 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 09:18:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:57 INFO - 'USER': 'cltbld', 09:18:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:57 INFO - 'XPC_FLAGS': '0x0', 09:18:57 INFO - 'XPC_SERVICE_NAME': '0', 09:18:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:57 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 09:18:57 INFO - Cleaning up... 09:18:57 INFO - Return code: 0 09:18:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:18:57 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')]} 09:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:18:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:57 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')]} 09:18:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:18:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:18:57 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 0x10e27cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e27f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa585b7a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e05ad30>, '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 0x10df81db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e04fbe8>, '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.nvbCUjpVfs/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.rWlV3GCbjn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 09:18:57 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 09:18:57 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 09:18:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvbCUjpVfs/Render', 09:18:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:18:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:18:57 INFO - 'HOME': '/Users/cltbld', 09:18:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:18:57 INFO - 'LOGNAME': 'cltbld', 09:18:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:18:57 INFO - 'MOZ_NO_REMOTE': '1', 09:18:57 INFO - 'NO_EM_RESTART': '1', 09:18:57 INFO - 'PAGER': '/bin/cat', 09:18:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:18:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:18:57 INFO - 'PWD': '/builds/slave/test', 09:18:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:18:57 INFO - 'SHELL': '/bin/bash', 09:18:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rWlV3GCbjn/Listeners', 09:18:57 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 09:18:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:18:57 INFO - 'USER': 'cltbld', 09:18:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:18:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:18:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:18:57 INFO - 'XPC_FLAGS': '0x0', 09:18:57 INFO - 'XPC_SERVICE_NAME': '0', 09:18:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:18:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:18:57 INFO - Downloading/unpacking psutil>=3.1.1 09:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:18:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:18:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:18:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:19:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:19:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:19:01 INFO - Installing collected packages: psutil 09:19:01 INFO - Running setup.py install for psutil 09:19:01 INFO - building 'psutil._psutil_osx' extension 09:19:01 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 09:19:02 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 09:19:02 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 09:19:02 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 09:19:02 INFO - building 'psutil._psutil_posix' extension 09:19:02 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 09:19:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:19:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:19:02 INFO - ^ 09:19:02 INFO - 1 warning generated. 09:19:02 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 09:19:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:19:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:19:02 INFO - Successfully installed psutil 09:19:02 INFO - Cleaning up... 09:19:02 INFO - Return code: 0 09:19:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:19:02 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')]} 09:19:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:02 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')]} 09:19:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:02 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 0x10e27cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e27f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa585b7a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e05ad30>, '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 0x10df81db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e04fbe8>, '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.nvbCUjpVfs/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.rWlV3GCbjn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 09:19:02 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 09:19:02 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 09:19:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvbCUjpVfs/Render', 09:19:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:02 INFO - 'HOME': '/Users/cltbld', 09:19:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:02 INFO - 'LOGNAME': 'cltbld', 09:19:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:02 INFO - 'MOZ_NO_REMOTE': '1', 09:19:02 INFO - 'NO_EM_RESTART': '1', 09:19:02 INFO - 'PAGER': '/bin/cat', 09:19:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:02 INFO - 'PWD': '/builds/slave/test', 09:19:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:02 INFO - 'SHELL': '/bin/bash', 09:19:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rWlV3GCbjn/Listeners', 09:19:02 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 09:19:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:02 INFO - 'USER': 'cltbld', 09:19:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:02 INFO - 'XPC_FLAGS': '0x0', 09:19:02 INFO - 'XPC_SERVICE_NAME': '0', 09:19:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:19:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:19:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:19:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:19:05 INFO - Installing collected packages: mozsystemmonitor 09:19:05 INFO - Running setup.py install for mozsystemmonitor 09:19:05 INFO - Successfully installed mozsystemmonitor 09:19:05 INFO - Cleaning up... 09:19:05 INFO - Return code: 0 09:19:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:06 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 0x10e27cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e27f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa585b7a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e05ad30>, '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 0x10df81db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e04fbe8>, '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.nvbCUjpVfs/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.rWlV3GCbjn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 09:19:06 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 09:19:06 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 09:19:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvbCUjpVfs/Render', 09:19:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:06 INFO - 'HOME': '/Users/cltbld', 09:19:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:06 INFO - 'LOGNAME': 'cltbld', 09:19:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:06 INFO - 'MOZ_NO_REMOTE': '1', 09:19:06 INFO - 'NO_EM_RESTART': '1', 09:19:06 INFO - 'PAGER': '/bin/cat', 09:19:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:06 INFO - 'PWD': '/builds/slave/test', 09:19:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:06 INFO - 'SHELL': '/bin/bash', 09:19:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rWlV3GCbjn/Listeners', 09:19:06 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 09:19:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:06 INFO - 'USER': 'cltbld', 09:19:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:06 INFO - 'XPC_FLAGS': '0x0', 09:19:06 INFO - 'XPC_SERVICE_NAME': '0', 09:19:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:06 INFO - Downloading/unpacking jsonschema==2.5.1 09:19:06 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 09:19:06 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 09:19:06 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 09:19:06 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 09:19:06 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 09:19:06 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 09:19:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:19:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:19:09 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 09:19:09 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 09:19:09 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 09:19:09 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 09:19:09 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 09:19:09 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 09:19:10 INFO - Downloading functools32-3.2.3-2.tar.gz 09:19:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:19:10 INFO - warning: no files found matching '*.txt' 09:19:10 INFO - no previously-included directories found matching 'build' 09:19:10 INFO - no previously-included directories found matching 'dist' 09:19:10 INFO - no previously-included directories found matching '.git*' 09:19:10 INFO - Installing collected packages: jsonschema, functools32 09:19:10 INFO - Running setup.py install for functools32 09:19:10 INFO - warning: no files found matching '*.txt' 09:19:10 INFO - no previously-included directories found matching 'build' 09:19:10 INFO - no previously-included directories found matching 'dist' 09:19:10 INFO - no previously-included directories found matching '.git*' 09:19:10 INFO - Successfully installed jsonschema functools32 09:19:10 INFO - Cleaning up... 09:19:10 INFO - Return code: 0 09:19:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:19:10 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')]} 09:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:10 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')]} 09:19:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:10 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 0x10e27cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e27f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa585b7a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e05ad30>, '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 0x10df81db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e04fbe8>, '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.nvbCUjpVfs/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.rWlV3GCbjn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 09:19:10 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 09:19:10 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 09:19:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvbCUjpVfs/Render', 09:19:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:10 INFO - 'HOME': '/Users/cltbld', 09:19:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:10 INFO - 'LOGNAME': 'cltbld', 09:19:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:10 INFO - 'MOZ_NO_REMOTE': '1', 09:19:10 INFO - 'NO_EM_RESTART': '1', 09:19:10 INFO - 'PAGER': '/bin/cat', 09:19:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:10 INFO - 'PWD': '/builds/slave/test', 09:19:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:10 INFO - 'SHELL': '/bin/bash', 09:19:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rWlV3GCbjn/Listeners', 09:19:10 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 09:19:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:10 INFO - 'USER': 'cltbld', 09:19:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:10 INFO - 'XPC_FLAGS': '0x0', 09:19:10 INFO - 'XPC_SERVICE_NAME': '0', 09:19:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:19:10 INFO - Cleaning up... 09:19:10 INFO - Return code: 0 09:19:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:19:10 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')]} 09:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:10 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')]} 09:19:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:10 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 0x10e27cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e27f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa585b7a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e05ad30>, '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 0x10df81db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e04fbe8>, '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.nvbCUjpVfs/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.rWlV3GCbjn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 09:19:10 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 09:19:10 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 09:19:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvbCUjpVfs/Render', 09:19:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:10 INFO - 'HOME': '/Users/cltbld', 09:19:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:10 INFO - 'LOGNAME': 'cltbld', 09:19:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:10 INFO - 'MOZ_NO_REMOTE': '1', 09:19:10 INFO - 'NO_EM_RESTART': '1', 09:19:10 INFO - 'PAGER': '/bin/cat', 09:19:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:10 INFO - 'PWD': '/builds/slave/test', 09:19:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:10 INFO - 'SHELL': '/bin/bash', 09:19:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rWlV3GCbjn/Listeners', 09:19:10 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 09:19:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:10 INFO - 'USER': 'cltbld', 09:19:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:10 INFO - 'XPC_FLAGS': '0x0', 09:19:10 INFO - 'XPC_SERVICE_NAME': '0', 09:19:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:11 INFO - Downloading/unpacking blobuploader==1.2.4 09:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:19:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:19:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:19:13 INFO - Downloading blobuploader-1.2.4.tar.gz 09:19:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:19:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:19:13 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 09:19:13 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 09:19:13 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 09:19:13 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 09:19:13 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 09:19:13 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 09:19:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:19:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:19:14 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 09:19:14 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 09:19:14 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 09:19:14 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 09:19:14 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 09:19:14 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 09:19:14 INFO - Downloading docopt-0.6.1.tar.gz 09:19:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:19:15 INFO - Installing collected packages: blobuploader, requests, docopt 09:19:15 INFO - Running setup.py install for blobuploader 09:19:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:19:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:19:15 INFO - Running setup.py install for requests 09:19:15 INFO - Running setup.py install for docopt 09:19:15 INFO - Successfully installed blobuploader requests docopt 09:19:15 INFO - Cleaning up... 09:19:15 INFO - Return code: 0 09:19:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:19:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:19:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:15 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 0x10e27cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e27f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa585b7a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e05ad30>, '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 0x10df81db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e04fbe8>, '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.nvbCUjpVfs/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.rWlV3GCbjn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 09:19:15 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 09:19:15 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 09:19:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvbCUjpVfs/Render', 09:19:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:15 INFO - 'HOME': '/Users/cltbld', 09:19:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:15 INFO - 'LOGNAME': 'cltbld', 09:19:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:15 INFO - 'MOZ_NO_REMOTE': '1', 09:19:15 INFO - 'NO_EM_RESTART': '1', 09:19:15 INFO - 'PAGER': '/bin/cat', 09:19:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:15 INFO - 'PWD': '/builds/slave/test', 09:19:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:15 INFO - 'SHELL': '/bin/bash', 09:19:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rWlV3GCbjn/Listeners', 09:19:15 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 09:19:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:15 INFO - 'USER': 'cltbld', 09:19:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:15 INFO - 'XPC_FLAGS': '0x0', 09:19:15 INFO - 'XPC_SERVICE_NAME': '0', 09:19:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:19:16 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-_NQzQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:19:16 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-H4Nkvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:19:16 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-3VVUDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:19:16 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-qBHJoi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:19:16 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-ZUUdGf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:19:16 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-2DH35s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:19:16 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-MVGSOd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:19:16 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-z63yLS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:19:17 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-B4FApC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:19:17 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-tYRqFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:19:17 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-QfMdFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:19:17 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-0O6nrp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:19:17 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-GcnWVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:19:17 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-roe963-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:19:17 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-OYGlVE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:19:17 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-l7LxI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:19:17 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-e1O_P5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:19:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:19:17 INFO - Running setup.py install for manifestparser 09:19:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:19:18 INFO - Running setup.py install for mozcrash 09:19:18 INFO - Running setup.py install for mozdebug 09:19:18 INFO - Running setup.py install for mozdevice 09:19:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:19:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:19:18 INFO - Running setup.py install for mozfile 09:19:18 INFO - Running setup.py install for mozhttpd 09:19:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:19:18 INFO - Running setup.py install for mozinfo 09:19:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:19:19 INFO - Running setup.py install for mozInstall 09:19:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:19:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:19:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:19:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:19:19 INFO - Running setup.py install for mozleak 09:19:19 INFO - Running setup.py install for mozlog 09:19:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:19:19 INFO - Running setup.py install for moznetwork 09:19:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:19:19 INFO - Running setup.py install for mozprocess 09:19:19 INFO - Running setup.py install for mozprofile 09:19:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:19:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:19:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:19:19 INFO - Running setup.py install for mozrunner 09:19:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:19:20 INFO - Running setup.py install for mozscreenshot 09:19:20 INFO - Running setup.py install for moztest 09:19:20 INFO - Running setup.py install for mozversion 09:19:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:19:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:19:20 INFO - Cleaning up... 09:19:20 INFO - Return code: 0 09:19:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:19: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')]} 09:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19: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')]} 09:19:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:20 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 0x10e27cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e27f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa585b7a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e05ad30>, '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 0x10df81db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e04fbe8>, '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.nvbCUjpVfs/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.rWlV3GCbjn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 09:19:20 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 09:19:20 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 09:19:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvbCUjpVfs/Render', 09:19:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:20 INFO - 'HOME': '/Users/cltbld', 09:19:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:20 INFO - 'LOGNAME': 'cltbld', 09:19:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:20 INFO - 'MOZ_NO_REMOTE': '1', 09:19:20 INFO - 'NO_EM_RESTART': '1', 09:19:20 INFO - 'PAGER': '/bin/cat', 09:19:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:20 INFO - 'PWD': '/builds/slave/test', 09:19:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:20 INFO - 'SHELL': '/bin/bash', 09:19:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rWlV3GCbjn/Listeners', 09:19:20 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 09:19:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:20 INFO - 'USER': 'cltbld', 09:19:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:20 INFO - 'XPC_FLAGS': '0x0', 09:19:20 INFO - 'XPC_SERVICE_NAME': '0', 09:19:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:19:20 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-iNzxu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:19:20 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)) 09:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:19:20 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-Cj1SOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:19:21 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)) 09:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:19:21 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-JLBnf7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:19:21 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)) 09:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:19:21 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-dZog9W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:19:21 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)) 09:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:19:21 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-aYUJEg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:19:21 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)) 09:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:19:21 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-0xEMh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:19:21 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)) 09:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:19:21 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-HJ6suo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:19:21 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)) 09:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:19:21 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-TlCXf9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:19:21 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)) 09:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:19:21 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-RSGzJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:19:21 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)) 09:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:19:21 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-kKwNia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:19:21 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)) 09:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:19:21 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-kaNG_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:19:22 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)) 09:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:19:22 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-LvX00c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:19:22 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)) 09:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:19:22 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-6u2gLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:19:22 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)) 09:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:19:22 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-x7Lqgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:19:22 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)) 09:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:19:22 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-0s_Ijl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:19:22 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)) 09:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:19:22 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-U4Ks24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:19:22 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)) 09:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:19:22 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-PW1Ru3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:19:22 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)) 09:19:22 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)) 09:19:22 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)) 09:19:22 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)) 09:19:22 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)) 09:19:22 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)) 09:19:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:19:22 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 09:19:22 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 09:19:22 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 09:19:22 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 09:19:22 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 09:19:22 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 09:19:25 INFO - Downloading blessings-1.6.tar.gz 09:19:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:19:25 INFO - Installing collected packages: blessings 09:19:25 INFO - Running setup.py install for blessings 09:19:25 INFO - Successfully installed blessings 09:19:25 INFO - Cleaning up... 09:19:25 INFO - Return code: 0 09:19:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:19:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:19:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:19:25 INFO - Reading from file tmpfile_stdout 09:19:25 INFO - Current package versions: 09:19:25 INFO - blessings == 1.6 09:19:25 INFO - blobuploader == 1.2.4 09:19:25 INFO - docopt == 0.6.1 09:19:25 INFO - functools32 == 3.2.3-2 09:19:25 INFO - jsonschema == 2.5.1 09:19:25 INFO - manifestparser == 1.1 09:19:25 INFO - mozInstall == 1.12 09:19:25 INFO - mozcrash == 1.0 09:19:25 INFO - mozdebug == 0.1 09:19:25 INFO - mozdevice == 0.49 09:19:25 INFO - mozfile == 1.2 09:19:25 INFO - mozhttpd == 0.7 09:19:25 INFO - mozinfo == 0.9 09:19:25 INFO - mozleak == 0.1 09:19:25 INFO - mozlog == 3.4 09:19:25 INFO - moznetwork == 0.27 09:19:25 INFO - mozprocess == 0.25 09:19:25 INFO - mozprofile == 0.28 09:19:25 INFO - mozrunner == 6.13 09:19:25 INFO - mozscreenshot == 0.1 09:19:25 INFO - mozsystemmonitor == 0.3 09:19:25 INFO - moztest == 0.8 09:19:25 INFO - mozversion == 1.4 09:19:25 INFO - psutil == 3.1.1 09:19:25 INFO - requests == 1.2.3 09:19:25 INFO - wsgiref == 0.1.2 09:19:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:19: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')]} 09:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:26 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')]} 09:19:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:26 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 0x10e27cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e27f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa585b7a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e05ad30>, '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 0x10df81db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e04fbe8>, '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.nvbCUjpVfs/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.rWlV3GCbjn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 09:19:26 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 09:19:26 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 09:19:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvbCUjpVfs/Render', 09:19:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:26 INFO - 'HOME': '/Users/cltbld', 09:19:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:26 INFO - 'LOGNAME': 'cltbld', 09:19:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:26 INFO - 'MOZ_NO_REMOTE': '1', 09:19:26 INFO - 'NO_EM_RESTART': '1', 09:19:26 INFO - 'PAGER': '/bin/cat', 09:19:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:26 INFO - 'PWD': '/builds/slave/test', 09:19:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:26 INFO - 'SHELL': '/bin/bash', 09:19:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rWlV3GCbjn/Listeners', 09:19:26 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 09:19:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:26 INFO - 'USER': 'cltbld', 09:19:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:26 INFO - 'XPC_FLAGS': '0x0', 09:19:26 INFO - 'XPC_SERVICE_NAME': '0', 09:19:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:26 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)) 09:19:26 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)) 09:19:26 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)) 09:19:26 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)) 09:19:26 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)) 09:19:26 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)) 09:19:26 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)) 09:19:26 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)) 09:19:26 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)) 09:19:26 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)) 09:19:26 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)) 09:19:26 INFO - Cleaning up... 09:19:26 INFO - Return code: 0 09:19:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:19:26 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')]} 09:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:19:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:26 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')]} 09:19:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:19:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:19:26 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 0x10e27cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e27f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa585b7a70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e05ad30>, '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 0x10df81db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e04fbe8>, '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.nvbCUjpVfs/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.rWlV3GCbjn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 09:19:26 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 09:19:26 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 09:19:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvbCUjpVfs/Render', 09:19:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:26 INFO - 'HOME': '/Users/cltbld', 09:19:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:26 INFO - 'LOGNAME': 'cltbld', 09:19:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:26 INFO - 'MOZ_NO_REMOTE': '1', 09:19:26 INFO - 'NO_EM_RESTART': '1', 09:19:26 INFO - 'PAGER': '/bin/cat', 09:19:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:26 INFO - 'PWD': '/builds/slave/test', 09:19:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:26 INFO - 'SHELL': '/bin/bash', 09:19:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rWlV3GCbjn/Listeners', 09:19:26 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 09:19:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:26 INFO - 'USER': 'cltbld', 09:19:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:26 INFO - 'XPC_FLAGS': '0x0', 09:19:26 INFO - 'XPC_SERVICE_NAME': '0', 09:19:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:19:26 INFO - Cleaning up... 09:19:26 INFO - Return code: 0 09:19:26 INFO - Running post-action listener: _resource_record_post_action 09:19:26 INFO - Running post-action listener: _start_resource_monitoring 09:19:26 INFO - Starting resource monitoring. 09:19:26 INFO - [mozharness: 2017-03-07 17:19:26.568216Z] Finished create-virtualenv step (success) 09:19:26 INFO - [mozharness: 2017-03-07 17:19:26.569188Z] Running install step. 09:19:26 INFO - Running pre-action listener: _resource_record_pre_action 09:19:26 INFO - Running main action method: install 09:19:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:19:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:19:26 INFO - Reading from file tmpfile_stdout 09:19:26 INFO - Detecting whether we're running mozinstall >=1.0... 09:19:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:19:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:19:26 INFO - Reading from file tmpfile_stdout 09:19:26 INFO - Output received: 09:19:26 INFO - Usage: mozinstall [options] installer 09:19:26 INFO - Options: 09:19:26 INFO - -h, --help show this help message and exit 09:19:26 INFO - -d DEST, --destination=DEST 09:19:26 INFO - Directory to install application into. [default: 09:19:26 INFO - "/builds/slave/test"] 09:19:26 INFO - --app=APP Application being installed. [default: firefox] 09:19:26 INFO - mkdir: /builds/slave/test/build/application 09:19:26 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'] 09:19:26 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 09:19:47 INFO - Reading from file tmpfile_stdout 09:19:47 INFO - Output received: 09:19:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:19:47 INFO - Running post-action listener: _resource_record_post_action 09:19:47 INFO - [mozharness: 2017-03-07 17:19:47.343312Z] Finished install step (success) 09:19:47 INFO - [mozharness: 2017-03-07 17:19:47.343470Z] Running run-tests step. 09:19:47 INFO - Running pre-action listener: _resource_record_pre_action 09:19:47 INFO - Running main action method: run_tests 09:19:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:19:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:19:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:19:47 INFO - 2017-03-07 09:19:47.357 screenresolution[1629:7349] starting screenresolution argv=screenresolution get 09:19:47 INFO - 2017-03-07 09:19:47.360 screenresolution[1629:7349] Display 0: 1600x1200x32@60 09:19:47 INFO - 2017-03-07 09:19:47.366 screenresolution[1630:7352] starting screenresolution argv=screenresolution list 09:19:47 INFO - Available Modes on Display 0 09:19:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:19:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:19:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:19:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:19:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:19:47 INFO - Intel Iris: 09:19:47 INFO - Chipset Model: Intel Iris 09:19:47 INFO - Type: GPU 09:19:47 INFO - Bus: Built-In 09:19:47 INFO - VRAM (Dynamic, Max): 1536 MB 09:19:47 INFO - Vendor: Intel (0x8086) 09:19:47 INFO - Device ID: 0x0a2e 09:19:47 INFO - Revision ID: 0x0009 09:19:47 INFO - Displays: 09:19:47 INFO - Display: 09:19:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:19:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:19:47 INFO - Display Serial Number: 1600x1200 60 09:19:47 INFO - Main Display: Yes 09:19:47 INFO - Mirror: Off 09:19:47 INFO - Online: Yes 09:19:47 INFO - Rotation: Supported 09:19:47 INFO - Return code: 0 09:19:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:19:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:19:47 INFO - Python 2.7.3 09:19:47 INFO - Return code: 0 09:19:47 INFO - grabbing minidump binary from tooltool 09:19: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')]} 09:19:47 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 0x10e05ad30>, '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 0x10df81db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e04fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:19:47 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 09:19:47 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 09:19:47 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 09:19:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:19:47 INFO - Return code: 0 09:19:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:19:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:19:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:19:47 INFO - ENV: RUST_BACKTRACE is now 1 09:19:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:19:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:19:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eYuBMkf_S6G_o_oLtNs0Sw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0240', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:19:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eYuBMkf_S6G_o_oLtNs0Sw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0240 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:19:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nvbCUjpVfs/Render', 09:19:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:19:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:19:47 INFO - 'HOME': '/Users/cltbld', 09:19:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:19:47 INFO - 'LOGNAME': 'cltbld', 09:19:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:19:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:19:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:19:47 INFO - 'MOZ_NO_REMOTE': '1', 09:19:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:19:47 INFO - 'NO_EM_RESTART': '1', 09:19:47 INFO - 'PAGER': '/bin/cat', 09:19:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:19:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:19:47 INFO - 'PWD': '/builds/slave/test', 09:19:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:19:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:19:47 INFO - 'RUST_BACKTRACE': '1', 09:19:47 INFO - 'SHELL': '/bin/bash', 09:19:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rWlV3GCbjn/Listeners', 09:19:47 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 09:19:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:19:47 INFO - 'USER': 'cltbld', 09:19:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:19:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:19:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:19:47 INFO - 'XPC_FLAGS': '0x0', 09:19:47 INFO - 'XPC_SERVICE_NAME': '0', 09:19:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:19:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eYuBMkf_S6G_o_oLtNs0Sw/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0240', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:19:47 INFO - mozversion application_buildid: 20170307073232 09:19:47 INFO - mozversion application_changeset: 575e82f15c295cd56746fe26847ba95eae197954 09:19:47 INFO - mozversion application_display_name: Nightly 09:19:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:19:47 INFO - mozversion application_name: Firefox 09:19:47 INFO - mozversion application_remotingname: firefox 09:19:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:19:47 INFO - mozversion application_vendor: Mozilla 09:19:47 INFO - mozversion application_version: 55.0a1 09:19:47 INFO - mozversion platform_buildid: 20170307073232 09:19:47 INFO - mozversion platform_changeset: 575e82f15c295cd56746fe26847ba95eae197954 09:19:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:19:47 INFO - mozversion platform_version: 55.0a1 09:19:47 INFO - using testdate: 1488907187 09:19:47 INFO - actual date: 1488907187 09:19:47 INFO - starting webserver on 'localhost:49242' 09:19:47 INFO - SUITE-START | Running 2 tests 09:19:47 INFO - TEST-START | dromaeo_css 09:19:47 INFO - operating with platform_type : mac_ 09:19:47 INFO - Initialising browser for dromaeo_css test... 09:19:47 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/tmptajuFQ/profile http://localhost:49242/getInfo.html) 09:19:50 INFO - PROCESS | 1640 | 2017-03-07 09:19:50.878 plugin-container[1642:7529] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 09:19:50 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:50 INFO - PROCESS | 1640 | 2017-03-07 09:19:50.881 plugin-container[1642:7529] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 09:19:50 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:50 INFO - PROCESS | 1640 | 2017-03-07 09:19:50.881 plugin-container[1642:7529] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:19:50 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 09:19:50 INFO - PROCESS | 1640 | colorDepth:24 09:19:50 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 09:19:50 INFO - PROCESS | 1640 | __metrics 09:19:51 ERROR - PROCESS | 1640 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 09:19:51 INFO - TEST-INFO | 1640: exit 0 09:19:51 INFO - Browser initialized. 09:19:51 INFO - Running cycle 1/1 for dromaeo_css test... 09:19:51 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/tmptajuFQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:19:54 INFO - PROCESS | 1643 | 2017-03-07 09:19:54.189 plugin-container[1644:7655] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443b, name = 'com.apple.tsm.portname' 09:19:54 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:54 INFO - PROCESS | 1643 | 2017-03-07 09:19:54.192 plugin-container[1644:7655] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 09:19:54 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:19:54 INFO - PROCESS | 1643 | 2017-03-07 09:19:54.192 plugin-container[1644:7655] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:20:03 INFO - PROCESS | 1643 | RSS: Main: 226660352 09:20:03 INFO - PROCESS | 1643 | 09:23:07 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 09:23:09 INFO - PROCESS | 1643 | RSS: Main: 250015744 09:23:09 INFO - PROCESS | 1643 | 09:24:52 INFO - PROCESS | 1643 | ************************* 09:24:52 INFO - PROCESS | 1643 | A coding exception was thrown and uncaught in a Task. 09:24:52 INFO - PROCESS | 1643 | 09:24:52 ERROR - PROCESS | 1643 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:24:52 INFO - PROCESS | 1643 | Full stack: 09:24:52 INFO - PROCESS | 1643 | ************************* 09:26:12 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 09:26:12 INFO - PROCESS | 1643 | RSS: Main: 257167360 09:26:12 INFO - PROCESS | 1643 | 09:29:14 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 09:29:15 INFO - PROCESS | 1643 | RSS: Main: 269520896 09:29:15 INFO - PROCESS | 1643 | 09:32:17 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 09:32:17 INFO - PROCESS | 1643 | RSS: Main: 262242304 09:32:17 INFO - PROCESS | 1643 | 09:35:20 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 09:35:21 INFO - PROCESS | 1643 | RSS: Main: 271990784 09:35:21 INFO - PROCESS | 1643 | 09:38:24 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 09:38:24 INFO - PROCESS | 1643 | RSS: Main: 267001856 09:38:24 INFO - PROCESS | 1643 | 09:38:24 INFO - PROCESS | 1643 | __start_tp_report 09:38:24 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 09:38:24 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 09:38:24 INFO - PROCESS | 1643 | |i|pagename|runs| 09:38:24 INFO - PROCESS | 1643 | |0;dojo.html;2960.467115919134;3467.7572569920108;3487.4768784682296;3496.125968507873;3508.03529029517;5817.621854579449;5989.940100598993;6007.699615019248;6029.6080754750965;6070;5932.288125424955;6031.427014433632;6050.2437195350585;6063.636181829093;6103.398280005564;5381.704006279663;5394.947985142879;5434.510894019538;5476.123820188771;5544.805931792388;5356.4107948125675;5425.782968681248;5534.391216966131;5572.804951826687;5582.162675598673;5331.466853314676;5379.300690910157;5471.917921231185;5474.233607294982;5483.479069488392;5438.265834112383;5462.289902312674;5468.535174510172;5474.589405794542;5516.696581688006;5417.6478528895495;5418.024755544;5421.945780542223;5427.891442171174;5448.37343705475;5735.741891614887;5883.970580147072;5892.528597712173;5897.3218079920925;5939.67331796751;5398.946010539923;6192.690365481708;6289.559730818844;6305.8108256752375;6340.365963403623;6423.4861211103;6425.710843012029;6436.774712885027;6452.290248072755;6452.709628066748;6116.021436570128;6197.1324014637985;6208.161898143738;6213.1922850029205;6235.9398902187195;5143.7428128594165;6297.4332310091395;6362.395572420613;6588.11060506835;6903.585784852825;6146.354632763585;6286.811395658137;6304.779332723333;6329.841753956188;6331.176946996861;6421.51838612106;6431.292557818636;6455.289918109004;6458.967705161444;6470.12653291809;6173.41352571505;6325.304216536178;6338.556301058974;6354.809355719336;6359.1733074700915;6219.720112594853;6242.250929888442;6264.310925798159;6352.777652782131;6404.071409645575;5119.10415677255;5177.352830896138;5178.378594568676;5183.092958732207;5195.740212989335;3902;4617.945014468596;4618.699784513996;4620.792064357112;4625.976870115628;5793.623414478046;5908.172855800191;6054.182685337423;6100.694965251809;6129.84675383119;6352.110704501373;6392.232932048184;6396.136521569621;6404.807855764335;6435.646039467781;6103.908441373383;6116.3883611638485;6142.201513803267;6155.599886007395;6158.1686472326555;5508.173773933861;5547.8890422192135;5550.500454959073;5612.494875461228;5624.240727501817;6491.545591808528;6635.8009259722285;6645.335466453509;6647.268800431852;6672.866542669022;6252.656103914328;6287.339829318039;6372.362763723776;6420.839479013062;6425.742970281323;6404.679766011774;6433.035044743138;6597.208334999831;6617.900731489128;6629.303923088007;6309.842253943688;6333.58831675958;6338.714757835816;6352.142460767922;6354.269259035085;5354.303940487712;5712.657525481425;6225.00399936008;6256.5620406572925;6309.779157729457;6236.158118653923;6301.558890877594;6302.558820882494;6311.305756366704;6316.936830631635;6286.15136956505;6286.27707813589;6293.590916590407;6293.685315734287;6314;6109.602875813236;6109.69451527413;6188.0717892314715;6309.432151106423;6312.305646378986;6248.812535623939;6249.25008998923;6295.433410993032;6297.874042519215;6317.30509643948;6251.468625166912;6255.906161407674;6271.96864015677;6280;6312.021636646327 09:38:24 INFO - PROCESS | 1643 | |1;ext.html;786.7993661616289;804.5253300552673;934.9672761453351;955.3312681123216;957.2198658093657;37572.248555028884;39135.804320978394;39230;39352.80323598378;39432;33264.833675831585;35168.472472912865;38046.85859424215;38075.61924380755;38169.618303816955;8677.305815534743;9151.405158664698;9183;9187.127222913812;9195.72412827613;8873.20141187293;8969.95515022424;8983.011868694472;9001.95499022504;9002.32482563807;8971.192592666659;8975.685850995216;8978.147076027766;9002.14479624438;9002.684906028291;7706.6917323307;8950.910490895072;8973.41672791273;8973.955130224307;8987.41581797181;7548.773536793905;8786.780330491725;8838.867416988685;8840.160184782435;8859.601317940707;33083.66916330854;33224;33257;33321.6667833321;33350.16624584381;3684.8341824617687;3704.481372607837;3712.814359282025;3716.182439863253;3852.114013776819;25016.4996700065;25602.615960760602;25850.224493265232;25855.577943213117;25864.87067564669;2362.8109751219863;3046.8628911699025;3136.702013308732;3176.475881479553;3185.394774992744;307648;307822;310297;310695;311175;36209.27581448356;36907.26185476276;40964.97587560335;41823.7908810454;41910.371344429856;36679.633203667625;44406.77796610996;44740;44898.77550612226;45017.774911125234;12712.237265763899;12765.489380424864;12812;12815.935920320526;12834.358282086045;10740.409277489656;10810.783784324427;10850.186236032328;11025.173112016631;11053.613123540797;11789.174757767045;11825.763484730258;11827.881721182677;11846.526138954518;11926.940365298118;4842;5795.304563452329;5798.623089499253;5815.767369305265;5828.6211396259105;1167.521316260346;1169.3159501691437;1174.4597485156753;1176.6999415149082;1219.2136072233359;27448.313792154957;28568.71431285702;28767.280817979718;29094.85452572766;29198.854005729834;1018.251585084962;1046.529061922138;1053.8208504554243;1071.0200166847249;1091.6070214722693;11841.822372664417;12895.548655797002;13565.864341356657;13602.931985340208;13868.375923083486;441289;444809;445055;445594;445739.7713011414;45773.77113114413;45829.54170458319;45873.311900321525;45893.5410645896;46001.84995375075;1348.8516263211038;1394.8953828462907;1400.859914008591;1411.5271384086445;1416;22617.5476490466;23809.40478987402;25724.485510290062;25922.87038564795;26022;39624.415023399895;39829.40255896222;39952.4007139899;39983.80008099941;39995.600043999184;432141;434950;437512;438059;439000;22727.86360681926;23151.073957041528;27361.905523778827;27830.025949091683;28264.71735282621;36492.45261321029;36571.81714091413;36902.446463302535;37374.87875363744;37393.81303093576;31149.532757009118;31286.843565782026;31311.686883131788;32031;32040.198995024377;26037;27431.725682742916;32763.017109486755;33738.831305844295;34608.30783384268;31538.684613153575;33229.83385083059;33280.833595831864;33300.83447079341;33416.66583334136;25371.238862834143;27475.587866181624;29042.419151617272;29575.704242958156;29659.96190133335;21260.893695531424;22635;23023.309300721005;23281.650775238046;23302.76697233006 09:38:24 INFO - PROCESS | 1643 | |2;jquery.html;330.51084395095245;394.9091708906951;410.8336123869833;411.6747769262283;439.1743522178304;15090.622734431645;19837.206511739532;20525.692114618294;21177.68233476499;21232.469188270283;20919.581608367862;21021;22428.439289017784;22446.32661020168;22759.431014224654;6334.588251763644;6685.364890335435;6685.598864068172;6694.397504224618;6715.32846715327;6473.320301368359;6625.668716564177;6712.765053223138;6752;6830.658467076626;6819.624920629363;6824.0446337512785;6839.521233513656;6840.179178498563;6864.7940561783225;6428.164338635971;6542.934570654304;6615.503837212228;6621.536492445531;6635.767748128841;6349.428551430347;6382.840428989266;6385.882470567679;6446.806595802133;6513.055606936968;23441;24779.752202478106;25867.22398328053;26533.867330663415;26838.5974210387;2804.803663743539;2848.8148270362362;2866.4123854609757;2886.927826804326;2917.920369463291;15539.689206215926;15969.201539922957;16021.187008079021;16166.75749863753;16594.170291485378;1752.4567384110967;2021.304028722746;2055.8972051397373;2106.6629339305637;2128.9574208515896;150280;157432;157433.21283393624;159126;161289;20049.498762530762;20561.869097199797;21076.73539587599;21122.788772112388;21384.251551195634;31681.84159079236;31711.36577268488;32099;32126.678733212837;41002.1799564007;11319.490622921989;11490.080793536665;11659.417029148508;11710.648680539598;11900.940495297467;8436.325093992466;8516.148385161434;8616.095309992486;9458.33791634579;9503.952480237554;9065.456072635663;9570;11839.940800295943;11903;11911.523539058513;4441.400410944481;4548.953740639671;4615.676902616785;4987.501249875056;5005.249212618136;872.9694460693845;959.6545243712258;960.846264597675;971.5482300730195;1005.9547320370601;18648.160832762558;19329.80670193308;19693.50766230827;21072.367828965154;21096.367108986757;932.7015355086443;945.6264775413673;973.1339108193713;973.9220862330997;974.0843607009389;10800.567977280822;10912.181586381053;10930.672079837617;10964.835527467094;10982.286151400134;351158;380673;458620;557626;558874;74883.25116748763;146047.2697636526;148509;150468.247658761;152515;1205.3129716061935;1205.8854408831146;1228.0912125027594;1229.1518851992275;1235.5860786636397;17711.55721106931;18236.723429359816;18308.908455457637;18353;18359.81640183581;49931.500684994135;50853.745731271105;56153.43846561482;56421.43578564326;61974.38025619686;78855.21144788478;79562;80340.99147521358;80407.79388309296;80768;9474.478903660365;11399.316041037564;11742.060635148997;11798.646040618794;11851.940740296242;10997.790243073096;11184;11995.880041199825;12955;13397.263150526815;10235.94882025615;10664.626738064131;11390.886091138982;11405.543778248777;11756.23584467007;10901.073408760345;10909.39998300101;11021.944890275498;11251.437428128724;11330.943345283551;9950.651727189514;10445.529951152064;11518.596849110241;11693.590724324948;11847.170698051053;9913.851292230767;11376.0330371916;11447.427628618703;11460.255083419552;11786.410679466164;11247.775044499227;11472.598459053894;11514.88485115138;11627.76744465089;11694.941525292319 09:38:24 INFO - PROCESS | 1643 | |3;mootools.html;1407.4088882669278;1480.4744315767896;1485.9108453492784;1509.7207016701911;1515.0152400939387;8246.299064579503;9565.760855978604;10463.895361046396;11184.664460066191;11190;9545.093216144473;9810.705678829647;9837.60649574018;9848.704538863827;9868.013198680128;2327.7555856635063;2370.810335173182;2371.905123795055;2374.4538756086113;2376.3346263046374;2074.305107788885;2245.2815099168274;2310.4223944013997;2349.859008459498;2355.5759963206615;2159.341400872733;2168.8938641292975;2302.5049614332916;2331.335569362732;2377.5958087125146;2290.541891621682;2301.1945818963313;2304.135949019118;2305.4351683837567;2313.7339205991275;2290.5533420982915;2332.521833024243;2333.0317918063984;2334.6031174700342;2360.3155085025323;9377.718668439958;10945.507452164671;10991.285566438157;11005.119590432747;11008.889911100947;5749.568782341341;5919.467247947706;5928.911066333965;5935.643861368331;5939.821805345803;3263.1189578813587;3410.4390546467666;3558.946615800765;3562.6615471530163;3617.674409303149;1141.161246483842;1190.815423609342;1215.574548907884;1252.4927404401233;1253.166644181618;14919.2540372982;15025.323860426308;15081.924590377088;15083.095014299177;15115.31981060855;4042.312806822847;4706.435227772689;4901.9019619608125;4947.950520494821;5005.199168133081;12507.93746031264;12570.1200915935;12574.43415046325;12611.495540178474;12620.242785432903;5847.766089356463;5900.996830538818;5910.202122713465;5926.962781513218;5952.285725712855;4517.051419201939;4923.581495572845;5132.666376685504;5174.534291913746;5257.395399529027;3933.1347103637154;4028.234635419261;4060.5330387005874;4100.138970816162;4102.630763231324;1127.7293449572044;1132.9943350283195;1136.170595465315;1144.347721798567;1146.7935771561033;871.934604904622;930.4045410937006;959.6641175588488;967.1295833749682;970.0929630795229;6429.967850160813;6722.529422940347;6755.662216889136;6827.317268273133;6862.794116176523;985.3595163144014;997;1007.7379881230933;1046.1212581431635;1046.7383154211448;4159.625633692982;4202.453681021509;4256.914861702749;4402.119576084796;4421.447319085114;12686.619401417973;13093.345332733325;13112;13113;13124.737505250032;12046.578369757017;12480.500779968881;12780.488780448864;12783.16909400886;13019.609411717664;9875.703728888002;9994.100530952395;10111.949440252753;10127.949360253151;10173.389596624227;5657.632253903483;6535.607863528204;6877.1747390313485;6963.7562685305775;7027.754028608974;11938.28370297785;11952.940235298767;12000.339981300764;12063.939680301542;12085.577004805142;12102.878971210175;12190.390480475764;12211.328376939353;12314.014878809852;12332.506699732268;7694;8902.15429534219;9115.134062264075;9187.494687791968;9303.581338839904;10052;10166.542505587415;10321.329113607591;10356.896431035895;10380.065794078268;8965.282777377664;9039.593218305323;9196.26429885621;9213.493257870881;9265.814683706423;8675.219230269306;8807.779805504657;8818.56919039211;8951.462912225288;8965.417247878868;7772.222777722409;7782.143964164051;7844.764657060297;7903.723369682033;7913.920860791319;4953.430355509163;5267.3152490176035;5616.297962754656;5624.943750562442;5651.886962260814;5830.4461076197695;5861.3845546217035;6017.458428741435;6081.148639190428;6200.224971878515 09:38:24 INFO - PROCESS | 1643 | |4;prototype.html;315.5645209610737;354.50192479566203;366.84626848561277;366.919277758893;380.19019488489505;4404.537523560028;5066.594672426207;5096.5667918226945;5353.544948679362;5388.410418926416;5082.84751457456;5190.584753219743;5194.610404219689;5203.245529398235;5227.843164705056;3874.1476875087437;4189.497260328765;4329.631981281595;4339.067100573376;4340.804663790122;4181.770002649853;4321.937378863874;4334.5448727883595;4362.912741745163;4379.189849877767;4208.158368326332;4224.049588842501;4255.319148936171;4256.382824490447;4272.743635381871;4275.593818587229;4283.879479657589;4284.164587905359;4286.807093680776;4358.259095953664;3315.0220684898013;3973.5033120859894;3983.3626619740703;4001.479437813612;4011.83952641894;3823.9235215295816;4032.4152997815154;4276.978615106936;4704.388429504143;5069.771860266297;4285.285885645735;4463.469578377949;4645.304463870697;4807.278908163768;4987.47631498691;5143.228515722672;5486.91769623456;5605.635633683838;5607.775688972436;5634.450526105313;3925.4700615417123;3997.6801855851463;4224.957750422487;4365.236083685342;4434.135343607998;4717.363155973934;4860;5457.590680698963;5851.435880013596;5951.732172052268;4346.913481528821;4433.093769679004;4468.731876087445;4506.639468842484;4579.17574836526;3961.2473630010204;3973.4238535412205;4085.835536871977;4426.712263702849;4650.255959046604;4584.9541504585195;4749.64377671676;4844.95155048452;4864.526513457921;4987.451380348159;4930.753462326869;5350.759215835296;5502.972485137629;5555.472230138131;5564.304461942257;1434.6485111147836;1534.1715473644108;1555.992220038915;1567.036272692286;1575.4249698859833;942.5428667096453;1113.9944300278446;1176.517627772609;1184.6435830973487;1190.9404529773476;4835.661503694708;5036.955653216155;5196.844094677166;5233.319668443078;5278.683279003272;4801.927971080437;5448.400675925645;5530.944690553043;5574.554035677136;5771.913421298684;4544.840930567414;4722.315264286659;4763.785629646674;4813.109574728686;4876.707397556158;2586;2952.6270284317893;2990.2972801391657;2992.8353940533043;2994.2215024093457;5419.748151109303;6000.969995149996;6016.322736382268;6021.548383871227;6023.337432882379;5430.375506816767;5455.427180146107;5478.534324582375;5687.687177205211;5769.971150144306;5933.703314834241;5964.254468191476;5968.343482217039;5983.581149319506;6008.399160084044;4975;5131.743412829269;5401.405845356929;5773.87409455169;5815.680137592473;5001.924971125509;5015.523525265094;5220.399654039834;5672.631278966854;5813.7674493021495;5213.973930130325;5780.8554786129;5837.328707198726;5951.880962380642;6060.363661815622;4564.88587785308;4577.093545598503;4607.976960115178;4616.515265897033;4644.86065418038;4997.650164488451;5556.972215139061;5787.627072900091;5864.765409383577;5879.824035192893;4695.859124226278;5020.648554601289;5464.901324006994;5511.448855114457;5640.492355688008;5203.33165118598;5269.57843372537;5309.65487243328;5636.351819540805;5644.463775941279;4239.9364009540495;5159.948400516097;5471.534919531725;5503.926734286776;5505.862353441196;5167.121589329748;5188.455212202664;5230.8169214077325;5291.365036195527;5316.308879845596;5458.536024437967;5511.940872815626;5533;5536.003519366553;5569.637973531708 09:38:24 INFO - PROCESS | 1643 | |5;yui.html;737.325347307214;763.6639878453481;763.736510903738;796.1163108949062;831.4595512916603;15074.170920599363;16162;16179.271932763038;16195.595110122224;16197.35210591578;14953;15110;15127.394904203818;15163.924180379083;15169.317380717866;897.1477096758073;915.8671992561075;939.3706216834737;940.1397721085233;944.4522177137244;928.299134153713;933.020328654913;943.5093751249346;944.504135328951;944.617429940875;856.4647095565272;1000.3797645459825;1004.75383531035;1005.5324274212489;1006.9446180460071;992.9602815887356;1000.9099181073666;1001;1001.5042553935775;1008.1531513528598;738.5605564689001;740.7111226621568;743.2938708227206;745.3627148787817;746.2761121711931;16124.193790310437;16333.428330008393;16346.509604711877;16841.747373789403;16978.660426791394;14003.019788614802;14050.086744361584;14066.71866562663;14111.294435278194;14195;11711.472983715754;11780.646580602595;11867.465964031553;11930.343831089287;12037;3579.8300102948597;3621.8551257949653;3666.4133738601695;3690.1143725505954;3790.1282704977734;111675;114052;114085;114488;115274;9516.666916657883;10133.240006999504;10486;10874.293170943864;11107.83338249927;21173.68239476409;21656;21757.782422175893;21774.45563860916;21823.563528729337;7285.585149152737;7350.889736653954;7460.85078298431;7519.849603008019;7583.27958843909;4211.536730959592;4250.851220207278;4256.659467242675;4290.871273861727;4352.978235108804;2789.27478855499;3301.224212310105;3371.713404360608;3383.746219033533;3387.7628566000635;514.7709269375167;524.8976449592369;613.9225658968571;614.2046050364788;618.7617767159586;598.9940100599025;632.9082283069022;648.6497291462651;650.9121268628675;661.272600139852;7347.971284020135;8200.794980125545;9586.429677117518;9803.656872009446;9870.358426702385;587.8530367408148;650.440621065883;650.7754824585511;658.2101478226148;663.1478550063146;4395.7142785718825;4548.749818759934;4554;4573.222552166139;4594.425696787902;85069.72395414073;89356.5532172335;101818;102582;103371.44942825864;82822;88764;90299.1940161193;90499.54750226339;90672.29285510805;74367.25632743712;74799.62600186965;75231;75579.62210189024;76012.0996975069;9004.234640055669;9081.818363632558;9125.361224714206;9141.222996045411;9171;31064;46222.53777462182;47853.80365490891;49419.50580494149;49500.75249623729;65577;67982.3201767976;69086;69148;69508.65245673739;10554.225704614708;11015.339079655247;11030.779384412428;12762.361881906054;13169;12593.433295501542;14506.638015509328;14867.702645947236;15290.541283761506;15447.768283475532;11583.710407239887;13651.453941842215;13743.450261989408;13931.58205253844;14184.43227614337;13703.45186192541;13841.785314349656;13929.791053134008;14071.577852664437;14327.283635818376;13414.12808167466;13495.055346125677;13513.39189736484;13547.254900980146;14484.203368814817;10772.192085593768;10868.271786125079;11025.519258166849;11235.606753763579;12100.334481603244;8392.87410688828;9544.33189676716;10057.044580764816;11337.489812958273;11339 09:38:24 INFO - PROCESS | 1643 | __end_tp_report 09:38:24 INFO - PROCESS | 1643 | __start_cc_report 09:38:24 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,424 09:38:24 INFO - PROCESS | 1643 | __end_cc_report 09:38:24 INFO - PROCESS | 1643 | __startTimestamp1488908304544__endTimestamp 09:38:24 INFO - PROCESS | 1643 | 09:38:24 INFO - PROCESS | 1643 | ------- Summary: start ------- 09:38:24 INFO - PROCESS | 1643 | Number of tests: 6 09:38:24 INFO - PROCESS | 1643 | 09:38:24 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5932.82 Median:6208.16 stddev:664.37 (10.7%) stddev-sans-first:624.15 09:38:24 INFO - PROCESS | 1643 | Values: 2960.5 3467.8 3487.5 3496.1 3508.0 5817.6 5989.9 6007.7 6029.6 6070.0 5932.3 6031.4 6050.2 6063.6 6103.4 5381.7 5394.9 5434.5 5476.1 5544.8 5356.4 5425.8 5534.4 5572.8 5582.2 5331.5 5379.3 5471.9 5474.2 5483.5 5438.3 5462.3 5468.5 5474.6 5516.7 5417.6 5418.0 5421.9 5427.9 5448.4 5735.7 5884.0 5892.5 5897.3 5939.7 5398.9 6192.7 6289.6 6305.8 6340.4 6423.5 6425.7 6436.8 6452.3 6452.7 6116.0 6197.1 6208.2 6213.2 6235.9 5143.7 6297.4 6362.4 6588.1 6903.6 6146.4 6286.8 6304.8 6329.8 6331.2 6421.5 6431.3 6455.3 6459.0 6470.1 6173.4 6325.3 6338.6 6354.8 6359.2 6219.7 6242.3 6264.3 6352.8 6404.1 5119.1 5177.4 5178.4 5183.1 5195.7 3902.0 4617.9 4618.7 4620.8 4626.0 5793.6 5908.2 6054.2 6100.7 6129.8 6352.1 6392.2 6396.1 6404.8 6435.6 6103.9 6116.4 6142.2 6155.6 6158.2 5508.2 5547.9 5550.5 5612.5 5624.2 6491.5 6635.8 6645.3 6647.3 6672.9 6252.7 6287.3 6372.4 6420.8 6425.7 6404.7 6433.0 6597.2 6617.9 6629.3 6309.8 6333.6 6338.7 6352.1 6354.3 5354.3 5712.7 6225.0 6256.6 6309.8 6236.2 6301.6 6302.6 6311.3 6316.9 6286.2 6286.3 6293.6 6293.7 6314.0 6109.6 6109.7 6188.1 6309.4 6312.3 6248.8 6249.3 6295.4 6297.9 6317.3 6251.5 6255.9 6272.0 6280.0 6312.0 09:38:24 INFO - PROCESS | 1643 | 09:38:24 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:51823.92 Median:33311.25 stddev:106722.00 (320.4%) stddev-sans-first:106952.59 09:38:24 INFO - PROCESS | 1643 | Values: 786.8 804.5 935.0 955.3 957.2 37572.2 39135.8 39230.0 39352.8 39432.0 33264.8 35168.5 38046.9 38075.6 38169.6 8677.3 9151.4 9183.0 9187.1 9195.7 8873.2 8970.0 8983.0 9002.0 9002.3 8971.2 8975.7 8978.1 9002.1 9002.7 7706.7 8950.9 8973.4 8974.0 8987.4 7548.8 8786.8 8838.9 8840.2 8859.6 33083.7 33224.0 33257.0 33321.7 33350.2 3684.8 3704.5 3712.8 3716.2 3852.1 25016.5 25602.6 25850.2 25855.6 25864.9 2362.8 3046.9 3136.7 3176.5 3185.4 307648.0 307822.0 310297.0 310695.0 311175.0 36209.3 36907.3 40965.0 41823.8 41910.4 36679.6 44406.8 44740.0 44898.8 45017.8 12712.2 12765.5 12812.0 12815.9 12834.4 10740.4 10810.8 10850.2 11025.2 11053.6 11789.2 11825.8 11827.9 11846.5 11926.9 4842.0 5795.3 5798.6 5815.8 5828.6 1167.5 1169.3 1174.5 1176.7 1219.2 27448.3 28568.7 28767.3 29094.9 29198.9 1018.3 1046.5 1053.8 1071.0 1091.6 11841.8 12895.5 13565.9 13602.9 13868.4 441289.0 444809.0 445055.0 445594.0 445739.8 45773.8 45829.5 45873.3 45893.5 46001.8 1348.9 1394.9 1400.9 1411.5 1416.0 22617.5 23809.4 25724.5 25922.9 26022.0 39624.4 39829.4 39952.4 39983.8 39995.6 432141.0 434950.0 437512.0 438059.0 439000.0 22727.9 23151.1 27361.9 27830.0 28264.7 36492.5 36571.8 36902.4 37374.9 37393.8 31149.5 31286.8 31311.7 32031.0 32040.2 26037.0 27431.7 32763.0 33738.8 34608.3 31538.7 33229.8 33280.8 33300.8 33416.7 25371.2 27475.6 29042.4 29575.7 29660.0 21260.9 22635.0 23023.3 23281.7 23302.8 09:38:24 INFO - PROCESS | 1643 | 09:38:24 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:34213.57 Median:11756.60 stddev:81374.85 (692.2%) stddev-sans-first:81563.37 09:38:24 INFO - PROCESS | 1643 | Values: 330.5 394.9 410.8 411.7 439.2 15090.6 19837.2 20525.7 21177.7 21232.5 20919.6 21021.0 22428.4 22446.3 22759.4 6334.6 6685.4 6685.6 6694.4 6715.3 6473.3 6625.7 6712.8 6752.0 6830.7 6819.6 6824.0 6839.5 6840.2 6864.8 6428.2 6542.9 6615.5 6621.5 6635.8 6349.4 6382.8 6385.9 6446.8 6513.1 23441.0 24779.8 25867.2 26533.9 26838.6 2804.8 2848.8 2866.4 2886.9 2917.9 15539.7 15969.2 16021.2 16166.8 16594.2 1752.5 2021.3 2055.9 2106.7 2129.0 150280.0 157432.0 157433.2 159126.0 161289.0 20049.5 20561.9 21076.7 21122.8 21384.3 31681.8 31711.4 32099.0 32126.7 41002.2 11319.5 11490.1 11659.4 11710.6 11900.9 8436.3 8516.1 8616.1 9458.3 9504.0 9065.5 9570.0 11839.9 11903.0 11911.5 4441.4 4549.0 4615.7 4987.5 5005.2 873.0 959.7 960.8 971.5 1006.0 18648.2 19329.8 19693.5 21072.4 21096.4 932.7 945.6 973.1 973.9 974.1 10800.6 10912.2 10930.7 10964.8 10982.3 351158.0 380673.0 458620.0 557626.0 558874.0 74883.3 146047.3 148509.0 150468.2 152515.0 1205.3 1205.9 1228.1 1229.2 1235.6 17711.6 18236.7 18308.9 18353.0 18359.8 49931.5 50853.7 56153.4 56421.4 61974.4 78855.2 79562.0 80341.0 80407.8 80768.0 9474.5 11399.3 11742.1 11798.6 11851.9 10997.8 11184.0 11995.9 12955.0 13397.3 10235.9 10664.6 11390.9 11405.5 11756.2 10901.1 10909.4 11021.9 11251.4 11330.9 9950.7 10445.5 11518.6 11693.6 11847.2 9913.9 11376.0 11447.4 11460.3 11786.4 11247.8 11472.6 11514.9 11627.8 11694.9 09:38:24 INFO - PROCESS | 1643 | 09:38:24 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:6624.21 Median:3560.80 stddev:4099.86 (115.1%) stddev-sans-first:4092.62 09:38:24 INFO - PROCESS | 1643 | Values: 1407.4 1480.5 1485.9 1509.7 1515.0 8246.3 9565.8 10463.9 11184.7 11190.0 9545.1 9810.7 9837.6 9848.7 9868.0 2327.8 2370.8 2371.9 2374.5 2376.3 2074.3 2245.3 2310.4 2349.9 2355.6 2159.3 2168.9 2302.5 2331.3 2377.6 2290.5 2301.2 2304.1 2305.4 2313.7 2290.6 2332.5 2333.0 2334.6 2360.3 9377.7 10945.5 10991.3 11005.1 11008.9 5749.6 5919.5 5928.9 5935.6 5939.8 3263.1 3410.4 3558.9 3562.7 3617.7 1141.2 1190.8 1215.6 1252.5 1253.2 14919.3 15025.3 15081.9 15083.1 15115.3 4042.3 4706.4 4901.9 4948.0 5005.2 12507.9 12570.1 12574.4 12611.5 12620.2 5847.8 5901.0 5910.2 5927.0 5952.3 4517.1 4923.6 5132.7 5174.5 5257.4 3933.1 4028.2 4060.5 4100.1 4102.6 1127.7 1133.0 1136.2 1144.3 1146.8 871.9 930.4 959.7 967.1 970.1 6430.0 6722.5 6755.7 6827.3 6862.8 985.4 997.0 1007.7 1046.1 1046.7 4159.6 4202.5 4256.9 4402.1 4421.4 12686.6 13093.3 13112.0 13113.0 13124.7 12046.6 12480.5 12780.5 12783.2 13019.6 9875.7 9994.1 10111.9 10127.9 10173.4 5657.6 6535.6 6877.2 6963.8 7027.8 11938.3 11952.9 12000.3 12063.9 12085.6 12102.9 12190.4 12211.3 12314.0 12332.5 7694.0 8902.2 9115.1 9187.5 9303.6 10052.0 10166.5 10321.3 10356.9 10380.1 8965.3 9039.6 9196.3 9213.5 9265.8 8675.2 8807.8 8818.6 8951.5 8965.4 7772.2 7782.1 7844.8 7903.7 7913.9 4953.4 5267.3 5616.3 5624.9 5651.9 5830.4 5861.4 6017.5 6081.1 6200.2 09:38:24 INFO - PROCESS | 1643 | 09:38:24 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4614.41 Median:4987.46 stddev:1296.83 (26.0%) stddev-sans-first:1259.69 09:38:24 INFO - PROCESS | 1643 | Values: 315.6 354.5 366.8 366.9 380.2 4404.5 5066.6 5096.6 5353.5 5388.4 5082.8 5190.6 5194.6 5203.2 5227.8 3874.1 4189.5 4329.6 4339.1 4340.8 4181.8 4321.9 4334.5 4362.9 4379.2 4208.2 4224.0 4255.3 4256.4 4272.7 4275.6 4283.9 4284.2 4286.8 4358.3 3315.0 3973.5 3983.4 4001.5 4011.8 3823.9 4032.4 4277.0 4704.4 5069.8 4285.3 4463.5 4645.3 4807.3 4987.5 5143.2 5486.9 5605.6 5607.8 5634.5 3925.5 3997.7 4225.0 4365.2 4434.1 4717.4 4860.0 5457.6 5851.4 5951.7 4346.9 4433.1 4468.7 4506.6 4579.2 3961.2 3973.4 4085.8 4426.7 4650.3 4585.0 4749.6 4845.0 4864.5 4987.5 4930.8 5350.8 5503.0 5555.5 5564.3 1434.6 1534.2 1556.0 1567.0 1575.4 942.5 1114.0 1176.5 1184.6 1190.9 4835.7 5037.0 5196.8 5233.3 5278.7 4801.9 5448.4 5530.9 5574.6 5771.9 4544.8 4722.3 4763.8 4813.1 4876.7 2586.0 2952.6 2990.3 2992.8 2994.2 5419.7 6001.0 6016.3 6021.5 6023.3 5430.4 5455.4 5478.5 5687.7 5770.0 5933.7 5964.3 5968.3 5983.6 6008.4 4975.0 5131.7 5401.4 5773.9 5815.7 5001.9 5015.5 5220.4 5672.6 5813.8 5214.0 5780.9 5837.3 5951.9 6060.4 4564.9 4577.1 4608.0 4616.5 4644.9 4997.7 5557.0 5787.6 5864.8 5879.8 4695.9 5020.6 5464.9 5511.4 5640.5 5203.3 5269.6 5309.7 5636.4 5644.5 4239.9 5159.9 5471.5 5503.9 5505.9 5167.1 5188.5 5230.8 5291.4 5316.3 5458.5 5511.9 5533.0 5536.0 5569.6 09:38:24 INFO - PROCESS | 1643 | 09:38:24 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:20276.54 Median:3644.13 stddev:29367.67 (805.9%) stddev-sans-first:29413.41 09:38:24 INFO - PROCESS | 1643 | Values: 737.3 763.7 763.7 796.1 831.5 15074.2 16162.0 16179.3 16195.6 16197.4 14953.0 15110.0 15127.4 15163.9 15169.3 897.1 915.9 939.4 940.1 944.5 928.3 933.0 943.5 944.5 944.6 856.5 1000.4 1004.8 1005.5 1006.9 993.0 1000.9 1001.0 1001.5 1008.2 738.6 740.7 743.3 745.4 746.3 16124.2 16333.4 16346.5 16841.7 16978.7 14003.0 14050.1 14066.7 14111.3 14195.0 11711.5 11780.6 11867.5 11930.3 12037.0 3579.8 3621.9 3666.4 3690.1 3790.1 111675.0 114052.0 114085.0 114488.0 115274.0 9516.7 10133.2 10486.0 10874.3 11107.8 21173.7 21656.0 21757.8 21774.5 21823.6 7285.6 7350.9 7460.9 7519.8 7583.3 4211.5 4250.9 4256.7 4290.9 4353.0 2789.3 3301.2 3371.7 3383.7 3387.8 514.8 524.9 613.9 614.2 618.8 599.0 632.9 648.6 650.9 661.3 7348.0 8200.8 9586.4 9803.7 9870.4 587.9 650.4 650.8 658.2 663.1 4395.7 4548.7 4554.0 4573.2 4594.4 85069.7 89356.6 101818.0 102582.0 103371.4 82822.0 88764.0 90299.2 90499.5 90672.3 74367.3 74799.6 75231.0 75579.6 76012.1 9004.2 9081.8 9125.4 9141.2 9171.0 31064.0 46222.5 47853.8 49419.5 49500.8 65577.0 67982.3 69086.0 69148.0 69508.7 10554.2 11015.3 11030.8 12762.4 13169.0 12593.4 14506.6 14867.7 15290.5 15447.8 11583.7 13651.5 13743.5 13931.6 14184.4 13703.5 13841.8 13929.8 14071.6 14327.3 13414.1 13495.1 13513.4 13547.3 14484.2 10772.2 10868.3 11025.5 11235.6 12100.3 8392.9 9544.3 10057.0 11337.5 11339.0 09:38:24 INFO - PROCESS | 1643 | -------- Summary: end -------- 09:38:24 INFO - PROCESS | 1643 | 09:38:25 INFO - TEST-INFO | 1643: exit 0 09:38:25 INFO - TEST-OK | dromaeo_css | took 1117272ms 09:38:25 INFO - TEST-START | kraken 09:38:25 INFO - operating with platform_type : mac_ 09:38:25 INFO - Initialising browser for kraken test... 09:38:25 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/tmpmtF_Vp/profile http://localhost:49242/getInfo.html) 09:38:27 INFO - PROCESS | 1656 | 2017-03-07 09:38:27.959 plugin-container[1657:10170] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e43, name = 'com.apple.tsm.portname' 09:38:27 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:27 INFO - PROCESS | 1656 | 2017-03-07 09:38:27.962 plugin-container[1657:10170] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 09:38:27 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:27 INFO - PROCESS | 1656 | 2017-03-07 09:38:27.963 plugin-container[1657:10170] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:38:28 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 09:38:28 INFO - PROCESS | 1656 | colorDepth:24 09:38:28 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 09:38:28 INFO - PROCESS | 1656 | __metrics 09:38:28 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3353: TypeError: this.tabs is undefined 09:38:28 INFO - TEST-INFO | 1656: exit 0 09:38:28 INFO - Browser initialized. 09:38:28 INFO - Running cycle 1/1 for kraken test... 09:38:28 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/tmpmtF_Vp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:38:31 INFO - PROCESS | 1658 | 2017-03-07 09:38:31.196 plugin-container[1659:10307] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 09:38:31 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:31 INFO - PROCESS | 1658 | 2017-03-07 09:38:31.197 plugin-container[1659:10307] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 09:38:31 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:38:31 INFO - PROCESS | 1658 | 2017-03-07 09:38:31.197 plugin-container[1659:10307] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:38:40 INFO - PROCESS | 1658 | RSS: Main: 226750464 09:38:40 INFO - PROCESS | 1658 | 09:38:43 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 09:38:44 INFO - PROCESS | 1658 | RSS: Main: 241020928 09:38:44 INFO - PROCESS | 1658 | 09:38:47 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 09:38:47 INFO - PROCESS | 1658 | RSS: Main: 249544704 09:38:47 INFO - PROCESS | 1658 | 09:38:51 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 09:38:51 INFO - PROCESS | 1658 | RSS: Main: 251863040 09:38:51 INFO - PROCESS | 1658 | 09:38:53 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 09:38:54 INFO - PROCESS | 1658 | RSS: Main: 254558208 09:38:54 INFO - PROCESS | 1658 | 09:38:56 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:38:57 INFO - PROCESS | 1658 | RSS: Main: 255008768 09:38:57 INFO - PROCESS | 1658 | 09:39:00 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 09:39:00 INFO - PROCESS | 1658 | RSS: Main: 260911104 09:39:00 INFO - PROCESS | 1658 | 09:39:04 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 09:39:05 INFO - PROCESS | 1658 | RSS: Main: 261156864 09:39:05 INFO - PROCESS | 1658 | 09:39:08 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 09:39:08 INFO - PROCESS | 1658 | RSS: Main: 261120000 09:39:08 INFO - PROCESS | 1658 | 09:39:10 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:39:11 INFO - PROCESS | 1658 | RSS: Main: 261029888 09:39:11 INFO - PROCESS | 1658 | 09:39:13 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:39:13 INFO - PROCESS | 1658 | RSS: Main: 260993024 09:39:13 INFO - PROCESS | 1658 | 09:39:16 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:39:16 INFO - PROCESS | 1658 | RSS: Main: 261324800 09:39:16 INFO - PROCESS | 1658 | 09:39:19 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:39:20 INFO - PROCESS | 1658 | RSS: Main: 261808128 09:39:20 INFO - PROCESS | 1658 | 09:39:23 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:39:24 INFO - PROCESS | 1658 | RSS: Main: 261414912 09:39:24 INFO - PROCESS | 1658 | 09:39:25 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 09:39:26 INFO - PROCESS | 1658 | RSS: Main: 263368704 09:39:26 INFO - PROCESS | 1658 | 09:39:26 INFO - PROCESS | 1658 | __start_tp_report 09:39:26 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 09:39:26 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 09:39:26 INFO - PROCESS | 1658 | |i|pagename|runs| 09:39:26 INFO - PROCESS | 1658 | |0;ai-astar;104;121;120;119;120;119;119;117;119;119 09:39:26 INFO - PROCESS | 1658 | |1;audio-beat-detection;136;112;114;146;116;123;140;127;131;117 09:39:26 INFO - PROCESS | 1658 | |2;audio-dft;194;203;206;207;211;227;202;200;202;228 09:39:26 INFO - PROCESS | 1658 | |3;audio-fft;80;65;58;87;60;61;62;61;74;61 09:39:26 INFO - PROCESS | 1658 | |4;audio-oscillator;85;71;85;76;95;103;116;80;71;85 09:39:26 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;132;128;89;89;89;104;88;108;93;88 09:39:26 INFO - PROCESS | 1658 | |6;imaging-darkroom;96;97;98;109;154;133;96;97;133;136 09:39:26 INFO - PROCESS | 1658 | |7;imaging-desaturate;92;90;90;141;143;92;91;90;95;92 09:39:26 INFO - PROCESS | 1658 | |8;json-parse-financial;67;68;68;84;100;100;79;67;68;66 09:39:26 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;56;63;62;62;62;57;56;57;57;57 09:39:26 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;76;78;75;74;111;116;110;106;69;115 09:39:26 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;139;150;151;168;153;151;164;144;205;195 09:39:26 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;173;174;177;178;178;179;173;177;172;169 09:39:26 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;67;68;70;66;68;68;65;66;66;69 09:39:26 INFO - PROCESS | 1658 | __end_tp_report 09:39:26 INFO - PROCESS | 1658 | __start_cc_report 09:39:26 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1025 09:39:26 INFO - PROCESS | 1658 | __end_cc_report 09:39:26 INFO - PROCESS | 1658 | __startTimestamp1488908366007__endTimestamp 09:39:26 INFO - PROCESS | 1658 | 09:39:26 INFO - PROCESS | 1658 | ------- Summary: start ------- 09:39:26 INFO - PROCESS | 1658 | Number of tests: 14 09:39:26 INFO - PROCESS | 1658 | 09:39:26 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:117.70 Median:119.00 stddev:4.92 (4.1%) stddev-sans-first:1.09 09:39:26 INFO - PROCESS | 1658 | Values: 104.0 121.0 120.0 119.0 120.0 119.0 119.0 117.0 119.0 119.0 09:39:26 INFO - PROCESS | 1658 | 09:39:26 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:126.20 Median:129.00 stddev:11.79 (9.1%) stddev-sans-first:11.96 09:39:26 INFO - PROCESS | 1658 | Values: 136.0 112.0 114.0 146.0 116.0 123.0 140.0 127.0 131.0 117.0 09:39:26 INFO - PROCESS | 1658 | 09:39:26 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:208.00 Median:206.50 stddev:11.22 (5.4%) stddev-sans-first:10.69 09:39:26 INFO - PROCESS | 1658 | Values: 194.0 203.0 206.0 207.0 211.0 227.0 202.0 200.0 202.0 228.0 09:39:26 INFO - PROCESS | 1658 | 09:39:26 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:66.90 Median:63.50 stddev:9.92 (15.6%) stddev-sans-first:9.32 09:39:26 INFO - PROCESS | 1658 | Values: 80.0 65.0 58.0 87.0 60.0 61.0 62.0 61.0 74.0 61.0 09:39:26 INFO - PROCESS | 1658 | 09:39:26 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:86.70 Median:82.50 stddev:14.35 (17.4%) stddev-sans-first:15.21 09:39:26 INFO - PROCESS | 1658 | Values: 85.0 71.0 85.0 76.0 95.0 103.0 116.0 80.0 71.0 85.0 09:39:26 INFO - PROCESS | 1658 | 09:39:26 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:100.80 Median:88.50 stddev:16.94 (19.1%) stddev-sans-first:13.69 09:39:26 INFO - PROCESS | 1658 | Values: 132.0 128.0 89.0 89.0 89.0 104.0 88.0 108.0 93.0 88.0 09:39:26 INFO - PROCESS | 1658 | 09:39:26 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:114.90 Median:96.00 stddev:21.87 (22.8%) stddev-sans-first:22.10 09:39:26 INFO - PROCESS | 1658 | Values: 96.0 97.0 98.0 109.0 154.0 133.0 96.0 97.0 133.0 136.0 09:39:26 INFO - PROCESS | 1658 | 09:39:26 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:101.60 Median:91.50 stddev:21.35 (23.3%) stddev-sans-first:22.36 09:39:26 INFO - PROCESS | 1658 | Values: 92.0 90.0 90.0 141.0 143.0 92.0 91.0 90.0 95.0 92.0 09:39:26 INFO - PROCESS | 1658 | 09:39:26 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:76.70 Median:68.00 stddev:13.64 (20.1%) stddev-sans-first:14.01 09:39:26 INFO - PROCESS | 1658 | Values: 67.0 68.0 68.0 84.0 100.0 100.0 79.0 67.0 68.0 66.0 09:39:26 INFO - PROCESS | 1658 | 09:39:26 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:58.90 Median:59.50 stddev:2.92 (4.9%) stddev-sans-first:2.91 09:39:26 INFO - PROCESS | 1658 | Values: 56.0 63.0 62.0 62.0 62.0 57.0 56.0 57.0 57.0 57.0 09:39:26 INFO - PROCESS | 1658 | 09:39:26 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:93.00 Median:71.50 stddev:19.92 (27.9%) stddev-sans-first:20.15 09:39:26 INFO - PROCESS | 1658 | Values: 76.0 78.0 75.0 74.0 111.0 116.0 110.0 106.0 69.0 115.0 09:39:26 INFO - PROCESS | 1658 | 09:39:26 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:162.00 Median:158.50 stddev:21.85 (13.8%) stddev-sans-first:21.54 09:39:26 INFO - PROCESS | 1658 | Values: 139.0 150.0 151.0 168.0 153.0 151.0 164.0 144.0 205.0 195.0 09:39:26 INFO - PROCESS | 1658 | 09:39:26 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.00 Median:177.00 stddev:3.27 (1.8%) stddev-sans-first:3.38 09:39:26 INFO - PROCESS | 1658 | Values: 173.0 174.0 177.0 178.0 178.0 179.0 173.0 177.0 172.0 169.0 09:39:26 INFO - PROCESS | 1658 | 09:39:26 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:68.00 stddev:1.57 (2.3%) stddev-sans-first:1.66 09:39:26 INFO - PROCESS | 1658 | Values: 67.0 68.0 70.0 66.0 68.0 68.0 65.0 66.0 66.0 69.0 09:39:26 INFO - PROCESS | 1658 | -------- Summary: end -------- 09:39:26 INFO - PROCESS | 1658 | 09:39:27 INFO - TEST-INFO | 1658: exit 0 09:39:27 INFO - TEST-OK | kraken | took 61843ms 09:39:27 INFO - SUITE-END | took 1179s 09:39:27 INFO - Completed test suite (00:19:39) 09:39:27 INFO - javascript benchmark 09:39:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7548.65652859332, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2960.467115919134, 3467.7572569920108, 3487.4768784682296, 3496.125968507873, 3508.03529029517, 5817.621854579449, 5989.940100598993, 6007.699615019248, 6029.6080754750965, 6070.0, 5932.288125424955, 6031.427014433632, 6050.2437195350585, 6063.636181829093, 6103.398280005564, 5381.704006279663, 5394.947985142879, 5434.510894019538, 5476.123820188771, 5544.805931792388, 5356.4107948125675, 5425.782968681248, 5534.391216966131, 5572.804951826687, 5582.162675598673, 5331.466853314676, 5379.300690910157, 5471.917921231185, 5474.233607294982, 5483.479069488392, 5438.265834112383, 5462.289902312674, 5468.535174510172, 5474.589405794542, 5516.696581688006, 5417.6478528895495, 5418.024755544, 5421.945780542223, 5427.891442171174, 5448.37343705475, 5735.741891614887, 5883.970580147072, 5892.528597712173, 5897.3218079920925, 5939.67331796751, 5398.946010539923, 6192.690365481708, 6289.559730818844, 6305.8108256752375, 6340.365963403623, 6423.4861211103, 6425.710843012029, 6436.774712885027, 6452.290248072755, 6452.709628066748, 6116.021436570128, 6197.1324014637985, 6208.161898143738, 6213.1922850029205, 6235.9398902187195, 5143.7428128594165, 6297.4332310091395, 6362.395572420613, 6588.11060506835, 6903.585784852825, 6146.354632763585, 6286.811395658137, 6304.779332723333, 6329.841753956188, 6331.176946996861, 6421.51838612106, 6431.292557818636, 6455.289918109004, 6458.967705161444, 6470.12653291809, 6173.41352571505, 6325.304216536178, 6338.556301058974, 6354.809355719336, 6359.1733074700915, 6219.720112594853, 6242.250929888442, 6264.310925798159, 6352.777652782131, 6404.071409645575, 5119.10415677255, 5177.352830896138, 5178.378594568676, 5183.092958732207, 5195.740212989335, 3902.0, 4617.945014468596, 4618.699784513996, 4620.792064357112, 4625.976870115628, 5793.623414478046, 5908.172855800191, 6054.182685337423, 6100.694965251809, 6129.84675383119, 6352.110704501373, 6392.232932048184, 6396.136521569621, 6404.807855764335, 6435.646039467781, 6103.908441373383, 6116.3883611638485, 6142.201513803267, 6155.599886007395, 6158.1686472326555, 5508.173773933861, 5547.8890422192135, 5550.500454959073, 5612.494875461228, 5624.240727501817, 6491.545591808528, 6635.8009259722285, 6645.335466453509, 6647.268800431852, 6672.866542669022, 6252.656103914328, 6287.339829318039, 6372.362763723776, 6420.839479013062, 6425.742970281323, 6404.679766011774, 6433.035044743138, 6597.208334999831, 6617.900731489128, 6629.303923088007, 6309.842253943688, 6333.58831675958, 6338.714757835816, 6352.142460767922, 6354.269259035085, 5354.303940487712, 5712.657525481425, 6225.00399936008, 6256.5620406572925, 6309.779157729457, 6236.158118653923, 6301.558890877594, 6302.558820882494, 6311.305756366704, 6316.936830631635, 6286.15136956505, 6286.27707813589, 6293.590916590407, 6293.685315734287, 6314.0, 6109.602875813236, 6109.69451527413, 6188.0717892314715, 6309.432151106423, 6312.305646378986, 6248.812535623939, 6249.25008998923, 6295.433410993032, 6297.874042519215, 6317.30509643948, 6251.468625166912, 6255.906161407674, 6271.96864015677, 6280.0, 6312.021636646327], "value": 5890.055460466168, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [786.7993661616289, 804.5253300552673, 934.9672761453351, 955.3312681123216, 957.2198658093657, 37572.248555028884, 39135.804320978394, 39230.0, 39352.80323598378, 39432.0, 33264.833675831585, 35168.472472912865, 38046.85859424215, 38075.61924380755, 38169.618303816955, 8677.305815534743, 9151.405158664698, 9183.0, 9187.127222913812, 9195.72412827613, 8873.20141187293, 8969.95515022424, 8983.011868694472, 9001.95499022504, 9002.32482563807, 8971.192592666659, 8975.685850995216, 8978.147076027766, 9002.14479624438, 9002.684906028291, 7706.6917323307, 8950.910490895072, 8973.41672791273, 8973.955130224307, 8987.41581797181, 7548.773536793905, 8786.780330491725, 8838.867416988685, 8840.160184782435, 8859.601317940707, 33083.66916330854, 33224.0, 33257.0, 33321.6667833321, 33350.16624584381, 3684.8341824617687, 3704.481372607837, 3712.814359282025, 3716.182439863253, 3852.114013776819, 25016.4996700065, 25602.615960760602, 25850.224493265232, 25855.577943213117, 25864.87067564669, 2362.8109751219863, 3046.8628911699025, 3136.702013308732, 3176.475881479553, 3185.394774992744, 307648.0, 307822.0, 310297.0, 310695.0, 311175.0, 36209.27581448356, 36907.26185476276, 40964.97587560335, 41823.7908810454, 41910.371344429856, 36679.633203667625, 44406.77796610996, 44740.0, 44898.77550612226, 45017.774911125234, 12712.237265763899, 12765.489380424864, 12812.0, 12815.935920320526, 12834.358282086045, 10740.409277489656, 10810.783784324427, 10850.186236032328, 11025.173112016631, 11053.613123540797, 11789.174757767045, 11825.763484730258, 11827.881721182677, 11846.526138954518, 11926.940365298118, 4842.0, 5795.304563452329, 5798.623089499253, 5815.767369305265, 5828.6211396259105, 1167.521316260346, 1169.3159501691437, 1174.4597485156753, 1176.6999415149082, 1219.2136072233359, 27448.313792154957, 28568.71431285702, 28767.280817979718, 29094.85452572766, 29198.854005729834, 1018.251585084962, 1046.529061922138, 1053.8208504554243, 1071.0200166847249, 1091.6070214722693, 11841.822372664417, 12895.548655797002, 13565.864341356657, 13602.931985340208, 13868.375923083486, 441289.0, 444809.0, 445055.0, 445594.0, 445739.7713011414, 45773.77113114413, 45829.54170458319, 45873.311900321525, 45893.5410645896, 46001.84995375075, 1348.8516263211038, 1394.8953828462907, 1400.859914008591, 1411.5271384086445, 1416.0, 22617.5476490466, 23809.40478987402, 25724.485510290062, 25922.87038564795, 26022.0, 39624.415023399895, 39829.40255896222, 39952.4007139899, 39983.80008099941, 39995.600043999184, 432141.0, 434950.0, 437512.0, 438059.0, 439000.0, 22727.86360681926, 23151.073957041528, 27361.905523778827, 27830.025949091683, 28264.71735282621, 36492.45261321029, 36571.81714091413, 36902.446463302535, 37374.87875363744, 37393.81303093576, 31149.532757009118, 31286.843565782026, 31311.686883131788, 32031.0, 32040.198995024377, 26037.0, 27431.725682742916, 32763.017109486755, 33738.831305844295, 34608.30783384268, 31538.684613153575, 33229.83385083059, 33280.833595831864, 33300.83447079341, 33416.66583334136, 25371.238862834143, 27475.587866181624, 29042.419151617272, 29575.704242958156, 29659.96190133335, 21260.893695531424, 22635.0, 23023.309300721005, 23281.650775238046, 23302.76697233006], "value": 17293.293450144873, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.51084395095245, 394.9091708906951, 410.8336123869833, 411.6747769262283, 439.1743522178304, 15090.622734431645, 19837.206511739532, 20525.692114618294, 21177.68233476499, 21232.469188270283, 20919.581608367862, 21021.0, 22428.439289017784, 22446.32661020168, 22759.431014224654, 6334.588251763644, 6685.364890335435, 6685.598864068172, 6694.397504224618, 6715.32846715327, 6473.320301368359, 6625.668716564177, 6712.765053223138, 6752.0, 6830.658467076626, 6819.624920629363, 6824.0446337512785, 6839.521233513656, 6840.179178498563, 6864.7940561783225, 6428.164338635971, 6542.934570654304, 6615.503837212228, 6621.536492445531, 6635.767748128841, 6349.428551430347, 6382.840428989266, 6385.882470567679, 6446.806595802133, 6513.055606936968, 23441.0, 24779.752202478106, 25867.22398328053, 26533.867330663415, 26838.5974210387, 2804.803663743539, 2848.8148270362362, 2866.4123854609757, 2886.927826804326, 2917.920369463291, 15539.689206215926, 15969.201539922957, 16021.187008079021, 16166.75749863753, 16594.170291485378, 1752.4567384110967, 2021.304028722746, 2055.8972051397373, 2106.6629339305637, 2128.9574208515896, 150280.0, 157432.0, 157433.21283393624, 159126.0, 161289.0, 20049.498762530762, 20561.869097199797, 21076.73539587599, 21122.788772112388, 21384.251551195634, 31681.84159079236, 31711.36577268488, 32099.0, 32126.678733212837, 41002.1799564007, 11319.490622921989, 11490.080793536665, 11659.417029148508, 11710.648680539598, 11900.940495297467, 8436.325093992466, 8516.148385161434, 8616.095309992486, 9458.33791634579, 9503.952480237554, 9065.456072635663, 9570.0, 11839.940800295943, 11903.0, 11911.523539058513, 4441.400410944481, 4548.953740639671, 4615.676902616785, 4987.501249875056, 5005.249212618136, 872.9694460693845, 959.6545243712258, 960.846264597675, 971.5482300730195, 1005.9547320370601, 18648.160832762558, 19329.80670193308, 19693.50766230827, 21072.367828965154, 21096.367108986757, 932.7015355086443, 945.6264775413673, 973.1339108193713, 973.9220862330997, 974.0843607009389, 10800.567977280822, 10912.181586381053, 10930.672079837617, 10964.835527467094, 10982.286151400134, 351158.0, 380673.0, 458620.0, 557626.0, 558874.0, 74883.25116748763, 146047.2697636526, 148509.0, 150468.247658761, 152515.0, 1205.3129716061935, 1205.8854408831146, 1228.0912125027594, 1229.1518851992275, 1235.5860786636397, 17711.55721106931, 18236.723429359816, 18308.908455457637, 18353.0, 18359.81640183581, 49931.500684994135, 50853.745731271105, 56153.43846561482, 56421.43578564326, 61974.38025619686, 78855.21144788478, 79562.0, 80340.99147521358, 80407.79388309296, 80768.0, 9474.478903660365, 11399.316041037564, 11742.060635148997, 11798.646040618794, 11851.940740296242, 10997.790243073096, 11184.0, 11995.880041199825, 12955.0, 13397.263150526815, 10235.94882025615, 10664.626738064131, 11390.886091138982, 11405.543778248777, 11756.23584467007, 10901.073408760345, 10909.39998300101, 11021.944890275498, 11251.437428128724, 11330.943345283551, 9950.651727189514, 10445.529951152064, 11518.596849110241, 11693.590724324948, 11847.170698051053, 9913.851292230767, 11376.0330371916, 11447.427628618703, 11460.255083419552, 11786.410679466164, 11247.775044499227, 11472.598459053894, 11514.88485115138, 11627.76744465089, 11694.941525292319], "value": 11322.993836206586, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1407.4088882669278, 1480.4744315767896, 1485.9108453492784, 1509.7207016701911, 1515.0152400939387, 8246.299064579503, 9565.760855978604, 10463.895361046396, 11184.664460066191, 11190.0, 9545.093216144473, 9810.705678829647, 9837.60649574018, 9848.704538863827, 9868.013198680128, 2327.7555856635063, 2370.810335173182, 2371.905123795055, 2374.4538756086113, 2376.3346263046374, 2074.305107788885, 2245.2815099168274, 2310.4223944013997, 2349.859008459498, 2355.5759963206615, 2159.341400872733, 2168.8938641292975, 2302.5049614332916, 2331.335569362732, 2377.5958087125146, 2290.541891621682, 2301.1945818963313, 2304.135949019118, 2305.4351683837567, 2313.7339205991275, 2290.5533420982915, 2332.521833024243, 2333.0317918063984, 2334.6031174700342, 2360.3155085025323, 9377.718668439958, 10945.507452164671, 10991.285566438157, 11005.119590432747, 11008.889911100947, 5749.568782341341, 5919.467247947706, 5928.911066333965, 5935.643861368331, 5939.821805345803, 3263.1189578813587, 3410.4390546467666, 3558.946615800765, 3562.6615471530163, 3617.674409303149, 1141.161246483842, 1190.815423609342, 1215.574548907884, 1252.4927404401233, 1253.166644181618, 14919.2540372982, 15025.323860426308, 15081.924590377088, 15083.095014299177, 15115.31981060855, 4042.312806822847, 4706.435227772689, 4901.9019619608125, 4947.950520494821, 5005.199168133081, 12507.93746031264, 12570.1200915935, 12574.43415046325, 12611.495540178474, 12620.242785432903, 5847.766089356463, 5900.996830538818, 5910.202122713465, 5926.962781513218, 5952.285725712855, 4517.051419201939, 4923.581495572845, 5132.666376685504, 5174.534291913746, 5257.395399529027, 3933.1347103637154, 4028.234635419261, 4060.5330387005874, 4100.138970816162, 4102.630763231324, 1127.7293449572044, 1132.9943350283195, 1136.170595465315, 1144.347721798567, 1146.7935771561033, 871.934604904622, 930.4045410937006, 959.6641175588488, 967.1295833749682, 970.0929630795229, 6429.967850160813, 6722.529422940347, 6755.662216889136, 6827.317268273133, 6862.794116176523, 985.3595163144014, 997.0, 1007.7379881230933, 1046.1212581431635, 1046.7383154211448, 4159.625633692982, 4202.453681021509, 4256.914861702749, 4402.119576084796, 4421.447319085114, 12686.619401417973, 13093.345332733325, 13112.0, 13113.0, 13124.737505250032, 12046.578369757017, 12480.500779968881, 12780.488780448864, 12783.16909400886, 13019.609411717664, 9875.703728888002, 9994.100530952395, 10111.949440252753, 10127.949360253151, 10173.389596624227, 5657.632253903483, 6535.607863528204, 6877.1747390313485, 6963.7562685305775, 7027.754028608974, 11938.28370297785, 11952.940235298767, 12000.339981300764, 12063.939680301542, 12085.577004805142, 12102.878971210175, 12190.390480475764, 12211.328376939353, 12314.014878809852, 12332.506699732268, 7694.0, 8902.15429534219, 9115.134062264075, 9187.494687791968, 9303.581338839904, 10052.0, 10166.542505587415, 10321.329113607591, 10356.896431035895, 10380.065794078268, 8965.282777377664, 9039.593218305323, 9196.26429885621, 9213.493257870881, 9265.814683706423, 8675.219230269306, 8807.779805504657, 8818.56919039211, 8951.462912225288, 8965.417247878868, 7772.222777722409, 7782.143964164051, 7844.764657060297, 7903.723369682033, 7913.920860791319, 4953.430355509163, 5267.3152490176035, 5616.297962754656, 5624.943750562442, 5651.886962260814, 5830.4461076197695, 5861.3845546217035, 6017.458428741435, 6081.148639190428, 6200.224971878515], "value": 5078.619782966834, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.5645209610737, 354.50192479566203, 366.84626848561277, 366.919277758893, 380.19019488489505, 4404.537523560028, 5066.594672426207, 5096.5667918226945, 5353.544948679362, 5388.410418926416, 5082.84751457456, 5190.584753219743, 5194.610404219689, 5203.245529398235, 5227.843164705056, 3874.1476875087437, 4189.497260328765, 4329.631981281595, 4339.067100573376, 4340.804663790122, 4181.770002649853, 4321.937378863874, 4334.5448727883595, 4362.912741745163, 4379.189849877767, 4208.158368326332, 4224.049588842501, 4255.319148936171, 4256.382824490447, 4272.743635381871, 4275.593818587229, 4283.879479657589, 4284.164587905359, 4286.807093680776, 4358.259095953664, 3315.0220684898013, 3973.5033120859894, 3983.3626619740703, 4001.479437813612, 4011.83952641894, 3823.9235215295816, 4032.4152997815154, 4276.978615106936, 4704.388429504143, 5069.771860266297, 4285.285885645735, 4463.469578377949, 4645.304463870697, 4807.278908163768, 4987.47631498691, 5143.228515722672, 5486.91769623456, 5605.635633683838, 5607.775688972436, 5634.450526105313, 3925.4700615417123, 3997.6801855851463, 4224.957750422487, 4365.236083685342, 4434.135343607998, 4717.363155973934, 4860.0, 5457.590680698963, 5851.435880013596, 5951.732172052268, 4346.913481528821, 4433.093769679004, 4468.731876087445, 4506.639468842484, 4579.17574836526, 3961.2473630010204, 3973.4238535412205, 4085.835536871977, 4426.712263702849, 4650.255959046604, 4584.9541504585195, 4749.64377671676, 4844.95155048452, 4864.526513457921, 4987.451380348159, 4930.753462326869, 5350.759215835296, 5502.972485137629, 5555.472230138131, 5564.304461942257, 1434.6485111147836, 1534.1715473644108, 1555.992220038915, 1567.036272692286, 1575.4249698859833, 942.5428667096453, 1113.9944300278446, 1176.517627772609, 1184.6435830973487, 1190.9404529773476, 4835.661503694708, 5036.955653216155, 5196.844094677166, 5233.319668443078, 5278.683279003272, 4801.927971080437, 5448.400675925645, 5530.944690553043, 5574.554035677136, 5771.913421298684, 4544.840930567414, 4722.315264286659, 4763.785629646674, 4813.109574728686, 4876.707397556158, 2586.0, 2952.6270284317893, 2990.2972801391657, 2992.8353940533043, 2994.2215024093457, 5419.748151109303, 6000.969995149996, 6016.322736382268, 6021.548383871227, 6023.337432882379, 5430.375506816767, 5455.427180146107, 5478.534324582375, 5687.687177205211, 5769.971150144306, 5933.703314834241, 5964.254468191476, 5968.343482217039, 5983.581149319506, 6008.399160084044, 4975.0, 5131.743412829269, 5401.405845356929, 5773.87409455169, 5815.680137592473, 5001.924971125509, 5015.523525265094, 5220.399654039834, 5672.631278966854, 5813.7674493021495, 5213.973930130325, 5780.8554786129, 5837.328707198726, 5951.880962380642, 6060.363661815622, 4564.88587785308, 4577.093545598503, 4607.976960115178, 4616.515265897033, 4644.86065418038, 4997.650164488451, 5556.972215139061, 5787.627072900091, 5864.765409383577, 5879.824035192893, 4695.859124226278, 5020.648554601289, 5464.901324006994, 5511.448855114457, 5640.492355688008, 5203.33165118598, 5269.57843372537, 5309.65487243328, 5636.351819540805, 5644.463775941279, 4239.9364009540495, 5159.948400516097, 5471.534919531725, 5503.926734286776, 5505.862353441196, 5167.121589329748, 5188.455212202664, 5230.8169214077325, 5291.365036195527, 5316.308879845596, 5458.536024437967, 5511.940872815626, 5533.0, 5536.003519366553, 5569.637973531708], "value": 4228.564673147836, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.325347307214, 763.6639878453481, 763.736510903738, 796.1163108949062, 831.4595512916603, 15074.170920599363, 16162.0, 16179.271932763038, 16195.595110122224, 16197.35210591578, 14953.0, 15110.0, 15127.394904203818, 15163.924180379083, 15169.317380717866, 897.1477096758073, 915.8671992561075, 939.3706216834737, 940.1397721085233, 944.4522177137244, 928.299134153713, 933.020328654913, 943.5093751249346, 944.504135328951, 944.617429940875, 856.4647095565272, 1000.3797645459825, 1004.75383531035, 1005.5324274212489, 1006.9446180460071, 992.9602815887356, 1000.9099181073666, 1001.0, 1001.5042553935775, 1008.1531513528598, 738.5605564689001, 740.7111226621568, 743.2938708227206, 745.3627148787817, 746.2761121711931, 16124.193790310437, 16333.428330008393, 16346.509604711877, 16841.747373789403, 16978.660426791394, 14003.019788614802, 14050.086744361584, 14066.71866562663, 14111.294435278194, 14195.0, 11711.472983715754, 11780.646580602595, 11867.465964031553, 11930.343831089287, 12037.0, 3579.8300102948597, 3621.8551257949653, 3666.4133738601695, 3690.1143725505954, 3790.1282704977734, 111675.0, 114052.0, 114085.0, 114488.0, 115274.0, 9516.666916657883, 10133.240006999504, 10486.0, 10874.293170943864, 11107.83338249927, 21173.68239476409, 21656.0, 21757.782422175893, 21774.45563860916, 21823.563528729337, 7285.585149152737, 7350.889736653954, 7460.85078298431, 7519.849603008019, 7583.27958843909, 4211.536730959592, 4250.851220207278, 4256.659467242675, 4290.871273861727, 4352.978235108804, 2789.27478855499, 3301.224212310105, 3371.713404360608, 3383.746219033533, 3387.7628566000635, 514.7709269375167, 524.8976449592369, 613.9225658968571, 614.2046050364788, 618.7617767159586, 598.9940100599025, 632.9082283069022, 648.6497291462651, 650.9121268628675, 661.272600139852, 7347.971284020135, 8200.794980125545, 9586.429677117518, 9803.656872009446, 9870.358426702385, 587.8530367408148, 650.440621065883, 650.7754824585511, 658.2101478226148, 663.1478550063146, 4395.7142785718825, 4548.749818759934, 4554.0, 4573.222552166139, 4594.425696787902, 85069.72395414073, 89356.5532172335, 101818.0, 102582.0, 103371.44942825864, 82822.0, 88764.0, 90299.1940161193, 90499.54750226339, 90672.29285510805, 74367.25632743712, 74799.62600186965, 75231.0, 75579.62210189024, 76012.0996975069, 9004.234640055669, 9081.818363632558, 9125.361224714206, 9141.222996045411, 9171.0, 31064.0, 46222.53777462182, 47853.80365490891, 49419.50580494149, 49500.75249623729, 65577.0, 67982.3201767976, 69086.0, 69148.0, 69508.65245673739, 10554.225704614708, 11015.339079655247, 11030.779384412428, 12762.361881906054, 13169.0, 12593.433295501542, 14506.638015509328, 14867.702645947236, 15290.541283761506, 15447.768283475532, 11583.710407239887, 13651.453941842215, 13743.450261989408, 13931.58205253844, 14184.43227614337, 13703.45186192541, 13841.785314349656, 13929.791053134008, 14071.577852664437, 14327.283635818376, 13414.12808167466, 13495.055346125677, 13513.39189736484, 13547.254900980146, 14484.203368814817, 10772.192085593768, 10868.271786125079, 11025.519258166849, 11235.606753763579, 12100.334481603244, 8392.87410688828, 9544.33189676716, 10057.044580764816, 11337.489812958273, 11339.0], "value": 7469.3461804119215, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1555.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 121.0, 120.0, 119.0, 120.0, 119.0, 119.0, 117.0, 119.0, 119.0], "value": 117.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 112.0, 114.0, 146.0, 116.0, 123.0, 140.0, 127.0, 131.0, 117.0], "value": 126.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 203.0, 206.0, 207.0, 211.0, 227.0, 202.0, 200.0, 202.0, 228.0], "value": 208.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 65.0, 58.0, 87.0, 60.0, 61.0, 62.0, 61.0, 74.0, 61.0], "value": 66.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 71.0, 85.0, 76.0, 95.0, 103.0, 116.0, 80.0, 71.0, 85.0], "value": 86.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 128.0, 89.0, 89.0, 89.0, 104.0, 88.0, 108.0, 93.0, 88.0], "value": 100.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 98.0, 109.0, 154.0, 133.0, 96.0, 97.0, 133.0, 136.0], "value": 114.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 90.0, 141.0, 143.0, 92.0, 91.0, 90.0, 95.0, 92.0], "value": 101.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 84.0, 100.0, 100.0, 79.0, 67.0, 68.0, 66.0], "value": 76.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 63.0, 62.0, 62.0, 62.0, 57.0, 56.0, 57.0, 57.0, 57.0], "value": 58.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 78.0, 75.0, 74.0, 111.0, 116.0, 110.0, 106.0, 69.0, 115.0], "value": 93.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 150.0, 151.0, 168.0, 153.0, 151.0, 164.0, 144.0, 205.0, 195.0], "value": 162.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 174.0, 177.0, 178.0, 178.0, 179.0, 173.0, 177.0, 172.0, 169.0], "value": 175.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 70.0, 66.0, 68.0, 68.0, 65.0, 66.0, 66.0, 69.0], "value": 67.3, "unit": "score"}]}]} 09:39:27 INFO - Return code: 0 09:39:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:39:27 ERROR - # TBPL SUCCESS # 09:39:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:39:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:39:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:39:27 INFO - 2017-03-07 09:39:27.361 screenresolution[1663:10698] starting screenresolution argv=screenresolution get 09:39:27 INFO - 2017-03-07 09:39:27.364 screenresolution[1663:10698] Display 0: 1600x1200x32@60 09:39:27 INFO - 2017-03-07 09:39:27.370 screenresolution[1664:10701] starting screenresolution argv=screenresolution list 09:39:27 INFO - Available Modes on Display 0 09:39:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:39:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:39:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:39:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:39:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:39:27 INFO - Intel Iris: 09:39:27 INFO - Chipset Model: Intel Iris 09:39:27 INFO - Type: GPU 09:39:27 INFO - Bus: Built-In 09:39:27 INFO - VRAM (Dynamic, Max): 1536 MB 09:39:27 INFO - Vendor: Intel (0x8086) 09:39:27 INFO - Device ID: 0x0a2e 09:39:27 INFO - Revision ID: 0x0009 09:39:27 INFO - Displays: 09:39:27 INFO - Display: 09:39:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:39:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:39:27 INFO - Display Serial Number: 1600x1200 60 09:39:27 INFO - Main Display: Yes 09:39:27 INFO - Mirror: Off 09:39:27 INFO - Online: Yes 09:39:27 INFO - Rotation: Supported 09:39:27 INFO - Return code: 0 09:39:27 INFO - Running post-action listener: _resource_record_post_action 09:39:27 INFO - [mozharness: 2017-03-07 17:39:27.621130Z] Finished run-tests step (success) 09:39:27 INFO - Running post-run listener: _resource_record_post_run 09:39:27 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 38783488; Write bytes: 516969472; Read time: 251; Write time: 1635 09:39:27 INFO - TinderboxPrint: CPU usage
54.7% 09:39:27 INFO - TinderboxPrint: I/O read bytes / time
38,783,488 / 251 09:39:27 INFO - TinderboxPrint: I/O write bytes / time
516,969,472 / 1,635 09:39:27 INFO - TinderboxPrint: CPU idle
2,172.9 (45.3%) 09:39:27 INFO - TinderboxPrint: CPU system
965.9 (20.1%) 09:39:27 INFO - TinderboxPrint: CPU user
1,662.9 (34.6%) 09:39:27 INFO - TinderboxPrint: Swap in / out
249,294,848 / 0 09:39:27 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 202615296; Write bytes: 203261952; Read time: 15849; Write time: 454 09:39:27 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 37820928; Write bytes: 303799296; Read time: 238; Write time: 1152 09:39:28 INFO - Running post-run listener: _upload_blobber_files 09:39:28 INFO - Blob upload gear active. 09:39:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:39:28 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:39:28 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'] 09:39:28 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 09:39:28 INFO - (blobuploader) - INFO - Open directory for files ... 09:39:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:39:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:39:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:39:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:39:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:39:29 INFO - (blobuploader) - INFO - Done attempting. 09:39:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:39:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:39:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:39:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:39:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:39:31 INFO - (blobuploader) - INFO - Done attempting. 09:39:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:39:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:39:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:39:32 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:39:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:39:32 INFO - (blobuploader) - INFO - Done attempting. 09:39:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:39:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:39:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:39:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:39:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:39:34 INFO - (blobuploader) - INFO - Done attempting. 09:39:34 INFO - (blobuploader) - INFO - Iteration through files over. 09:39:34 INFO - Return code: 0 09:39:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:39:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:39:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d39e7368e4ae9787adafa17cb550bc1d495d8e2319314e480233abebb7db1502fa5bd731a811b566223fc31bd166ed6433594e6bc97f58833faf2d7a7e57773", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/862e0296de6451599296f3911878aee213394c089ac9484f0b54c2ee20dc7771586bb5946c86278a7ac0a27bb920e7f2c651682cc03e176b89e4ceba85ddc73b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ce6994c4e651e0ce067993dff115f13d13fd8effadd178ad5a64e2561dee42ff4ccc22aeac183d932a8a13dda0c5653ba408de003e3e215fec9b0e956fde550", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/902cf6efe6caf1d45029b1516a938ca53057f358e30611fb97e8ebf03236f45954741ad063fcaf4cfbe4fa9de78a09c9d4498ecb2219a4424badace34d638802"} 09:39:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:39:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:39:34 INFO - Contents: 09:39:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d39e7368e4ae9787adafa17cb550bc1d495d8e2319314e480233abebb7db1502fa5bd731a811b566223fc31bd166ed6433594e6bc97f58833faf2d7a7e57773", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/862e0296de6451599296f3911878aee213394c089ac9484f0b54c2ee20dc7771586bb5946c86278a7ac0a27bb920e7f2c651682cc03e176b89e4ceba85ddc73b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ce6994c4e651e0ce067993dff115f13d13fd8effadd178ad5a64e2561dee42ff4ccc22aeac183d932a8a13dda0c5653ba408de003e3e215fec9b0e956fde550", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/902cf6efe6caf1d45029b1516a938ca53057f358e30611fb97e8ebf03236f45954741ad063fcaf4cfbe4fa9de78a09c9d4498ecb2219a4424badace34d638802"} 09:39:34 INFO - Running post-run listener: copy_logs_to_upload_dir 09:39:34 INFO - Copying logs to upload dir... 09:39:34 INFO - mkdir: /builds/slave/test/build/upload/logs 09:39:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.927423 ========= master_lag: 0.81 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-03-07 09:39:35.205333) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-07 09:39:35.209478) ========= 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.nvbCUjpVfs/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.rWlV3GCbjn/Listeners TMPDIR=/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d39e7368e4ae9787adafa17cb550bc1d495d8e2319314e480233abebb7db1502fa5bd731a811b566223fc31bd166ed6433594e6bc97f58833faf2d7a7e57773", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/862e0296de6451599296f3911878aee213394c089ac9484f0b54c2ee20dc7771586bb5946c86278a7ac0a27bb920e7f2c651682cc03e176b89e4ceba85ddc73b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ce6994c4e651e0ce067993dff115f13d13fd8effadd178ad5a64e2561dee42ff4ccc22aeac183d932a8a13dda0c5653ba408de003e3e215fec9b0e956fde550", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/902cf6efe6caf1d45029b1516a938ca53057f358e30611fb97e8ebf03236f45954741ad063fcaf4cfbe4fa9de78a09c9d4498ecb2219a4424badace34d638802"} build_url:https://queue.taskcluster.net/v1/task/eYuBMkf_S6G_o_oLtNs0Sw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011245 build_url: 'https://queue.taskcluster.net/v1/task/eYuBMkf_S6G_o_oLtNs0Sw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d39e7368e4ae9787adafa17cb550bc1d495d8e2319314e480233abebb7db1502fa5bd731a811b566223fc31bd166ed6433594e6bc97f58833faf2d7a7e57773", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/862e0296de6451599296f3911878aee213394c089ac9484f0b54c2ee20dc7771586bb5946c86278a7ac0a27bb920e7f2c651682cc03e176b89e4ceba85ddc73b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ce6994c4e651e0ce067993dff115f13d13fd8effadd178ad5a64e2561dee42ff4ccc22aeac183d932a8a13dda0c5653ba408de003e3e215fec9b0e956fde550", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/902cf6efe6caf1d45029b1516a938ca53057f358e30611fb97e8ebf03236f45954741ad063fcaf4cfbe4fa9de78a09c9d4498ecb2219a4424badace34d638802"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-07 09:39:35.249913) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 09:39:35.250350) ========= 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.nvbCUjpVfs/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.rWlV3GCbjn/Listeners TMPDIR=/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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.005014 ========= master_lag: 0.24 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-07 09:39:35.495488) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-07 09:39:35.495852) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-07 09:39:35.496278) ========= ========= Total master_lag: 1.16 =========