builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0074 starttime: 1491440453.72 results: success (0) buildid: 20170405162320 builduid: f8799ddf30f949c9b40aa96eaa01242d revision: f09f78db26a594b76139cee19a2487f9509d92ee ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-05 18:00:53.718436) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-05 18:00:53.718918) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-05 18:00:53.719203) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-05 18:00:53.746033) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 18:00:53.746402) ========= 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.tvmDRCYkNV/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.P0aTKGj5da/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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-04-05 18:00:53-- 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% 28.1M=0s 2017-04-05 18:00:53 (28.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.143796 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 18:00:53.914495) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 18:00:53.914812) ========= 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.tvmDRCYkNV/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.P0aTKGj5da/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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.029080 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 18:00:53.966032) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-05 18:00:53.966633) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f09f78db26a594b76139cee19a2487f9509d92ee --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f09f78db26a594b76139cee19a2487f9509d92ee --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tvmDRCYkNV/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.P0aTKGj5da/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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-04-05 18:00:54,066 truncating revision to first 12 chars 2017-04-05 18:00:54,067 Setting DEBUG logging. 2017-04-05 18:00:54,067 attempt 1/10 2017-04-05 18:00:54,067 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f09f78db26a5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-05 18:00:55,806 unpacking tar archive at: mozilla-aurora-f09f78db26a5/testing/mozharness/ program finished with exit code 0 elapsedTime=2.010550 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-05 18:00:56.001275) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-05 18:00:56.001607) ========= script_repo_revision: f09f78db26a594b76139cee19a2487f9509d92ee ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-05 18:00:56.002011) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-05 18:00:56.002283) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-05 18:00:56.022244) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-05 18:00:56.022618) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tvmDRCYkNV/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.P0aTKGj5da/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 18:00:56 INFO - MultiFileLogger online at 20170405 18:00:56 in /builds/slave/test 18:00:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 18:00:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:00:56 INFO - {'append_to_log': False, 18:00:56 INFO - 'base_work_dir': '/builds/slave/test', 18:00:56 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:00:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:00:56 INFO - 'branch': 'Mozilla-Aurora', 18:00:56 INFO - 'buildbot_json_path': 'buildprops.json', 18:00:56 INFO - 'config_files': ('talos/mac_config.py',), 18:00:56 INFO - 'default_actions': ('clobber', 18:00:56 INFO - 'read-buildbot-config', 18:00:56 INFO - 'download-and-extract', 18:00:56 INFO - 'populate-webroot', 18:00:56 INFO - 'create-virtualenv', 18:00:56 INFO - 'install', 18:00:56 INFO - 'run-tests'), 18:00:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:00:56 INFO - 'download_minidump_stackwalk': True, 18:00:56 INFO - 'download_symbols': 'ondemand', 18:00:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:00:56 INFO - 'tooltool.py': '/tools/tooltool.py', 18:00:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:00:56 INFO - '/tools/misc-python/virtualenv.py')}, 18:00:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:00:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:00:56 INFO - 'gecko_profile': False, 18:00:56 INFO - 'gecko_profile_interval': 0, 18:00:56 INFO - 'installer_path': 'installer.exe', 18:00:56 INFO - 'log_level': 'info', 18:00:56 INFO - 'log_name': 'talos', 18:00:56 INFO - 'log_to_console': True, 18:00:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:00:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:00:56 INFO - 'opt_config_files': (), 18:00:56 INFO - 'pip_index': False, 18:00:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:00:56 INFO - 'cmd': ('bash', 18:00:56 INFO - '-c', 18:00:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:00:56 INFO - 'enabled': True, 18:00:56 INFO - 'halt_on_failure': False, 18:00:56 INFO - 'name': 'check_screen_resolution'},), 18:00:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:00:56 INFO - 'cmd': ('bash', 18:00:56 INFO - '-c', 18:00:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:00:56 INFO - 'enabled': True, 18:00:56 INFO - 'halt_on_failure': False, 18:00:56 INFO - 'name': 'check_screen_resolution'},), 18:00:56 INFO - 'run_cmd_checks_enabled': True, 18:00:56 INFO - 'suite': 'dromaeojs-e10s', 18:00:56 INFO - 'system_bits': '32', 18:00:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:00:56 INFO - 'title': 't-yosemite-r7-0074', 18:00:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:00:56 INFO - 'use_talos_json': True, 18:00:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:00:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:00:56 INFO - 'work_dir': 'build'} 18:00:56 INFO - [mozharness: 2017-04-06 01:00:56.236430Z] Running clobber step. 18:00:56 INFO - Running pre-action listener: _resource_record_pre_action 18:00:56 INFO - Running main action method: clobber 18:00:56 INFO - rmtree: /builds/slave/test/build 18:00:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:00:58 INFO - Running post-action listener: _resource_record_post_action 18:00:58 INFO - [mozharness: 2017-04-06 01:00:58.773792Z] Finished clobber step (success) 18:00:58 INFO - [mozharness: 2017-04-06 01:00:58.773918Z] Running read-buildbot-config step. 18:00:58 INFO - Running pre-action listener: _resource_record_pre_action 18:00:58 INFO - Running main action method: read_buildbot_config 18:00:58 INFO - Using buildbot properties: 18:00:58 INFO - { 18:00:58 INFO - "project": "", 18:00:58 INFO - "product": "firefox", 18:00:58 INFO - "script_repo_revision": "production", 18:00:58 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 18:00:58 INFO - "repository": "", 18:00:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 18:00:58 INFO - "buildid": "20170405162320", 18:00:58 INFO - "pgo_build": "False", 18:00:58 INFO - "basedir": "/builds/slave/test", 18:00:58 INFO - "buildnumber": 100, 18:00:58 INFO - "slavename": "t-yosemite-r7-0074", 18:00:58 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 18:00:58 INFO - "platform": "yosemite_r7", 18:00:58 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 18:00:58 INFO - "branch": "mozilla-aurora", 18:00:58 INFO - "repo_path": "releases/mozilla-aurora", 18:00:58 INFO - "revision": "f09f78db26a594b76139cee19a2487f9509d92ee", 18:00:58 INFO - "stage_platform": "macosx64", 18:00:58 INFO - "builduid": "f8799ddf30f949c9b40aa96eaa01242d", 18:00:58 INFO - "slavebuilddir": "test" 18:00:58 INFO - } 18:00:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/HnSS3-I-QaKXnhnl0lmolg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 18:00:58 INFO - Running post-action listener: _resource_record_post_action 18:00:58 INFO - [mozharness: 2017-04-06 01:00:58.777110Z] Finished read-buildbot-config step (success) 18:00:58 INFO - [mozharness: 2017-04-06 01:00:58.777226Z] Running download-and-extract step. 18:00:58 INFO - Running pre-action listener: _resource_record_pre_action 18:00:58 INFO - Running main action method: download_and_extract 18:00:58 INFO - mkdir: /builds/slave/test/build/tests 18:00:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:00:58 INFO - trying https://queue.taskcluster.net/v1/task/HnSS3-I-QaKXnhnl0lmolg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 18:00:58 INFO - Downloading https://queue.taskcluster.net/v1/task/HnSS3-I-QaKXnhnl0lmolg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 18:00:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HnSS3-I-QaKXnhnl0lmolg/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 18:01:00 INFO - Downloaded 1199 bytes. 18:01:00 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 18:01:00 INFO - Using the following test package requirements: 18:01:00 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 18:01:00 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:01:00 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 18:01:00 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:01:00 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 18:01:00 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:01:00 INFO - u'jsshell-mac.zip'], 18:01:00 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:01:00 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 18:01:00 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 18:01:00 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:01:00 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 18:01:00 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:01:00 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 18:01:00 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:01:00 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 18:01:00 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 18:01:00 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 18:01:00 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 18:01:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HnSS3-I-QaKXnhnl0lmolg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 18:01:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HnSS3-I-QaKXnhnl0lmolg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:01:00 INFO - Fetch https://queue.taskcluster.net/v1/task/HnSS3-I-QaKXnhnl0lmolg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 18:01:01 INFO - Expected file size: 51790019 18:01:01 INFO - Obtained file size: 51790019 18:01:17 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 18:01:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HnSS3-I-QaKXnhnl0lmolg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 18:01:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HnSS3-I-QaKXnhnl0lmolg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:01:17 INFO - Fetch https://queue.taskcluster.net/v1/task/HnSS3-I-QaKXnhnl0lmolg/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 18:01:19 INFO - Expected file size: 51790019 18:01:19 INFO - Obtained file size: 51790019 18:01:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HnSS3-I-QaKXnhnl0lmolg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 18:01:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HnSS3-I-QaKXnhnl0lmolg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 18:01:35 INFO - Fetch https://queue.taskcluster.net/v1/task/HnSS3-I-QaKXnhnl0lmolg/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 18:01:36 INFO - Expected file size: 14404438 18:01:36 INFO - Obtained file size: 14404438 18:01:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:36 INFO - trying https://queue.taskcluster.net/v1/task/HnSS3-I-QaKXnhnl0lmolg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 18:01:36 INFO - Downloading https://queue.taskcluster.net/v1/task/HnSS3-I-QaKXnhnl0lmolg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 18:01:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HnSS3-I-QaKXnhnl0lmolg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 18:01:38 INFO - Downloaded 74674852 bytes. 18:01:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HnSS3-I-QaKXnhnl0lmolg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 18:01:38 INFO - mkdir: /builds/slave/test/properties 18:01:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:01:38 INFO - Writing to file /builds/slave/test/properties/build_url 18:01:38 INFO - Contents: 18:01:38 INFO - build_url:https://queue.taskcluster.net/v1/task/HnSS3-I-QaKXnhnl0lmolg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 18:01:39 INFO - Running post-action listener: _resource_record_post_action 18:01:39 INFO - Running post-action listener: set_extra_try_arguments 18:01:39 INFO - [mozharness: 2017-04-06 01:01:39.398885Z] Finished download-and-extract step (success) 18:01:39 INFO - [mozharness: 2017-04-06 01:01:39.399017Z] Running populate-webroot step. 18:01:39 INFO - Running pre-action listener: _resource_record_pre_action 18:01:39 INFO - Running main action method: populate_webroot 18:01:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:01:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:01:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:01:39 INFO - '--tppagecycles', 18:01:39 INFO - '7'], 18:01:39 INFO - 'tests': ['tsvgm']}}, 18:01:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:01:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:01:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:01:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:01:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:01:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:01:39 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:01:39 INFO - 'talos_options': ['--disable-e10s'], 18:01:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:01:39 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:01:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:01:39 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:01:39 INFO - 'talos_options': ['--disable-e10s'], 18:01:39 INFO - 'tests': ['damp', 'tps']}, 18:01:39 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:01:39 INFO - 'tests': ['damp', 'tps']}, 18:01:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:01:39 INFO - 'tests': ['dromaeo_dom']}, 18:01:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:01:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:01:39 INFO - 'tests': ['basic_compositor_video']}, 18:01:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:01:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:01:39 INFO - 'tests': ['a11yr', 18:01:39 INFO - 'ts_paint', 18:01:39 INFO - 'tpaint', 18:01:39 INFO - 'sessionrestore', 18:01:39 INFO - 'sessionrestore_no_auto_restore', 18:01:39 INFO - 'tabpaint']}, 18:01:39 INFO - 'other-e10s': {'tests': ['a11yr', 18:01:39 INFO - 'ts_paint', 18:01:39 INFO - 'tpaint', 18:01:39 INFO - 'sessionrestore', 18:01:39 INFO - 'sessionrestore_no_auto_restore', 18:01:39 INFO - 'tabpaint']}, 18:01:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:01:39 INFO - 'ts_paint', 18:01:39 INFO - 'tpaint', 18:01:39 INFO - 'sessionrestore', 18:01:39 INFO - 'sessionrestore_no_auto_restore', 18:01:39 INFO - 'tabpaint']}, 18:01:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:01:39 INFO - 'ts_paint', 18:01:39 INFO - 'tpaint', 18:01:39 INFO - 'sessionrestore', 18:01:39 INFO - 'sessionrestore_no_auto_restore', 18:01:39 INFO - 'tabpaint']}, 18:01:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:01:39 INFO - 'tests': ['a11yr', 18:01:39 INFO - 'ts_paint', 18:01:39 INFO - 'tpaint', 18:01:39 INFO - 'sessionrestore', 18:01:39 INFO - 'sessionrestore_no_auto_restore', 18:01:39 INFO - 'tabpaint']}, 18:01:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:01:39 INFO - 'tests': ['a11yr', 18:01:39 INFO - 'ts_paint', 18:01:39 INFO - 'tpaint', 18:01:39 INFO - 'sessionrestore', 18:01:39 INFO - 'sessionrestore_no_auto_restore', 18:01:39 INFO - 'tabpaint']}, 18:01:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:01:39 INFO - 'tests': ['tsvgx', 18:01:39 INFO - 'tsvgr_opacity', 18:01:39 INFO - 'tart', 18:01:39 INFO - 'tscrollx', 18:01:39 INFO - 'cart', 18:01:39 INFO - 'tsvg_static']}, 18:01:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:01:39 INFO - 'tsvgr_opacity', 18:01:39 INFO - 'tart', 18:01:39 INFO - 'tscrollx', 18:01:39 INFO - 'cart', 18:01:39 INFO - 'tsvg_static']}, 18:01:39 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:01:39 INFO - 'talos_options': ['--disable-e10s'], 18:01:39 INFO - 'tests': ['tp5o']}, 18:01:39 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:01:39 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:01:39 INFO - 'talos_options': ['--disable-e10s', 18:01:39 INFO - '--xperf_path', 18:01:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:01:39 INFO - 'tests': ['tp5n']}, 18:01:39 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:01:39 INFO - 'talos_options': ['--xperf_path', 18:01:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:01:39 INFO - 'tests': ['tp5n']}}, 18:01:39 INFO - 'talos.zip': {'path': '', 18:01:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:01:39 INFO - Running post-action listener: _resource_record_post_action 18:01:39 INFO - [mozharness: 2017-04-06 01:01:39.417327Z] Finished populate-webroot step (success) 18:01:39 INFO - [mozharness: 2017-04-06 01:01:39.417466Z] Running create-virtualenv step. 18:01:39 INFO - Running pre-action listener: _resource_record_pre_action 18:01:39 INFO - Running main action method: create_virtualenv 18:01:39 INFO - Creating virtualenv /builds/slave/test/build/venv 18:01:39 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 18:01:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:01:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:01:39 INFO - Using real prefix '/tools/python27' 18:01:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:01:40 INFO - Installing distribute.............................................................................................................................................................................................done. 18:01:43 INFO - Installing pip.................done. 18:01:43 INFO - Return code: 0 18:01:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:01:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:01:43 INFO - Reading from file tmpfile_stdout 18:01:43 INFO - Output received: 18:01:43 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) 18:01:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:43 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 0x108d42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecb2d9b8b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b20d30>, '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 0x108a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b15be8>, '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.tvmDRCYkNV/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.P0aTKGj5da/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 18:01:43 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 18:01:43 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 18:01:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tvmDRCYkNV/Render', 18:01:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:43 INFO - 'HOME': '/Users/cltbld', 18:01:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:43 INFO - 'LOGNAME': 'cltbld', 18:01:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:43 INFO - 'MOZ_NO_REMOTE': '1', 18:01:43 INFO - 'NO_EM_RESTART': '1', 18:01:43 INFO - 'PAGER': '/bin/cat', 18:01:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:43 INFO - 'PWD': '/builds/slave/test', 18:01:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:43 INFO - 'SHELL': '/bin/bash', 18:01:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P0aTKGj5da/Listeners', 18:01:43 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 18:01:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:43 INFO - 'USER': 'cltbld', 18:01:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:43 INFO - 'XPC_FLAGS': '0x0', 18:01:43 INFO - 'XPC_SERVICE_NAME': '0', 18:01:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:43 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 18:01:43 INFO - Cleaning up... 18:01:43 INFO - Return code: 0 18:01:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:43 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 0x108d42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecb2d9b8b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b20d30>, '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 0x108a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b15be8>, '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.tvmDRCYkNV/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.P0aTKGj5da/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 18:01:43 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 18:01:43 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 18:01:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tvmDRCYkNV/Render', 18:01:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:43 INFO - 'HOME': '/Users/cltbld', 18:01:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:43 INFO - 'LOGNAME': 'cltbld', 18:01:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:43 INFO - 'MOZ_NO_REMOTE': '1', 18:01:43 INFO - 'NO_EM_RESTART': '1', 18:01:43 INFO - 'PAGER': '/bin/cat', 18:01:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:43 INFO - 'PWD': '/builds/slave/test', 18:01:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:43 INFO - 'SHELL': '/bin/bash', 18:01:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P0aTKGj5da/Listeners', 18:01:43 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 18:01:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:43 INFO - 'USER': 'cltbld', 18:01:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:43 INFO - 'XPC_FLAGS': '0x0', 18:01:43 INFO - 'XPC_SERVICE_NAME': '0', 18:01:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:43 INFO - Downloading/unpacking psutil>=3.1.1 18:01:43 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 18:01:43 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 18:01:43 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 18:01:43 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 18:01:43 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 18:01:43 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 18:01:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:01:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:01:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:01:47 INFO - Installing collected packages: psutil 18:01:47 INFO - Running setup.py install for psutil 18:01:47 INFO - building 'psutil._psutil_osx' extension 18:01:47 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 18:01:48 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 18:01:48 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 18:01:48 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 18:01:48 INFO - building 'psutil._psutil_posix' extension 18:01:48 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 18:01:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:01:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:01:48 INFO - ^ 18:01:48 INFO - 1 warning generated. 18:01:48 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 18:01:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:01:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:01:48 INFO - Successfully installed psutil 18:01:48 INFO - Cleaning up... 18:01:48 INFO - Return code: 0 18:01:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:01:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:01:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108d42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecb2d9b8b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b20d30>, '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 0x108a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b15be8>, '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.tvmDRCYkNV/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.P0aTKGj5da/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 18:01:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:01:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:01:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tvmDRCYkNV/Render', 18:01:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:48 INFO - 'HOME': '/Users/cltbld', 18:01:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:48 INFO - 'LOGNAME': 'cltbld', 18:01:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:48 INFO - 'MOZ_NO_REMOTE': '1', 18:01:48 INFO - 'NO_EM_RESTART': '1', 18:01:48 INFO - 'PAGER': '/bin/cat', 18:01:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:48 INFO - 'PWD': '/builds/slave/test', 18:01:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:48 INFO - 'SHELL': '/bin/bash', 18:01:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P0aTKGj5da/Listeners', 18:01:48 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 18:01:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:48 INFO - 'USER': 'cltbld', 18:01:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:48 INFO - 'XPC_FLAGS': '0x0', 18:01:48 INFO - 'XPC_SERVICE_NAME': '0', 18:01:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:01:48 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 18:01:48 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 18:01:48 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 18:01:48 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 18:01:48 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 18:01:48 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 18:01:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:01:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:01:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:01:52 INFO - Installing collected packages: mozsystemmonitor 18:01:52 INFO - Running setup.py install for mozsystemmonitor 18:01:52 INFO - Successfully installed mozsystemmonitor 18:01:52 INFO - Cleaning up... 18:01:52 INFO - Return code: 0 18:01:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:01:52 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')]} 18:01:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:52 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')]} 18:01:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:52 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 0x108d42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecb2d9b8b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b20d30>, '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 0x108a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b15be8>, '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.tvmDRCYkNV/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.P0aTKGj5da/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 18:01:52 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 18:01:52 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 18:01:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tvmDRCYkNV/Render', 18:01:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:52 INFO - 'HOME': '/Users/cltbld', 18:01:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:52 INFO - 'LOGNAME': 'cltbld', 18:01:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:52 INFO - 'MOZ_NO_REMOTE': '1', 18:01:52 INFO - 'NO_EM_RESTART': '1', 18:01:52 INFO - 'PAGER': '/bin/cat', 18:01:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:52 INFO - 'PWD': '/builds/slave/test', 18:01:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:52 INFO - 'SHELL': '/bin/bash', 18:01:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P0aTKGj5da/Listeners', 18:01:52 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 18:01:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:52 INFO - 'USER': 'cltbld', 18:01:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:52 INFO - 'XPC_FLAGS': '0x0', 18:01:52 INFO - 'XPC_SERVICE_NAME': '0', 18:01:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:52 INFO - Downloading/unpacking jsonschema==2.5.1 18:01:52 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 18:01:52 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 18:01:52 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 18:01:52 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 18:01:52 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 18:01:52 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 18:01:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:01:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:01:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:01:55 INFO - Downloading functools32-3.2.3-2.tar.gz 18:01:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:01:56 INFO - warning: no files found matching '*.txt' 18:01:56 INFO - no previously-included directories found matching 'build' 18:01:56 INFO - no previously-included directories found matching 'dist' 18:01:56 INFO - no previously-included directories found matching '.git*' 18:01:56 INFO - Installing collected packages: jsonschema, functools32 18:01:56 INFO - Running setup.py install for functools32 18:01:56 INFO - warning: no files found matching '*.txt' 18:01:56 INFO - no previously-included directories found matching 'build' 18:01:56 INFO - no previously-included directories found matching 'dist' 18:01:56 INFO - no previously-included directories found matching '.git*' 18:01:56 INFO - Successfully installed jsonschema functools32 18:01:56 INFO - Cleaning up... 18:01:56 INFO - Return code: 0 18:01:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:01:56 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')]} 18:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:56 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')]} 18:01:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:56 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 0x108d42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecb2d9b8b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b20d30>, '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 0x108a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b15be8>, '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.tvmDRCYkNV/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.P0aTKGj5da/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 18:01:56 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 18:01:56 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 18:01:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tvmDRCYkNV/Render', 18:01:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:56 INFO - 'HOME': '/Users/cltbld', 18:01:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:56 INFO - 'LOGNAME': 'cltbld', 18:01:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:56 INFO - 'MOZ_NO_REMOTE': '1', 18:01:56 INFO - 'NO_EM_RESTART': '1', 18:01:56 INFO - 'PAGER': '/bin/cat', 18:01:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:56 INFO - 'PWD': '/builds/slave/test', 18:01:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:56 INFO - 'SHELL': '/bin/bash', 18:01:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P0aTKGj5da/Listeners', 18:01:56 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 18:01:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:56 INFO - 'USER': 'cltbld', 18:01:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:56 INFO - 'XPC_FLAGS': '0x0', 18:01:56 INFO - 'XPC_SERVICE_NAME': '0', 18:01:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:01:56 INFO - Cleaning up... 18:01:56 INFO - Return code: 0 18:01:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:01:56 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')]} 18:01:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:01:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:56 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')]} 18:01:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:01:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:01:56 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 0x108d42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecb2d9b8b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b20d30>, '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 0x108a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b15be8>, '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.tvmDRCYkNV/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.P0aTKGj5da/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 18:01:56 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 18:01:56 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 18:01:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tvmDRCYkNV/Render', 18:01:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:01:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:01:56 INFO - 'HOME': '/Users/cltbld', 18:01:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:01:56 INFO - 'LOGNAME': 'cltbld', 18:01:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:01:56 INFO - 'MOZ_NO_REMOTE': '1', 18:01:56 INFO - 'NO_EM_RESTART': '1', 18:01:56 INFO - 'PAGER': '/bin/cat', 18:01:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:01:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:01:56 INFO - 'PWD': '/builds/slave/test', 18:01:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:01:56 INFO - 'SHELL': '/bin/bash', 18:01:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P0aTKGj5da/Listeners', 18:01:56 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 18:01:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:01:56 INFO - 'USER': 'cltbld', 18:01:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:01:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:01:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:01:56 INFO - 'XPC_FLAGS': '0x0', 18:01:56 INFO - 'XPC_SERVICE_NAME': '0', 18:01:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:01:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:01:56 INFO - Downloading/unpacking blobuploader==1.2.4 18:01:56 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 18:01:56 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 18:01:56 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 18:01:56 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 18:01:56 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 18:01:56 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 18:01:59 INFO - Downloading blobuploader-1.2.4.tar.gz 18:01:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:01:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:01:59 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 18:01:59 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 18:01:59 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 18:01:59 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 18:01:59 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 18:01:59 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 18:02:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:02:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:02:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:02:00 INFO - Downloading docopt-0.6.1.tar.gz 18:02:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:02:00 INFO - Installing collected packages: blobuploader, requests, docopt 18:02:00 INFO - Running setup.py install for blobuploader 18:02:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:02:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:02:00 INFO - Running setup.py install for requests 18:02:01 INFO - Running setup.py install for docopt 18:02:01 INFO - Successfully installed blobuploader requests docopt 18:02:01 INFO - Cleaning up... 18:02:01 INFO - Return code: 0 18:02:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:02:01 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')]} 18:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:01 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')]} 18:02:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:01 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 0x108d42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecb2d9b8b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b20d30>, '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 0x108a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b15be8>, '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.tvmDRCYkNV/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.P0aTKGj5da/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 18:02:01 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 18:02:01 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 18:02:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tvmDRCYkNV/Render', 18:02:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:01 INFO - 'HOME': '/Users/cltbld', 18:02:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:01 INFO - 'LOGNAME': 'cltbld', 18:02:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:01 INFO - 'MOZ_NO_REMOTE': '1', 18:02:01 INFO - 'NO_EM_RESTART': '1', 18:02:01 INFO - 'PAGER': '/bin/cat', 18:02:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:01 INFO - 'PWD': '/builds/slave/test', 18:02:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:01 INFO - 'SHELL': '/bin/bash', 18:02:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P0aTKGj5da/Listeners', 18:02:01 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 18:02:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:01 INFO - 'USER': 'cltbld', 18:02:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:01 INFO - 'XPC_FLAGS': '0x0', 18:02:01 INFO - 'XPC_SERVICE_NAME': '0', 18:02:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:02:01 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-3UJTAo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:02:02 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-9wC3CO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:02:02 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-cDr2c1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:02:02 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-dxmWzx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:02:02 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-qCPn9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:02:02 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-uVzBIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:02:02 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-0zvBVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:02:02 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-f1CUWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:02:02 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-bF5jZE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:02:02 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-kxHGr3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:02:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:02:02 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-yyDRwu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:02:03 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-v2hNTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:02:03 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-btaZ5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:02:03 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-RkYXSz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:03 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-IKQ3Kk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:02:03 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-EVdJDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:02:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:02:03 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-XkIRo5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:02:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:02:03 INFO - Running setup.py install for manifestparser 18:02:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:02:03 INFO - Running setup.py install for mozcrash 18:02:03 INFO - Running setup.py install for mozdebug 18:02:04 INFO - Running setup.py install for mozdevice 18:02:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:02:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:02:04 INFO - Running setup.py install for mozfile 18:02:04 INFO - Running setup.py install for mozhttpd 18:02:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:02:04 INFO - Running setup.py install for mozinfo 18:02:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:02:04 INFO - Running setup.py install for mozInstall 18:02:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:02:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:02:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:02:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:02:04 INFO - Running setup.py install for mozleak 18:02:05 INFO - Running setup.py install for mozlog 18:02:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:02:05 INFO - Running setup.py install for moznetwork 18:02:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:02:05 INFO - Running setup.py install for mozprocess 18:02:05 INFO - Running setup.py install for mozprofile 18:02:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:02:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:02:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:02:05 INFO - Running setup.py install for mozrunner 18:02:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:02:05 INFO - Running setup.py install for mozscreenshot 18:02:05 INFO - Running setup.py install for moztest 18:02:06 INFO - Running setup.py install for mozversion 18:02:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:02:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:02:06 INFO - Cleaning up... 18:02:06 INFO - Return code: 0 18:02:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:02: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')]} 18:02:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02: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')]} 18:02:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:06 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 0x108d42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecb2d9b8b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b20d30>, '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 0x108a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b15be8>, '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.tvmDRCYkNV/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.P0aTKGj5da/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 18:02:06 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 18:02:06 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 18:02:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tvmDRCYkNV/Render', 18:02:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:06 INFO - 'HOME': '/Users/cltbld', 18:02:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:06 INFO - 'LOGNAME': 'cltbld', 18:02:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:06 INFO - 'MOZ_NO_REMOTE': '1', 18:02:06 INFO - 'NO_EM_RESTART': '1', 18:02:06 INFO - 'PAGER': '/bin/cat', 18:02:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:06 INFO - 'PWD': '/builds/slave/test', 18:02:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:06 INFO - 'SHELL': '/bin/bash', 18:02:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P0aTKGj5da/Listeners', 18:02:06 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 18:02:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:06 INFO - 'USER': 'cltbld', 18:02:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:06 INFO - 'XPC_FLAGS': '0x0', 18:02:06 INFO - 'XPC_SERVICE_NAME': '0', 18:02:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:02:06 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-LLebEK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:02:06 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)) 18:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:02:06 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-3e1Fv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:02:06 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)) 18:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:02:06 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-yrx7b6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:02:06 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)) 18:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:02:06 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-HSmxRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:02:07 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)) 18:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:02:07 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-B61Unt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:02:07 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)) 18:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:02:07 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-GhqDwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:02:07 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)) 18:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:02:07 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-o4QJma-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:02:07 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)) 18:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:02:07 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-Y2xuD7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:02:07 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)) 18:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:02:07 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-z6Uw7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:02:07 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)) 18:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:02:07 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-t0ts0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:02:07 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)) 18:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:02:07 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-EAv963-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:02:07 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)) 18:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:02:07 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-gRQKoJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:02:07 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)) 18:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:02:07 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-WZ9gp3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:02:07 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)) 18:02:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:02:07 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-i2PwVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:02:08 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)) 18:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:08 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-QayI35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:02:08 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)) 18:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:02:08 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-6Qrr_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:02:08 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)) 18:02:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:02:08 INFO - Running setup.py (path:/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/pip-c41747-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:02:08 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)) 18:02:08 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)) 18:02:08 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)) 18:02:08 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)) 18:02:08 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)) 18:02:08 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)) 18:02:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:02:08 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 18:02:08 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 18:02:08 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 18:02:08 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 18:02:08 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 18:02:08 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 18:02:10 INFO - Downloading blessings-1.6.tar.gz 18:02:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:02:11 INFO - Installing collected packages: blessings 18:02:11 INFO - Running setup.py install for blessings 18:02:11 INFO - Successfully installed blessings 18:02:11 INFO - Cleaning up... 18:02:11 INFO - Return code: 0 18:02:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:02:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:02:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:02:11 INFO - Reading from file tmpfile_stdout 18:02:11 INFO - Current package versions: 18:02:11 INFO - blessings == 1.6 18:02:11 INFO - blobuploader == 1.2.4 18:02:11 INFO - docopt == 0.6.1 18:02:11 INFO - functools32 == 3.2.3-2 18:02:11 INFO - jsonschema == 2.5.1 18:02:11 INFO - manifestparser == 1.1 18:02:11 INFO - mozInstall == 1.12 18:02:11 INFO - mozcrash == 1.0 18:02:11 INFO - mozdebug == 0.1 18:02:11 INFO - mozdevice == 0.49 18:02:11 INFO - mozfile == 1.2 18:02:11 INFO - mozhttpd == 0.7 18:02:11 INFO - mozinfo == 0.9 18:02:11 INFO - mozleak == 0.1 18:02:11 INFO - mozlog == 3.4 18:02:11 INFO - moznetwork == 0.27 18:02:11 INFO - mozprocess == 0.25 18:02:11 INFO - mozprofile == 0.28 18:02:11 INFO - mozrunner == 6.13 18:02:11 INFO - mozscreenshot == 0.1 18:02:11 INFO - mozsystemmonitor == 0.3 18:02:11 INFO - moztest == 0.8 18:02:11 INFO - mozversion == 1.4 18:02:11 INFO - psutil == 3.1.1 18:02:11 INFO - requests == 1.2.3 18:02:11 INFO - wsgiref == 0.1.2 18:02:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:11 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 0x108d42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecb2d9b8b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b20d30>, '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 0x108a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b15be8>, '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.tvmDRCYkNV/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.P0aTKGj5da/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 18:02:11 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 18:02:11 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 18:02:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tvmDRCYkNV/Render', 18:02:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:11 INFO - 'HOME': '/Users/cltbld', 18:02:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:11 INFO - 'LOGNAME': 'cltbld', 18:02:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:11 INFO - 'MOZ_NO_REMOTE': '1', 18:02:11 INFO - 'NO_EM_RESTART': '1', 18:02:11 INFO - 'PAGER': '/bin/cat', 18:02:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:11 INFO - 'PWD': '/builds/slave/test', 18:02:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:11 INFO - 'SHELL': '/bin/bash', 18:02:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P0aTKGj5da/Listeners', 18:02:11 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 18:02:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:11 INFO - 'USER': 'cltbld', 18:02:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:11 INFO - 'XPC_FLAGS': '0x0', 18:02:11 INFO - 'XPC_SERVICE_NAME': '0', 18:02:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:11 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)) 18:02:11 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)) 18:02:11 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)) 18:02:11 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)) 18:02:11 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)) 18:02:11 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)) 18:02:11 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)) 18:02:11 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)) 18:02:11 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)) 18:02:11 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)) 18:02:11 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)) 18:02:11 INFO - Cleaning up... 18:02:11 INFO - Return code: 0 18:02:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:02:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:02:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:02:11 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 0x108d42b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d45768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fecb2d9b8b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b20d30>, '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 0x108a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b15be8>, '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.tvmDRCYkNV/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.P0aTKGj5da/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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 18:02:11 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 18:02:11 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 18:02:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tvmDRCYkNV/Render', 18:02:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:11 INFO - 'HOME': '/Users/cltbld', 18:02:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:11 INFO - 'LOGNAME': 'cltbld', 18:02:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:11 INFO - 'MOZ_NO_REMOTE': '1', 18:02:11 INFO - 'NO_EM_RESTART': '1', 18:02:11 INFO - 'PAGER': '/bin/cat', 18:02:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:11 INFO - 'PWD': '/builds/slave/test', 18:02:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:11 INFO - 'SHELL': '/bin/bash', 18:02:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P0aTKGj5da/Listeners', 18:02:11 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 18:02:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:11 INFO - 'USER': 'cltbld', 18:02:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:11 INFO - 'XPC_FLAGS': '0x0', 18:02:11 INFO - 'XPC_SERVICE_NAME': '0', 18:02:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:02:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:02:12 INFO - Cleaning up... 18:02:12 INFO - Return code: 0 18:02:12 INFO - Running post-action listener: _resource_record_post_action 18:02:12 INFO - Running post-action listener: _start_resource_monitoring 18:02:12 INFO - Starting resource monitoring. 18:02:12 INFO - [mozharness: 2017-04-06 01:02:12.183854Z] Finished create-virtualenv step (success) 18:02:12 INFO - [mozharness: 2017-04-06 01:02:12.184801Z] Running install step. 18:02:12 INFO - Running pre-action listener: _resource_record_pre_action 18:02:12 INFO - Running main action method: install 18:02:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:02:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:02:12 INFO - Reading from file tmpfile_stdout 18:02:12 INFO - Detecting whether we're running mozinstall >=1.0... 18:02:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:02:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:02:12 INFO - Reading from file tmpfile_stdout 18:02:12 INFO - Output received: 18:02:12 INFO - Usage: mozinstall [options] installer 18:02:12 INFO - Options: 18:02:12 INFO - -h, --help show this help message and exit 18:02:12 INFO - -d DEST, --destination=DEST 18:02:12 INFO - Directory to install application into. [default: 18:02:12 INFO - "/builds/slave/test"] 18:02:12 INFO - --app=APP Application being installed. [default: firefox] 18:02:12 INFO - mkdir: /builds/slave/test/build/application 18:02:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:02:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 18:02:32 INFO - Reading from file tmpfile_stdout 18:02:32 INFO - Output received: 18:02:32 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 18:02:32 INFO - Running post-action listener: _resource_record_post_action 18:02:32 INFO - [mozharness: 2017-04-06 01:02:32.601845Z] Finished install step (success) 18:02:32 INFO - [mozharness: 2017-04-06 01:02:32.601979Z] Running run-tests step. 18:02:32 INFO - Running pre-action listener: _resource_record_pre_action 18:02:32 INFO - Running main action method: run_tests 18:02:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:02:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:02:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:02:32 INFO - 2017-04-05 18:02:32.616 screenresolution[1619:7436] starting screenresolution argv=screenresolution get 18:02:32 INFO - 2017-04-05 18:02:32.619 screenresolution[1619:7436] Display 0: 1600x1200x32@60 18:02:32 INFO - 2017-04-05 18:02:32.624 screenresolution[1620:7439] starting screenresolution argv=screenresolution list 18:02:32 INFO - Available Modes on Display 0 18:02:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:02:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:02:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:02:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:02:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:02:32 INFO - Intel Iris: 18:02:32 INFO - Chipset Model: Intel Iris 18:02:32 INFO - Type: GPU 18:02:32 INFO - Bus: Built-In 18:02:32 INFO - VRAM (Dynamic, Max): 1536 MB 18:02:32 INFO - Vendor: Intel (0x8086) 18:02:32 INFO - Device ID: 0x0a2e 18:02:32 INFO - Revision ID: 0x0009 18:02:32 INFO - Displays: 18:02:32 INFO - Display: 18:02:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:02:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:02:32 INFO - Display Serial Number: 1600x1200 60 18:02:32 INFO - Main Display: Yes 18:02:32 INFO - Mirror: Off 18:02:32 INFO - Online: Yes 18:02:32 INFO - Rotation: Supported 18:02:32 INFO - Return code: 0 18:02:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:02:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:02:32 INFO - Python 2.7.3 18:02:32 INFO - Return code: 0 18:02:32 INFO - grabbing minidump binary from tooltool 18:02:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:32 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 0x108b20d30>, '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 0x108a47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108b15be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:02:32 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 18:02:32 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 18:02:32 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 18:02:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:02:32 INFO - Return code: 0 18:02:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:02:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:02:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:02:32 INFO - ENV: RUST_BACKTRACE is now 1 18:02:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:02:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:02:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HnSS3-I-QaKXnhnl0lmolg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0074', '--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 18:02:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HnSS3-I-QaKXnhnl0lmolg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0074 --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 18:02:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tvmDRCYkNV/Render', 18:02:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:02:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:02:32 INFO - 'HOME': '/Users/cltbld', 18:02:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:02:32 INFO - 'LOGNAME': 'cltbld', 18:02:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:02:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:02:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:02:32 INFO - 'MOZ_NO_REMOTE': '1', 18:02:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:02:32 INFO - 'NO_EM_RESTART': '1', 18:02:32 INFO - 'PAGER': '/bin/cat', 18:02:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:02:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:02:32 INFO - 'PWD': '/builds/slave/test', 18:02:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:02:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:02:32 INFO - 'RUST_BACKTRACE': '1', 18:02:32 INFO - 'SHELL': '/bin/bash', 18:02:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.P0aTKGj5da/Listeners', 18:02:32 INFO - 'TMPDIR': '/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/', 18:02:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:02:32 INFO - 'USER': 'cltbld', 18:02:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:02:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:02:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:02:32 INFO - 'XPC_FLAGS': '0x0', 18:02:32 INFO - 'XPC_SERVICE_NAME': '0', 18:02:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:02:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HnSS3-I-QaKXnhnl0lmolg/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0074', '--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 18:02:33 INFO - mozversion application_buildid: 20170405162320 18:02:33 INFO - mozversion application_changeset: f09f78db26a594b76139cee19a2487f9509d92ee 18:02:33 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:02:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:02:33 INFO - mozversion application_name: Firefox 18:02:33 INFO - mozversion application_remotingname: firefox-dev 18:02:33 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:02:33 INFO - mozversion application_vendor: Mozilla 18:02:33 INFO - mozversion application_version: 54.0a2 18:02:33 INFO - mozversion platform_buildid: 20170405162320 18:02:33 INFO - mozversion platform_changeset: f09f78db26a594b76139cee19a2487f9509d92ee 18:02:33 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:02:33 INFO - mozversion platform_version: 54.0a2 18:02:33 INFO - using testdate: 1491440553 18:02:33 INFO - actual date: 1491440553 18:02:33 INFO - starting webserver on 'localhost:49244' 18:02:33 INFO - SUITE-START | Running 2 tests 18:02:33 INFO - TEST-START | dromaeo_css 18:02:33 INFO - operating with platform_type : mac_ 18:02:33 INFO - Initialising browser for dromaeo_css test... 18:02:33 INFO - TEST-INFO | started process 1630 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmpOvZ4cy/profile http://localhost:49244/getInfo.html) 18:02:36 INFO - PROCESS | 1630 | 2017-04-05 18:02:36.018 plugin-container[1632:7620] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 18:02:36 INFO - PROCESS | 1630 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:02:36 INFO - PROCESS | 1630 | 2017-04-05 18:02:36.021 plugin-container[1632:7620] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 18:02:36 INFO - PROCESS | 1630 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:02:36 INFO - PROCESS | 1630 | 2017-04-05 18:02:36.021 plugin-container[1632:7620] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:02:36 INFO - PROCESS | 1630 | __metrics Screen width/height:1600/1200 18:02:36 INFO - PROCESS | 1630 | colorDepth:24 18:02:36 INFO - PROCESS | 1630 | Browser inner width/height: 1024/689 18:02:36 INFO - PROCESS | 1630 | __metrics 18:02:36 ERROR - PROCESS | 1630 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 18:02:36 INFO - TEST-INFO | 1630: exit 0 18:02:36 INFO - Browser initialized. 18:02:36 INFO - Running cycle 1/1 for dromaeo_css test... 18:02:36 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmpOvZ4cy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:02:39 INFO - PROCESS | 1633 | 2017-04-05 18:02:39.219 plugin-container[1634:7755] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 18:02:39 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:02:39 INFO - PROCESS | 1633 | 2017-04-05 18:02:39.219 plugin-container[1634:7755] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 18:02:39 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:02:39 INFO - PROCESS | 1633 | 2017-04-05 18:02:39.220 plugin-container[1634:7755] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:02:48 INFO - PROCESS | 1633 | RSS: Main: 221024256 18:02:48 INFO - PROCESS | 1633 | 18:05:52 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 18:05:54 INFO - PROCESS | 1633 | RSS: Main: 241950720 18:05:54 INFO - PROCESS | 1633 | 18:07:37 INFO - PROCESS | 1633 | ************************* 18:07:37 INFO - PROCESS | 1633 | A coding exception was thrown and uncaught in a Task. 18:07:37 INFO - PROCESS | 1633 | 18:07:37 ERROR - PROCESS | 1633 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:07:37 INFO - PROCESS | 1633 | Full stack: 18:07:37 INFO - PROCESS | 1633 | ************************* 18:07:37 INFO - PROCESS | 1633 | ************************* 18:07:37 INFO - PROCESS | 1633 | A coding exception was thrown and uncaught in a Task. 18:07:37 INFO - PROCESS | 1633 | 18:07:37 ERROR - PROCESS | 1633 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:07:37 INFO - PROCESS | 1633 | Full stack: 18:07:37 INFO - PROCESS | 1633 | ************************* 18:07:37 INFO - PROCESS | 1633 | ************************* 18:07:37 INFO - PROCESS | 1633 | A coding exception was thrown and uncaught in a Task. 18:07:37 INFO - PROCESS | 1633 | 18:07:37 ERROR - PROCESS | 1633 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:07:37 INFO - PROCESS | 1633 | Full stack: 18:07:37 INFO - PROCESS | 1633 | ************************* 18:07:37 INFO - PROCESS | 1633 | ************************* 18:07:37 INFO - PROCESS | 1633 | A coding exception was thrown and uncaught in a Task. 18:07:37 INFO - PROCESS | 1633 | 18:07:37 ERROR - PROCESS | 1633 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:07:37 INFO - PROCESS | 1633 | Full stack: 18:07:37 INFO - PROCESS | 1633 | ************************* 18:08:56 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 18:08:57 INFO - PROCESS | 1633 | RSS: Main: 252481536 18:08:57 INFO - PROCESS | 1633 | 18:11:59 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 18:11:59 INFO - PROCESS | 1633 | RSS: Main: 259727360 18:11:59 INFO - PROCESS | 1633 | 18:15:01 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 18:15:02 INFO - PROCESS | 1633 | RSS: Main: 255942656 18:15:02 INFO - PROCESS | 1633 | 18:18:05 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 18:18:06 INFO - PROCESS | 1633 | RSS: Main: 261050368 18:18:06 INFO - PROCESS | 1633 | 18:21:08 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 18:21:09 INFO - PROCESS | 1633 | RSS: Main: 257236992 18:21:09 INFO - PROCESS | 1633 | 18:21:09 INFO - PROCESS | 1633 | __start_tp_report 18:21:09 INFO - PROCESS | 1633 | _x_x_mozilla_page_load 18:21:09 INFO - PROCESS | 1633 | _x_x_mozilla_page_load_details 18:21:09 INFO - PROCESS | 1633 | |i|pagename|runs| 18:21:09 INFO - PROCESS | 1633 | |0;dojo.html;3324.634290228075;3455.222574920642;3456.706179974702;3472.7048200902914;3480.4605286180617;6033.336333003372;6040.154378387029;6081.118237855509;6081.452669259771;6112.419320164578;5123.410807757109;5892.615235419673;6021.427964343391;6076.422739839719;6110.266767987837;5438.700871452069;5458.590605704569;5509.641873278224;5526.336839579256;5574.10814269717;5522.696251706155;5547.0570003099365;5562.110062390018;5566.832995010136;5568.524341049625;5339.6262261641705;5355;5530.336359636849;5534.640248383842;5538.833834984936;5491.807786727465;5498.890022199574;5500.119980803072;5557.527610153142;5587.441255874421;5394.676319420847;5492.835214943558;5514.503694667499;5524.861878453031;5547.362053363834;5807.30312362515;6129.478994285491;6149.954507733696;6187.845303867394;6192.442680158807;6297.4017468340435;6313.179286692701;6319.241690997063;6326.93673063268;6329.525285603552;4785.617150627942;6534.673266336664;6542.93457065428;6554.606723596599;6581.901271480931;6188.164597779332;6207.379262073801;6234.283057448406;6257.687115644199;6276.46650034743;6364.968175159187;6398.584092034003;6525.21697396306;6584.703688334036;6693.029510720919;5296.708681022581;6380.393862583047;6386.457151142205;6409.67951602418;6419.069234960904;4985.850424487271;6101.664408457577;6505.902411463831;6535.117759102555;6535.509836762262;6180.845478863064;6257.249130104326;6405.19935008124;6502.902456463157;6508.316626754217;6174.475169610633;6309.526785491014;6331.176946996953;6359.745610175633;6375.139356186948;5193.870153246199;5207.479252074762;5224.660397074254;5246.291750613695;5263.605229607795;4624.95375046252;4634.976825115853;4646.883827904329;4653.045316224222;4685.8594242172785;4922.606191504671;6042.274927008787;6173.56785025044;6276.027215781562;6278.089676996809;6395.840103997344;6447.451966582799;6535.150430444012;6551.508636852255;6559.24568674599;5122.34438084214;6227.595206311576;6235.469985051322;6257.937420625735;6274.560780745304;5588.888222235532;5775.220345253421;5778.711064446761;5800.303963524404;5809.128630705343;6555.3772391622715;6623.801285961429;6664.8000559983275;6713.362230588086;6726.024726414545;6596.505262105457;6606.8017959461295;6611.999999999807;6626.171728533934;6654.667266636746;6641.070250165078;6794;6832.863342733018;6835.487338449736;6842.281560436281;6245.125682404558;6307.463865571478;6363.427291543783;6366.6180029198395;6399.456046235936;5596.6921819298705;6394.552381333262;6569.967150164219;6573.704183311666;6604.504662150454;6285.151504546828;6298.464630506458;6320.6839658016;6322.715477803418;6354.745810167541;6187.535934804818;6236.064590311308;6269.592476489013;6276.435120839146;6350.682465876595;5710.457506536873;6090.177825993434;6209.906851397142;6331.366863313816;6362.872742545031;5221;5726.4273572642405;6235.688215589294;6366.64983425916;6370.713317900613;6186.597871138361;6234.314225435289;6262.09199666055;6320.905186422116;6354.4916406686425 18:21:09 INFO - PROCESS | 1633 | |1;ext.html;742.6323969635032;806.6128258435951;832.895888013998;962.6775030364831;965.5172413793103;31918;37232.813835930814;38209.85370438888;39752.20495590087;39838.402423963664;38149.23701525975;38295.808520957355;38357;38360.80819595905;38503.80748096263;7867.213278672144;8016.719414820483;10437.165026797838;10677.305975111592;10874.836877446845;8973.730788076367;10634.734131646732;10751.193660475457;10753.354798712064;10762.730931726692;10744.946275268652;10759.139268858473;10806.837897431546;10816.351018938849;10820.837687434656;10737.40944248066;10760.73098172544;10760.838587421194;10763.569457221702;10799.24405291626;9569.952150239274;9966.9501652492;10562;10574.94250574944;10592.894071059345;31202.90789822368;34287;34442.96671099871;34469.655303446896;35362.64637353619;3422.5379573757473;3748.887533374003;3765.548134223911;3780.0738818989525;3807.505024346845;24914.252572422858;25000.37499062538;25732.87133564339;26275.73724262752;26436;3018.154916623349;3071.155432256125;3110.844457777102;3245.221146924721;3256.918577035593;334746;337853.3107334472;337938;339046.60953390395;339369;53320.20019699707;53525.732371337894;53552.73223633935;53710.46289537055;53819;55552;55751.721241393534;55844.72077639667;55938;56133;15200.391984320724;15450;15541.300641471162;15548.92225538865;15551.22243887801;13064.934675326562;13076.803847942288;13098.410571524304;13099.803502947463;13113.213207207598;12982;13584.456621735217;13617.319134043457;13670.453181872614;13722.519711810039;5306.7611957462;5610.102383618683;5994.910076348859;6213.937860621336;6253.374662533801;1085.7882712870914;1091.5197313182334;1101.730076131337;1105.9004689577816;1158.4207896051973;25838.354041149123;26409.73590264111;26541.3364665881;26667.724909718854;26924.461510769674;1031.83490641497;1124.5951457475303;1220.1763809428778;1235.5119727707686;1279.0918447902093;13629.318534073454;13786.65533361654;14023.228722420161;14045.85954140466;14071.507497237752;494106;498382;499696;500692.49653751496;500903;56959.71520142373;57071;57352;57361.42638573644;57438.42561574414;1185.4985979583073;1379.7585422551215;1413.1662319231702;1420.2330741399735;1425.4298280687808;21697.674534882306;25824.483510330065;27061.86469067721;27333.589996149676;27454.45091098207;37464.25071498609;37655.43516847304;37658.8117059413;37688.2462350746;37808.810955946145;503671;504038;506142;507101;507262;29619.703802961696;29926.551101733927;30459.543106853856;31425.05724828259;31723.889663862577;31978.52032219565;37996.81001594974;38127;38666.42000369941;39119.41320880246;27399.589006164526;27744.02895898634;27976;28161.859190704734;28729.56905646459;27527;27586.448271034868;28124.87500500039;28233.43533129367;28610.570841437813;31756;32630.85791997269;33031.50452743163;33064.50403243905;33078.834605826814;19585.216591336186;23276.836158191683;26772.59841102346;28501.00246491363;28766.849326026724;20872.165113395295;21662;22922.19772308028;22994.310170694906;23235.44410114852 18:21:09 INFO - PROCESS | 1633 | |2;jquery.html;371.4576717991732;410.54224539638295;413.8448081969262;425.3768229543718;444.01428828001826;19860;20112.692674976133;20669.793302066973;21008.579828403424;21179.94100294985;16214.837851621509;17575.29698812046;20008.39974800758;21834.344969650898;22390;6290.779822706206;6658.866822663544;6740.561863478858;6789.830254243659;6814.693338799766;6105.664188469633;6200.976838821589;6377.011563207687;6539.117219175398;6622.668866556677;6108.358622344656;6662.100616416795;6691.631960242209;6696.665166741644;6700.497462690293;6580.5722628029025;6590.538662293641;6727.461803055744;6764.8308792280095;6768.966155169242;6347.143135676671;6575.145231119924;6589.769358072493;6593.604383736991;6628.966855165741;25000;25428.618570721454;25707.100251491294;25800;26000.73999260002;2671.5725483922674;2786.4009238013923;2879.136259122255;2919.8978035768646;2921.3134913295357;12043.939780301042;15471;15780.921095394564;15924.601884952854;16168.676626467404;2011.215625905898;2016.7294604399135;2018.4550171453773;2032.8373730101557;2066.2045112631745;82387.7641835367;106125.00933462805;116719;127460.72539274581;132262;15047.849521504644;20544.486387840425;20545.178192872416;20829.895850520952;21112.47218819511;25537.87231063833;29606.259843503653;30038.549421758693;30243.092707218817;30286.84856575747;10157.492125393701;10311;10471;10486.895131048745;10580.418077005685;7887.487313324616;8270.875936860952;8750.212480876624;8831.779205519913;8949.955250223707;9403.388779729295;9544.379615324982;9663.758406039764;9693;9735.367201131903;4226.5773422657485;4290.2706539888295;4291.184674911757;4303.397524346594;4329.718568293051;863.8963324401112;871.8692196170625;894.6332003878508;915.5056269614466;927.2350310993457;17271.39550115765;20933.79066209349;21056.15775368968;21084.84033378148;21299.148034078775;913.2465715784505;917.3395155488038;947.2847999760274;947.7535840681335;949.0793929887998;8389.706360277361;10555.47222638865;10963.50664220112;10966.945165274283;10970;638821;651423;664114;664378;668291;143227;143930;147230;148894;176922;1204.2172587817988;1282.4870051979096;1289.8323217981601;1309.5809341010975;1316.9473221071241;17888.195031223368;19119.426417207505;19507.902460487607;19862.70205946883;19883.005849707166;50203.74490637763;52005.479945200066;54179;64371.35628643654;64469.38826529375;85627.14372856192;90074;91110;91880.54059729658;92750.53624731833;10611.257211995395;10859.022687957806;10904.672859814218;11074.723131921444;11254.606088786853;11839.526418943145;11888;11901.107416943936;12120.393980301125;12226.266424014588;10051.798964020825;10068.848967265643;10124.291299608956;10238.385696858213;10450.843237351293;8037.23646253605;8041;9389;9638.277129215197;9802.215822734022;10082.89917100849;10276.17790576737;10686.25196236256;10722.195835312226;10870.61952831647;11498.367589782642;11638.825417618573;11820.586279480176;11861.288322700666;11967;8677.522736249566;10501.89498105009;10792.08267297257;10807.783844322912;11200.71191812952 18:21:09 INFO - PROCESS | 1633 | |3;mootools.html;1409.6687278489555;1553.316540722083;1557.2447363028937;1584.1999790105995;1591.3316407109016;10640.467976601169;11112.61105861295;11318.377489238088;11345.546178152874;11420.771584568303;11222.887771122287;11377.26047806894;11384.601538946139;11391.202615816896;11404.087672986161;2376.5484557933983;2412.9034838606435;2415.6618073469726;2419.334682962182;2422.39440139965;2322.268485427085;2398.7721166489155;2403.4472071423584;2415.4927465232236;2417.3593997590565;1940.2530025939973;2049.86675866069;2342.4729435876875;2408.7109546854313;2409.5662780699467;2358.1864256831363;2367.195153647768;2376.3346263046287;2383.8450500717486;2390.6055500842253;2369.1471070414636;2382.2257766226044;2387.1883559589824;2403.086827005744;2407.9277621671376;11049.508316377267;11132.220744547885;11251.774964500746;11259;11269.436528173559;5803.564732645069;5936.614120082181;5983.252093488314;5995.6702381369005;5999.910001349983;3521.330947120039;3543.681068703829;3631.527901372831;3658.8536458541626;3696.9075773105887;1176.4588289386893;1180.3744015671707;1218.9207701499374;1221.8717034711317;1225.8774122587758;13540.796888046687;13697;15013.24933753308;15408.306626201736;15567.610809729735;4678.6491013174145;4727.834525791581;4741.924217754686;4840.491748366441;4850.544836549092;11612.419379031015;11613.30320180775;11705.824412633818;11723.179377443497;11750.001249893854;5800.361960184377;5864.354920958691;5866.589338746332;5875.089361149029;5887.0580707086665;5214.0875346814155;5280.102382594891;5303.496167864047;5342.0384330820825;5371.301730774974;3886.553046399644;3899.317619416591;3956.643902048772;3972.900677483086;4034.596540345942;1069.5828626835541;1071.421432426481;1075.8762742284737;1080.6541906589973;1085.766560189563;921.3872774604813;923.5797712041006;931.4364809290417;937.7889974755761;959.2421986630624;5432.293801805819;5457.836264912059;5603.80386686464;6610.702518386588;6841.794746157524;894.5259012723267;984.9409035457752;985.69936169469;1024.9846252306222;1040.8022475729588;4152.626263636287;4160.958390416117;4214.599613036757;4224.197402493516;4281.743095414223;10293.897061029444;10584.364938103585;13336.799948000787;13946.930265348608;13982.951278654142;13179.868201317864;13240.271785051915;13718.45126194941;13775.862241377657;13786.586402407944;8744.956275218583;10020.899791002143;10341;10350.792984140275;10363.015513526203;6404.807855764335;6729.467029809389;6833.760818371333;6895.931040689529;6991.545549539263;11978.46096925658;11992.400379980792;11994.880051199376;12002.459889304828;12067.51729930828;10418.322809017389;11929.343886086348;12455.501779929064;12611.747765044463;12829.23024618526;9306.115918987956;9337.299702522203;9352.812943741223;9362.923263824747;9502.619895204114;10297.9485102574;10371.377717336984;10420.947895260475;10434.634787782392;10452.216083793593;8792.07683193281;8819.029906710384;8835.07231740658;8878.245349145136;8902.376833621584;8593.44142630727;8623;8629.956850215709;8715.91284087151;8717.259032982014;7808.180141085331;7827.373810095065;7932.127465978622;7933.920660793549;7934.563599001879;5833.79581714638;5852.063669812809;5940.851478712894;5965.552583556338;6023.5181185505935;4924.655274130777;5486.423925487767;5854.707264636836;6040.6677632730625;6192.133101365723 18:21:09 INFO - PROCESS | 1633 | |4;prototype.html;366.0555766123402;370.31491740280467;379.25476438797756;388.6754559942447;391.7708140737668;4376.949532112294;4565.726056436608;4713.468122860067;4784.473707892129;5103.56619687327;4526.615237704799;4797;4962.925556116661;5077.64456488046;5123.564497017758;3497.6852083312497;3783.3379158647263;4118.06450968046;4266.36004599311;4323.459567554056;3641.3991691370893;3930.9189972757576;4098.692598055143;4261.957380426203;4286.464191976003;4130.339145736682;4322.82708691652;4323.789338985089;4325.534445366143;4352.738835669854;3501.0044274763395;3705.7961812100325;4263.424437700902;4293.926518370407;4329.372241025065;3784.7161462890117;3931.0320707865662;3938;3970.769061590916;3991.860284890044;5005.399352077738;5011.1481048221885;5059.772310246048;5072.2391641253735;5089.262057001751;4254.893627659302;4472;4601.493835678073;4662.650301227422;4814.9277760833265;4896.46138924718;5348.251244825728;5392.937558350252;5455.972720136414;5577.888442231173;3821.6560509554274;4313.633341166004;4486.4840543337605;4489.730616163008;4503.977480112611;4745.905081898342;5115.769790359405;5312.760925758312;5593.692346920918;5895.793647222326;3514.4344628421395;4478.962072257115;4497;4517.480489743656;4539.523350048264;4554.339620755039;4602.470715867651;4607.331936869202;4617;4666.440027196758;4571.748553829505;4716.599089077398;4969.807246260872;4996.225585034325;5004.2493625956395;5277.548674114649;5449.5640348772;5488.780448782084;5560.833175004756;5563.5271001964475;1350.1426594112613;1543.4829332173847;1556.6497538053848;1560.4382422327953;1564.5384611539623;1094.7974624694457;1118.994405027986;1166.4051333819643;1173.3018853781987;1174.3423682737694;4550.635949124128;4655.371524844103;4823.252395878644;4989.077020751172;5142.948570514247;4931.9753401232765;5521.309836270466;5743.195952566644;5762.567807414461;5774;4478.910421791611;4637.976810115928;4844.878878028007;5183.377994640592;5219.8434046978655;2785.9303517412227;2917.4748545262055;2927.9414411711646;2961.526155815059;2995.670476247611;5782.537397008229;5994.400559943971;6014.398560143951;6014.609050411709;6015.556266496097;5127.846164615067;5302.628815982921;5653.604247702621;5690.772369105272;5846.473817356458;5232.502912223333;5492.434656122986;5722.484976352065;5941.999290358342;5967.224260846062;4889.804407823647;5014.2865706236025;5467.922189568098;5780.0173970424285;5839.620424672383;5237.6333656643665;5296.3379577552805;5351.5183633471615;5550.888982220252;5705.515031222392;5318.36179658428;5644.3508996465935;6002.549808764273;6013.579049466495;6027.969860150672;4594.747288899142;4611.723296602215;4618.861434156981;4624.3294722265755;4628.560286772751;5202;5532.925153067599;5553.666779993213;5753.252077229933;5791.204726534787;5021.7740201690285;5566.638168519034;5573.54763903881;5580.665160090408;5589.385167631639;5390.353157620953;5410.918836217381;5413.296271484682;5564.081926482085;5583;4688.9449873779495;4753.572178503951;4939.1968184498455;5461.972690136524;5470.671759694272;5596;5599.60431061631;5630.987421729355;5632.46539057434;5678.655828291277;4993.300937868628;5371.973140134274;5400.9459905400445;5493.560515158698;5519.853683400584 18:21:09 INFO - PROCESS | 1633 | |5;yui.html;709.7232079488997;737.1633196322173;748.842743023965;786.988195177072;825.4840724547159;12997.935010324934;15049.322780474877;16095.597610059755;16138.547530722233;16210.189490525456;14939.02896311739;15094.245287735625;15104.697906041873;15140.242987850592;15164;914.2137761525083;951.2722767083187;960.5149399553216;960.5725452173785;965.1892410375283;946.3612061858219;948.2650945517211;949.8052899155656;949.9382540134903;950.1591091883664;999.5951639585943;1000.5297510170209;1004.0060340263124;1004.0561871840447;1006.1498034161123;985.1232403161192;995.328153496387;996.8255555277798;997.9950100249489;998.346083315426;735.7902997645646;740.1968863782802;740.6111791309552;742.1465314887922;742.5544673196039;16216.837831621651;16223.918880405523;16257.918710406373;16817.243224054946;17035.744463833053;13843;13876.167429954236;13922.240429018362;13937.512187073506;14108.788368174486;9358.204552613037;11228.66314010581;11342.319460832376;11351.318920864775;11388;3486.3724529584665;3558.466230065485;3569.089882080079;3594.155373487228;3730.0301921500604;74228.25771742268;80087;97270;99727.50136249345;100113;10377.377357358582;10538.261186904228;10699.518521666543;10754.838677419844;10759.870213627613;21579;21653.917304135044;21681.67477487839;21846.126154953625;21914.780852191594;7374.265056411852;7549.2073332300415;7593.544387336778;7686.500377475446;7786.610669466504;4306.267934451151;4356.891077723082;4392.165488557228;4410.360497727812;4491.685582009228;2878.647035893127;3442.4836274559016;3484.3553942520716;3510.14001569612;3512.227309991798;459.3202060949813;589.4842013238416;602.3374288282854;628.8019273928697;631.3717850738544;643.5270076493773;664.9700763465559;665.1585744033802;677.9864402711918;683.8837397642412;8894;10411.5835366586;10414.739631509274;10531.210159238088;10651.147908167328;644.9645269510128;647.397919934456;681.6251061915926;693.38635307753;709.9219085900547;4189.329707246826;4459.7993090310365;4607.216773148572;4709.505501922249;5011.744714878319;84944.15055849486;90674;106357.25117726954;111342.44328778304;115578.42210788892;95940;96176;97158.51420742851;99558.50662240072;101173;83367.16632833716;84069.57965210135;85172.14827851766;86395;86869;9786.657466988621;9816.2147028239;9846.45844478532;9869.654562090293;9892.85160722604;54279.643008923515;55115.346539603874;55246.895062099335;55953.44046559645;57107.71446142743;70858.93711594227;72015;72114;73012.26987730055;73257;11017.44912754375;11160.386178760245;11275.87241275866;11276.774464510829;11312.490937907649;12271.75456490883;12508.874911251136;12865.871341286842;12900.806487902877;13339.66650833737;11865.347405892759;12059.698507537381;12092.274463532216;12241.755164896831;12541.247525148521;11616.244944078608;11811.704707382385;12002.459889305177;12326.876731232573;12339.197952133083;11374;11686.824697629712;11841;11919.821202681793;11947.581834635746;8859.24696400813;9152.633894644407;9404.341696081208;9477.810443790948;9735.610575576897;7740.729074482366;10062.345947513388;10193.337433067123;10214.948925255327;10239.43683097407 18:21:09 INFO - PROCESS | 1633 | __end_tp_report 18:21:09 INFO - PROCESS | 1633 | __start_cc_report 18:21:09 INFO - PROCESS | 1633 | _x_x_mozilla_cycle_collect,416 18:21:09 INFO - PROCESS | 1633 | __end_cc_report 18:21:09 INFO - PROCESS | 1633 | __startTimestamp1491441669093__endTimestamp 18:21:09 INFO - PROCESS | 1633 | 18:21:09 INFO - PROCESS | 1633 | ------- Summary: start ------- 18:21:09 INFO - PROCESS | 1633 | Number of tests: 6 18:21:09 INFO - PROCESS | 1633 | 18:21:09 INFO - PROCESS | 1633 | [#0] dojo.html Cycles:165 Average:5971.25 Median:6234.31 stddev:684.68 (11.0%) stddev-sans-first:654.54 18:21:09 INFO - PROCESS | 1633 | Values: 3324.6 3455.2 3456.7 3472.7 3480.5 6033.3 6040.2 6081.1 6081.5 6112.4 5123.4 5892.6 6021.4 6076.4 6110.3 5438.7 5458.6 5509.6 5526.3 5574.1 5522.7 5547.1 5562.1 5566.8 5568.5 5339.6 5355.0 5530.3 5534.6 5538.8 5491.8 5498.9 5500.1 5557.5 5587.4 5394.7 5492.8 5514.5 5524.9 5547.4 5807.3 6129.5 6150.0 6187.8 6192.4 6297.4 6313.2 6319.2 6326.9 6329.5 4785.6 6534.7 6542.9 6554.6 6581.9 6188.2 6207.4 6234.3 6257.7 6276.5 6365.0 6398.6 6525.2 6584.7 6693.0 5296.7 6380.4 6386.5 6409.7 6419.1 4985.9 6101.7 6505.9 6535.1 6535.5 6180.8 6257.2 6405.2 6502.9 6508.3 6174.5 6309.5 6331.2 6359.7 6375.1 5193.9 5207.5 5224.7 5246.3 5263.6 4625.0 4635.0 4646.9 4653.0 4685.9 4922.6 6042.3 6173.6 6276.0 6278.1 6395.8 6447.5 6535.2 6551.5 6559.2 5122.3 6227.6 6235.5 6257.9 6274.6 5588.9 5775.2 5778.7 5800.3 5809.1 6555.4 6623.8 6664.8 6713.4 6726.0 6596.5 6606.8 6612.0 6626.2 6654.7 6641.1 6794.0 6832.9 6835.5 6842.3 6245.1 6307.5 6363.4 6366.6 6399.5 5596.7 6394.6 6570.0 6573.7 6604.5 6285.2 6298.5 6320.7 6322.7 6354.7 6187.5 6236.1 6269.6 6276.4 6350.7 5710.5 6090.2 6209.9 6331.4 6362.9 5221.0 5726.4 6235.7 6366.6 6370.7 6186.6 6234.3 6262.1 6320.9 6354.5 18:21:09 INFO - PROCESS | 1633 | 18:21:09 INFO - PROCESS | 1633 | [#1] ext.html Cycles:180 Average:57317.63 Median:29773.13 stddev:121044.96 (406.6%) stddev-sans-first:121309.99 18:21:09 INFO - PROCESS | 1633 | Values: 742.6 806.6 832.9 962.7 965.5 31918.0 37232.8 38209.9 39752.2 39838.4 38149.2 38295.8 38357.0 38360.8 38503.8 7867.2 8016.7 10437.2 10677.3 10874.8 8973.7 10634.7 10751.2 10753.4 10762.7 10744.9 10759.1 10806.8 10816.4 10820.8 10737.4 10760.7 10760.8 10763.6 10799.2 9570.0 9967.0 10562.0 10574.9 10592.9 31202.9 34287.0 34443.0 34469.7 35362.6 3422.5 3748.9 3765.5 3780.1 3807.5 24914.3 25000.4 25732.9 26275.7 26436.0 3018.2 3071.2 3110.8 3245.2 3256.9 334746.0 337853.3 337938.0 339046.6 339369.0 53320.2 53525.7 53552.7 53710.5 53819.0 55552.0 55751.7 55844.7 55938.0 56133.0 15200.4 15450.0 15541.3 15548.9 15551.2 13064.9 13076.8 13098.4 13099.8 13113.2 12982.0 13584.5 13617.3 13670.5 13722.5 5306.8 5610.1 5994.9 6213.9 6253.4 1085.8 1091.5 1101.7 1105.9 1158.4 25838.4 26409.7 26541.3 26667.7 26924.5 1031.8 1124.6 1220.2 1235.5 1279.1 13629.3 13786.7 14023.2 14045.9 14071.5 494106.0 498382.0 499696.0 500692.5 500903.0 56959.7 57071.0 57352.0 57361.4 57438.4 1185.5 1379.8 1413.2 1420.2 1425.4 21697.7 25824.5 27061.9 27333.6 27454.5 37464.3 37655.4 37658.8 37688.2 37808.8 503671.0 504038.0 506142.0 507101.0 507262.0 29619.7 29926.6 30459.5 31425.1 31723.9 31978.5 37996.8 38127.0 38666.4 39119.4 27399.6 27744.0 27976.0 28161.9 28729.6 27527.0 27586.4 28124.9 28233.4 28610.6 31756.0 32630.9 33031.5 33064.5 33078.8 19585.2 23276.8 26772.6 28501.0 28766.8 20872.2 21662.0 22922.2 22994.3 23235.4 18:21:09 INFO - PROCESS | 1633 | 18:21:09 INFO - PROCESS | 1633 | [#2] jquery.html Cycles:180 Average:38837.72 Median:25753.55 stddev:109719.50 (426.0%) stddev-sans-first:109989.27 18:21:09 INFO - PROCESS | 1633 | Values: 371.5 410.5 413.8 425.4 444.0 19860.0 20112.7 20669.8 21008.6 21179.9 16214.8 17575.3 20008.4 21834.3 22390.0 6290.8 6658.9 6740.6 6789.8 6814.7 6105.7 6201.0 6377.0 6539.1 6622.7 6108.4 6662.1 6691.6 6696.7 6700.5 6580.6 6590.5 6727.5 6764.8 6769.0 6347.1 6575.1 6589.8 6593.6 6629.0 25000.0 25428.6 25707.1 25800.0 26000.7 2671.6 2786.4 2879.1 2919.9 2921.3 12043.9 15471.0 15780.9 15924.6 16168.7 2011.2 2016.7 2018.5 2032.8 2066.2 82387.8 106125.0 116719.0 127460.7 132262.0 15047.8 20544.5 20545.2 20829.9 21112.5 25537.9 29606.3 30038.5 30243.1 30286.8 10157.5 10311.0 10471.0 10486.9 10580.4 7887.5 8270.9 8750.2 8831.8 8950.0 9403.4 9544.4 9663.8 9693.0 9735.4 4226.6 4290.3 4291.2 4303.4 4329.7 863.9 871.9 894.6 915.5 927.2 17271.4 20933.8 21056.2 21084.8 21299.1 913.2 917.3 947.3 947.8 949.1 8389.7 10555.5 10963.5 10966.9 10970.0 638821.0 651423.0 664114.0 664378.0 668291.0 143227.0 143930.0 147230.0 148894.0 176922.0 1204.2 1282.5 1289.8 1309.6 1316.9 17888.2 19119.4 19507.9 19862.7 19883.0 50203.7 52005.5 54179.0 64371.4 64469.4 85627.1 90074.0 91110.0 91880.5 92750.5 10611.3 10859.0 10904.7 11074.7 11254.6 11839.5 11888.0 11901.1 12120.4 12226.3 10051.8 10068.8 10124.3 10238.4 10450.8 8037.2 8041.0 9389.0 9638.3 9802.2 10082.9 10276.2 10686.3 10722.2 10870.6 11498.4 11638.8 11820.6 11861.3 11967.0 8677.5 10501.9 10792.1 10807.8 11200.7 18:21:09 INFO - PROCESS | 1633 | 18:21:09 INFO - PROCESS | 1633 | [#3] mootools.html Cycles:180 Average:6687.46 Median:2414.20 stddev:4140.90 (171.5%) stddev-sans-first:4133.52 18:21:09 INFO - PROCESS | 1633 | Values: 1409.7 1553.3 1557.2 1584.2 1591.3 10640.5 11112.6 11318.4 11345.5 11420.8 11222.9 11377.3 11384.6 11391.2 11404.1 2376.5 2412.9 2415.7 2419.3 2422.4 2322.3 2398.8 2403.4 2415.5 2417.4 1940.3 2049.9 2342.5 2408.7 2409.6 2358.2 2367.2 2376.3 2383.8 2390.6 2369.1 2382.2 2387.2 2403.1 2407.9 11049.5 11132.2 11251.8 11259.0 11269.4 5803.6 5936.6 5983.3 5995.7 5999.9 3521.3 3543.7 3631.5 3658.9 3696.9 1176.5 1180.4 1218.9 1221.9 1225.9 13540.8 13697.0 15013.2 15408.3 15567.6 4678.6 4727.8 4741.9 4840.5 4850.5 11612.4 11613.3 11705.8 11723.2 11750.0 5800.4 5864.4 5866.6 5875.1 5887.1 5214.1 5280.1 5303.5 5342.0 5371.3 3886.6 3899.3 3956.6 3972.9 4034.6 1069.6 1071.4 1075.9 1080.7 1085.8 921.4 923.6 931.4 937.8 959.2 5432.3 5457.8 5603.8 6610.7 6841.8 894.5 984.9 985.7 1025.0 1040.8 4152.6 4161.0 4214.6 4224.2 4281.7 10293.9 10584.4 13336.8 13946.9 13983.0 13179.9 13240.3 13718.5 13775.9 13786.6 8745.0 10020.9 10341.0 10350.8 10363.0 6404.8 6729.5 6833.8 6895.9 6991.5 11978.5 11992.4 11994.9 12002.5 12067.5 10418.3 11929.3 12455.5 12611.7 12829.2 9306.1 9337.3 9352.8 9362.9 9502.6 10297.9 10371.4 10420.9 10434.6 10452.2 8792.1 8819.0 8835.1 8878.2 8902.4 8593.4 8623.0 8630.0 8715.9 8717.3 7808.2 7827.4 7932.1 7933.9 7934.6 5833.8 5852.1 5940.9 5965.6 6023.5 4924.7 5486.4 5854.7 6040.7 6192.1 18:21:09 INFO - PROCESS | 1633 | 18:21:09 INFO - PROCESS | 1633 | [#4] prototype.html Cycles:180 Average:4607.87 Median:4951.06 stddev:1292.21 (26.1%) stddev-sans-first:1256.00 18:21:09 INFO - PROCESS | 1633 | Values: 366.1 370.3 379.3 388.7 391.8 4376.9 4565.7 4713.5 4784.5 5103.6 4526.6 4797.0 4962.9 5077.6 5123.6 3497.7 3783.3 4118.1 4266.4 4323.5 3641.4 3930.9 4098.7 4262.0 4286.5 4130.3 4322.8 4323.8 4325.5 4352.7 3501.0 3705.8 4263.4 4293.9 4329.4 3784.7 3931.0 3938.0 3970.8 3991.9 5005.4 5011.1 5059.8 5072.2 5089.3 4254.9 4472.0 4601.5 4662.7 4814.9 4896.5 5348.3 5392.9 5456.0 5577.9 3821.7 4313.6 4486.5 4489.7 4504.0 4745.9 5115.8 5312.8 5593.7 5895.8 3514.4 4479.0 4497.0 4517.5 4539.5 4554.3 4602.5 4607.3 4617.0 4666.4 4571.7 4716.6 4969.8 4996.2 5004.2 5277.5 5449.6 5488.8 5560.8 5563.5 1350.1 1543.5 1556.6 1560.4 1564.5 1094.8 1119.0 1166.4 1173.3 1174.3 4550.6 4655.4 4823.3 4989.1 5142.9 4932.0 5521.3 5743.2 5762.6 5774.0 4478.9 4638.0 4844.9 5183.4 5219.8 2785.9 2917.5 2927.9 2961.5 2995.7 5782.5 5994.4 6014.4 6014.6 6015.6 5127.8 5302.6 5653.6 5690.8 5846.5 5232.5 5492.4 5722.5 5942.0 5967.2 4889.8 5014.3 5467.9 5780.0 5839.6 5237.6 5296.3 5351.5 5550.9 5705.5 5318.4 5644.4 6002.5 6013.6 6028.0 4594.7 4611.7 4618.9 4624.3 4628.6 5202.0 5532.9 5553.7 5753.3 5791.2 5021.8 5566.6 5573.5 5580.7 5589.4 5390.4 5410.9 5413.3 5564.1 5583.0 4688.9 4753.6 4939.2 5462.0 5470.7 5596.0 5599.6 5631.0 5632.5 5678.7 4993.3 5372.0 5400.9 5493.6 5519.9 18:21:09 INFO - PROCESS | 1633 | 18:21:09 INFO - PROCESS | 1633 | [#5] yui.html Cycles:180 Average:20471.71 Median:4435.08 stddev:29767.63 (671.2%) stddev-sans-first:29814.15 18:21:09 INFO - PROCESS | 1633 | Values: 709.7 737.2 748.8 787.0 825.5 12997.9 15049.3 16095.6 16138.5 16210.2 14939.0 15094.2 15104.7 15140.2 15164.0 914.2 951.3 960.5 960.6 965.2 946.4 948.3 949.8 949.9 950.2 999.6 1000.5 1004.0 1004.1 1006.1 985.1 995.3 996.8 998.0 998.3 735.8 740.2 740.6 742.1 742.6 16216.8 16223.9 16257.9 16817.2 17035.7 13843.0 13876.2 13922.2 13937.5 14108.8 9358.2 11228.7 11342.3 11351.3 11388.0 3486.4 3558.5 3569.1 3594.2 3730.0 74228.3 80087.0 97270.0 99727.5 100113.0 10377.4 10538.3 10699.5 10754.8 10759.9 21579.0 21653.9 21681.7 21846.1 21914.8 7374.3 7549.2 7593.5 7686.5 7786.6 4306.3 4356.9 4392.2 4410.4 4491.7 2878.6 3442.5 3484.4 3510.1 3512.2 459.3 589.5 602.3 628.8 631.4 643.5 665.0 665.2 678.0 683.9 8894.0 10411.6 10414.7 10531.2 10651.1 645.0 647.4 681.6 693.4 709.9 4189.3 4459.8 4607.2 4709.5 5011.7 84944.2 90674.0 106357.3 111342.4 115578.4 95940.0 96176.0 97158.5 99558.5 101173.0 83367.2 84069.6 85172.1 86395.0 86869.0 9786.7 9816.2 9846.5 9869.7 9892.9 54279.6 55115.3 55246.9 55953.4 57107.7 70858.9 72015.0 72114.0 73012.3 73257.0 11017.4 11160.4 11275.9 11276.8 11312.5 12271.8 12508.9 12865.9 12900.8 13339.7 11865.3 12059.7 12092.3 12241.8 12541.2 11616.2 11811.7 12002.5 12326.9 12339.2 11374.0 11686.8 11841.0 11919.8 11947.6 8859.2 9152.6 9404.3 9477.8 9735.6 7740.7 10062.3 10193.3 10214.9 10239.4 18:21:09 INFO - PROCESS | 1633 | -------- Summary: end -------- 18:21:09 INFO - PROCESS | 1633 | 18:21:09 INFO - TEST-INFO | 1633: exit 0 18:21:09 INFO - TEST-OK | dromaeo_css | took 1116524ms 18:21:09 INFO - TEST-START | kraken 18:21:09 INFO - operating with platform_type : mac_ 18:21:09 INFO - Initialising browser for kraken test... 18:21:09 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmpstcxwB/profile http://localhost:49244/getInfo.html) 18:21:12 INFO - PROCESS | 1644 | 2017-04-05 18:21:12.687 plugin-container[1645:10264] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 18:21:12 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:21:12 INFO - PROCESS | 1644 | 2017-04-05 18:21:12.690 plugin-container[1645:10264] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 18:21:12 INFO - PROCESS | 1644 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:21:12 INFO - PROCESS | 1644 | 2017-04-05 18:21:12.690 plugin-container[1645:10264] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:21:12 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 18:21:12 INFO - PROCESS | 1644 | colorDepth:24 18:21:12 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 18:21:12 INFO - PROCESS | 1644 | __metrics 18:21:12 ERROR - PROCESS | 1644 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 18:21:13 INFO - TEST-INFO | 1644: exit 0 18:21:13 INFO - Browser initialized. 18:21:13 INFO - Running cycle 1/1 for kraken test... 18:21:13 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/T/tmpstcxwB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:21:16 INFO - PROCESS | 1646 | 2017-04-05 18:21:16.039 plugin-container[1647:10392] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a47, name = 'com.apple.tsm.portname' 18:21:16 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:21:16 INFO - PROCESS | 1646 | 2017-04-05 18:21:16.039 plugin-container[1647:10392] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900f, name = 'com.apple.CFPasteboardClient' 18:21:16 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:21:16 INFO - PROCESS | 1646 | 2017-04-05 18:21:16.039 plugin-container[1647:10392] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:21:25 INFO - PROCESS | 1646 | RSS: Main: 221372416 18:21:25 INFO - PROCESS | 1646 | 18:21:28 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 18:21:29 INFO - PROCESS | 1646 | RSS: Main: 235745280 18:21:29 INFO - PROCESS | 1646 | 18:21:32 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 18:21:32 INFO - PROCESS | 1646 | RSS: Main: 243597312 18:21:32 INFO - PROCESS | 1646 | 18:21:36 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 18:21:36 INFO - PROCESS | 1646 | RSS: Main: 247263232 18:21:36 INFO - PROCESS | 1646 | 18:21:38 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 18:21:39 INFO - PROCESS | 1646 | RSS: Main: 248655872 18:21:39 INFO - PROCESS | 1646 | 18:21:41 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:21:42 INFO - PROCESS | 1646 | RSS: Main: 249036800 18:21:42 INFO - PROCESS | 1646 | 18:21:44 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 18:21:45 INFO - PROCESS | 1646 | RSS: Main: 252833792 18:21:45 INFO - PROCESS | 1646 | 18:21:48 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 18:21:49 INFO - PROCESS | 1646 | RSS: Main: 252727296 18:21:49 INFO - PROCESS | 1646 | 18:21:52 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 18:21:53 INFO - PROCESS | 1646 | RSS: Main: 252350464 18:21:53 INFO - PROCESS | 1646 | 18:21:55 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:21:55 INFO - PROCESS | 1646 | RSS: Main: 251633664 18:21:55 INFO - PROCESS | 1646 | 18:21:57 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:21:58 INFO - PROCESS | 1646 | RSS: Main: 251670528 18:21:58 INFO - PROCESS | 1646 | 18:22:00 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:22:01 INFO - PROCESS | 1646 | RSS: Main: 251969536 18:22:01 INFO - PROCESS | 1646 | 18:22:04 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:22:05 INFO - PROCESS | 1646 | RSS: Main: 252338176 18:22:05 INFO - PROCESS | 1646 | 18:22:08 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:22:08 INFO - PROCESS | 1646 | RSS: Main: 252145664 18:22:08 INFO - PROCESS | 1646 | 18:22:10 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 18:22:10 INFO - PROCESS | 1646 | RSS: Main: 254242816 18:22:10 INFO - PROCESS | 1646 | 18:22:10 INFO - PROCESS | 1646 | __start_tp_report 18:22:10 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 18:22:10 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 18:22:10 INFO - PROCESS | 1646 | |i|pagename|runs| 18:22:10 INFO - PROCESS | 1646 | |0;ai-astar;106;119;120;119;118;119;120;118;118;159 18:22:10 INFO - PROCESS | 1646 | |1;audio-beat-detection;135;114;128;168;111;112;106;115;129;111 18:22:10 INFO - PROCESS | 1646 | |2;audio-dft;194;197;198;200;196;196;197;229;256;195 18:22:10 INFO - PROCESS | 1646 | |3;audio-fft;88;59;60;88;61;61;64;63;94;86 18:22:10 INFO - PROCESS | 1646 | |4;audio-oscillator;89;70;83;72;71;69;81;70;70;82 18:22:10 INFO - PROCESS | 1646 | |5;imaging-gaussian-blur;90;89;88;89;89;91;89;93;89;90 18:22:10 INFO - PROCESS | 1646 | |6;imaging-darkroom;97;98;126;96;92;91;156;156;96;97 18:22:10 INFO - PROCESS | 1646 | |7;imaging-desaturate;91;80;143;146;90;105;143;139;99;146 18:22:10 INFO - PROCESS | 1646 | |8;json-parse-financial;66;66;67;75;102;107;110;84;69;69 18:22:10 INFO - PROCESS | 1646 | |9;json-stringify-tinderbox;58;57;64;67;68;64;57;64;84;86 18:22:10 INFO - PROCESS | 1646 | |10;stanford-crypto-aes;86;86;82;97;101;84;88;81;72;74 18:22:10 INFO - PROCESS | 1646 | |11;stanford-crypto-ccm;142;138;142;151;150;147;153;151;209;165 18:22:10 INFO - PROCESS | 1646 | |12;stanford-crypto-pbkdf2;159;168;169;167;169;203;231;214;167;171 18:22:10 INFO - PROCESS | 1646 | |13;stanford-crypto-sha256-iterative;65;64;65;68;61;67;80;86;87;83 18:22:10 INFO - PROCESS | 1646 | __end_tp_report 18:22:10 INFO - PROCESS | 1646 | __start_cc_report 18:22:10 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,1021 18:22:10 INFO - PROCESS | 1646 | __end_cc_report 18:22:10 INFO - PROCESS | 1646 | __startTimestamp1491441730735__endTimestamp 18:22:10 INFO - PROCESS | 1646 | 18:22:10 INFO - PROCESS | 1646 | ------- Summary: start ------- 18:22:10 INFO - PROCESS | 1646 | Number of tests: 14 18:22:10 INFO - PROCESS | 1646 | 18:22:10 INFO - PROCESS | 1646 | [#0] ai-astar Cycles:10 Average:121.60 Median:119.00 stddev:13.77 (11.6%) stddev-sans-first:13.40 18:22:10 INFO - PROCESS | 1646 | Values: 106.0 119.0 120.0 119.0 118.0 119.0 120.0 118.0 118.0 159.0 18:22:10 INFO - PROCESS | 1646 | 18:22:10 INFO - PROCESS | 1646 | [#1] audio-beat-detection Cycles:10 Average:122.90 Median:121.50 stddev:18.48 (15.2%) stddev-sans-first:19.07 18:22:10 INFO - PROCESS | 1646 | Values: 135.0 114.0 128.0 168.0 111.0 112.0 106.0 115.0 129.0 111.0 18:22:10 INFO - PROCESS | 1646 | 18:22:10 INFO - PROCESS | 1646 | [#2] audio-dft Cycles:10 Average:205.80 Median:197.50 stddev:20.43 (10.3%) stddev-sans-first:21.22 18:22:10 INFO - PROCESS | 1646 | Values: 194.0 197.0 198.0 200.0 196.0 196.0 197.0 229.0 256.0 195.0 18:22:10 INFO - PROCESS | 1646 | 18:22:10 INFO - PROCESS | 1646 | [#3] audio-fft Cycles:10 Average:72.40 Median:75.00 stddev:14.49 (19.3%) stddev-sans-first:14.23 18:22:10 INFO - PROCESS | 1646 | Values: 88.0 59.0 60.0 88.0 61.0 61.0 64.0 63.0 94.0 86.0 18:22:10 INFO - PROCESS | 1646 | 18:22:10 INFO - PROCESS | 1646 | [#4] audio-oscillator Cycles:10 Average:75.70 Median:76.50 stddev:7.27 (9.5%) stddev-sans-first:5.91 18:22:10 INFO - PROCESS | 1646 | Values: 89.0 70.0 83.0 72.0 71.0 69.0 81.0 70.0 70.0 82.0 18:22:10 INFO - PROCESS | 1646 | 18:22:10 INFO - PROCESS | 1646 | [#5] imaging-gaussian-blur Cycles:10 Average:89.70 Median:89.50 stddev:1.42 (1.6%) stddev-sans-first:1.50 18:22:10 INFO - PROCESS | 1646 | Values: 90.0 89.0 88.0 89.0 89.0 91.0 89.0 93.0 89.0 90.0 18:22:10 INFO - PROCESS | 1646 | 18:22:10 INFO - PROCESS | 1646 | [#6] imaging-darkroom Cycles:10 Average:110.50 Median:96.00 stddev:25.92 (27.0%) stddev-sans-first:27.02 18:22:10 INFO - PROCESS | 1646 | Values: 97.0 98.0 126.0 96.0 92.0 91.0 156.0 156.0 96.0 97.0 18:22:10 INFO - PROCESS | 1646 | 18:22:10 INFO - PROCESS | 1646 | [#7] imaging-desaturate Cycles:10 Average:118.20 Median:113.00 stddev:27.38 (24.2%) stddev-sans-first:27.21 18:22:10 INFO - PROCESS | 1646 | Values: 91.0 80.0 143.0 146.0 90.0 105.0 143.0 139.0 99.0 146.0 18:22:10 INFO - PROCESS | 1646 | 18:22:10 INFO - PROCESS | 1646 | [#8] json-parse-financial Cycles:10 Average:81.50 Median:68.00 stddev:18.06 (26.6%) stddev-sans-first:18.26 18:22:10 INFO - PROCESS | 1646 | Values: 66.0 66.0 67.0 75.0 102.0 107.0 110.0 84.0 69.0 69.0 18:22:10 INFO - PROCESS | 1646 | 18:22:10 INFO - PROCESS | 1646 | [#9] json-stringify-tinderbox Cycles:10 Average:66.90 Median:65.50 stddev:10.32 (15.8%) stddev-sans-first:10.43 18:22:10 INFO - PROCESS | 1646 | Values: 58.0 57.0 64.0 67.0 68.0 64.0 57.0 64.0 84.0 86.0 18:22:10 INFO - PROCESS | 1646 | 18:22:10 INFO - PROCESS | 1646 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:85.00 stddev:8.99 (10.6%) stddev-sans-first:9.53 18:22:10 INFO - PROCESS | 1646 | Values: 86.0 86.0 82.0 97.0 101.0 84.0 88.0 81.0 72.0 74.0 18:22:10 INFO - PROCESS | 1646 | 18:22:10 INFO - PROCESS | 1646 | [#11] stanford-crypto-ccm Cycles:10 Average:154.80 Median:151.00 stddev:20.46 (13.5%) stddev-sans-first:21.17 18:22:10 INFO - PROCESS | 1646 | Values: 142.0 138.0 142.0 151.0 150.0 147.0 153.0 151.0 209.0 165.0 18:22:10 INFO - PROCESS | 1646 | 18:22:10 INFO - PROCESS | 1646 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.80 Median:170.00 stddev:24.72 (14.5%) stddev-sans-first:24.80 18:22:10 INFO - PROCESS | 1646 | Values: 159.0 168.0 169.0 167.0 169.0 203.0 231.0 214.0 167.0 171.0 18:22:10 INFO - PROCESS | 1646 | 18:22:10 INFO - PROCESS | 1646 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.60 Median:74.00 stddev:10.15 (13.7%) stddev-sans-first:10.38 18:22:10 INFO - PROCESS | 1646 | Values: 65.0 64.0 65.0 68.0 61.0 67.0 80.0 86.0 87.0 83.0 18:22:10 INFO - PROCESS | 1646 | -------- Summary: end -------- 18:22:10 INFO - PROCESS | 1646 | 18:22:11 INFO - TEST-INFO | 1646: exit 0 18:22:11 INFO - TEST-OK | kraken | took 61918ms 18:22:11 INFO - SUITE-END | took 1178s 18:22:11 INFO - Completed test suite (00:19:38) 18:22:11 INFO - javascript benchmark 18:22:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7612.04651221937, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3324.634290228075, 3455.222574920642, 3456.706179974702, 3472.7048200902914, 3480.4605286180617, 6033.336333003372, 6040.154378387029, 6081.118237855509, 6081.452669259771, 6112.419320164578, 5123.410807757109, 5892.615235419673, 6021.427964343391, 6076.422739839719, 6110.266767987837, 5438.700871452069, 5458.590605704569, 5509.641873278224, 5526.336839579256, 5574.10814269717, 5522.696251706155, 5547.0570003099365, 5562.110062390018, 5566.832995010136, 5568.524341049625, 5339.6262261641705, 5355.0, 5530.336359636849, 5534.640248383842, 5538.833834984936, 5491.807786727465, 5498.890022199574, 5500.119980803072, 5557.527610153142, 5587.441255874421, 5394.676319420847, 5492.835214943558, 5514.503694667499, 5524.861878453031, 5547.362053363834, 5807.30312362515, 6129.478994285491, 6149.954507733696, 6187.845303867394, 6192.442680158807, 6297.4017468340435, 6313.179286692701, 6319.241690997063, 6326.93673063268, 6329.525285603552, 4785.617150627942, 6534.673266336664, 6542.93457065428, 6554.606723596599, 6581.901271480931, 6188.164597779332, 6207.379262073801, 6234.283057448406, 6257.687115644199, 6276.46650034743, 6364.968175159187, 6398.584092034003, 6525.21697396306, 6584.703688334036, 6693.029510720919, 5296.708681022581, 6380.393862583047, 6386.457151142205, 6409.67951602418, 6419.069234960904, 4985.850424487271, 6101.664408457577, 6505.902411463831, 6535.117759102555, 6535.509836762262, 6180.845478863064, 6257.249130104326, 6405.19935008124, 6502.902456463157, 6508.316626754217, 6174.475169610633, 6309.526785491014, 6331.176946996953, 6359.745610175633, 6375.139356186948, 5193.870153246199, 5207.479252074762, 5224.660397074254, 5246.291750613695, 5263.605229607795, 4624.95375046252, 4634.976825115853, 4646.883827904329, 4653.045316224222, 4685.8594242172785, 4922.606191504671, 6042.274927008787, 6173.56785025044, 6276.027215781562, 6278.089676996809, 6395.840103997344, 6447.451966582799, 6535.150430444012, 6551.508636852255, 6559.24568674599, 5122.34438084214, 6227.595206311576, 6235.469985051322, 6257.937420625735, 6274.560780745304, 5588.888222235532, 5775.220345253421, 5778.711064446761, 5800.303963524404, 5809.128630705343, 6555.3772391622715, 6623.801285961429, 6664.8000559983275, 6713.362230588086, 6726.024726414545, 6596.505262105457, 6606.8017959461295, 6611.999999999807, 6626.171728533934, 6654.667266636746, 6641.070250165078, 6794.0, 6832.863342733018, 6835.487338449736, 6842.281560436281, 6245.125682404558, 6307.463865571478, 6363.427291543783, 6366.6180029198395, 6399.456046235936, 5596.6921819298705, 6394.552381333262, 6569.967150164219, 6573.704183311666, 6604.504662150454, 6285.151504546828, 6298.464630506458, 6320.6839658016, 6322.715477803418, 6354.745810167541, 6187.535934804818, 6236.064590311308, 6269.592476489013, 6276.435120839146, 6350.682465876595, 5710.457506536873, 6090.177825993434, 6209.906851397142, 6331.366863313816, 6362.872742545031, 5221.0, 5726.4273572642405, 6235.688215589294, 6366.64983425916, 6370.713317900613, 6186.597871138361, 6234.314225435289, 6262.09199666055, 6320.905186422116, 6354.4916406686425], "value": 5930.450689056378, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.6323969635032, 806.6128258435951, 832.895888013998, 962.6775030364831, 965.5172413793103, 31918.0, 37232.813835930814, 38209.85370438888, 39752.20495590087, 39838.402423963664, 38149.23701525975, 38295.808520957355, 38357.0, 38360.80819595905, 38503.80748096263, 7867.213278672144, 8016.719414820483, 10437.165026797838, 10677.305975111592, 10874.836877446845, 8973.730788076367, 10634.734131646732, 10751.193660475457, 10753.354798712064, 10762.730931726692, 10744.946275268652, 10759.139268858473, 10806.837897431546, 10816.351018938849, 10820.837687434656, 10737.40944248066, 10760.73098172544, 10760.838587421194, 10763.569457221702, 10799.24405291626, 9569.952150239274, 9966.9501652492, 10562.0, 10574.94250574944, 10592.894071059345, 31202.90789822368, 34287.0, 34442.96671099871, 34469.655303446896, 35362.64637353619, 3422.5379573757473, 3748.887533374003, 3765.548134223911, 3780.0738818989525, 3807.505024346845, 24914.252572422858, 25000.37499062538, 25732.87133564339, 26275.73724262752, 26436.0, 3018.154916623349, 3071.155432256125, 3110.844457777102, 3245.221146924721, 3256.918577035593, 334746.0, 337853.3107334472, 337938.0, 339046.60953390395, 339369.0, 53320.20019699707, 53525.732371337894, 53552.73223633935, 53710.46289537055, 53819.0, 55552.0, 55751.721241393534, 55844.72077639667, 55938.0, 56133.0, 15200.391984320724, 15450.0, 15541.300641471162, 15548.92225538865, 15551.22243887801, 13064.934675326562, 13076.803847942288, 13098.410571524304, 13099.803502947463, 13113.213207207598, 12982.0, 13584.456621735217, 13617.319134043457, 13670.453181872614, 13722.519711810039, 5306.7611957462, 5610.102383618683, 5994.910076348859, 6213.937860621336, 6253.374662533801, 1085.7882712870914, 1091.5197313182334, 1101.730076131337, 1105.9004689577816, 1158.4207896051973, 25838.354041149123, 26409.73590264111, 26541.3364665881, 26667.724909718854, 26924.461510769674, 1031.83490641497, 1124.5951457475303, 1220.1763809428778, 1235.5119727707686, 1279.0918447902093, 13629.318534073454, 13786.65533361654, 14023.228722420161, 14045.85954140466, 14071.507497237752, 494106.0, 498382.0, 499696.0, 500692.49653751496, 500903.0, 56959.71520142373, 57071.0, 57352.0, 57361.42638573644, 57438.42561574414, 1185.4985979583073, 1379.7585422551215, 1413.1662319231702, 1420.2330741399735, 1425.4298280687808, 21697.674534882306, 25824.483510330065, 27061.86469067721, 27333.589996149676, 27454.45091098207, 37464.25071498609, 37655.43516847304, 37658.8117059413, 37688.2462350746, 37808.810955946145, 503671.0, 504038.0, 506142.0, 507101.0, 507262.0, 29619.703802961696, 29926.551101733927, 30459.543106853856, 31425.05724828259, 31723.889663862577, 31978.52032219565, 37996.81001594974, 38127.0, 38666.42000369941, 39119.41320880246, 27399.589006164526, 27744.02895898634, 27976.0, 28161.859190704734, 28729.56905646459, 27527.0, 27586.448271034868, 28124.87500500039, 28233.43533129367, 28610.570841437813, 31756.0, 32630.85791997269, 33031.50452743163, 33064.50403243905, 33078.834605826814, 19585.216591336186, 23276.836158191683, 26772.59841102346, 28501.00246491363, 28766.849326026724, 20872.165113395295, 21662.0, 22922.19772308028, 22994.310170694906, 23235.44410114852], "value": 18440.72287843612, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.4576717991732, 410.54224539638295, 413.8448081969262, 425.3768229543718, 444.01428828001826, 19860.0, 20112.692674976133, 20669.793302066973, 21008.579828403424, 21179.94100294985, 16214.837851621509, 17575.29698812046, 20008.39974800758, 21834.344969650898, 22390.0, 6290.779822706206, 6658.866822663544, 6740.561863478858, 6789.830254243659, 6814.693338799766, 6105.664188469633, 6200.976838821589, 6377.011563207687, 6539.117219175398, 6622.668866556677, 6108.358622344656, 6662.100616416795, 6691.631960242209, 6696.665166741644, 6700.497462690293, 6580.5722628029025, 6590.538662293641, 6727.461803055744, 6764.8308792280095, 6768.966155169242, 6347.143135676671, 6575.145231119924, 6589.769358072493, 6593.604383736991, 6628.966855165741, 25000.0, 25428.618570721454, 25707.100251491294, 25800.0, 26000.73999260002, 2671.5725483922674, 2786.4009238013923, 2879.136259122255, 2919.8978035768646, 2921.3134913295357, 12043.939780301042, 15471.0, 15780.921095394564, 15924.601884952854, 16168.676626467404, 2011.215625905898, 2016.7294604399135, 2018.4550171453773, 2032.8373730101557, 2066.2045112631745, 82387.7641835367, 106125.00933462805, 116719.0, 127460.72539274581, 132262.0, 15047.849521504644, 20544.486387840425, 20545.178192872416, 20829.895850520952, 21112.47218819511, 25537.87231063833, 29606.259843503653, 30038.549421758693, 30243.092707218817, 30286.84856575747, 10157.492125393701, 10311.0, 10471.0, 10486.895131048745, 10580.418077005685, 7887.487313324616, 8270.875936860952, 8750.212480876624, 8831.779205519913, 8949.955250223707, 9403.388779729295, 9544.379615324982, 9663.758406039764, 9693.0, 9735.367201131903, 4226.5773422657485, 4290.2706539888295, 4291.184674911757, 4303.397524346594, 4329.718568293051, 863.8963324401112, 871.8692196170625, 894.6332003878508, 915.5056269614466, 927.2350310993457, 17271.39550115765, 20933.79066209349, 21056.15775368968, 21084.84033378148, 21299.148034078775, 913.2465715784505, 917.3395155488038, 947.2847999760274, 947.7535840681335, 949.0793929887998, 8389.706360277361, 10555.47222638865, 10963.50664220112, 10966.945165274283, 10970.0, 638821.0, 651423.0, 664114.0, 664378.0, 668291.0, 143227.0, 143930.0, 147230.0, 148894.0, 176922.0, 1204.2172587817988, 1282.4870051979096, 1289.8323217981601, 1309.5809341010975, 1316.9473221071241, 17888.195031223368, 19119.426417207505, 19507.902460487607, 19862.70205946883, 19883.005849707166, 50203.74490637763, 52005.479945200066, 54179.0, 64371.35628643654, 64469.38826529375, 85627.14372856192, 90074.0, 91110.0, 91880.54059729658, 92750.53624731833, 10611.257211995395, 10859.022687957806, 10904.672859814218, 11074.723131921444, 11254.606088786853, 11839.526418943145, 11888.0, 11901.107416943936, 12120.393980301125, 12226.266424014588, 10051.798964020825, 10068.848967265643, 10124.291299608956, 10238.385696858213, 10450.843237351293, 8037.23646253605, 8041.0, 9389.0, 9638.277129215197, 9802.215822734022, 10082.89917100849, 10276.17790576737, 10686.25196236256, 10722.195835312226, 10870.61952831647, 11498.367589782642, 11638.825417618573, 11820.586279480176, 11861.288322700666, 11967.0, 8677.522736249566, 10501.89498105009, 10792.08267297257, 10807.783844322912, 11200.71191812952], "value": 11055.579502327622, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1409.6687278489555, 1553.316540722083, 1557.2447363028937, 1584.1999790105995, 1591.3316407109016, 10640.467976601169, 11112.61105861295, 11318.377489238088, 11345.546178152874, 11420.771584568303, 11222.887771122287, 11377.26047806894, 11384.601538946139, 11391.202615816896, 11404.087672986161, 2376.5484557933983, 2412.9034838606435, 2415.6618073469726, 2419.334682962182, 2422.39440139965, 2322.268485427085, 2398.7721166489155, 2403.4472071423584, 2415.4927465232236, 2417.3593997590565, 1940.2530025939973, 2049.86675866069, 2342.4729435876875, 2408.7109546854313, 2409.5662780699467, 2358.1864256831363, 2367.195153647768, 2376.3346263046287, 2383.8450500717486, 2390.6055500842253, 2369.1471070414636, 2382.2257766226044, 2387.1883559589824, 2403.086827005744, 2407.9277621671376, 11049.508316377267, 11132.220744547885, 11251.774964500746, 11259.0, 11269.436528173559, 5803.564732645069, 5936.614120082181, 5983.252093488314, 5995.6702381369005, 5999.910001349983, 3521.330947120039, 3543.681068703829, 3631.527901372831, 3658.8536458541626, 3696.9075773105887, 1176.4588289386893, 1180.3744015671707, 1218.9207701499374, 1221.8717034711317, 1225.8774122587758, 13540.796888046687, 13697.0, 15013.24933753308, 15408.306626201736, 15567.610809729735, 4678.6491013174145, 4727.834525791581, 4741.924217754686, 4840.491748366441, 4850.544836549092, 11612.419379031015, 11613.30320180775, 11705.824412633818, 11723.179377443497, 11750.001249893854, 5800.361960184377, 5864.354920958691, 5866.589338746332, 5875.089361149029, 5887.0580707086665, 5214.0875346814155, 5280.102382594891, 5303.496167864047, 5342.0384330820825, 5371.301730774974, 3886.553046399644, 3899.317619416591, 3956.643902048772, 3972.900677483086, 4034.596540345942, 1069.5828626835541, 1071.421432426481, 1075.8762742284737, 1080.6541906589973, 1085.766560189563, 921.3872774604813, 923.5797712041006, 931.4364809290417, 937.7889974755761, 959.2421986630624, 5432.293801805819, 5457.836264912059, 5603.80386686464, 6610.702518386588, 6841.794746157524, 894.5259012723267, 984.9409035457752, 985.69936169469, 1024.9846252306222, 1040.8022475729588, 4152.626263636287, 4160.958390416117, 4214.599613036757, 4224.197402493516, 4281.743095414223, 10293.897061029444, 10584.364938103585, 13336.799948000787, 13946.930265348608, 13982.951278654142, 13179.868201317864, 13240.271785051915, 13718.45126194941, 13775.862241377657, 13786.586402407944, 8744.956275218583, 10020.899791002143, 10341.0, 10350.792984140275, 10363.015513526203, 6404.807855764335, 6729.467029809389, 6833.760818371333, 6895.931040689529, 6991.545549539263, 11978.46096925658, 11992.400379980792, 11994.880051199376, 12002.459889304828, 12067.51729930828, 10418.322809017389, 11929.343886086348, 12455.501779929064, 12611.747765044463, 12829.23024618526, 9306.115918987956, 9337.299702522203, 9352.812943741223, 9362.923263824747, 9502.619895204114, 10297.9485102574, 10371.377717336984, 10420.947895260475, 10434.634787782392, 10452.216083793593, 8792.07683193281, 8819.029906710384, 8835.07231740658, 8878.245349145136, 8902.376833621584, 8593.44142630727, 8623.0, 8629.956850215709, 8715.91284087151, 8717.259032982014, 7808.180141085331, 7827.373810095065, 7932.127465978622, 7933.920660793549, 7934.563599001879, 5833.79581714638, 5852.063669812809, 5940.851478712894, 5965.552583556338, 6023.5181185505935, 4924.655274130777, 5486.423925487767, 5854.707264636836, 6040.6677632730625, 6192.133101365723], "value": 5118.207505779807, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.0555766123402, 370.31491740280467, 379.25476438797756, 388.6754559942447, 391.7708140737668, 4376.949532112294, 4565.726056436608, 4713.468122860067, 4784.473707892129, 5103.56619687327, 4526.615237704799, 4797.0, 4962.925556116661, 5077.64456488046, 5123.564497017758, 3497.6852083312497, 3783.3379158647263, 4118.06450968046, 4266.36004599311, 4323.459567554056, 3641.3991691370893, 3930.9189972757576, 4098.692598055143, 4261.957380426203, 4286.464191976003, 4130.339145736682, 4322.82708691652, 4323.789338985089, 4325.534445366143, 4352.738835669854, 3501.0044274763395, 3705.7961812100325, 4263.424437700902, 4293.926518370407, 4329.372241025065, 3784.7161462890117, 3931.0320707865662, 3938.0, 3970.769061590916, 3991.860284890044, 5005.399352077738, 5011.1481048221885, 5059.772310246048, 5072.2391641253735, 5089.262057001751, 4254.893627659302, 4472.0, 4601.493835678073, 4662.650301227422, 4814.9277760833265, 4896.46138924718, 5348.251244825728, 5392.937558350252, 5455.972720136414, 5577.888442231173, 3821.6560509554274, 4313.633341166004, 4486.4840543337605, 4489.730616163008, 4503.977480112611, 4745.905081898342, 5115.769790359405, 5312.760925758312, 5593.692346920918, 5895.793647222326, 3514.4344628421395, 4478.962072257115, 4497.0, 4517.480489743656, 4539.523350048264, 4554.339620755039, 4602.470715867651, 4607.331936869202, 4617.0, 4666.440027196758, 4571.748553829505, 4716.599089077398, 4969.807246260872, 4996.225585034325, 5004.2493625956395, 5277.548674114649, 5449.5640348772, 5488.780448782084, 5560.833175004756, 5563.5271001964475, 1350.1426594112613, 1543.4829332173847, 1556.6497538053848, 1560.4382422327953, 1564.5384611539623, 1094.7974624694457, 1118.994405027986, 1166.4051333819643, 1173.3018853781987, 1174.3423682737694, 4550.635949124128, 4655.371524844103, 4823.252395878644, 4989.077020751172, 5142.948570514247, 4931.9753401232765, 5521.309836270466, 5743.195952566644, 5762.567807414461, 5774.0, 4478.910421791611, 4637.976810115928, 4844.878878028007, 5183.377994640592, 5219.8434046978655, 2785.9303517412227, 2917.4748545262055, 2927.9414411711646, 2961.526155815059, 2995.670476247611, 5782.537397008229, 5994.400559943971, 6014.398560143951, 6014.609050411709, 6015.556266496097, 5127.846164615067, 5302.628815982921, 5653.604247702621, 5690.772369105272, 5846.473817356458, 5232.502912223333, 5492.434656122986, 5722.484976352065, 5941.999290358342, 5967.224260846062, 4889.804407823647, 5014.2865706236025, 5467.922189568098, 5780.0173970424285, 5839.620424672383, 5237.6333656643665, 5296.3379577552805, 5351.5183633471615, 5550.888982220252, 5705.515031222392, 5318.36179658428, 5644.3508996465935, 6002.549808764273, 6013.579049466495, 6027.969860150672, 4594.747288899142, 4611.723296602215, 4618.861434156981, 4624.3294722265755, 4628.560286772751, 5202.0, 5532.925153067599, 5553.666779993213, 5753.252077229933, 5791.204726534787, 5021.7740201690285, 5566.638168519034, 5573.54763903881, 5580.665160090408, 5589.385167631639, 5390.353157620953, 5410.918836217381, 5413.296271484682, 5564.081926482085, 5583.0, 4688.9449873779495, 4753.572178503951, 4939.1968184498455, 5461.972690136524, 5470.671759694272, 5596.0, 5599.60431061631, 5630.987421729355, 5632.46539057434, 5678.655828291277, 4993.300937868628, 5371.973140134274, 5400.9459905400445, 5493.560515158698, 5519.853683400584], "value": 4229.73172068734, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.7232079488997, 737.1633196322173, 748.842743023965, 786.988195177072, 825.4840724547159, 12997.935010324934, 15049.322780474877, 16095.597610059755, 16138.547530722233, 16210.189490525456, 14939.02896311739, 15094.245287735625, 15104.697906041873, 15140.242987850592, 15164.0, 914.2137761525083, 951.2722767083187, 960.5149399553216, 960.5725452173785, 965.1892410375283, 946.3612061858219, 948.2650945517211, 949.8052899155656, 949.9382540134903, 950.1591091883664, 999.5951639585943, 1000.5297510170209, 1004.0060340263124, 1004.0561871840447, 1006.1498034161123, 985.1232403161192, 995.328153496387, 996.8255555277798, 997.9950100249489, 998.346083315426, 735.7902997645646, 740.1968863782802, 740.6111791309552, 742.1465314887922, 742.5544673196039, 16216.837831621651, 16223.918880405523, 16257.918710406373, 16817.243224054946, 17035.744463833053, 13843.0, 13876.167429954236, 13922.240429018362, 13937.512187073506, 14108.788368174486, 9358.204552613037, 11228.66314010581, 11342.319460832376, 11351.318920864775, 11388.0, 3486.3724529584665, 3558.466230065485, 3569.089882080079, 3594.155373487228, 3730.0301921500604, 74228.25771742268, 80087.0, 97270.0, 99727.50136249345, 100113.0, 10377.377357358582, 10538.261186904228, 10699.518521666543, 10754.838677419844, 10759.870213627613, 21579.0, 21653.917304135044, 21681.67477487839, 21846.126154953625, 21914.780852191594, 7374.265056411852, 7549.2073332300415, 7593.544387336778, 7686.500377475446, 7786.610669466504, 4306.267934451151, 4356.891077723082, 4392.165488557228, 4410.360497727812, 4491.685582009228, 2878.647035893127, 3442.4836274559016, 3484.3553942520716, 3510.14001569612, 3512.227309991798, 459.3202060949813, 589.4842013238416, 602.3374288282854, 628.8019273928697, 631.3717850738544, 643.5270076493773, 664.9700763465559, 665.1585744033802, 677.9864402711918, 683.8837397642412, 8894.0, 10411.5835366586, 10414.739631509274, 10531.210159238088, 10651.147908167328, 644.9645269510128, 647.397919934456, 681.6251061915926, 693.38635307753, 709.9219085900547, 4189.329707246826, 4459.7993090310365, 4607.216773148572, 4709.505501922249, 5011.744714878319, 84944.15055849486, 90674.0, 106357.25117726954, 111342.44328778304, 115578.42210788892, 95940.0, 96176.0, 97158.51420742851, 99558.50662240072, 101173.0, 83367.16632833716, 84069.57965210135, 85172.14827851766, 86395.0, 86869.0, 9786.657466988621, 9816.2147028239, 9846.45844478532, 9869.654562090293, 9892.85160722604, 54279.643008923515, 55115.346539603874, 55246.895062099335, 55953.44046559645, 57107.71446142743, 70858.93711594227, 72015.0, 72114.0, 73012.26987730055, 73257.0, 11017.44912754375, 11160.386178760245, 11275.87241275866, 11276.774464510829, 11312.490937907649, 12271.75456490883, 12508.874911251136, 12865.871341286842, 12900.806487902877, 13339.66650833737, 11865.347405892759, 12059.698507537381, 12092.274463532216, 12241.755164896831, 12541.247525148521, 11616.244944078608, 11811.704707382385, 12002.459889305177, 12326.876731232573, 12339.197952133083, 11374.0, 11686.824697629712, 11841.0, 11919.821202681793, 11947.581834635746, 8859.24696400813, 9152.633894644407, 9404.341696081208, 9477.810443790948, 9735.610575576897, 7740.729074482366, 10062.345947513388, 10193.337433067123, 10214.948925255327, 10239.43683097407], "value": 7431.749035907565, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1559.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 119.0, 120.0, 119.0, 118.0, 119.0, 120.0, 118.0, 118.0, 159.0], "value": 121.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 114.0, 128.0, 168.0, 111.0, 112.0, 106.0, 115.0, 129.0, 111.0], "value": 122.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 197.0, 198.0, 200.0, 196.0, 196.0, 197.0, 229.0, 256.0, 195.0], "value": 205.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 59.0, 60.0, 88.0, 61.0, 61.0, 64.0, 63.0, 94.0, 86.0], "value": 72.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 83.0, 72.0, 71.0, 69.0, 81.0, 70.0, 70.0, 82.0], "value": 75.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 88.0, 89.0, 89.0, 91.0, 89.0, 93.0, 89.0, 90.0], "value": 89.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 126.0, 96.0, 92.0, 91.0, 156.0, 156.0, 96.0, 97.0], "value": 110.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 80.0, 143.0, 146.0, 90.0, 105.0, 143.0, 139.0, 99.0, 146.0], "value": 118.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 75.0, 102.0, 107.0, 110.0, 84.0, 69.0, 69.0], "value": 81.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 64.0, 67.0, 68.0, 64.0, 57.0, 64.0, 84.0, 86.0], "value": 66.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 82.0, 97.0, 101.0, 84.0, 88.0, 81.0, 72.0, 74.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 138.0, 142.0, 151.0, 150.0, 147.0, 153.0, 151.0, 209.0, 165.0], "value": 154.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 168.0, 169.0, 167.0, 169.0, 203.0, 231.0, 214.0, 167.0, 171.0], "value": 181.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 68.0, 61.0, 67.0, 80.0, 86.0, 87.0, 83.0], "value": 72.6, "unit": "score"}]}]} 18:22:11 INFO - Return code: 0 18:22:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:22:12 ERROR - # TBPL SUCCESS # 18:22:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:22:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:22:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:22:12 INFO - 2017-04-05 18:22:12.072 screenresolution[1651:10782] starting screenresolution argv=screenresolution get 18:22:12 INFO - 2017-04-05 18:22:12.075 screenresolution[1651:10782] Display 0: 1600x1200x32@60 18:22:12 INFO - 2017-04-05 18:22:12.081 screenresolution[1652:10785] starting screenresolution argv=screenresolution list 18:22:12 INFO - Available Modes on Display 0 18:22:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:22:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:22:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:22:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:22:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:22:12 INFO - Intel Iris: 18:22:12 INFO - Chipset Model: Intel Iris 18:22:12 INFO - Type: GPU 18:22:12 INFO - Bus: Built-In 18:22:12 INFO - VRAM (Dynamic, Max): 1536 MB 18:22:12 INFO - Vendor: Intel (0x8086) 18:22:12 INFO - Device ID: 0x0a2e 18:22:12 INFO - Revision ID: 0x0009 18:22:12 INFO - Displays: 18:22:12 INFO - Display: 18:22:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:22:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:22:12 INFO - Display Serial Number: 1600x1200 60 18:22:12 INFO - Main Display: Yes 18:22:12 INFO - Mirror: Off 18:22:12 INFO - Online: Yes 18:22:12 INFO - Rotation: Supported 18:22:12 INFO - Return code: 0 18:22:12 INFO - Running post-action listener: _resource_record_post_action 18:22:12 INFO - [mozharness: 2017-04-06 01:22:12.319634Z] Finished run-tests step (success) 18:22:12 INFO - Running post-run listener: _resource_record_post_run 18:22:12 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 41273856; Write bytes: 515057664; Read time: 403; Write time: 1748 18:22:12 INFO - TinderboxPrint: CPU usage
54.5% 18:22:12 INFO - TinderboxPrint: I/O read bytes / time
41,273,856 / 403 18:22:12 INFO - TinderboxPrint: I/O write bytes / time
515,057,664 / 1,748 18:22:12 INFO - TinderboxPrint: CPU idle
2,180.8 (45.5%) 18:22:12 INFO - TinderboxPrint: CPU system
962.5 (20.1%) 18:22:12 INFO - TinderboxPrint: CPU user
1,654.5 (34.5%) 18:22:12 INFO - TinderboxPrint: Swap in / out
248,258,560 / 0 18:22:12 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 203880960; Write bytes: 201844736; Read time: 15686; Write time: 436 18:22:12 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 39156224; Write bytes: 302272512; Read time: 380; Write time: 1282 18:22:12 INFO - Running post-run listener: _upload_blobber_files 18:22:12 INFO - Blob upload gear active. 18:22:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:22:12 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 18:22:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:22:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:22:13 INFO - (blobuploader) - INFO - Open directory for files ... 18:22:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:22:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:22:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:14 INFO - (blobuploader) - INFO - Done attempting. 18:22:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:22:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:22:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:14 INFO - (blobuploader) - INFO - Done attempting. 18:22:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:22:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:16 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:22:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:16 INFO - (blobuploader) - INFO - Done attempting. 18:22:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:22:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:22:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:22:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:22:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:22:19 INFO - (blobuploader) - INFO - Done attempting. 18:22:19 INFO - (blobuploader) - INFO - Iteration through files over. 18:22:19 INFO - Return code: 0 18:22:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:22:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:22:19 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/af9801de4b1c0fafc8edf3679675aa0fe7669da6e09a2fb9de58edc84ffc502294e7ff21a787954ab9f778a8c8f44abc626f3b31fd2835b8533f23e7622494da", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57a30ca872be61a595e3e124b61f3e0256404a66be3e0db6873d0b073705d9908449b54cf64571a1d0b023bba54888f5d3b8d619455fdf2a0db7c69a7b166886", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bba184206a2ac74783c747b2e85001e8588a96931f98d6961abb5a8c5a195d51f49c5ecd5c2722f74f96488c9a8c367b7bdaf1209cdc231e95b38d65126a49d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ffce3c857485d81e2507f9d592f1d33d654290c34a0c7bcec00c06bc8f399b73415d8adeabd5f1912bac76d58697a479754eead55740707bcdb7f8112cf18fe6"} 18:22:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:22:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:22:19 INFO - Contents: 18:22:19 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/af9801de4b1c0fafc8edf3679675aa0fe7669da6e09a2fb9de58edc84ffc502294e7ff21a787954ab9f778a8c8f44abc626f3b31fd2835b8533f23e7622494da", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57a30ca872be61a595e3e124b61f3e0256404a66be3e0db6873d0b073705d9908449b54cf64571a1d0b023bba54888f5d3b8d619455fdf2a0db7c69a7b166886", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bba184206a2ac74783c747b2e85001e8588a96931f98d6961abb5a8c5a195d51f49c5ecd5c2722f74f96488c9a8c367b7bdaf1209cdc231e95b38d65126a49d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ffce3c857485d81e2507f9d592f1d33d654290c34a0c7bcec00c06bc8f399b73415d8adeabd5f1912bac76d58697a479754eead55740707bcdb7f8112cf18fe6"} 18:22:19 INFO - Running post-run listener: copy_logs_to_upload_dir 18:22:19 INFO - Copying logs to upload dir... 18:22:19 INFO - mkdir: /builds/slave/test/build/upload/logs 18:22:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1283.093718 ========= master_lag: 0.31 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 23 secs) (at 2017-04-05 18:22:19.430974) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-05 18:22:19.436382) ========= 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.tvmDRCYkNV/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.P0aTKGj5da/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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/Mozilla-Aurora/sha512/af9801de4b1c0fafc8edf3679675aa0fe7669da6e09a2fb9de58edc84ffc502294e7ff21a787954ab9f778a8c8f44abc626f3b31fd2835b8533f23e7622494da", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57a30ca872be61a595e3e124b61f3e0256404a66be3e0db6873d0b073705d9908449b54cf64571a1d0b023bba54888f5d3b8d619455fdf2a0db7c69a7b166886", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bba184206a2ac74783c747b2e85001e8588a96931f98d6961abb5a8c5a195d51f49c5ecd5c2722f74f96488c9a8c367b7bdaf1209cdc231e95b38d65126a49d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ffce3c857485d81e2507f9d592f1d33d654290c34a0c7bcec00c06bc8f399b73415d8adeabd5f1912bac76d58697a479754eead55740707bcdb7f8112cf18fe6"} build_url:https://queue.taskcluster.net/v1/task/HnSS3-I-QaKXnhnl0lmolg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011092 build_url: 'https://queue.taskcluster.net/v1/task/HnSS3-I-QaKXnhnl0lmolg/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/af9801de4b1c0fafc8edf3679675aa0fe7669da6e09a2fb9de58edc84ffc502294e7ff21a787954ab9f778a8c8f44abc626f3b31fd2835b8533f23e7622494da", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/57a30ca872be61a595e3e124b61f3e0256404a66be3e0db6873d0b073705d9908449b54cf64571a1d0b023bba54888f5d3b8d619455fdf2a0db7c69a7b166886", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bba184206a2ac74783c747b2e85001e8588a96931f98d6961abb5a8c5a195d51f49c5ecd5c2722f74f96488c9a8c367b7bdaf1209cdc231e95b38d65126a49d0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ffce3c857485d81e2507f9d592f1d33d654290c34a0c7bcec00c06bc8f399b73415d8adeabd5f1912bac76d58697a479754eead55740707bcdb7f8112cf18fe6"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-05 18:22:19.480085) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 18:22:19.480453) ========= 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.tvmDRCYkNV/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.P0aTKGj5da/Listeners TMPDIR=/var/folders/vl/3c7h2z3s5b9bcs05s56c_y5w00000w/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.005068 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-05 18:22:19.529032) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-05 18:22:19.529433) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-05 18:22:19.529793) ========= ========= Total master_lag: 0.46 =========