builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0243 starttime: 1492445327.99 results: success (0) buildid: 20170417074007 builduid: 7314988962fd43fe8d2f4db3cc874dd8 revision: d68c89ad6e51a45ebbe260f865852549bb622e46 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-17 09:08:47.990533) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-17 09:08:47.990996) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-17 09:08:47.991289) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-17 09:08:48.018338) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 09:08:48.018776) ========= 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.MzysDDNlFw/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.eLbL0192lf/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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-17 09:08:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.1M=0.001s 2017-04-17 09:08:48 (18.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.146196 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 09:08:48.191280) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 09:08:48.191717) ========= 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.MzysDDNlFw/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.eLbL0192lf/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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.038000 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 09:08:48.251622) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-04-17 09:08:48.252016) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev d68c89ad6e51a45ebbe260f865852549bb622e46 --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 d68c89ad6e51a45ebbe260f865852549bb622e46 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MzysDDNlFw/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.eLbL0192lf/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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-17 09:08:48,523 truncating revision to first 12 chars 2017-04-17 09:08:48,523 Setting DEBUG logging. 2017-04-17 09:08:48,523 attempt 1/10 2017-04-17 09:08:48,523 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/d68c89ad6e51?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-17 09:08:50,043 unpacking tar archive at: mozilla-aurora-d68c89ad6e51/testing/mozharness/ program finished with exit code 0 elapsedTime=1.784852 ========= master_lag: 1.77 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-04-17 09:08:51.804980) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-17 09:08:51.805301) ========= script_repo_revision: d68c89ad6e51a45ebbe260f865852549bb622e46 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-17 09:08:51.805698) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-17 09:08:51.809002) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-17 09:08:51.832320) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-17 09:08:51.832633) ========= /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.MzysDDNlFw/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.eLbL0192lf/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:08:52 INFO - MultiFileLogger online at 20170417 09:08:52 in /builds/slave/test 09:08:52 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 09:08:52 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:08:52 INFO - {'append_to_log': False, 09:08:52 INFO - 'base_work_dir': '/builds/slave/test', 09:08:52 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:08:52 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:08:52 INFO - 'branch': 'Mozilla-Aurora', 09:08:52 INFO - 'buildbot_json_path': 'buildprops.json', 09:08:52 INFO - 'config_files': ('talos/mac_config.py',), 09:08:52 INFO - 'default_actions': ('clobber', 09:08:52 INFO - 'read-buildbot-config', 09:08:52 INFO - 'download-and-extract', 09:08:52 INFO - 'populate-webroot', 09:08:52 INFO - 'create-virtualenv', 09:08:52 INFO - 'install', 09:08:52 INFO - 'run-tests'), 09:08:52 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:08:52 INFO - 'download_minidump_stackwalk': True, 09:08:52 INFO - 'download_symbols': 'ondemand', 09:08:52 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:08:52 INFO - 'tooltool.py': '/tools/tooltool.py', 09:08:52 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:08:52 INFO - '/tools/misc-python/virtualenv.py')}, 09:08:52 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:08:52 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:08:52 INFO - 'gecko_profile': False, 09:08:52 INFO - 'gecko_profile_interval': 0, 09:08:52 INFO - 'installer_path': 'installer.exe', 09:08:52 INFO - 'log_level': 'info', 09:08:52 INFO - 'log_name': 'talos', 09:08:52 INFO - 'log_to_console': True, 09:08:52 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:08:52 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:08:52 INFO - 'opt_config_files': (), 09:08:52 INFO - 'pip_index': False, 09:08:52 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:08:52 INFO - 'cmd': ('bash', 09:08:52 INFO - '-c', 09:08:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:08:52 INFO - 'enabled': True, 09:08:52 INFO - 'halt_on_failure': False, 09:08:52 INFO - 'name': 'check_screen_resolution'},), 09:08:52 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:08:52 INFO - 'cmd': ('bash', 09:08:52 INFO - '-c', 09:08:52 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:08:52 INFO - 'enabled': True, 09:08:52 INFO - 'halt_on_failure': False, 09:08:52 INFO - 'name': 'check_screen_resolution'},), 09:08:52 INFO - 'run_cmd_checks_enabled': True, 09:08:52 INFO - 'suite': 'dromaeojs-e10s', 09:08:52 INFO - 'system_bits': '32', 09:08:52 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:08:52 INFO - 'title': 't-yosemite-r7-0243', 09:08:52 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:08:52 INFO - 'use_talos_json': True, 09:08:52 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:08:52 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:08:52 INFO - 'work_dir': 'build'} 09:08:52 INFO - [mozharness: 2017-04-17 16:08:52.208018Z] Running clobber step. 09:08:52 INFO - Running pre-action listener: _resource_record_pre_action 09:08:52 INFO - Running main action method: clobber 09:08:52 INFO - rmtree: /builds/slave/test/build 09:08:52 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:08:54 INFO - Running post-action listener: _resource_record_post_action 09:08:54 INFO - [mozharness: 2017-04-17 16:08:54.599963Z] Finished clobber step (success) 09:08:54 INFO - [mozharness: 2017-04-17 16:08:54.600184Z] Running read-buildbot-config step. 09:08:54 INFO - Running pre-action listener: _resource_record_pre_action 09:08:54 INFO - Running main action method: read_buildbot_config 09:08:54 INFO - Using buildbot properties: 09:08:54 INFO - { 09:08:54 INFO - "project": "", 09:08:54 INFO - "product": "firefox", 09:08:54 INFO - "script_repo_revision": "production", 09:08:54 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:08:54 INFO - "repository": "", 09:08:54 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:08:54 INFO - "buildid": "20170417074007", 09:08:54 INFO - "pgo_build": "False", 09:08:54 INFO - "basedir": "/builds/slave/test", 09:08:54 INFO - "buildnumber": 42, 09:08:54 INFO - "slavename": "t-yosemite-r7-0243", 09:08:54 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:08:54 INFO - "platform": "yosemite_r7", 09:08:54 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:08:54 INFO - "branch": "mozilla-aurora", 09:08:54 INFO - "repo_path": "releases/mozilla-aurora", 09:08:54 INFO - "revision": "d68c89ad6e51a45ebbe260f865852549bb622e46", 09:08:54 INFO - "stage_platform": "macosx64", 09:08:54 INFO - "builduid": "7314988962fd43fe8d2f4db3cc874dd8", 09:08:54 INFO - "slavebuilddir": "test" 09:08:54 INFO - } 09:08:54 INFO - Found installer url https://queue.taskcluster.net/v1/task/S0kFTKvcSi-jOgJchOkJBw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 09:08:54 INFO - Running post-action listener: _resource_record_post_action 09:08:54 INFO - [mozharness: 2017-04-17 16:08:54.605798Z] Finished read-buildbot-config step (success) 09:08:54 INFO - [mozharness: 2017-04-17 16:08:54.606028Z] Running download-and-extract step. 09:08:54 INFO - Running pre-action listener: _resource_record_pre_action 09:08:54 INFO - Running main action method: download_and_extract 09:08:54 INFO - mkdir: /builds/slave/test/build/tests 09:08:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:08:54 INFO - trying https://queue.taskcluster.net/v1/task/S0kFTKvcSi-jOgJchOkJBw/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 09:08:54 INFO - Downloading https://queue.taskcluster.net/v1/task/S0kFTKvcSi-jOgJchOkJBw/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 09:08:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S0kFTKvcSi-jOgJchOkJBw/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 09:08:55 INFO - Downloaded 1199 bytes. 09:08:55 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 09:08:55 INFO - Using the following test package requirements: 09:08:55 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 09:08:55 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:08:55 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 09:08:55 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:08:55 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 09:08:55 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:08:55 INFO - u'jsshell-mac.zip'], 09:08:55 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:08:55 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 09:08:55 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 09:08:55 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:08:55 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 09:08:55 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:08:55 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 09:08:55 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:08:55 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 09:08:55 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 09:08:55 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 09:08:55 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:08:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S0kFTKvcSi-jOgJchOkJBw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 09:08:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S0kFTKvcSi-jOgJchOkJBw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:08:55 INFO - Fetch https://queue.taskcluster.net/v1/task/S0kFTKvcSi-jOgJchOkJBw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 09:08:57 INFO - Expected file size: 51790867 09:08:57 INFO - Obtained file size: 51790867 09:09:12 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 09:09:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S0kFTKvcSi-jOgJchOkJBw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 09:09:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S0kFTKvcSi-jOgJchOkJBw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:09:12 INFO - Fetch https://queue.taskcluster.net/v1/task/S0kFTKvcSi-jOgJchOkJBw/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 09:09:14 INFO - Expected file size: 51790867 09:09:14 INFO - Obtained file size: 51790867 09:09:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S0kFTKvcSi-jOgJchOkJBw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 09:09:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S0kFTKvcSi-jOgJchOkJBw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:09:30 INFO - Fetch https://queue.taskcluster.net/v1/task/S0kFTKvcSi-jOgJchOkJBw/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 09:09:31 INFO - Expected file size: 14404424 09:09:31 INFO - Obtained file size: 14404424 09:09:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:31 INFO - trying https://queue.taskcluster.net/v1/task/S0kFTKvcSi-jOgJchOkJBw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:09:31 INFO - Downloading https://queue.taskcluster.net/v1/task/S0kFTKvcSi-jOgJchOkJBw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 09:09:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S0kFTKvcSi-jOgJchOkJBw/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 09:09:33 INFO - Downloaded 74744321 bytes. 09:09:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/S0kFTKvcSi-jOgJchOkJBw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:09:33 INFO - mkdir: /builds/slave/test/properties 09:09:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:09:33 INFO - Writing to file /builds/slave/test/properties/build_url 09:09:33 INFO - Contents: 09:09:33 INFO - build_url:https://queue.taskcluster.net/v1/task/S0kFTKvcSi-jOgJchOkJBw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 09:09:34 INFO - Running post-action listener: _resource_record_post_action 09:09:34 INFO - Running post-action listener: set_extra_try_arguments 09:09:34 INFO - [mozharness: 2017-04-17 16:09:34.529488Z] Finished download-and-extract step (success) 09:09:34 INFO - [mozharness: 2017-04-17 16:09:34.529646Z] Running populate-webroot step. 09:09:34 INFO - Running pre-action listener: _resource_record_pre_action 09:09:34 INFO - Running main action method: populate_webroot 09:09:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:09:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:09:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:09:34 INFO - '--tppagecycles', 09:09:34 INFO - '7'], 09:09:34 INFO - 'tests': ['tsvgm']}}, 09:09:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:09:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:09:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:09:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:09:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:09:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:09:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:09:34 INFO - 'talos_options': ['--disable-e10s'], 09:09:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:09:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:09:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:09:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:09:34 INFO - 'talos_options': ['--disable-e10s'], 09:09:34 INFO - 'tests': ['damp', 'tps']}, 09:09:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:09:34 INFO - 'tests': ['damp', 'tps']}, 09:09:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:09:34 INFO - 'tests': ['dromaeo_dom']}, 09:09:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:09:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:09:34 INFO - 'tests': ['basic_compositor_video']}, 09:09:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:09:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:09:34 INFO - 'tests': ['a11yr', 09:09:34 INFO - 'ts_paint', 09:09:34 INFO - 'tpaint', 09:09:34 INFO - 'sessionrestore', 09:09:34 INFO - 'sessionrestore_no_auto_restore', 09:09:34 INFO - 'tabpaint']}, 09:09:34 INFO - 'other-e10s': {'tests': ['a11yr', 09:09:34 INFO - 'ts_paint', 09:09:34 INFO - 'tpaint', 09:09:34 INFO - 'sessionrestore', 09:09:34 INFO - 'sessionrestore_no_auto_restore', 09:09:34 INFO - 'tabpaint']}, 09:09:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:09:34 INFO - 'ts_paint', 09:09:34 INFO - 'tpaint', 09:09:34 INFO - 'sessionrestore', 09:09:34 INFO - 'sessionrestore_no_auto_restore', 09:09:34 INFO - 'tabpaint']}, 09:09:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:09:34 INFO - 'ts_paint', 09:09:34 INFO - 'tpaint', 09:09:34 INFO - 'sessionrestore', 09:09:34 INFO - 'sessionrestore_no_auto_restore', 09:09:34 INFO - 'tabpaint']}, 09:09:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:09:34 INFO - 'tests': ['a11yr', 09:09:34 INFO - 'ts_paint', 09:09:34 INFO - 'tpaint', 09:09:34 INFO - 'sessionrestore', 09:09:34 INFO - 'sessionrestore_no_auto_restore', 09:09:34 INFO - 'tabpaint']}, 09:09:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:09:34 INFO - 'tests': ['a11yr', 09:09:34 INFO - 'ts_paint', 09:09:34 INFO - 'tpaint', 09:09:34 INFO - 'sessionrestore', 09:09:34 INFO - 'sessionrestore_no_auto_restore', 09:09:34 INFO - 'tabpaint']}, 09:09:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:09:34 INFO - 'tests': ['tsvgx', 09:09:34 INFO - 'tsvgr_opacity', 09:09:34 INFO - 'tart', 09:09:34 INFO - 'tscrollx', 09:09:34 INFO - 'cart', 09:09:34 INFO - 'tsvg_static']}, 09:09:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:09:34 INFO - 'tsvgr_opacity', 09:09:34 INFO - 'tart', 09:09:34 INFO - 'tscrollx', 09:09:34 INFO - 'cart', 09:09:34 INFO - 'tsvg_static']}, 09:09:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:09:34 INFO - 'talos_options': ['--disable-e10s'], 09:09:34 INFO - 'tests': ['tp5o']}, 09:09:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:09:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:09:34 INFO - 'talos_options': ['--disable-e10s', 09:09:34 INFO - '--xperf_path', 09:09:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:09:34 INFO - 'tests': ['tp5n']}, 09:09:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:09:34 INFO - 'talos_options': ['--xperf_path', 09:09:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:09:34 INFO - 'tests': ['tp5n']}}, 09:09:34 INFO - 'talos.zip': {'path': '', 09:09:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:09:34 INFO - Running post-action listener: _resource_record_post_action 09:09:34 INFO - [mozharness: 2017-04-17 16:09:34.548557Z] Finished populate-webroot step (success) 09:09:34 INFO - [mozharness: 2017-04-17 16:09:34.548650Z] Running create-virtualenv step. 09:09:34 INFO - Running pre-action listener: _resource_record_pre_action 09:09:34 INFO - Running main action method: create_virtualenv 09:09:34 INFO - Creating virtualenv /builds/slave/test/build/venv 09:09:34 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:09:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:09:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:09:34 INFO - Using real prefix '/tools/python27' 09:09:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:09:35 INFO - Installing distribute.............................................................................................................................................................................................done. 09:09:38 INFO - Installing pip.................done. 09:09:38 INFO - Return code: 0 09:09:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:09:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:09:38 INFO - Reading from file tmpfile_stdout 09:09:38 INFO - Output received: 09:09:38 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:09:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:38 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 0x10b379b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b37c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e89dc28f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b157d30>, '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 0x10b07edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b14cbe8>, '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.MzysDDNlFw/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.eLbL0192lf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:09:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:09:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:09:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzysDDNlFw/Render', 09:09:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:09:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:09:38 INFO - 'HOME': '/Users/cltbld', 09:09:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:09:38 INFO - 'LOGNAME': 'cltbld', 09:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:38 INFO - 'MOZ_NO_REMOTE': '1', 09:09:38 INFO - 'NO_EM_RESTART': '1', 09:09:38 INFO - 'PAGER': '/bin/cat', 09:09:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:09:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:38 INFO - 'PWD': '/builds/slave/test', 09:09:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:09:38 INFO - 'SHELL': '/bin/bash', 09:09:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eLbL0192lf/Listeners', 09:09:38 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:09:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:09:38 INFO - 'USER': 'cltbld', 09:09:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:09:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:38 INFO - 'XPC_FLAGS': '0x0', 09:09:38 INFO - 'XPC_SERVICE_NAME': '0', 09:09:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:09:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:38 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:09:38 INFO - Cleaning up... 09:09:38 INFO - Return code: 0 09:09:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:38 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 0x10b379b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b37c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e89dc28f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b157d30>, '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 0x10b07edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b14cbe8>, '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.MzysDDNlFw/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.eLbL0192lf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:09:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:09:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:09:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzysDDNlFw/Render', 09:09:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:09:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:09:38 INFO - 'HOME': '/Users/cltbld', 09:09:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:09:38 INFO - 'LOGNAME': 'cltbld', 09:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:38 INFO - 'MOZ_NO_REMOTE': '1', 09:09:38 INFO - 'NO_EM_RESTART': '1', 09:09:38 INFO - 'PAGER': '/bin/cat', 09:09:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:09:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:38 INFO - 'PWD': '/builds/slave/test', 09:09:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:09:38 INFO - 'SHELL': '/bin/bash', 09:09:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eLbL0192lf/Listeners', 09:09:38 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:09:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:09:38 INFO - 'USER': 'cltbld', 09:09:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:09:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:38 INFO - 'XPC_FLAGS': '0x0', 09:09:38 INFO - 'XPC_SERVICE_NAME': '0', 09:09:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:09:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:39 INFO - Downloading/unpacking psutil>=3.1.1 09:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:09:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:09:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:09:42 INFO - Installing collected packages: psutil 09:09:42 INFO - Running setup.py install for psutil 09:09:42 INFO - building 'psutil._psutil_osx' extension 09:09:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:09:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:09:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:09:43 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:09:43 INFO - building 'psutil._psutil_posix' extension 09:09:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:09:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:09:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:09:43 INFO - ^ 09:09:43 INFO - 1 warning generated. 09:09:43 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:09:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:09:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:09:43 INFO - Successfully installed psutil 09:09:43 INFO - Cleaning up... 09:09:43 INFO - Return code: 0 09:09:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:09: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')]} 09:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:44 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 0x10b379b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b37c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e89dc28f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b157d30>, '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 0x10b07edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b14cbe8>, '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.MzysDDNlFw/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.eLbL0192lf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:09:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:09:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:09:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzysDDNlFw/Render', 09:09:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:09:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:09:44 INFO - 'HOME': '/Users/cltbld', 09:09:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:09:44 INFO - 'LOGNAME': 'cltbld', 09:09:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:44 INFO - 'MOZ_NO_REMOTE': '1', 09:09:44 INFO - 'NO_EM_RESTART': '1', 09:09:44 INFO - 'PAGER': '/bin/cat', 09:09:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:09:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:44 INFO - 'PWD': '/builds/slave/test', 09:09:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:09:44 INFO - 'SHELL': '/bin/bash', 09:09:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eLbL0192lf/Listeners', 09:09:44 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:09:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:09:44 INFO - 'USER': 'cltbld', 09:09:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:09:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:09:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:44 INFO - 'XPC_FLAGS': '0x0', 09:09:44 INFO - 'XPC_SERVICE_NAME': '0', 09:09:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:09:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:09:47 INFO - Installing collected packages: mozsystemmonitor 09:09:47 INFO - Running setup.py install for mozsystemmonitor 09:09:47 INFO - Successfully installed mozsystemmonitor 09:09:47 INFO - Cleaning up... 09:09:47 INFO - Return code: 0 09:09:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:09:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b379b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b37c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e89dc28f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b157d30>, '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 0x10b07edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b14cbe8>, '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.MzysDDNlFw/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.eLbL0192lf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:09:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:09:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:09:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzysDDNlFw/Render', 09:09:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:09:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:09:47 INFO - 'HOME': '/Users/cltbld', 09:09:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:09:47 INFO - 'LOGNAME': 'cltbld', 09:09:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:47 INFO - 'MOZ_NO_REMOTE': '1', 09:09:47 INFO - 'NO_EM_RESTART': '1', 09:09:47 INFO - 'PAGER': '/bin/cat', 09:09:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:09:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:47 INFO - 'PWD': '/builds/slave/test', 09:09:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:09:47 INFO - 'SHELL': '/bin/bash', 09:09:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eLbL0192lf/Listeners', 09:09:47 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:09:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:09:47 INFO - 'USER': 'cltbld', 09:09:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:09:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:09:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:47 INFO - 'XPC_FLAGS': '0x0', 09:09:47 INFO - 'XPC_SERVICE_NAME': '0', 09:09:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:09:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:48 INFO - Downloading/unpacking jsonschema==2.5.1 09:09: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 09:09: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 09:09: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 09:09: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 09:09: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 09:09: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 09:09:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:09:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:51 INFO - Downloading functools32-3.2.3-2.tar.gz 09:09:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:09:51 INFO - warning: no files found matching '*.txt' 09:09:51 INFO - no previously-included directories found matching 'build' 09:09:51 INFO - no previously-included directories found matching 'dist' 09:09:51 INFO - no previously-included directories found matching '.git*' 09:09:51 INFO - Installing collected packages: jsonschema, functools32 09:09:51 INFO - Running setup.py install for functools32 09:09:51 INFO - warning: no files found matching '*.txt' 09:09:51 INFO - no previously-included directories found matching 'build' 09:09:51 INFO - no previously-included directories found matching 'dist' 09:09:51 INFO - no previously-included directories found matching '.git*' 09:09:51 INFO - Successfully installed jsonschema functools32 09:09:51 INFO - Cleaning up... 09:09:51 INFO - Return code: 0 09:09:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:09:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:51 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 0x10b379b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b37c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e89dc28f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b157d30>, '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 0x10b07edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b14cbe8>, '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.MzysDDNlFw/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.eLbL0192lf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:09:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:09:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:09:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzysDDNlFw/Render', 09:09:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:09:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:09:51 INFO - 'HOME': '/Users/cltbld', 09:09:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:09:51 INFO - 'LOGNAME': 'cltbld', 09:09:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:51 INFO - 'MOZ_NO_REMOTE': '1', 09:09:51 INFO - 'NO_EM_RESTART': '1', 09:09:51 INFO - 'PAGER': '/bin/cat', 09:09:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:09:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:51 INFO - 'PWD': '/builds/slave/test', 09:09:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:09:51 INFO - 'SHELL': '/bin/bash', 09:09:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eLbL0192lf/Listeners', 09:09:51 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:09:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:09:51 INFO - 'USER': 'cltbld', 09:09:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:09:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:09:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:51 INFO - 'XPC_FLAGS': '0x0', 09:09:51 INFO - 'XPC_SERVICE_NAME': '0', 09:09:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:51 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:09:51 INFO - Cleaning up... 09:09:51 INFO - Return code: 0 09:09:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:09:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:09:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:51 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 0x10b379b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b37c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e89dc28f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b157d30>, '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 0x10b07edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b14cbe8>, '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.MzysDDNlFw/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.eLbL0192lf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:09:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:09:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:09:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzysDDNlFw/Render', 09:09:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:09:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:09:51 INFO - 'HOME': '/Users/cltbld', 09:09:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:09:51 INFO - 'LOGNAME': 'cltbld', 09:09:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:51 INFO - 'MOZ_NO_REMOTE': '1', 09:09:51 INFO - 'NO_EM_RESTART': '1', 09:09:51 INFO - 'PAGER': '/bin/cat', 09:09:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:09:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:51 INFO - 'PWD': '/builds/slave/test', 09:09:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:09:51 INFO - 'SHELL': '/bin/bash', 09:09:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eLbL0192lf/Listeners', 09:09:51 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:09:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:09:51 INFO - 'USER': 'cltbld', 09:09:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:09:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:09:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:51 INFO - 'XPC_FLAGS': '0x0', 09:09:51 INFO - 'XPC_SERVICE_NAME': '0', 09:09:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:09:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:52 INFO - Downloading/unpacking blobuploader==1.2.4 09:09: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 09:09: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 09:09: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 09:09: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 09:09: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 09:09: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 09:09:54 INFO - Downloading blobuploader-1.2.4.tar.gz 09:09:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:09:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:09:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:09:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:09:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:09: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 09:09: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 09:09: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 09:09: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 09:09: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 09:09: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 09:09:55 INFO - Downloading docopt-0.6.1.tar.gz 09:09:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:09:56 INFO - Installing collected packages: blobuploader, requests, docopt 09:09:56 INFO - Running setup.py install for blobuploader 09:09:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:09:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:09:56 INFO - Running setup.py install for requests 09:09:56 INFO - Running setup.py install for docopt 09:09:56 INFO - Successfully installed blobuploader requests docopt 09:09:56 INFO - Cleaning up... 09:09:56 INFO - Return code: 0 09:09:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:09: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')]} 09:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:09:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09: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')]} 09:09:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:09:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:09:56 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 0x10b379b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b37c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e89dc28f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b157d30>, '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 0x10b07edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b14cbe8>, '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.MzysDDNlFw/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.eLbL0192lf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:09:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:09:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:09:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzysDDNlFw/Render', 09:09:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:09:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:09:56 INFO - 'HOME': '/Users/cltbld', 09:09:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:09:56 INFO - 'LOGNAME': 'cltbld', 09:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:09:56 INFO - 'MOZ_NO_REMOTE': '1', 09:09:56 INFO - 'NO_EM_RESTART': '1', 09:09:56 INFO - 'PAGER': '/bin/cat', 09:09:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:09:56 INFO - 'PWD': '/builds/slave/test', 09:09:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:09:56 INFO - 'SHELL': '/bin/bash', 09:09:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eLbL0192lf/Listeners', 09:09:56 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:09:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:09:56 INFO - 'USER': 'cltbld', 09:09:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:09:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:09:56 INFO - 'XPC_FLAGS': '0x0', 09:09:56 INFO - 'XPC_SERVICE_NAME': '0', 09:09:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:09:57 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-ZllvnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:09:57 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-K1cXJW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:09:57 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-UuzGBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:09:57 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-DYhG5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:09:57 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-ELwWu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:09:57 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-S2w3Lu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:09:57 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-9zalLA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:09:57 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-hiNgOE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:09:58 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-U7Ff6A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:09:58 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-dpejf6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:09:58 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-4ag10R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:09:58 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-K7wXLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:09:58 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-TqYfEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:09:58 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-nNxviJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:09:58 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-r2Dkyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:09:58 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-v8q4i_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:09:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:09:58 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-a5LSsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:09:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:09:58 INFO - Running setup.py install for manifestparser 09:09:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:09:59 INFO - Running setup.py install for mozcrash 09:09:59 INFO - Running setup.py install for mozdebug 09:09:59 INFO - Running setup.py install for mozdevice 09:09:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:09:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:09:59 INFO - Running setup.py install for mozfile 09:09:59 INFO - Running setup.py install for mozhttpd 09:09:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:09:59 INFO - Running setup.py install for mozinfo 09:09:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:10:00 INFO - Running setup.py install for mozInstall 09:10:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:10:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:10:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:10:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:10:00 INFO - Running setup.py install for mozleak 09:10:00 INFO - Running setup.py install for mozlog 09:10:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:10:00 INFO - Running setup.py install for moznetwork 09:10:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:10:00 INFO - Running setup.py install for mozprocess 09:10:00 INFO - Running setup.py install for mozprofile 09:10:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:10:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:10:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:10:00 INFO - Running setup.py install for mozrunner 09:10:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:10:01 INFO - Running setup.py install for mozscreenshot 09:10:01 INFO - Running setup.py install for moztest 09:10:01 INFO - Running setup.py install for mozversion 09:10:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:10:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:10:01 INFO - Cleaning up... 09:10:01 INFO - Return code: 0 09:10:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:10: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')]} 09:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10: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')]} 09:10:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:01 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 0x10b379b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b37c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e89dc28f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b157d30>, '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 0x10b07edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b14cbe8>, '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.MzysDDNlFw/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.eLbL0192lf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:10:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:10:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:10:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzysDDNlFw/Render', 09:10:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:01 INFO - 'HOME': '/Users/cltbld', 09:10:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:01 INFO - 'LOGNAME': 'cltbld', 09:10:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:01 INFO - 'MOZ_NO_REMOTE': '1', 09:10:01 INFO - 'NO_EM_RESTART': '1', 09:10:01 INFO - 'PAGER': '/bin/cat', 09:10:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:01 INFO - 'PWD': '/builds/slave/test', 09:10:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:01 INFO - 'SHELL': '/bin/bash', 09:10:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eLbL0192lf/Listeners', 09:10:01 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:10:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:01 INFO - 'USER': 'cltbld', 09:10:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:01 INFO - 'XPC_FLAGS': '0x0', 09:10:01 INFO - 'XPC_SERVICE_NAME': '0', 09:10:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:10:01 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-rKsotG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:10:01 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:10:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:10:01 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-HIPaNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:10:02 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-ddY3Qi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:10:02 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-WMg_77-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:10:02 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-G6UYF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:10:02 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-5NBZwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:10:02 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-wAfNta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:10:02 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-RLprgZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:10:02 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-jYKOSX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:10:02 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-HmLMdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:10:02 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-ZG91Q8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:10:03 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-d5EI3i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:10:03 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-pk4oVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:10:03 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-CUWEIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:03 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-CfBvcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:10:03 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-_DVDh2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:10:03 INFO - Running setup.py (path:/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/pip-hC4Su0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:10:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:10:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:10:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:10:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:10:06 INFO - Downloading blessings-1.6.tar.gz 09:10:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:10:06 INFO - Installing collected packages: blessings 09:10:06 INFO - Running setup.py install for blessings 09:10:06 INFO - Successfully installed blessings 09:10:06 INFO - Cleaning up... 09:10:06 INFO - Return code: 0 09:10:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:10:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:10:06 INFO - Reading from file tmpfile_stdout 09:10:06 INFO - Current package versions: 09:10:06 INFO - blessings == 1.6 09:10:06 INFO - blobuploader == 1.2.4 09:10:06 INFO - docopt == 0.6.1 09:10:06 INFO - functools32 == 3.2.3-2 09:10:06 INFO - jsonschema == 2.5.1 09:10:06 INFO - manifestparser == 1.1 09:10:06 INFO - mozInstall == 1.12 09:10:06 INFO - mozcrash == 1.0 09:10:06 INFO - mozdebug == 0.1 09:10:06 INFO - mozdevice == 0.49 09:10:06 INFO - mozfile == 1.2 09:10:06 INFO - mozhttpd == 0.7 09:10:06 INFO - mozinfo == 0.9 09:10:06 INFO - mozleak == 0.1 09:10:06 INFO - mozlog == 3.4 09:10:06 INFO - moznetwork == 0.27 09:10:06 INFO - mozprocess == 0.25 09:10:06 INFO - mozprofile == 0.28 09:10:06 INFO - mozrunner == 6.13 09:10:06 INFO - mozscreenshot == 0.1 09:10:06 INFO - mozsystemmonitor == 0.3 09:10:06 INFO - moztest == 0.8 09:10:06 INFO - mozversion == 1.4 09:10:06 INFO - psutil == 3.1.1 09:10:06 INFO - requests == 1.2.3 09:10:06 INFO - wsgiref == 0.1.2 09:10:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:10:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:06 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 0x10b379b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b37c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e89dc28f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b157d30>, '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 0x10b07edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b14cbe8>, '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.MzysDDNlFw/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.eLbL0192lf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:10:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:10:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzysDDNlFw/Render', 09:10:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:06 INFO - 'HOME': '/Users/cltbld', 09:10:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:06 INFO - 'LOGNAME': 'cltbld', 09:10:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:06 INFO - 'MOZ_NO_REMOTE': '1', 09:10:06 INFO - 'NO_EM_RESTART': '1', 09:10:06 INFO - 'PAGER': '/bin/cat', 09:10:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:06 INFO - 'PWD': '/builds/slave/test', 09:10:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:06 INFO - 'SHELL': '/bin/bash', 09:10:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eLbL0192lf/Listeners', 09:10:06 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:10:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:06 INFO - 'USER': 'cltbld', 09:10:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:06 INFO - 'XPC_FLAGS': '0x0', 09:10:06 INFO - 'XPC_SERVICE_NAME': '0', 09:10:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:10:07 INFO - Cleaning up... 09:10:07 INFO - Return code: 0 09:10:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:10:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:10:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:10:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:10:07 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 0x10b379b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b37c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8e89dc28f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b157d30>, '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 0x10b07edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b14cbe8>, '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.MzysDDNlFw/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.eLbL0192lf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:10:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:10:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:10:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzysDDNlFw/Render', 09:10:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:07 INFO - 'HOME': '/Users/cltbld', 09:10:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:07 INFO - 'LOGNAME': 'cltbld', 09:10:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:07 INFO - 'MOZ_NO_REMOTE': '1', 09:10:07 INFO - 'NO_EM_RESTART': '1', 09:10:07 INFO - 'PAGER': '/bin/cat', 09:10:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:07 INFO - 'PWD': '/builds/slave/test', 09:10:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:07 INFO - 'SHELL': '/bin/bash', 09:10:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eLbL0192lf/Listeners', 09:10:07 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:10:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:07 INFO - 'USER': 'cltbld', 09:10:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:07 INFO - 'XPC_FLAGS': '0x0', 09:10:07 INFO - 'XPC_SERVICE_NAME': '0', 09:10:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:10:07 INFO - Cleaning up... 09:10:07 INFO - Return code: 0 09:10:07 INFO - Running post-action listener: _resource_record_post_action 09:10:07 INFO - Running post-action listener: _start_resource_monitoring 09:10:07 INFO - Starting resource monitoring. 09:10:07 INFO - [mozharness: 2017-04-17 16:10:07.428810Z] Finished create-virtualenv step (success) 09:10:07 INFO - [mozharness: 2017-04-17 16:10:07.429754Z] Running install step. 09:10:07 INFO - Running pre-action listener: _resource_record_pre_action 09:10:07 INFO - Running main action method: install 09:10:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:10:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:10:07 INFO - Reading from file tmpfile_stdout 09:10:07 INFO - Detecting whether we're running mozinstall >=1.0... 09:10:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:10:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:10:07 INFO - Reading from file tmpfile_stdout 09:10:07 INFO - Output received: 09:10:07 INFO - Usage: mozinstall [options] installer 09:10:07 INFO - Options: 09:10:07 INFO - -h, --help show this help message and exit 09:10:07 INFO - -d DEST, --destination=DEST 09:10:07 INFO - Directory to install application into. [default: 09:10:07 INFO - "/builds/slave/test"] 09:10:07 INFO - --app=APP Application being installed. [default: firefox] 09:10:07 INFO - mkdir: /builds/slave/test/build/application 09:10:07 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'] 09:10:07 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 09:10:28 INFO - Reading from file tmpfile_stdout 09:10:28 INFO - Output received: 09:10:28 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:10:28 INFO - Running post-action listener: _resource_record_post_action 09:10:28 INFO - [mozharness: 2017-04-17 16:10:28.196452Z] Finished install step (success) 09:10:28 INFO - [mozharness: 2017-04-17 16:10:28.196585Z] Running run-tests step. 09:10:28 INFO - Running pre-action listener: _resource_record_pre_action 09:10:28 INFO - Running main action method: run_tests 09:10:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:10:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:10:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:10:28 INFO - 2017-04-17 09:10:28.211 screenresolution[1625:7418] starting screenresolution argv=screenresolution get 09:10:28 INFO - 2017-04-17 09:10:28.213 screenresolution[1625:7418] Display 0: 1600x1200x32@60 09:10:28 INFO - 2017-04-17 09:10:28.219 screenresolution[1626:7421] starting screenresolution argv=screenresolution list 09:10:28 INFO - Available Modes on Display 0 09:10:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:10:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:10:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:10:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:10:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:10:28 INFO - Intel Iris: 09:10:28 INFO - Chipset Model: Intel Iris 09:10:28 INFO - Type: GPU 09:10:28 INFO - Bus: Built-In 09:10:28 INFO - VRAM (Dynamic, Max): 1536 MB 09:10:28 INFO - Vendor: Intel (0x8086) 09:10:28 INFO - Device ID: 0x0a2e 09:10:28 INFO - Revision ID: 0x0009 09:10:28 INFO - Displays: 09:10:28 INFO - Display: 09:10:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:10:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:10:28 INFO - Display Serial Number: 1600x1200 60 09:10:28 INFO - Main Display: Yes 09:10:28 INFO - Mirror: Off 09:10:28 INFO - Online: Yes 09:10:28 INFO - Rotation: Supported 09:10:28 INFO - Return code: 0 09:10:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:10:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:10:28 INFO - Python 2.7.3 09:10:28 INFO - Return code: 0 09:10:28 INFO - grabbing minidump binary from tooltool 09:10:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:10:28 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 0x10b157d30>, '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 0x10b07edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b14cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:10:28 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:10:28 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:10:28 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:10:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:10:28 INFO - Return code: 0 09:10:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:10:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:10:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:10:28 INFO - ENV: RUST_BACKTRACE is now 1 09:10:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:10:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:10:28 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/S0kFTKvcSi-jOgJchOkJBw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0243', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:10:28 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/S0kFTKvcSi-jOgJchOkJBw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0243 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:10:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzysDDNlFw/Render', 09:10:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:10:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:10:28 INFO - 'HOME': '/Users/cltbld', 09:10:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:10:28 INFO - 'LOGNAME': 'cltbld', 09:10:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:10:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:10:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:10:28 INFO - 'MOZ_NO_REMOTE': '1', 09:10:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:10:28 INFO - 'NO_EM_RESTART': '1', 09:10:28 INFO - 'PAGER': '/bin/cat', 09:10:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:10:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:10:28 INFO - 'PWD': '/builds/slave/test', 09:10:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:10:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:10:28 INFO - 'RUST_BACKTRACE': '1', 09:10:28 INFO - 'SHELL': '/bin/bash', 09:10:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eLbL0192lf/Listeners', 09:10:28 INFO - 'TMPDIR': '/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/', 09:10:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:10:28 INFO - 'USER': 'cltbld', 09:10:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:10:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:10:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:10:28 INFO - 'XPC_FLAGS': '0x0', 09:10:28 INFO - 'XPC_SERVICE_NAME': '0', 09:10:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:10:28 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/S0kFTKvcSi-jOgJchOkJBw/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0243', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:10:28 INFO - mozversion application_buildid: 20170417074007 09:10:28 INFO - mozversion application_changeset: d68c89ad6e51a45ebbe260f865852549bb622e46 09:10:28 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:10:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:10:28 INFO - mozversion application_name: Firefox 09:10:28 INFO - mozversion application_remotingname: firefox-dev 09:10:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:10:28 INFO - mozversion application_vendor: Mozilla 09:10:28 INFO - mozversion application_version: 54.0a2 09:10:28 INFO - mozversion platform_buildid: 20170417074007 09:10:28 INFO - mozversion platform_changeset: d68c89ad6e51a45ebbe260f865852549bb622e46 09:10:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:10:28 INFO - mozversion platform_version: 54.0a2 09:10:28 INFO - using testdate: 1492445428 09:10:28 INFO - actual date: 1492445428 09:10:28 INFO - starting webserver on 'localhost:49245' 09:10:28 INFO - SUITE-START | Running 2 tests 09:10:28 INFO - TEST-START | dromaeo_css 09:10:28 INFO - operating with platform_type : mac_ 09:10:28 INFO - Initialising browser for dromaeo_css test... 09:10:28 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpFvlxWM/profile http://localhost:49245/getInfo.html) 09:10:31 INFO - PROCESS | 1636 | 2017-04-17 09:10:31.640 plugin-container[1638:7592] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 09:10:31 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:10:31 INFO - PROCESS | 1636 | 2017-04-17 09:10:31.643 plugin-container[1638:7592] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e43, name = 'com.apple.CFPasteboardClient' 09:10:31 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:10:31 INFO - PROCESS | 1636 | 2017-04-17 09:10:31.643 plugin-container[1638:7592] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:10:31 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 09:10:31 INFO - PROCESS | 1636 | colorDepth:24 09:10:31 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 09:10:31 INFO - PROCESS | 1636 | __metrics 09:10:31 ERROR - PROCESS | 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 09:10:32 INFO - TEST-INFO | 1636: exit 0 09:10:32 INFO - Browser initialized. 09:10:32 INFO - Running cycle 1/1 for dromaeo_css test... 09:10:32 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmpFvlxWM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:10:35 INFO - PROCESS | 1639 | 2017-04-17 09:10:35.156 plugin-container[1640:7737] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 09:10:35 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:10:35 INFO - PROCESS | 1639 | 2017-04-17 09:10:35.157 plugin-container[1640:7737] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 09:10:35 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:10:35 INFO - PROCESS | 1639 | 2017-04-17 09:10:35.157 plugin-container[1640:7737] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:10:44 INFO - PROCESS | 1639 | RSS: Main: 220942336 09:10:44 INFO - PROCESS | 1639 | 09:13:48 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 09:13:50 INFO - PROCESS | 1639 | RSS: Main: 245682176 09:13:50 INFO - PROCESS | 1639 | 09:15:32 INFO - PROCESS | 1639 | ************************* 09:15:32 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 09:15:32 INFO - PROCESS | 1639 | 09:15:32 ERROR - PROCESS | 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:15:32 INFO - PROCESS | 1639 | Full stack: 09:15:32 INFO - PROCESS | 1639 | ************************* 09:15:32 INFO - PROCESS | 1639 | ************************* 09:15:32 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 09:15:32 INFO - PROCESS | 1639 | 09:15:32 ERROR - PROCESS | 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:15:32 INFO - PROCESS | 1639 | Full stack: 09:15:32 INFO - PROCESS | 1639 | ************************* 09:15:32 INFO - PROCESS | 1639 | ************************* 09:15:32 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 09:15:32 INFO - PROCESS | 1639 | 09:15:32 ERROR - PROCESS | 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:15:32 INFO - PROCESS | 1639 | Full stack: 09:15:32 INFO - PROCESS | 1639 | ************************* 09:15:32 INFO - PROCESS | 1639 | ************************* 09:15:32 INFO - PROCESS | 1639 | A coding exception was thrown and uncaught in a Task. 09:15:32 INFO - PROCESS | 1639 | 09:15:32 ERROR - PROCESS | 1639 | Full message: TypeError: NetworkError when attempting to fetch resource. 09:15:32 INFO - PROCESS | 1639 | Full stack: 09:15:32 INFO - PROCESS | 1639 | ************************* 09:16:52 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 09:16:53 INFO - PROCESS | 1639 | RSS: Main: 252583936 09:16:53 INFO - PROCESS | 1639 | 09:19:54 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 09:19:55 INFO - PROCESS | 1639 | RSS: Main: 259002368 09:19:55 INFO - PROCESS | 1639 | 09:22:57 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 09:22:58 INFO - PROCESS | 1639 | RSS: Main: 255410176 09:22:58 INFO - PROCESS | 1639 | 09:26:01 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 09:26:02 INFO - PROCESS | 1639 | RSS: Main: 260767744 09:26:02 INFO - PROCESS | 1639 | 09:29:05 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 09:29:05 INFO - PROCESS | 1639 | RSS: Main: 257089536 09:29:05 INFO - PROCESS | 1639 | 09:29:05 INFO - PROCESS | 1639 | __start_tp_report 09:29:05 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 09:29:05 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 09:29:05 INFO - PROCESS | 1639 | |i|pagename|runs| 09:29:05 INFO - PROCESS | 1639 | |0;dojo.html;3471.1322169457635;3499.8075105869166;3507.508948747175;3519.6656317649813;3524.383232934236;6054.243219597551;6082.543809214305;6111.816645500631;6138.693065346726;6162.445379915806;6127.35662755411;6150.876982460348;6182.659953702545;6198.690065496729;6207.565470417061;5486.807961721331;5536.363318218396;5538.695371754552;5570.136628822539;5619.522340601054;5608.186812912125;5611.719414029302;5626.184203290521;5636.239107720467;5657.8585535361735;5362.0616392131415;5414.160805075219;5560.443955604448;5587.8603034924245;5591.580631452637;5556.361018482886;5558.610897237195;5577.832665020056;5602.24369710088;5625.437456254383;5419.3225846769155;5494.120940649477;5529.972350138223;5533.00405926933;5545.390007099216;5727.198192253088;6120.234970628671;6153.292371377304;6186.84532886677;6194.194754681907;6263.749450022039;6302.243730752293;6312.779052733178;6325.430711235965;6328.240611126648;6460.3862633049785;6492.610443373413;6501.642409667514;6523.608583485006;6529.771457998995;6174.413430724074;6177.289611694623;6179.783707570214;6203.2245969253845;6226.968865155645;6399.520035997226;6401;6603.339666033358;6608.471322294205;6668.599884006975;6326.652034138075;6343.175387199634;6370.362963703593;6377.649229292433;6393.872122557523;5556.777728890799;6424.935750642528;6484.967575162094;6548.443382312556;6563.540552161398;6273.12176295319;6319.6208227506495;6435.066915297256;6455.7417703292285;6477;5010.5240002199735;6145.525073982212;6225.844353891099;6277.2781130170615;6328.019157030575;5139.743012849342;5148.176291793295;5180.533751962342;5212.661177023481;5218.165093585084;4608.9308660370125;4628.583427491542;4630.86107416778;4644.233701439225;4652;6009.06859436788;6149.631022138686;6247.562670613104;6266.060090986389;6289.0880822280515;6313.842153946096;6319.178506794179;6367.586106903036;6483.870322593521;6523.478121750249;5195.636305458657;6195.81412557615;6216.098665693449;6229.127922090911;6233.345498722569;5570.108782594765;5754.19441278213;5763.7406316715005;5776.451237132465;5789.8263052108505;6465.935340646533;6488.578242414228;6623.933760662332;6657.533972622064;6774.983752437174;6484.740610375721;6489.059086432347;6541.574797638138;6575.901361479678;6579.342065793574;6701.66491675424;6786.287439818945;6805.353491418307;6805.421539169226;6808.148981377328;6255.593386429868;6257.342978987141;6326.398992095744;6339.985602303609;6447.290798012121;5308.283381743569;6435.871282574416;6461.321561236003;6481.4166724994975;6481.805545833632;6023.66869822164;6256.374362563707;6280.277768056732;6308.463780578702;6313.494920406449;6128.32588415265;6155.076738489083;6172.783952561639;6322.114903913548;6324.494040476843;6130.734471166852;6151.692415379123;6275.497960163085;6299.685015749286;6307.211598550181;6322.241331040304;6334.1765570475545;6337.049442583465;6339.683015849281;6350.872982540416;6106.915739268716;6150.831342044997;6231.563790534619;6243.594166379171;6297.96851015742 09:29:05 INFO - PROCESS | 1639 | |1;ext.html;737.5795796396053;775.5153029356652;824.7979245084955;966.4974213409027;969.9806003879918;37404.251914961686;38129.80935095328;40692;40790;40965.385519217234;39304.60695393045;39480.21039579207;39540.40689389654;39602.00994975127;39646.603533964655;7813.1796161402945;7935.722249721232;10459.79080418395;10758.300710453796;10954.561817527328;10610.946945265263;10635.787284254311;10718.571257149704;10724.356538607708;10746.408947507884;10681.839772403382;10721.624743133993;10723.892761072406;10735.355878647268;10758.246922715412;10591.682249532527;10611.46942652872;10634.415107169103;10663.680089597325;10666.093382062534;10391;10395.376277423378;10450.529726162342;10492.527836247387;10501.474926253733;34593.6540634593;34642.48036279458;34828.477572836426;34839;34899.4765078524;3312.7184189343934;3473.9826300868585;3490.9701638016845;3811.6378944000276;3814.065553939275;24925.279766455915;25400.36499087519;26447.73552264491;26727.46545069107;27007.32481687954;3101.6743241959493;3104.4256812489534;3140.811551306906;3235.9838200808845;3250.2524419383676;281164;345966;353510;356909.2154539237;362888;45273.77363113163;45369.09261814745;45602.77198613986;45640.8589785258;45735;46797.53202468;46844.7657761709;46911;47060;47090.293645595346;13778.448862045407;13896.23570703621;13911.86088139126;13920.37358318878;13945.302734863217;12221.144519883523;12242.204256723284;12274.570390036306;12277.20198187115;12305.876941230474;12114.75770484603;12129.33288669114;12140.696482588006;12152.21010634306;12237.571684990982;5522.861928451821;5598.188262701886;5606;5616.045272303718;5650.519705825051;905.8143080668447;921.083521895718;941.3504681769557;943.9622415103456;970.1801977329145;28449.28876778097;28677.28306792305;28713.56929646057;28774.712252877624;28786.280342991173;991.108002797488;1028.2391030637275;1044.9425281609433;1060.1042119409087;1082.9620963266248;14230.2173380463;14672.486462973744;14681.853181468263;15033.774493382609;15074.170920599472;452368;454832;455049;455390;455573;57918.710406447695;58197.70901145552;58431.4156858426;58572.70713646404;58915;1428.5428662827796;1440.4094321328205;1455.3596417576232;1465.2527211122404;1470.3383477435198;20806.479838004172;24821.1312604058;25027.624585630867;27054.51200184009;27104.32239194036;48363.03273934572;48735;48914.309712966126;48999.75500122477;49217.52347429583;436908;437540;438116;439280;439298.8035059804;31209.219769505944;31962.840185798923;32275.677243227266;32319;32480.187995300308;35991;40337.59662403456;44260;45049.324260135465;45227.54772452233;24132.51734965326;27411.31471713223;28923.13230603001;29210.7078929208;29728.554071689374;26237.212883613523;26286.60570091412;28035;28897.988570399935;28943.56584651274;23005.194818181284;27634.5854812182;32613.510797337585;32708.182295442803;33121.33757324888;28302.726377313484;28518.42963140767;28786.28034299159;29167.85416072906;29524.114276571738;22548.66177007379;23183.88408057949;23198.884005579865;23676;24278 09:29:05 INFO - PROCESS | 1639 | |2;jquery.html;345.2404709638795;407.1165570711557;410.2901979575333;411.4096271849894;451.51236664402427;14464.783028254584;16721;19874.801251987494;21191.258305959313;21375.57248855024;20953.790462095374;21604.675929861027;22249.44376390591;22253.44366390841;22319.442013949658;5006.849794506162;6514.478841692653;6585.242697089848;6587.209534855824;6589.703463344161;6265.436110750032;6469.191351081115;6491.350864913518;6548.672566371686;6603.504737144709;6518.413342799147;6536.052272420497;6542.542022058457;6544.443722283611;6547.6071435713775;6241.344658810827;6514.511411644098;6533;6553.574017688835;6597.1093902323055;6156.907646385308;6377.521685873532;6418.871622567569;6425.357464253537;6438.935610643881;20650.5869882603;25802;25825.354366140997;26078.86960565204;26309.473810523872;2905.099419180061;2929.9707002929913;2931.369755502556;2933.4279815436;2991.5811786349927;15335.003224790353;16419.096949667764;16514.58713532159;16644.50066498007;16852.410165644145;1792.6056267621102;2023.494126468383;2047.3653167518116;2064.1846470644014;2085.6454402751415;135524;138828;143173;144881.2755936224;145566;22324.441888952908;22393.664095038588;22968.425789355064;22986.540269194524;23210.883945580503;34597.65402345944;35117.29765404729;35814;35890.10274743152;35913.102172445375;10630.255190841597;10945.671629851116;11113.55545778176;11116.38859862699;11207.047400971009;9038.683646072488;9057;9082.682106126254;9144.131307525775;9202.447853128833;10249.795004099877;10445.955404459644;10458.26792124544;10562.683119506428;10585.84121238182;4148.357004664222;4287.8499252526635;4316.892077698083;4351.8694439166875;4363.781810909505;1046.9162467002623;1052.8157572424795;1052.8789189243182;1081.4592703648175;1096.4237121490462;21207;21932;21934.670979935312;21947.67078493824;22019;936.8547875079373;987.5309228116731;1060.067140915989;1091.252492042953;1096.7587130831205;9246.768830779285;10913;11475.77048459026;11562.76874462523;11599.420028998516;730647;771263;788434;798316;808198;134426;135932;137523;140643.89034164496;153137;1146.8910453506903;1174.277819141222;1193.7433451807904;1195.7130288730816;1256.4032084759665;18029.819701802815;18906.716399254296;19566.510837229183;19845.801541984394;20160.79839201589;64801;66156.99999999808;67470.66264668645;67490.66254668696;69067.65466172837;97238.54142188015;98786;99911;101216;104637;8554.27288680469;11325;11462.82805757931;11473.36896470702;11584.594539191088;13008.544700935421;13070.281134537692;13248.536301229411;13325.400356983766;13325.800112998506;11076.280041797258;11145.052670523097;11226.438678066228;11307.265027773168;11316.189409694409;10436.217283703601;10459.320144190604;10688.732781680521;10741.892581074402;10796.514156862802;9652.951735241279;11168.609098681507;11332.980031797179;11491.770164596828;11508.654740357802;11352.432378380883;11503;11696.766064678828;11740.295582265091;11989.34058626784;10407.323523970917;11126.165537584551;11146.275492092987;11297.887021129683;11309.377984210947 09:29:05 INFO - PROCESS | 1639 | |3;mootools.html;1196.5811965811968;1337.8862796662283;1350.419319692533;1355.8169647097639;1372.224693048428;10801.297915635489;11323.943380283097;11326.546938122487;11366.204365694404;11600.535978560847;9225.584848681825;11055.22613417061;11160.720981975455;11260.324380537173;11281;2346.706661667296;2359.6578496118054;2370.8103351731907;2371.620540713486;2376.239603326936;2116.767155612889;2274.1358283852114;2350.8354415190943;2358.6462030695448;2361.8346715729904;1982.5737466444712;2013.8288245499077;2153.9892300538477;2271.886405679718;2316.5714342846627;1915.1286164795054;2266.8866556672265;2342.16853017179;2345.0033735662446;2345.753695861944;1863.4409677096817;1992.481954691776;2061.835053195741;2360.067773229565;2396.5686176488225;11106.88931106891;11184.608538701106;11203.159763017724;11204.383758893258;11210.495527701272;5724.484796368305;5884.617499862538;5896.380880007623;5909.586328956974;5942.197803296543;3366.5118557809224;3628.51015112959;3666.3583872822414;3683.4106542953;3720.9441858372147;1159.8608167019927;1162.1167912386563;1198.2211562484367;1222.4376786678138;1224.8101544260653;15330.693386132327;15346.462873799475;15510.844891551053;15516.379344826193;15538.689226215412;4304.612584867377;4581.175388430114;4621.95378046222;4648.535146485392;4659.161350956792;10130.898691012995;11674.416279186007;11700.765984680258;11728.648140555797;11745.236559623769;5625.634333768374;5678.4037676044245;5679.119736440858;5714.771324165325;5715.599908006399;4945.381827271591;5168;5173.818916337986;5191.169412893994;5245.239440281138;3234.498652708834;3715.405535114423;3734.9439758403646;3760.5487341519192;3836.36699944506;996.3772642098688;997.7954519323666;1004.1966426858484;1013.4831236069657;1020.3010937507831;894.9955250223708;917.7476194046557;922.9708874604902;928.1553786054658;946.2713710442921;6174.598651087755;6546.312637173123;6600.5049621278595;6650.800475985729;6686.431653309523;888.8888888888889;889.3596610440472;890.4523717913437;897.3987428422972;902.255639097747;3201.567788348543;3223.3714425686767;3237.6438591754886;3284.7372210223602;3303.1454276730033;13266.73466530664;13972.790408143886;14029.298535073205;14031.438742450391;14079.788803167961;12889.935550322376;13411.396487158101;13616.931915340558;13646;13725;9978.850317245247;10201.489925503694;10211.591536338463;10224.386536807815;10432.113270371952;6365.490760739222;6636.43590294811;6649.202095748347;6804.455643548603;6815.318468153145;10035.849462258218;11924.284542927453;12233;12315.568955086528;12478.18891772032;11275.605353812905;12385.876141238832;12455.128141030042;12596.748065038832;12825.807612886001;9137.817243655223;9240.983491815761;9335.393199442015;9335.766605834908;9371.906280937375;10269.794604107727;10279.383237005804;10354.17166626653;10360.119389851947;10373.58505659765;8693.652253910026;8709.433886797338;8715.607797648994;8796.824063518821;8798.560071996502;8612.267957223707;8631.654733810577;8671.176238257356;8676.609552570275;8722.258608018135;7689.038870141232;7767.844643107219;7825.530468171928;7830.569318687527;7863;5506.972465137648;5529.585281103853;5596.972015139898;5705.771769129188;5710.428957104256;5816.47651711348;5878.470937615635;6012.969935150296;6071.392860713893;6199 09:29:05 INFO - PROCESS | 1639 | |4;prototype.html;366.2967103161929;373.5107009817139;373.7589254930572;386.7466809239947;389.38088439381386;3913.021744563859;4863.835593161045;4999.700197869407;5184.351956005499;5348.572114230862;3948.763074215542;4445.510993790688;5101.38783345999;5152.74236288186;5248.874205946598;4255.382954477302;4289.375477721336;4335.523092459827;4338.869833904972;4396.120775844828;4097.631213190813;4259.382389553512;4373.803178856959;4374.650027997753;4410.889727756799;3896.4350169225445;4264.744115353089;4284.785760711968;4287.742735435868;4305.483341998949;4130.1001539291965;4332.696711230215;4336.891577710551;4343.391925130484;4390.429244198266;3459.567554055743;3737.2157742482027;3814.4633818510656;3989.489249460485;4063.841805085566;4118.897027574335;5033.238366571707;5049.469805670426;5094.79620815167;5170.586353091744;4264.57354264571;4780.402449693788;5022.547970682656;5040.092783299005;5069.974650126763;4622.86131416058;4901.313816065754;5303.681779093266;5589.580781441408;5591.860203494904;3983.98008009961;4221.915561688749;4279.8872293203995;4466.82132714691;4481.596656300949;5534.972325138388;5707.62900411476;5714.314282286152;5872.120921305184;5910.527157827363;3599.9820000899826;4498.820047198075;4531.776420366483;4544.818207271672;4553.908921821611;4466.816293682177;4606.470255920545;4612.677112602086;4652.162610730101;4659.276067854916;4365.759883206454;4404.744647775368;4749.335093086971;4894.360389269635;4975.925361119586;5073.315102461194;5211.765470553834;5470.64440811346;5559.916601250984;5622.91565626516;1441.371250486964;1558.984410155884;1571.1672813408911;1579.1946107485264;1581.549258461333;1091.9454027298602;1181.5923506390282;1189.3339729751194;1202.7113492761673;1210.1665976046297;4826.975865120652;5042.520960508746;5129.794808207705;5300.429291793477;5316.388615309212;5254.527092561688;5364.034473794754;5794.21778059965;5828.0476040526055;5859.736311865976;4640.466346370143;5227.9544091181915;5273.077211487975;5286.990943441468;5295.472371122712;2674.1041751013627;2712.294803351143;2832.7592154666672;2885.5960165576835;3011.382666553351;4740.146773580788;5472.221528003415;5929.14027466015;5950.393393783655;5993.123300815546;4831.95168048322;5002.399712034507;5145.22821576759;5483.8080667176455;5922.644641321534;5125.564327032244;5659.43405659439;6011.0382338825575;6033.78881739137;6107.358727333566;4818.542238487338;5003.699778013331;5532.6680399176175;5758.62568933018;5814.331351894586;4910.1586904910655;4987.700737955734;5122.975404919106;5454.536364409069;5623.915641265303;5375.892482150413;5603.3750212437935;5645.802396916089;5717.742701578356;5960.493358064613;4286.399904013379;4511.842085526991;4512.4133862597655;4514.909701806011;4525.864224073283;5238.947610523998;5611.775528978958;5623.2408624835125;5743.295574354602;5851.342910680824;4992.700437973734;5124.256982737407;5522.641028333145;5539.446055394428;5648.293963254593;4839.612830973584;4874.979400440382;5100.649691134561;5558.693142345881;5576.247206627216;4615.469221039489;5151.2788209649925;5156.9428267205885;5162.767675454688;5453.836384908459;4776.474587795409;5107.591392688501;5208.322918020633;5450.700211488406;5577.3028371453565;4915.230517013801;5120.74396280177;5456.890862182813;5463.918041229305;5476.726163691879 09:29:05 INFO - PROCESS | 1639 | |5;yui.html;597.946184843364;716.3839098375396;794.0273165372416;816.4325793573469;840.4285086141423;15210.315535800888;15888.76166857498;16239.106849123293;16241.431549895757;16279.83720162798;13288.800667989964;13410.26243556604;14987.700245995073;15041.022333548312;15077.698446031072;935.9812803743938;953.5041778275292;956.6269155029544;966.859805328227;967.5549247346229;948.4783369146976;952.633236204063;955.0878910640356;957.4973139101958;957.7174733453613;1003.372891942536;1006.9395836249848;1007.3250921882357;1007.5113569918584;1008.4604736465992;985.9408435493893;1003.7490627343165;1004.4174378860243;1004.573056451009;1004.9095581397636;746.2090184404549;746.3954197100312;746.6080307838374;749.1946157880246;749.711361125965;13170.934145329307;13444.529441469502;14255.572332830032;14606.269686515632;14784.778228326584;13758.518451854137;14058.297085145701;14193.574192774233;14352.282385880664;14361.353739081766;11584.652460426201;11691.356975366352;11737.002723167821;11798.882011179865;11823.881761182363;3605.13476765577;3732.8320225589914;3745;3771.0383852117807;3863.459115723801;97463.99530222018;106658;109316;110040.34939475915;110955;10799.83800242997;10882.510287037103;10934.125269978542;11280.605178818701;11320.37737924422;21621.459463513536;21986.340409787732;22158.667619985714;22293.44266393321;22328.218512352316;7624.8475030500185;7682.347000504908;7701.075870895416;7704.6918123274445;7923.524588524591;4257.40396344512;4275.508316543575;4362.411074504901;4381.87786213847;4521.525239849834;3111.9066428007195;3158.510430883217;3160;3181.650018497963;3194.7284480818926;571.44855214718;596.6390333848046;613.7299588181186;626.5394934722975;635.8791829552371;570.6262398129232;584.2550747796594;602.9758809647652;641.3970867384644;697.5396238482577;9530.857037144448;9624.278179136592;10180.949095254477;10418.739531511774;10783.191260655325;555.8026900450362;567.2455634006761;598.1508989651605;605.9484943779828;672.7779832655212;4684.929726054112;4891.926621100686;4907.9263811042865;4924.63065270099;4950.183219768698;90204;96615.06769864704;108145;108897;110826;93559;94750.0524994755;96675.51662241644;96711;97289.5406568902;82402.58798706088;83083;83409;83535;84781.72827407718;9394.67118650844;9546.0767562924;9806.705798826046;9811.215102791617;9811.241757091428;42618.5738142627;47331.526684732715;52087.437376878755;54145.72927135497;54365;67687.66156169187;67922.66038669775;68601;69891.65054174696;70725.64637176781;11902.642920712393;12042.518299267931;12075.57735479254;12203.389830508262;12574;12770.744585108432;12946.417411216706;12972;13073;13096.607101786962;11826.526938922347;12118.151729378858;12217.389130543615;12473;12514.687132821753;11636.41817909118;11918.582849600223;12245.510179593073;12400.51193856743;12419.503219871103;10415.895841041493;11684.824727628922;11806.037950834774;12028.819567706667;12430.689232769253;8350.83298334042;8673.392862499817;8678.349123815613;8781.560921954004;8881.555922203735;9735.9026409735;9919.752006199902;9921.751956201153;9933.403995760278;10105.44420056904 09:29:05 INFO - PROCESS | 1639 | __end_tp_report 09:29:05 INFO - PROCESS | 1639 | __start_cc_report 09:29:05 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,416 09:29:05 INFO - PROCESS | 1639 | __end_cc_report 09:29:05 INFO - PROCESS | 1639 | __startTimestamp1492446545441__endTimestamp 09:29:05 INFO - PROCESS | 1639 | 09:29:05 INFO - PROCESS | 1639 | ------- Summary: start ------- 09:29:05 INFO - PROCESS | 1639 | Number of tests: 6 09:29:05 INFO - PROCESS | 1639 | 09:29:05 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:6003.02 Median:6226.97 stddev:648.73 (10.4%) stddev-sans-first:619.57 09:29:05 INFO - PROCESS | 1639 | Values: 3471.1 3499.8 3507.5 3519.7 3524.4 6054.2 6082.5 6111.8 6138.7 6162.4 6127.4 6150.9 6182.7 6198.7 6207.6 5486.8 5536.4 5538.7 5570.1 5619.5 5608.2 5611.7 5626.2 5636.2 5657.9 5362.1 5414.2 5560.4 5587.9 5591.6 5556.4 5558.6 5577.8 5602.2 5625.4 5419.3 5494.1 5530.0 5533.0 5545.4 5727.2 6120.2 6153.3 6186.8 6194.2 6263.7 6302.2 6312.8 6325.4 6328.2 6460.4 6492.6 6501.6 6523.6 6529.8 6174.4 6177.3 6179.8 6203.2 6227.0 6399.5 6401.0 6603.3 6608.5 6668.6 6326.7 6343.2 6370.4 6377.6 6393.9 5556.8 6424.9 6485.0 6548.4 6563.5 6273.1 6319.6 6435.1 6455.7 6477.0 5010.5 6145.5 6225.8 6277.3 6328.0 5139.7 5148.2 5180.5 5212.7 5218.2 4608.9 4628.6 4630.9 4644.2 4652.0 6009.1 6149.6 6247.6 6266.1 6289.1 6313.8 6319.2 6367.6 6483.9 6523.5 5195.6 6195.8 6216.1 6229.1 6233.3 5570.1 5754.2 5763.7 5776.5 5789.8 6465.9 6488.6 6623.9 6657.5 6775.0 6484.7 6489.1 6541.6 6575.9 6579.3 6701.7 6786.3 6805.4 6805.4 6808.1 6255.6 6257.3 6326.4 6340.0 6447.3 5308.3 6435.9 6461.3 6481.4 6481.8 6023.7 6256.4 6280.3 6308.5 6313.5 6128.3 6155.1 6172.8 6322.1 6324.5 6130.7 6151.7 6275.5 6299.7 6307.2 6322.2 6334.2 6337.0 6339.7 6350.9 6106.9 6150.8 6231.6 6243.6 6298.0 09:29:05 INFO - PROCESS | 1639 | 09:29:05 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:54441.23 Median:3103.05 stddev:109991.93 (3544.6%) stddev-sans-first:110226.58 09:29:05 INFO - PROCESS | 1639 | Values: 737.6 775.5 824.8 966.5 970.0 37404.3 38129.8 40692.0 40790.0 40965.4 39304.6 39480.2 39540.4 39602.0 39646.6 7813.2 7935.7 10459.8 10758.3 10954.6 10610.9 10635.8 10718.6 10724.4 10746.4 10681.8 10721.6 10723.9 10735.4 10758.2 10591.7 10611.5 10634.4 10663.7 10666.1 10391.0 10395.4 10450.5 10492.5 10501.5 34593.7 34642.5 34828.5 34839.0 34899.5 3312.7 3474.0 3491.0 3811.6 3814.1 24925.3 25400.4 26447.7 26727.5 27007.3 3101.7 3104.4 3140.8 3236.0 3250.3 281164.0 345966.0 353510.0 356909.2 362888.0 45273.8 45369.1 45602.8 45640.9 45735.0 46797.5 46844.8 46911.0 47060.0 47090.3 13778.4 13896.2 13911.9 13920.4 13945.3 12221.1 12242.2 12274.6 12277.2 12305.9 12114.8 12129.3 12140.7 12152.2 12237.6 5522.9 5598.2 5606.0 5616.0 5650.5 905.8 921.1 941.4 944.0 970.2 28449.3 28677.3 28713.6 28774.7 28786.3 991.1 1028.2 1044.9 1060.1 1083.0 14230.2 14672.5 14681.9 15033.8 15074.2 452368.0 454832.0 455049.0 455390.0 455573.0 57918.7 58197.7 58431.4 58572.7 58915.0 1428.5 1440.4 1455.4 1465.3 1470.3 20806.5 24821.1 25027.6 27054.5 27104.3 48363.0 48735.0 48914.3 48999.8 49217.5 436908.0 437540.0 438116.0 439280.0 439298.8 31209.2 31962.8 32275.7 32319.0 32480.2 35991.0 40337.6 44260.0 45049.3 45227.5 24132.5 27411.3 28923.1 29210.7 29728.6 26237.2 26286.6 28035.0 28898.0 28943.6 23005.2 27634.6 32613.5 32708.2 33121.3 28302.7 28518.4 28786.3 29167.9 29524.1 22548.7 23183.9 23198.9 23676.0 24278.0 09:29:05 INFO - PROCESS | 1639 | 09:29:05 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:43781.58 Median:11519.72 stddev:129402.71 (1123.3%) stddev-sans-first:129724.62 09:29:05 INFO - PROCESS | 1639 | Values: 345.2 407.1 410.3 411.4 451.5 14464.8 16721.0 19874.8 21191.3 21375.6 20953.8 21604.7 22249.4 22253.4 22319.4 5006.8 6514.5 6585.2 6587.2 6589.7 6265.4 6469.2 6491.4 6548.7 6603.5 6518.4 6536.1 6542.5 6544.4 6547.6 6241.3 6514.5 6533.0 6553.6 6597.1 6156.9 6377.5 6418.9 6425.4 6438.9 20650.6 25802.0 25825.4 26078.9 26309.5 2905.1 2930.0 2931.4 2933.4 2991.6 15335.0 16419.1 16514.6 16644.5 16852.4 1792.6 2023.5 2047.4 2064.2 2085.6 135524.0 138828.0 143173.0 144881.3 145566.0 22324.4 22393.7 22968.4 22986.5 23210.9 34597.7 35117.3 35814.0 35890.1 35913.1 10630.3 10945.7 11113.6 11116.4 11207.0 9038.7 9057.0 9082.7 9144.1 9202.4 10249.8 10446.0 10458.3 10562.7 10585.8 4148.4 4287.8 4316.9 4351.9 4363.8 1046.9 1052.8 1052.9 1081.5 1096.4 21207.0 21932.0 21934.7 21947.7 22019.0 936.9 987.5 1060.1 1091.3 1096.8 9246.8 10913.0 11475.8 11562.8 11599.4 730647.0 771263.0 788434.0 798316.0 808198.0 134426.0 135932.0 137523.0 140643.9 153137.0 1146.9 1174.3 1193.7 1195.7 1256.4 18029.8 18906.7 19566.5 19845.8 20160.8 64801.0 66157.0 67470.7 67490.7 69067.7 97238.5 98786.0 99911.0 101216.0 104637.0 8554.3 11325.0 11462.8 11473.4 11584.6 13008.5 13070.3 13248.5 13325.4 13325.8 11076.3 11145.1 11226.4 11307.3 11316.2 10436.2 10459.3 10688.7 10741.9 10796.5 9653.0 11168.6 11333.0 11491.8 11508.7 11352.4 11503.0 11696.8 11740.3 11989.3 10407.3 11126.2 11146.3 11297.9 11309.4 09:29:05 INFO - PROCESS | 1639 | 09:29:05 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:6657.29 Median:2799.07 stddev:4276.11 (152.8%) stddev-sans-first:4268.41 09:29:05 INFO - PROCESS | 1639 | Values: 1196.6 1337.9 1350.4 1355.8 1372.2 10801.3 11323.9 11326.5 11366.2 11600.5 9225.6 11055.2 11160.7 11260.3 11281.0 2346.7 2359.7 2370.8 2371.6 2376.2 2116.8 2274.1 2350.8 2358.6 2361.8 1982.6 2013.8 2154.0 2271.9 2316.6 1915.1 2266.9 2342.2 2345.0 2345.8 1863.4 1992.5 2061.8 2360.1 2396.6 11106.9 11184.6 11203.2 11204.4 11210.5 5724.5 5884.6 5896.4 5909.6 5942.2 3366.5 3628.5 3666.4 3683.4 3720.9 1159.9 1162.1 1198.2 1222.4 1224.8 15330.7 15346.5 15510.8 15516.4 15538.7 4304.6 4581.2 4622.0 4648.5 4659.2 10130.9 11674.4 11700.8 11728.6 11745.2 5625.6 5678.4 5679.1 5714.8 5715.6 4945.4 5168.0 5173.8 5191.2 5245.2 3234.5 3715.4 3734.9 3760.5 3836.4 996.4 997.8 1004.2 1013.5 1020.3 895.0 917.7 923.0 928.2 946.3 6174.6 6546.3 6600.5 6650.8 6686.4 888.9 889.4 890.5 897.4 902.3 3201.6 3223.4 3237.6 3284.7 3303.1 13266.7 13972.8 14029.3 14031.4 14079.8 12889.9 13411.4 13616.9 13646.0 13725.0 9978.9 10201.5 10211.6 10224.4 10432.1 6365.5 6636.4 6649.2 6804.5 6815.3 10035.8 11924.3 12233.0 12315.6 12478.2 11275.6 12385.9 12455.1 12596.7 12825.8 9137.8 9241.0 9335.4 9335.8 9371.9 10269.8 10279.4 10354.2 10360.1 10373.6 8693.7 8709.4 8715.6 8796.8 8798.6 8612.3 8631.7 8671.2 8676.6 8722.3 7689.0 7767.8 7825.5 7830.6 7863.0 5507.0 5529.6 5597.0 5705.8 5710.4 5816.5 5878.5 6013.0 6071.4 6199.0 09:29:05 INFO - PROCESS | 1639 | 09:29:05 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4599.69 Median:4990.20 stddev:1274.98 (25.5%) stddev-sans-first:1238.32 09:29:05 INFO - PROCESS | 1639 | Values: 366.3 373.5 373.8 386.7 389.4 3913.0 4863.8 4999.7 5184.4 5348.6 3948.8 4445.5 5101.4 5152.7 5248.9 4255.4 4289.4 4335.5 4338.9 4396.1 4097.6 4259.4 4373.8 4374.7 4410.9 3896.4 4264.7 4284.8 4287.7 4305.5 4130.1 4332.7 4336.9 4343.4 4390.4 3459.6 3737.2 3814.5 3989.5 4063.8 4118.9 5033.2 5049.5 5094.8 5170.6 4264.6 4780.4 5022.5 5040.1 5070.0 4622.9 4901.3 5303.7 5589.6 5591.9 3984.0 4221.9 4279.9 4466.8 4481.6 5535.0 5707.6 5714.3 5872.1 5910.5 3600.0 4498.8 4531.8 4544.8 4553.9 4466.8 4606.5 4612.7 4652.2 4659.3 4365.8 4404.7 4749.3 4894.4 4975.9 5073.3 5211.8 5470.6 5559.9 5622.9 1441.4 1559.0 1571.2 1579.2 1581.5 1091.9 1181.6 1189.3 1202.7 1210.2 4827.0 5042.5 5129.8 5300.4 5316.4 5254.5 5364.0 5794.2 5828.0 5859.7 4640.5 5228.0 5273.1 5287.0 5295.5 2674.1 2712.3 2832.8 2885.6 3011.4 4740.1 5472.2 5929.1 5950.4 5993.1 4832.0 5002.4 5145.2 5483.8 5922.6 5125.6 5659.4 6011.0 6033.8 6107.4 4818.5 5003.7 5532.7 5758.6 5814.3 4910.2 4987.7 5123.0 5454.5 5623.9 5375.9 5603.4 5645.8 5717.7 5960.5 4286.4 4511.8 4512.4 4514.9 4525.9 5238.9 5611.8 5623.2 5743.3 5851.3 4992.7 5124.3 5522.6 5539.4 5648.3 4839.6 4875.0 5100.6 5558.7 5576.2 4615.5 5151.3 5156.9 5162.8 5453.8 4776.5 5107.6 5208.3 5450.7 5577.3 4915.2 5120.7 5456.9 5463.9 5476.7 09:29:05 INFO - PROCESS | 1639 | 09:29:05 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:20615.68 Median:23437.99 stddev:30407.29 (129.7%) stddev-sans-first:30455.44 09:29:05 INFO - PROCESS | 1639 | Values: 597.9 716.4 794.0 816.4 840.4 15210.3 15888.8 16239.1 16241.4 16279.8 13288.8 13410.3 14987.7 15041.0 15077.7 936.0 953.5 956.6 966.9 967.6 948.5 952.6 955.1 957.5 957.7 1003.4 1006.9 1007.3 1007.5 1008.5 985.9 1003.7 1004.4 1004.6 1004.9 746.2 746.4 746.6 749.2 749.7 13170.9 13444.5 14255.6 14606.3 14784.8 13758.5 14058.3 14193.6 14352.3 14361.4 11584.7 11691.4 11737.0 11798.9 11823.9 3605.1 3732.8 3745.0 3771.0 3863.5 97464.0 106658.0 109316.0 110040.3 110955.0 10799.8 10882.5 10934.1 11280.6 11320.4 21621.5 21986.3 22158.7 22293.4 22328.2 7624.8 7682.3 7701.1 7704.7 7923.5 4257.4 4275.5 4362.4 4381.9 4521.5 3111.9 3158.5 3160.0 3181.7 3194.7 571.4 596.6 613.7 626.5 635.9 570.6 584.3 603.0 641.4 697.5 9530.9 9624.3 10180.9 10418.7 10783.2 555.8 567.2 598.2 605.9 672.8 4684.9 4891.9 4907.9 4924.6 4950.2 90204.0 96615.1 108145.0 108897.0 110826.0 93559.0 94750.1 96675.5 96711.0 97289.5 82402.6 83083.0 83409.0 83535.0 84781.7 9394.7 9546.1 9806.7 9811.2 9811.2 42618.6 47331.5 52087.4 54145.7 54365.0 67687.7 67922.7 68601.0 69891.7 70725.6 11902.6 12042.5 12075.6 12203.4 12574.0 12770.7 12946.4 12972.0 13073.0 13096.6 11826.5 12118.2 12217.4 12473.0 12514.7 11636.4 11918.6 12245.5 12400.5 12419.5 10415.9 11684.8 11806.0 12028.8 12430.7 8350.8 8673.4 8678.3 8781.6 8881.6 9735.9 9919.8 9921.8 9933.4 10105.4 09:29:05 INFO - PROCESS | 1639 | -------- Summary: end -------- 09:29:05 INFO - PROCESS | 1639 | 09:29:06 INFO - TEST-INFO | 1639: exit 0 09:29:06 INFO - TEST-OK | dromaeo_css | took 1117269ms 09:29:06 INFO - TEST-START | kraken 09:29:06 INFO - operating with platform_type : mac_ 09:29:06 INFO - Initialising browser for kraken test... 09:29:06 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmptoXa2q/profile http://localhost:49245/getInfo.html) 09:29:09 INFO - PROCESS | 1651 | 2017-04-17 09:29:09.097 plugin-container[1652:10216] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8933, name = 'com.apple.tsm.portname' 09:29:09 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:29:09 INFO - PROCESS | 1651 | 2017-04-17 09:29:09.100 plugin-container[1652:10216] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 09:29:09 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:29:09 INFO - PROCESS | 1651 | 2017-04-17 09:29:09.100 plugin-container[1652:10216] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:29:09 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 09:29:09 INFO - PROCESS | 1651 | colorDepth:24 09:29:09 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 09:29:09 INFO - PROCESS | 1651 | __metrics 09:29:09 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3360: TypeError: this.tabs is undefined 09:29:09 INFO - TEST-INFO | 1651: exit 0 09:29:09 INFO - Browser initialized. 09:29:09 INFO - Running cycle 1/1 for kraken test... 09:29:10 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/T/tmptoXa2q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:29:12 INFO - PROCESS | 1653 | 2017-04-17 09:29:12.293 plugin-container[1654:10340] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 09:29:12 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:29:12 INFO - PROCESS | 1653 | 2017-04-17 09:29:12.293 plugin-container[1654:10340] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4713, name = 'com.apple.CFPasteboardClient' 09:29:12 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:29:12 INFO - PROCESS | 1653 | 2017-04-17 09:29:12.294 plugin-container[1654:10340] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:29:22 INFO - PROCESS | 1653 | RSS: Main: 220643328 09:29:22 INFO - PROCESS | 1653 | 09:29:25 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 09:29:25 INFO - PROCESS | 1653 | RSS: Main: 235171840 09:29:25 INFO - PROCESS | 1653 | 09:29:28 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 09:29:29 INFO - PROCESS | 1653 | RSS: Main: 243105792 09:29:29 INFO - PROCESS | 1653 | 09:29:32 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 09:29:33 INFO - PROCESS | 1653 | RSS: Main: 246743040 09:29:33 INFO - PROCESS | 1653 | 09:29:35 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 09:29:35 INFO - PROCESS | 1653 | RSS: Main: 247119872 09:29:35 INFO - PROCESS | 1653 | 09:29:37 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:29:38 INFO - PROCESS | 1653 | RSS: Main: 247185408 09:29:38 INFO - PROCESS | 1653 | 09:29:41 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 09:29:42 INFO - PROCESS | 1653 | RSS: Main: 250912768 09:29:42 INFO - PROCESS | 1653 | 09:29:45 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 09:29:46 INFO - PROCESS | 1653 | RSS: Main: 251879424 09:29:46 INFO - PROCESS | 1653 | 09:29:49 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 09:29:49 INFO - PROCESS | 1653 | RSS: Main: 251883520 09:29:49 INFO - PROCESS | 1653 | 09:29:51 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:29:51 INFO - PROCESS | 1653 | RSS: Main: 251523072 09:29:51 INFO - PROCESS | 1653 | 09:29:53 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:29:54 INFO - PROCESS | 1653 | RSS: Main: 251547648 09:29:54 INFO - PROCESS | 1653 | 09:29:56 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:29:57 INFO - PROCESS | 1653 | RSS: Main: 251531264 09:29:57 INFO - PROCESS | 1653 | 09:30:00 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:30:01 INFO - PROCESS | 1653 | RSS: Main: 251936768 09:30:01 INFO - PROCESS | 1653 | 09:30:04 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:30:04 INFO - PROCESS | 1653 | RSS: Main: 251719680 09:30:04 INFO - PROCESS | 1653 | 09:30:06 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 09:30:06 INFO - PROCESS | 1653 | RSS: Main: 253739008 09:30:06 INFO - PROCESS | 1653 | 09:30:06 INFO - PROCESS | 1653 | __start_tp_report 09:30:06 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 09:30:06 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 09:30:06 INFO - PROCESS | 1653 | |i|pagename|runs| 09:30:06 INFO - PROCESS | 1653 | |0;ai-astar;109;121;119;119;142;119;120;118;118;120 09:30:06 INFO - PROCESS | 1653 | |1;audio-beat-detection;141;112;109;155;127;99;95;114;161;143 09:30:06 INFO - PROCESS | 1653 | |2;audio-dft;193;198;195;202;196;197;197;192;197;196 09:30:06 INFO - PROCESS | 1653 | |3;audio-fft;82;63;54;86;76;84;89;58;97;54 09:30:06 INFO - PROCESS | 1653 | |4;audio-oscillator;90;70;86;72;72;73;103;95;103;114 09:30:06 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;84;105;136;137;89;86;137;135;100;91 09:30:06 INFO - PROCESS | 1653 | |6;imaging-darkroom;107;97;98;97;97;97;98;103;149;99 09:30:06 INFO - PROCESS | 1653 | |7;imaging-desaturate;132;139;92;97;89;91;90;91;92;90 09:30:06 INFO - PROCESS | 1653 | |8;json-parse-financial;65;66;67;67;70;69;67;68;69;68 09:30:06 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;61;60;59;68;67;64;57;58;59;61 09:30:06 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;86;77;106;76;73;74;79;107;73;98 09:30:06 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;166;149;146;139;140;150;153;159;186;151 09:30:06 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;168;169;241;163;212;228;197;167;169;172 09:30:06 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;66;90;89;89;84;65;63;80;86;86 09:30:06 INFO - PROCESS | 1653 | __end_tp_report 09:30:06 INFO - PROCESS | 1653 | __start_cc_report 09:30:06 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1004 09:30:06 INFO - PROCESS | 1653 | __end_cc_report 09:30:06 INFO - PROCESS | 1653 | __startTimestamp1492446606851__endTimestamp 09:30:06 INFO - PROCESS | 1653 | 09:30:06 INFO - PROCESS | 1653 | ------- Summary: start ------- 09:30:06 INFO - PROCESS | 1653 | Number of tests: 14 09:30:06 INFO - PROCESS | 1653 | 09:30:06 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:120.50 Median:119.50 stddev:8.26 (6.9%) stddev-sans-first:7.64 09:30:06 INFO - PROCESS | 1653 | Values: 109.0 121.0 119.0 119.0 142.0 119.0 120.0 118.0 118.0 120.0 09:30:06 INFO - PROCESS | 1653 | 09:30:06 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:125.60 Median:149.00 stddev:23.33 (15.7%) stddev-sans-first:24.07 09:30:06 INFO - PROCESS | 1653 | Values: 141.0 112.0 109.0 155.0 127.0 99.0 95.0 114.0 161.0 143.0 09:30:06 INFO - PROCESS | 1653 | 09:30:06 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:196.30 Median:197.00 stddev:2.75 (1.4%) stddev-sans-first:2.65 09:30:06 INFO - PROCESS | 1653 | Values: 193.0 198.0 195.0 202.0 196.0 197.0 197.0 192.0 197.0 196.0 09:30:06 INFO - PROCESS | 1653 | 09:30:06 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:74.30 Median:83.00 stddev:15.78 (19.0%) stddev-sans-first:16.49 09:30:06 INFO - PROCESS | 1653 | Values: 82.0 63.0 54.0 86.0 76.0 84.0 89.0 58.0 97.0 54.0 09:30:06 INFO - PROCESS | 1653 | 09:30:06 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:87.80 Median:72.50 stddev:15.79 (21.8%) stddev-sans-first:16.73 09:30:06 INFO - PROCESS | 1653 | Values: 90.0 70.0 86.0 72.0 72.0 73.0 103.0 95.0 103.0 114.0 09:30:06 INFO - PROCESS | 1653 | 09:30:06 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:110.00 Median:110.50 stddev:23.42 (21.2%) stddev-sans-first:22.88 09:30:06 INFO - PROCESS | 1653 | Values: 84.0 105.0 136.0 137.0 89.0 86.0 137.0 135.0 100.0 91.0 09:30:06 INFO - PROCESS | 1653 | 09:30:06 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:104.20 Median:97.00 stddev:16.08 (16.6%) stddev-sans-first:17.03 09:30:06 INFO - PROCESS | 1653 | Values: 107.0 97.0 98.0 97.0 97.0 97.0 98.0 103.0 149.0 99.0 09:30:06 INFO - PROCESS | 1653 | 09:30:06 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:100.30 Median:91.00 stddev:18.75 (20.6%) stddev-sans-first:16.00 09:30:06 INFO - PROCESS | 1653 | Values: 132.0 139.0 92.0 97.0 89.0 91.0 90.0 91.0 92.0 90.0 09:30:06 INFO - PROCESS | 1653 | 09:30:06 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:67.60 Median:68.00 stddev:1.51 (2.2%) stddev-sans-first:1.27 09:30:06 INFO - PROCESS | 1653 | Values: 65.0 66.0 67.0 67.0 70.0 69.0 67.0 68.0 69.0 68.0 09:30:06 INFO - PROCESS | 1653 | 09:30:06 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:61.40 Median:61.00 stddev:3.75 (6.1%) stddev-sans-first:3.97 09:30:06 INFO - PROCESS | 1653 | Values: 61.0 60.0 59.0 68.0 67.0 64.0 57.0 58.0 59.0 61.0 09:30:06 INFO - PROCESS | 1653 | 09:30:06 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:76.50 stddev:13.68 (17.9%) stddev-sans-first:14.51 09:30:06 INFO - PROCESS | 1653 | Values: 86.0 77.0 106.0 76.0 73.0 74.0 79.0 107.0 73.0 98.0 09:30:06 INFO - PROCESS | 1653 | 09:30:06 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:153.90 Median:152.00 stddev:13.86 (9.1%) stddev-sans-first:13.99 09:30:06 INFO - PROCESS | 1653 | Values: 166.0 149.0 146.0 139.0 140.0 150.0 153.0 159.0 186.0 151.0 09:30:06 INFO - PROCESS | 1653 | 09:30:06 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.60 Median:184.50 stddev:28.88 (15.7%) stddev-sans-first:29.65 09:30:06 INFO - PROCESS | 1653 | Values: 168.0 169.0 241.0 163.0 212.0 228.0 197.0 167.0 169.0 172.0 09:30:06 INFO - PROCESS | 1653 | 09:30:06 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.80 Median:86.00 stddev:10.85 (12.6%) stddev-sans-first:10.30 09:30:06 INFO - PROCESS | 1653 | Values: 66.0 90.0 89.0 89.0 84.0 65.0 63.0 80.0 86.0 86.0 09:30:06 INFO - PROCESS | 1653 | -------- Summary: end -------- 09:30:06 INFO - PROCESS | 1653 | 09:30:07 INFO - TEST-INFO | 1653: exit 0 09:30:07 INFO - TEST-OK | kraken | took 61700ms 09:30:07 INFO - SUITE-END | took 1178s 09:30:08 INFO - Completed test suite (00:19:39) 09:30:08 INFO - javascript benchmark 09:30:08 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7637.006015914965, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3471.1322169457635, 3499.8075105869166, 3507.508948747175, 3519.6656317649813, 3524.383232934236, 6054.243219597551, 6082.543809214305, 6111.816645500631, 6138.693065346726, 6162.445379915806, 6127.35662755411, 6150.876982460348, 6182.659953702545, 6198.690065496729, 6207.565470417061, 5486.807961721331, 5536.363318218396, 5538.695371754552, 5570.136628822539, 5619.522340601054, 5608.186812912125, 5611.719414029302, 5626.184203290521, 5636.239107720467, 5657.8585535361735, 5362.0616392131415, 5414.160805075219, 5560.443955604448, 5587.8603034924245, 5591.580631452637, 5556.361018482886, 5558.610897237195, 5577.832665020056, 5602.24369710088, 5625.437456254383, 5419.3225846769155, 5494.120940649477, 5529.972350138223, 5533.00405926933, 5545.390007099216, 5727.198192253088, 6120.234970628671, 6153.292371377304, 6186.84532886677, 6194.194754681907, 6263.749450022039, 6302.243730752293, 6312.779052733178, 6325.430711235965, 6328.240611126648, 6460.3862633049785, 6492.610443373413, 6501.642409667514, 6523.608583485006, 6529.771457998995, 6174.413430724074, 6177.289611694623, 6179.783707570214, 6203.2245969253845, 6226.968865155645, 6399.520035997226, 6401.0, 6603.339666033358, 6608.471322294205, 6668.599884006975, 6326.652034138075, 6343.175387199634, 6370.362963703593, 6377.649229292433, 6393.872122557523, 5556.777728890799, 6424.935750642528, 6484.967575162094, 6548.443382312556, 6563.540552161398, 6273.12176295319, 6319.6208227506495, 6435.066915297256, 6455.7417703292285, 6477.0, 5010.5240002199735, 6145.525073982212, 6225.844353891099, 6277.2781130170615, 6328.019157030575, 5139.743012849342, 5148.176291793295, 5180.533751962342, 5212.661177023481, 5218.165093585084, 4608.9308660370125, 4628.583427491542, 4630.86107416778, 4644.233701439225, 4652.0, 6009.06859436788, 6149.631022138686, 6247.562670613104, 6266.060090986389, 6289.0880822280515, 6313.842153946096, 6319.178506794179, 6367.586106903036, 6483.870322593521, 6523.478121750249, 5195.636305458657, 6195.81412557615, 6216.098665693449, 6229.127922090911, 6233.345498722569, 5570.108782594765, 5754.19441278213, 5763.7406316715005, 5776.451237132465, 5789.8263052108505, 6465.935340646533, 6488.578242414228, 6623.933760662332, 6657.533972622064, 6774.983752437174, 6484.740610375721, 6489.059086432347, 6541.574797638138, 6575.901361479678, 6579.342065793574, 6701.66491675424, 6786.287439818945, 6805.353491418307, 6805.421539169226, 6808.148981377328, 6255.593386429868, 6257.342978987141, 6326.398992095744, 6339.985602303609, 6447.290798012121, 5308.283381743569, 6435.871282574416, 6461.321561236003, 6481.4166724994975, 6481.805545833632, 6023.66869822164, 6256.374362563707, 6280.277768056732, 6308.463780578702, 6313.494920406449, 6128.32588415265, 6155.076738489083, 6172.783952561639, 6322.114903913548, 6324.494040476843, 6130.734471166852, 6151.692415379123, 6275.497960163085, 6299.685015749286, 6307.211598550181, 6322.241331040304, 6334.1765570475545, 6337.049442583465, 6339.683015849281, 6350.872982540416, 6106.915739268716, 6150.831342044997, 6231.563790534619, 6243.594166379171, 6297.96851015742], "value": 5963.40738699954, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.5795796396053, 775.5153029356652, 824.7979245084955, 966.4974213409027, 969.9806003879918, 37404.251914961686, 38129.80935095328, 40692.0, 40790.0, 40965.385519217234, 39304.60695393045, 39480.21039579207, 39540.40689389654, 39602.00994975127, 39646.603533964655, 7813.1796161402945, 7935.722249721232, 10459.79080418395, 10758.300710453796, 10954.561817527328, 10610.946945265263, 10635.787284254311, 10718.571257149704, 10724.356538607708, 10746.408947507884, 10681.839772403382, 10721.624743133993, 10723.892761072406, 10735.355878647268, 10758.246922715412, 10591.682249532527, 10611.46942652872, 10634.415107169103, 10663.680089597325, 10666.093382062534, 10391.0, 10395.376277423378, 10450.529726162342, 10492.527836247387, 10501.474926253733, 34593.6540634593, 34642.48036279458, 34828.477572836426, 34839.0, 34899.4765078524, 3312.7184189343934, 3473.9826300868585, 3490.9701638016845, 3811.6378944000276, 3814.065553939275, 24925.279766455915, 25400.36499087519, 26447.73552264491, 26727.46545069107, 27007.32481687954, 3101.6743241959493, 3104.4256812489534, 3140.811551306906, 3235.9838200808845, 3250.2524419383676, 281164.0, 345966.0, 353510.0, 356909.2154539237, 362888.0, 45273.77363113163, 45369.09261814745, 45602.77198613986, 45640.8589785258, 45735.0, 46797.53202468, 46844.7657761709, 46911.0, 47060.0, 47090.293645595346, 13778.448862045407, 13896.23570703621, 13911.86088139126, 13920.37358318878, 13945.302734863217, 12221.144519883523, 12242.204256723284, 12274.570390036306, 12277.20198187115, 12305.876941230474, 12114.75770484603, 12129.33288669114, 12140.696482588006, 12152.21010634306, 12237.571684990982, 5522.861928451821, 5598.188262701886, 5606.0, 5616.045272303718, 5650.519705825051, 905.8143080668447, 921.083521895718, 941.3504681769557, 943.9622415103456, 970.1801977329145, 28449.28876778097, 28677.28306792305, 28713.56929646057, 28774.712252877624, 28786.280342991173, 991.108002797488, 1028.2391030637275, 1044.9425281609433, 1060.1042119409087, 1082.9620963266248, 14230.2173380463, 14672.486462973744, 14681.853181468263, 15033.774493382609, 15074.170920599472, 452368.0, 454832.0, 455049.0, 455390.0, 455573.0, 57918.710406447695, 58197.70901145552, 58431.4156858426, 58572.70713646404, 58915.0, 1428.5428662827796, 1440.4094321328205, 1455.3596417576232, 1465.2527211122404, 1470.3383477435198, 20806.479838004172, 24821.1312604058, 25027.624585630867, 27054.51200184009, 27104.32239194036, 48363.03273934572, 48735.0, 48914.309712966126, 48999.75500122477, 49217.52347429583, 436908.0, 437540.0, 438116.0, 439280.0, 439298.8035059804, 31209.219769505944, 31962.840185798923, 32275.677243227266, 32319.0, 32480.187995300308, 35991.0, 40337.59662403456, 44260.0, 45049.324260135465, 45227.54772452233, 24132.51734965326, 27411.31471713223, 28923.13230603001, 29210.7078929208, 29728.554071689374, 26237.212883613523, 26286.60570091412, 28035.0, 28897.988570399935, 28943.56584651274, 23005.194818181284, 27634.5854812182, 32613.510797337585, 32708.182295442803, 33121.33757324888, 28302.726377313484, 28518.42963140767, 28786.28034299159, 29167.85416072906, 29524.114276571738, 22548.66177007379, 23183.88408057949, 23198.884005579865, 23676.0, 24278.0], "value": 18247.5273514353, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.2404709638795, 407.1165570711557, 410.2901979575333, 411.4096271849894, 451.51236664402427, 14464.783028254584, 16721.0, 19874.801251987494, 21191.258305959313, 21375.57248855024, 20953.790462095374, 21604.675929861027, 22249.44376390591, 22253.44366390841, 22319.442013949658, 5006.849794506162, 6514.478841692653, 6585.242697089848, 6587.209534855824, 6589.703463344161, 6265.436110750032, 6469.191351081115, 6491.350864913518, 6548.672566371686, 6603.504737144709, 6518.413342799147, 6536.052272420497, 6542.542022058457, 6544.443722283611, 6547.6071435713775, 6241.344658810827, 6514.511411644098, 6533.0, 6553.574017688835, 6597.1093902323055, 6156.907646385308, 6377.521685873532, 6418.871622567569, 6425.357464253537, 6438.935610643881, 20650.5869882603, 25802.0, 25825.354366140997, 26078.86960565204, 26309.473810523872, 2905.099419180061, 2929.9707002929913, 2931.369755502556, 2933.4279815436, 2991.5811786349927, 15335.003224790353, 16419.096949667764, 16514.58713532159, 16644.50066498007, 16852.410165644145, 1792.6056267621102, 2023.494126468383, 2047.3653167518116, 2064.1846470644014, 2085.6454402751415, 135524.0, 138828.0, 143173.0, 144881.2755936224, 145566.0, 22324.441888952908, 22393.664095038588, 22968.425789355064, 22986.540269194524, 23210.883945580503, 34597.65402345944, 35117.29765404729, 35814.0, 35890.10274743152, 35913.102172445375, 10630.255190841597, 10945.671629851116, 11113.55545778176, 11116.38859862699, 11207.047400971009, 9038.683646072488, 9057.0, 9082.682106126254, 9144.131307525775, 9202.447853128833, 10249.795004099877, 10445.955404459644, 10458.26792124544, 10562.683119506428, 10585.84121238182, 4148.357004664222, 4287.8499252526635, 4316.892077698083, 4351.8694439166875, 4363.781810909505, 1046.9162467002623, 1052.8157572424795, 1052.8789189243182, 1081.4592703648175, 1096.4237121490462, 21207.0, 21932.0, 21934.670979935312, 21947.67078493824, 22019.0, 936.8547875079373, 987.5309228116731, 1060.067140915989, 1091.252492042953, 1096.7587130831205, 9246.768830779285, 10913.0, 11475.77048459026, 11562.76874462523, 11599.420028998516, 730647.0, 771263.0, 788434.0, 798316.0, 808198.0, 134426.0, 135932.0, 137523.0, 140643.89034164496, 153137.0, 1146.8910453506903, 1174.277819141222, 1193.7433451807904, 1195.7130288730816, 1256.4032084759665, 18029.819701802815, 18906.716399254296, 19566.510837229183, 19845.801541984394, 20160.79839201589, 64801.0, 66156.99999999808, 67470.66264668645, 67490.66254668696, 69067.65466172837, 97238.54142188015, 98786.0, 99911.0, 101216.0, 104637.0, 8554.27288680469, 11325.0, 11462.82805757931, 11473.36896470702, 11584.594539191088, 13008.544700935421, 13070.281134537692, 13248.536301229411, 13325.400356983766, 13325.800112998506, 11076.280041797258, 11145.052670523097, 11226.438678066228, 11307.265027773168, 11316.189409694409, 10436.217283703601, 10459.320144190604, 10688.732781680521, 10741.892581074402, 10796.514156862802, 9652.951735241279, 11168.609098681507, 11332.980031797179, 11491.770164596828, 11508.654740357802, 11352.432378380883, 11503.0, 11696.766064678828, 11740.295582265091, 11989.34058626784, 10407.323523970917, 11126.165537584551, 11146.275492092987, 11297.887021129683, 11309.377984210947], "value": 11650.638390907696, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1196.5811965811968, 1337.8862796662283, 1350.419319692533, 1355.8169647097639, 1372.224693048428, 10801.297915635489, 11323.943380283097, 11326.546938122487, 11366.204365694404, 11600.535978560847, 9225.584848681825, 11055.22613417061, 11160.720981975455, 11260.324380537173, 11281.0, 2346.706661667296, 2359.6578496118054, 2370.8103351731907, 2371.620540713486, 2376.239603326936, 2116.767155612889, 2274.1358283852114, 2350.8354415190943, 2358.6462030695448, 2361.8346715729904, 1982.5737466444712, 2013.8288245499077, 2153.9892300538477, 2271.886405679718, 2316.5714342846627, 1915.1286164795054, 2266.8866556672265, 2342.16853017179, 2345.0033735662446, 2345.753695861944, 1863.4409677096817, 1992.481954691776, 2061.835053195741, 2360.067773229565, 2396.5686176488225, 11106.88931106891, 11184.608538701106, 11203.159763017724, 11204.383758893258, 11210.495527701272, 5724.484796368305, 5884.617499862538, 5896.380880007623, 5909.586328956974, 5942.197803296543, 3366.5118557809224, 3628.51015112959, 3666.3583872822414, 3683.4106542953, 3720.9441858372147, 1159.8608167019927, 1162.1167912386563, 1198.2211562484367, 1222.4376786678138, 1224.8101544260653, 15330.693386132327, 15346.462873799475, 15510.844891551053, 15516.379344826193, 15538.689226215412, 4304.612584867377, 4581.175388430114, 4621.95378046222, 4648.535146485392, 4659.161350956792, 10130.898691012995, 11674.416279186007, 11700.765984680258, 11728.648140555797, 11745.236559623769, 5625.634333768374, 5678.4037676044245, 5679.119736440858, 5714.771324165325, 5715.599908006399, 4945.381827271591, 5168.0, 5173.818916337986, 5191.169412893994, 5245.239440281138, 3234.498652708834, 3715.405535114423, 3734.9439758403646, 3760.5487341519192, 3836.36699944506, 996.3772642098688, 997.7954519323666, 1004.1966426858484, 1013.4831236069657, 1020.3010937507831, 894.9955250223708, 917.7476194046557, 922.9708874604902, 928.1553786054658, 946.2713710442921, 6174.598651087755, 6546.312637173123, 6600.5049621278595, 6650.800475985729, 6686.431653309523, 888.8888888888889, 889.3596610440472, 890.4523717913437, 897.3987428422972, 902.255639097747, 3201.567788348543, 3223.3714425686767, 3237.6438591754886, 3284.7372210223602, 3303.1454276730033, 13266.73466530664, 13972.790408143886, 14029.298535073205, 14031.438742450391, 14079.788803167961, 12889.935550322376, 13411.396487158101, 13616.931915340558, 13646.0, 13725.0, 9978.850317245247, 10201.489925503694, 10211.591536338463, 10224.386536807815, 10432.113270371952, 6365.490760739222, 6636.43590294811, 6649.202095748347, 6804.455643548603, 6815.318468153145, 10035.849462258218, 11924.284542927453, 12233.0, 12315.568955086528, 12478.18891772032, 11275.605353812905, 12385.876141238832, 12455.128141030042, 12596.748065038832, 12825.807612886001, 9137.817243655223, 9240.983491815761, 9335.393199442015, 9335.766605834908, 9371.906280937375, 10269.794604107727, 10279.383237005804, 10354.17166626653, 10360.119389851947, 10373.58505659765, 8693.652253910026, 8709.433886797338, 8715.607797648994, 8796.824063518821, 8798.560071996502, 8612.267957223707, 8631.654733810577, 8671.176238257356, 8676.609552570275, 8722.258608018135, 7689.038870141232, 7767.844643107219, 7825.530468171928, 7830.569318687527, 7863.0, 5506.972465137648, 5529.585281103853, 5596.972015139898, 5705.771769129188, 5710.428957104256, 5816.47651711348, 5878.470937615635, 6012.969935150296, 6071.392860713893, 6199.0], "value": 4989.943217890117, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.2967103161929, 373.5107009817139, 373.7589254930572, 386.7466809239947, 389.38088439381386, 3913.021744563859, 4863.835593161045, 4999.700197869407, 5184.351956005499, 5348.572114230862, 3948.763074215542, 4445.510993790688, 5101.38783345999, 5152.74236288186, 5248.874205946598, 4255.382954477302, 4289.375477721336, 4335.523092459827, 4338.869833904972, 4396.120775844828, 4097.631213190813, 4259.382389553512, 4373.803178856959, 4374.650027997753, 4410.889727756799, 3896.4350169225445, 4264.744115353089, 4284.785760711968, 4287.742735435868, 4305.483341998949, 4130.1001539291965, 4332.696711230215, 4336.891577710551, 4343.391925130484, 4390.429244198266, 3459.567554055743, 3737.2157742482027, 3814.4633818510656, 3989.489249460485, 4063.841805085566, 4118.897027574335, 5033.238366571707, 5049.469805670426, 5094.79620815167, 5170.586353091744, 4264.57354264571, 4780.402449693788, 5022.547970682656, 5040.092783299005, 5069.974650126763, 4622.86131416058, 4901.313816065754, 5303.681779093266, 5589.580781441408, 5591.860203494904, 3983.98008009961, 4221.915561688749, 4279.8872293203995, 4466.82132714691, 4481.596656300949, 5534.972325138388, 5707.62900411476, 5714.314282286152, 5872.120921305184, 5910.527157827363, 3599.9820000899826, 4498.820047198075, 4531.776420366483, 4544.818207271672, 4553.908921821611, 4466.816293682177, 4606.470255920545, 4612.677112602086, 4652.162610730101, 4659.276067854916, 4365.759883206454, 4404.744647775368, 4749.335093086971, 4894.360389269635, 4975.925361119586, 5073.315102461194, 5211.765470553834, 5470.64440811346, 5559.916601250984, 5622.91565626516, 1441.371250486964, 1558.984410155884, 1571.1672813408911, 1579.1946107485264, 1581.549258461333, 1091.9454027298602, 1181.5923506390282, 1189.3339729751194, 1202.7113492761673, 1210.1665976046297, 4826.975865120652, 5042.520960508746, 5129.794808207705, 5300.429291793477, 5316.388615309212, 5254.527092561688, 5364.034473794754, 5794.21778059965, 5828.0476040526055, 5859.736311865976, 4640.466346370143, 5227.9544091181915, 5273.077211487975, 5286.990943441468, 5295.472371122712, 2674.1041751013627, 2712.294803351143, 2832.7592154666672, 2885.5960165576835, 3011.382666553351, 4740.146773580788, 5472.221528003415, 5929.14027466015, 5950.393393783655, 5993.123300815546, 4831.95168048322, 5002.399712034507, 5145.22821576759, 5483.8080667176455, 5922.644641321534, 5125.564327032244, 5659.43405659439, 6011.0382338825575, 6033.78881739137, 6107.358727333566, 4818.542238487338, 5003.699778013331, 5532.6680399176175, 5758.62568933018, 5814.331351894586, 4910.1586904910655, 4987.700737955734, 5122.975404919106, 5454.536364409069, 5623.915641265303, 5375.892482150413, 5603.3750212437935, 5645.802396916089, 5717.742701578356, 5960.493358064613, 4286.399904013379, 4511.842085526991, 4512.4133862597655, 4514.909701806011, 4525.864224073283, 5238.947610523998, 5611.775528978958, 5623.2408624835125, 5743.295574354602, 5851.342910680824, 4992.700437973734, 5124.256982737407, 5522.641028333145, 5539.446055394428, 5648.293963254593, 4839.612830973584, 4874.979400440382, 5100.649691134561, 5558.693142345881, 5576.247206627216, 4615.469221039489, 5151.2788209649925, 5156.9428267205885, 5162.767675454688, 5453.836384908459, 4776.474587795409, 5107.591392688501, 5208.322918020633, 5450.700211488406, 5577.3028371453565, 4915.230517013801, 5120.74396280177, 5456.890862182813, 5463.918041229305, 5476.726163691879], "value": 4230.104531767172, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [597.946184843364, 716.3839098375396, 794.0273165372416, 816.4325793573469, 840.4285086141423, 15210.315535800888, 15888.76166857498, 16239.106849123293, 16241.431549895757, 16279.83720162798, 13288.800667989964, 13410.26243556604, 14987.700245995073, 15041.022333548312, 15077.698446031072, 935.9812803743938, 953.5041778275292, 956.6269155029544, 966.859805328227, 967.5549247346229, 948.4783369146976, 952.633236204063, 955.0878910640356, 957.4973139101958, 957.7174733453613, 1003.372891942536, 1006.9395836249848, 1007.3250921882357, 1007.5113569918584, 1008.4604736465992, 985.9408435493893, 1003.7490627343165, 1004.4174378860243, 1004.573056451009, 1004.9095581397636, 746.2090184404549, 746.3954197100312, 746.6080307838374, 749.1946157880246, 749.711361125965, 13170.934145329307, 13444.529441469502, 14255.572332830032, 14606.269686515632, 14784.778228326584, 13758.518451854137, 14058.297085145701, 14193.574192774233, 14352.282385880664, 14361.353739081766, 11584.652460426201, 11691.356975366352, 11737.002723167821, 11798.882011179865, 11823.881761182363, 3605.13476765577, 3732.8320225589914, 3745.0, 3771.0383852117807, 3863.459115723801, 97463.99530222018, 106658.0, 109316.0, 110040.34939475915, 110955.0, 10799.83800242997, 10882.510287037103, 10934.125269978542, 11280.605178818701, 11320.37737924422, 21621.459463513536, 21986.340409787732, 22158.667619985714, 22293.44266393321, 22328.218512352316, 7624.8475030500185, 7682.347000504908, 7701.075870895416, 7704.6918123274445, 7923.524588524591, 4257.40396344512, 4275.508316543575, 4362.411074504901, 4381.87786213847, 4521.525239849834, 3111.9066428007195, 3158.510430883217, 3160.0, 3181.650018497963, 3194.7284480818926, 571.44855214718, 596.6390333848046, 613.7299588181186, 626.5394934722975, 635.8791829552371, 570.6262398129232, 584.2550747796594, 602.9758809647652, 641.3970867384644, 697.5396238482577, 9530.857037144448, 9624.278179136592, 10180.949095254477, 10418.739531511774, 10783.191260655325, 555.8026900450362, 567.2455634006761, 598.1508989651605, 605.9484943779828, 672.7779832655212, 4684.929726054112, 4891.926621100686, 4907.9263811042865, 4924.63065270099, 4950.183219768698, 90204.0, 96615.06769864704, 108145.0, 108897.0, 110826.0, 93559.0, 94750.0524994755, 96675.51662241644, 96711.0, 97289.5406568902, 82402.58798706088, 83083.0, 83409.0, 83535.0, 84781.72827407718, 9394.67118650844, 9546.0767562924, 9806.705798826046, 9811.215102791617, 9811.241757091428, 42618.5738142627, 47331.526684732715, 52087.437376878755, 54145.72927135497, 54365.0, 67687.66156169187, 67922.66038669775, 68601.0, 69891.65054174696, 70725.64637176781, 11902.642920712393, 12042.518299267931, 12075.57735479254, 12203.389830508262, 12574.0, 12770.744585108432, 12946.417411216706, 12972.0, 13073.0, 13096.607101786962, 11826.526938922347, 12118.151729378858, 12217.389130543615, 12473.0, 12514.687132821753, 11636.41817909118, 11918.582849600223, 12245.510179593073, 12400.51193856743, 12419.503219871103, 10415.895841041493, 11684.824727628922, 11806.037950834774, 12028.819567706667, 12430.689232769253, 8350.83298334042, 8673.392862499817, 8678.349123815613, 8781.560921954004, 8881.555922203735, 9735.9026409735, 9919.752006199902, 9921.751956201153, 9933.403995760278, 10105.44420056904], "value": 7413.151782928283, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1555.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 121.0, 119.0, 119.0, 142.0, 119.0, 120.0, 118.0, 118.0, 120.0], "value": 120.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 112.0, 109.0, 155.0, 127.0, 99.0, 95.0, 114.0, 161.0, 143.0], "value": 125.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 198.0, 195.0, 202.0, 196.0, 197.0, 197.0, 192.0, 197.0, 196.0], "value": 196.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 63.0, 54.0, 86.0, 76.0, 84.0, 89.0, 58.0, 97.0, 54.0], "value": 74.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 86.0, 72.0, 72.0, 73.0, 103.0, 95.0, 103.0, 114.0], "value": 87.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 105.0, 136.0, 137.0, 89.0, 86.0, 137.0, 135.0, 100.0, 91.0], "value": 110.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 97.0, 98.0, 97.0, 97.0, 97.0, 98.0, 103.0, 149.0, 99.0], "value": 104.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 139.0, 92.0, 97.0, 89.0, 91.0, 90.0, 91.0, 92.0, 90.0], "value": 100.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 67.0, 70.0, 69.0, 67.0, 68.0, 69.0, 68.0], "value": 67.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 60.0, 59.0, 68.0, 67.0, 64.0, 57.0, 58.0, 59.0, 61.0], "value": 61.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 77.0, 106.0, 76.0, 73.0, 74.0, 79.0, 107.0, 73.0, 98.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 149.0, 146.0, 139.0, 140.0, 150.0, 153.0, 159.0, 186.0, 151.0], "value": 153.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 169.0, 241.0, 163.0, 212.0, 228.0, 197.0, 167.0, 169.0, 172.0], "value": 188.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 90.0, 89.0, 89.0, 84.0, 65.0, 63.0, 80.0, 86.0, 86.0], "value": 79.8, "unit": "score"}]}]} 09:30:08 INFO - Return code: 0 09:30:08 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:30:08 ERROR - # TBPL SUCCESS # 09:30:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:30:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:30:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:30:08 INFO - 2017-04-17 09:30:08.168 screenresolution[1659:10739] starting screenresolution argv=screenresolution get 09:30:08 INFO - 2017-04-17 09:30:08.171 screenresolution[1659:10739] Display 0: 1600x1200x32@60 09:30:08 INFO - 2017-04-17 09:30:08.178 screenresolution[1660:10742] starting screenresolution argv=screenresolution list 09:30:08 INFO - Available Modes on Display 0 09:30:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:30:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:30:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:30:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:30:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:30:08 INFO - Intel Iris: 09:30:08 INFO - Chipset Model: Intel Iris 09:30:08 INFO - Type: GPU 09:30:08 INFO - Bus: Built-In 09:30:08 INFO - VRAM (Dynamic, Max): 1536 MB 09:30:08 INFO - Vendor: Intel (0x8086) 09:30:08 INFO - Device ID: 0x0a2e 09:30:08 INFO - Revision ID: 0x0009 09:30:08 INFO - Displays: 09:30:08 INFO - Display: 09:30:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:30:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:30:08 INFO - Display Serial Number: 1600x1200 60 09:30:08 INFO - Main Display: Yes 09:30:08 INFO - Mirror: Off 09:30:08 INFO - Online: Yes 09:30:08 INFO - Rotation: Supported 09:30:08 INFO - Return code: 0 09:30:08 INFO - Running post-action listener: _resource_record_post_action 09:30:08 INFO - [mozharness: 2017-04-17 16:30:08.414387Z] Finished run-tests step (success) 09:30:08 INFO - Running post-run listener: _resource_record_post_run 09:30:08 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 39987712; Write bytes: 514174464; Read time: 259; Write time: 1597 09:30:08 INFO - TinderboxPrint: CPU usage
54.6% 09:30:08 INFO - TinderboxPrint: I/O read bytes / time
39,987,712 / 259 09:30:08 INFO - TinderboxPrint: I/O write bytes / time
514,174,464 / 1,597 09:30:08 INFO - TinderboxPrint: CPU idle
2,181.1 (45.4%) 09:30:08 INFO - TinderboxPrint: CPU system
965.5 (20.1%) 09:30:08 INFO - TinderboxPrint: CPU user
1,655.1 (34.5%) 09:30:08 INFO - TinderboxPrint: Swap in / out
247,615,488 / 0 09:30:08 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 200811008; Write bytes: 201758720; Read time: 15548; Write time: 408 09:30:08 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 38980096; Write bytes: 302495232; Read time: 246; Write time: 1155 09:30:08 INFO - Running post-run listener: _upload_blobber_files 09:30:08 INFO - Blob upload gear active. 09:30:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:30:08 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:30:08 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'] 09:30:08 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 09:30:09 INFO - (blobuploader) - INFO - Open directory for files ... 09:30:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:30:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:30:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:30:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:30:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:30:10 INFO - (blobuploader) - INFO - Done attempting. 09:30:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:30:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:30:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:30:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:30:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:30:11 INFO - (blobuploader) - INFO - Done attempting. 09:30:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:30:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:30:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:30:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:30:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:30:12 INFO - (blobuploader) - INFO - Done attempting. 09:30:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:30:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:30:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:30:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:30:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:30:14 INFO - (blobuploader) - INFO - Done attempting. 09:30:14 INFO - (blobuploader) - INFO - Iteration through files over. 09:30:14 INFO - Return code: 0 09:30:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:30:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:30:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4476978d14e140a39db616dab117b880c6da609ae96ee54eed659c93b91dc1f085f712487913fd29452a0064a8e35ba54b5d50ad622ca65074b995e4f970c605", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0a65f08a34eb8c47a993f7a712aeac8766378d5dabfb013479ddc7eaeaeb2d06b61aad0f559162f97ff59ce31e16dbe29b4a2ad361e64316df6645804409f9d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac8337b5032d9e558cbf500778b12bba5c460b1b6b52be4acf1d609abf1aca52318530c7ecadc397e1e2d1acdc3de775090afac67cc4fce5353f82a90e4118d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/66aa9265154707ed798b180b12fcc2e56e73efcc107a7b3a5f5340f6f019baeac0466f7e1467c3356133665696bcc29e4d0523d5db72f8894aeee94214880a19"} 09:30:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:30:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:30:14 INFO - Contents: 09:30:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4476978d14e140a39db616dab117b880c6da609ae96ee54eed659c93b91dc1f085f712487913fd29452a0064a8e35ba54b5d50ad622ca65074b995e4f970c605", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0a65f08a34eb8c47a993f7a712aeac8766378d5dabfb013479ddc7eaeaeb2d06b61aad0f559162f97ff59ce31e16dbe29b4a2ad361e64316df6645804409f9d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac8337b5032d9e558cbf500778b12bba5c460b1b6b52be4acf1d609abf1aca52318530c7ecadc397e1e2d1acdc3de775090afac67cc4fce5353f82a90e4118d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/66aa9265154707ed798b180b12fcc2e56e73efcc107a7b3a5f5340f6f019baeac0466f7e1467c3356133665696bcc29e4d0523d5db72f8894aeee94214880a19"} 09:30:14 INFO - Running post-run listener: copy_logs_to_upload_dir 09:30:14 INFO - Copying logs to upload dir... 09:30:14 INFO - mkdir: /builds/slave/test/build/upload/logs 09:30:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.421754 ========= master_lag: 0.10 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-04-17 09:30:14.358091) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-17 09:30:14.362484) ========= 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.MzysDDNlFw/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.eLbL0192lf/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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/4476978d14e140a39db616dab117b880c6da609ae96ee54eed659c93b91dc1f085f712487913fd29452a0064a8e35ba54b5d50ad622ca65074b995e4f970c605", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0a65f08a34eb8c47a993f7a712aeac8766378d5dabfb013479ddc7eaeaeb2d06b61aad0f559162f97ff59ce31e16dbe29b4a2ad361e64316df6645804409f9d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac8337b5032d9e558cbf500778b12bba5c460b1b6b52be4acf1d609abf1aca52318530c7ecadc397e1e2d1acdc3de775090afac67cc4fce5353f82a90e4118d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/66aa9265154707ed798b180b12fcc2e56e73efcc107a7b3a5f5340f6f019baeac0466f7e1467c3356133665696bcc29e4d0523d5db72f8894aeee94214880a19"} build_url:https://queue.taskcluster.net/v1/task/S0kFTKvcSi-jOgJchOkJBw/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011175 build_url: 'https://queue.taskcluster.net/v1/task/S0kFTKvcSi-jOgJchOkJBw/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/4476978d14e140a39db616dab117b880c6da609ae96ee54eed659c93b91dc1f085f712487913fd29452a0064a8e35ba54b5d50ad622ca65074b995e4f970c605", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c0a65f08a34eb8c47a993f7a712aeac8766378d5dabfb013479ddc7eaeaeb2d06b61aad0f559162f97ff59ce31e16dbe29b4a2ad361e64316df6645804409f9d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac8337b5032d9e558cbf500778b12bba5c460b1b6b52be4acf1d609abf1aca52318530c7ecadc397e1e2d1acdc3de775090afac67cc4fce5353f82a90e4118d7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/66aa9265154707ed798b180b12fcc2e56e73efcc107a7b3a5f5340f6f019baeac0466f7e1467c3356133665696bcc29e4d0523d5db72f8894aeee94214880a19"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-17 09:30:14.398048) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 09:30:14.398455) ========= 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.MzysDDNlFw/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.eLbL0192lf/Listeners TMPDIR=/var/folders/h8/xgp20z9j4yg2ltsjq_3yvg_000000w/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.005198 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-17 09:30:14.447941) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-17 09:30:14.448258) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-17 09:30:14.448597) ========= ========= Total master_lag: 1.99 =========