builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0321 starttime: 1495757772.06 results: success (0) buildid: 20170525155044 builduid: a4f8c8479bb5416591901e9c8e6543a0 revision: 51c849c595a0b5428d1d56e4ae668653437283e2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 17:16:12.059399) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 17:16:12.059798) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 17:16:12.060069) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 17:16:12.085037) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:16:12.085334) ========= 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.InBYSZR5Yj/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.wYIK9oMPVk/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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-05-25 17:16:12-- 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% 164M=0s 2017-05-25 17:16:12 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.151790 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:16:12.259986) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:16:12.260300) ========= 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.InBYSZR5Yj/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.wYIK9oMPVk/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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.026475 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:16:12.309284) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:16:12.309641) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 51c849c595a0b5428d1d56e4ae668653437283e2 --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-beta --rev 51c849c595a0b5428d1d56e4ae668653437283e2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.InBYSZR5Yj/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.wYIK9oMPVk/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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-05-25 17:16:12,408 truncating revision to first 12 chars 2017-05-25 17:16:12,409 Setting DEBUG logging. 2017-05-25 17:16:12,409 attempt 1/10 2017-05-25 17:16:12,409 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/51c849c595a0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-25 17:16:13,066 unpacking tar archive at: mozilla-beta-51c849c595a0/testing/mozharness/ program finished with exit code 0 elapsedTime=0.937751 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:16:13.269450) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 17:16:13.269770) ========= script_repo_revision: 51c849c595a0b5428d1d56e4ae668653437283e2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 17:16:13.270146) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 17:16:13.270425) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 17:16:13.289400) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-25 17:16:13.289784) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.InBYSZR5Yj/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.wYIK9oMPVk/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 17:16:13 INFO - MultiFileLogger online at 20170525 17:16:13 in /builds/slave/test 17:16:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 17:16:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:16:13 INFO - {'append_to_log': False, 17:16:13 INFO - 'base_work_dir': '/builds/slave/test', 17:16:13 INFO - 'blob_upload_branch': 'Mozilla-Beta', 17:16:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:16:13 INFO - 'branch': 'Mozilla-Beta', 17:16:13 INFO - 'buildbot_json_path': 'buildprops.json', 17:16:13 INFO - 'config_files': ('talos/mac_config.py',), 17:16:13 INFO - 'default_actions': ('clobber', 17:16:13 INFO - 'read-buildbot-config', 17:16:13 INFO - 'download-and-extract', 17:16:13 INFO - 'populate-webroot', 17:16:13 INFO - 'create-virtualenv', 17:16:13 INFO - 'install', 17:16:13 INFO - 'run-tests'), 17:16:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:16:13 INFO - 'download_minidump_stackwalk': True, 17:16:13 INFO - 'download_symbols': 'ondemand', 17:16:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:16:13 INFO - 'tooltool.py': '/tools/tooltool.py', 17:16:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:16:13 INFO - '/tools/misc-python/virtualenv.py')}, 17:16:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:16:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:16:13 INFO - 'gecko_profile': False, 17:16:13 INFO - 'gecko_profile_interval': 0, 17:16:13 INFO - 'installer_path': 'installer.exe', 17:16:13 INFO - 'log_level': 'info', 17:16:13 INFO - 'log_name': 'talos', 17:16:13 INFO - 'log_to_console': True, 17:16:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:16:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:16:13 INFO - 'opt_config_files': (), 17:16:13 INFO - 'pip_index': False, 17:16:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:16:13 INFO - 'cmd': ('bash', 17:16:13 INFO - '-c', 17:16:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:16:13 INFO - 'enabled': True, 17:16:13 INFO - 'halt_on_failure': False, 17:16:13 INFO - 'name': 'check_screen_resolution'},), 17:16:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:16:13 INFO - 'cmd': ('bash', 17:16:13 INFO - '-c', 17:16:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:16:13 INFO - 'enabled': True, 17:16:13 INFO - 'halt_on_failure': False, 17:16:13 INFO - 'name': 'check_screen_resolution'},), 17:16:13 INFO - 'run_cmd_checks_enabled': True, 17:16:13 INFO - 'suite': 'dromaeojs-e10s', 17:16:13 INFO - 'system_bits': '32', 17:16:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:16:13 INFO - 'title': 't-yosemite-r7-0321', 17:16:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:16:13 INFO - 'use_talos_json': True, 17:16:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:16:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:16:13 INFO - 'work_dir': 'build'} 17:16:13 INFO - [mozharness: 2017-05-26 00:16:13.492873Z] Running clobber step. 17:16:13 INFO - Running pre-action listener: _resource_record_pre_action 17:16:13 INFO - Running main action method: clobber 17:16:13 INFO - rmtree: /builds/slave/test/build 17:16:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:16:15 INFO - Running post-action listener: _resource_record_post_action 17:16:15 INFO - [mozharness: 2017-05-26 00:16:15.887697Z] Finished clobber step (success) 17:16:15 INFO - [mozharness: 2017-05-26 00:16:15.887899Z] Running read-buildbot-config step. 17:16:15 INFO - Running pre-action listener: _resource_record_pre_action 17:16:15 INFO - Running main action method: read_buildbot_config 17:16:15 INFO - Using buildbot properties: 17:16:15 INFO - { 17:16:15 INFO - "project": "", 17:16:15 INFO - "product": "firefox", 17:16:15 INFO - "script_repo_revision": "production", 17:16:15 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 17:16:15 INFO - "repository": "", 17:16:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 17:16:15 INFO - "buildid": "20170525155044", 17:16:15 INFO - "pgo_build": "False", 17:16:15 INFO - "basedir": "/builds/slave/test", 17:16:15 INFO - "buildnumber": 7, 17:16:15 INFO - "slavename": "t-yosemite-r7-0321", 17:16:15 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 17:16:15 INFO - "platform": "yosemite_r7", 17:16:15 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 17:16:15 INFO - "branch": "mozilla-beta", 17:16:15 INFO - "repo_path": "releases/mozilla-beta", 17:16:15 INFO - "revision": "51c849c595a0b5428d1d56e4ae668653437283e2", 17:16:15 INFO - "stage_platform": "macosx64", 17:16:15 INFO - "builduid": "a4f8c8479bb5416591901e9c8e6543a0", 17:16:15 INFO - "slavebuilddir": "test" 17:16:15 INFO - } 17:16:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 17:16:15 INFO - Running post-action listener: _resource_record_post_action 17:16:15 INFO - [mozharness: 2017-05-26 00:16:15.893505Z] Finished read-buildbot-config step (success) 17:16:15 INFO - [mozharness: 2017-05-26 00:16:15.893572Z] Running download-and-extract step. 17:16:15 INFO - Running pre-action listener: _resource_record_pre_action 17:16:15 INFO - Running main action method: download_and_extract 17:16:15 INFO - mkdir: /builds/slave/test/build/tests 17:16:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:15 INFO - trying https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 17:16:15 INFO - Downloading https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 17:16:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 17:16:17 INFO - Downloaded 1287 bytes. 17:16:17 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 17:16:17 INFO - Using the following test package requirements: 17:16:17 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:16:17 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 17:16:17 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 17:16:17 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:16:17 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 17:16:17 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:16:17 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 17:16:17 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 17:16:17 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:16:17 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 17:16:17 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 17:16:17 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:16:17 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 17:16:17 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:16:17 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 17:16:17 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:16:17 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 17:16:17 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 17:16:17 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 17:16:17 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 17:16:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 17:16:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 17:16:17 INFO - Fetch https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 17:16:18 INFO - Expected file size: 51393282 17:16:18 INFO - Obtained file size: 51393282 17:16:34 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 17:16:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 17:16:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 17:16:34 INFO - Fetch https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 17:16:36 INFO - Expected file size: 51393282 17:16:36 INFO - Obtained file size: 51393282 17:16:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 17:16:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 17:16:51 INFO - Fetch https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 17:16:51 INFO - Expected file size: 14404449 17:16:51 INFO - Obtained file size: 14404449 17:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:52 INFO - trying https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 17:16:52 INFO - Downloading https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 17:16:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 17:16:54 INFO - Downloaded 59648164 bytes. 17:16:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 17:16:54 INFO - mkdir: /builds/slave/test/properties 17:16:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:16:54 INFO - Writing to file /builds/slave/test/properties/build_url 17:16:54 INFO - Contents: 17:16:54 INFO - build_url:https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 17:16:54 INFO - Running post-action listener: _resource_record_post_action 17:16:54 INFO - Running post-action listener: set_extra_try_arguments 17:16:54 INFO - [mozharness: 2017-05-26 00:16:54.692457Z] Finished download-and-extract step (success) 17:16:54 INFO - [mozharness: 2017-05-26 00:16:54.692641Z] Running populate-webroot step. 17:16:54 INFO - Running pre-action listener: _resource_record_pre_action 17:16:54 INFO - Running main action method: populate_webroot 17:16:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:16:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:16:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:16:54 INFO - '--tppagecycles', 17:16:54 INFO - '7'], 17:16:54 INFO - 'tests': ['tsvgm']}}, 17:16:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:16:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:16:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:16:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:16:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:16:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:16:54 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:16:54 INFO - 'talos_options': ['--disable-e10s'], 17:16:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:16:54 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:16:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:16:54 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:16:54 INFO - 'talos_options': ['--disable-e10s'], 17:16:54 INFO - 'tests': ['damp', 'tps']}, 17:16:54 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:16:54 INFO - 'tests': ['damp', 'tps']}, 17:16:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:16:54 INFO - 'tests': ['dromaeo_dom']}, 17:16:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:16:54 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:16:54 INFO - 'tests': ['basic_compositor_video']}, 17:16:54 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:16:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:16:54 INFO - 'tests': ['a11yr', 17:16:54 INFO - 'ts_paint', 17:16:54 INFO - 'tpaint', 17:16:54 INFO - 'sessionrestore', 17:16:54 INFO - 'sessionrestore_no_auto_restore', 17:16:54 INFO - 'tabpaint']}, 17:16:54 INFO - 'other-e10s': {'tests': ['a11yr', 17:16:54 INFO - 'ts_paint', 17:16:54 INFO - 'tpaint', 17:16:54 INFO - 'sessionrestore', 17:16:54 INFO - 'sessionrestore_no_auto_restore', 17:16:54 INFO - 'tabpaint']}, 17:16:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:16:54 INFO - 'ts_paint', 17:16:54 INFO - 'tpaint', 17:16:54 INFO - 'sessionrestore', 17:16:54 INFO - 'sessionrestore_no_auto_restore', 17:16:54 INFO - 'tabpaint']}, 17:16:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:16:54 INFO - 'ts_paint', 17:16:54 INFO - 'tpaint', 17:16:54 INFO - 'sessionrestore', 17:16:54 INFO - 'sessionrestore_no_auto_restore', 17:16:54 INFO - 'tabpaint']}, 17:16:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:16:54 INFO - 'tests': ['a11yr', 17:16:54 INFO - 'ts_paint', 17:16:54 INFO - 'tpaint', 17:16:54 INFO - 'sessionrestore', 17:16:54 INFO - 'sessionrestore_no_auto_restore', 17:16:54 INFO - 'tabpaint']}, 17:16:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:16:54 INFO - 'tests': ['a11yr', 17:16:54 INFO - 'ts_paint', 17:16:54 INFO - 'tpaint', 17:16:54 INFO - 'sessionrestore', 17:16:54 INFO - 'sessionrestore_no_auto_restore', 17:16:54 INFO - 'tabpaint']}, 17:16:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:16:54 INFO - 'tests': ['tsvgx', 17:16:54 INFO - 'tsvgr_opacity', 17:16:54 INFO - 'tart', 17:16:54 INFO - 'tscrollx', 17:16:54 INFO - 'cart', 17:16:54 INFO - 'tsvg_static']}, 17:16:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:16:54 INFO - 'tsvgr_opacity', 17:16:54 INFO - 'tart', 17:16:54 INFO - 'tscrollx', 17:16:54 INFO - 'cart', 17:16:54 INFO - 'tsvg_static']}, 17:16:54 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:16:54 INFO - 'talos_options': ['--disable-e10s'], 17:16:54 INFO - 'tests': ['tp5o']}, 17:16:54 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:16:54 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:16:54 INFO - 'talos_options': ['--disable-e10s', 17:16:54 INFO - '--xperf_path', 17:16:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:16:54 INFO - 'tests': ['tp5n']}, 17:16:54 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:16:54 INFO - 'talos_options': ['--xperf_path', 17:16:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:16:54 INFO - 'tests': ['tp5n']}}, 17:16:54 INFO - 'talos.zip': {'path': '', 17:16:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:16:54 INFO - Running post-action listener: _resource_record_post_action 17:16:54 INFO - [mozharness: 2017-05-26 00:16:54.710896Z] Finished populate-webroot step (success) 17:16:54 INFO - [mozharness: 2017-05-26 00:16:54.711011Z] Running create-virtualenv step. 17:16:54 INFO - Running pre-action listener: _resource_record_pre_action 17:16:54 INFO - Running main action method: create_virtualenv 17:16:54 INFO - Creating virtualenv /builds/slave/test/build/venv 17:16:54 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 17:16:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:16:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:16:54 INFO - Using real prefix '/tools/python27' 17:16:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:16:55 INFO - Installing distribute.............................................................................................................................................................................................done. 17:16:58 INFO - Installing pip.................done. 17:16:58 INFO - Return code: 0 17:16:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:16:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:16:58 INFO - Reading from file tmpfile_stdout 17:16:58 INFO - Output received: 17:16:58 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) 17:16:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:58 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/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 0x10889bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10889e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee885ab7e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108679d30>, '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 0x1085a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10866ebe8>, '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.InBYSZR5Yj/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.wYIK9oMPVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 17:16:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 17:16:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:16:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.InBYSZR5Yj/Render', 17:16:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:58 INFO - 'HOME': '/Users/cltbld', 17:16:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:58 INFO - 'LOGNAME': 'cltbld', 17:16:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:58 INFO - 'MOZ_NO_REMOTE': '1', 17:16:58 INFO - 'NO_EM_RESTART': '1', 17:16:58 INFO - 'PAGER': '/bin/cat', 17:16:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:58 INFO - 'PWD': '/builds/slave/test', 17:16:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:58 INFO - 'SHELL': '/bin/bash', 17:16:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wYIK9oMPVk/Listeners', 17:16:58 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 17:16:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:58 INFO - 'USER': 'cltbld', 17:16:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:58 INFO - 'XPC_FLAGS': '0x0', 17:16:58 INFO - 'XPC_SERVICE_NAME': '0', 17:16:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:58 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 17:16:58 INFO - Cleaning up... 17:16:58 INFO - Return code: 0 17:16:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:16:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:16:58 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/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 0x10889bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10889e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee885ab7e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108679d30>, '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 0x1085a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10866ebe8>, '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.InBYSZR5Yj/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.wYIK9oMPVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 17:16:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 17:16:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:16:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.InBYSZR5Yj/Render', 17:16:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:16:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:16:58 INFO - 'HOME': '/Users/cltbld', 17:16:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:16:58 INFO - 'LOGNAME': 'cltbld', 17:16:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:16:58 INFO - 'MOZ_NO_REMOTE': '1', 17:16:58 INFO - 'NO_EM_RESTART': '1', 17:16:58 INFO - 'PAGER': '/bin/cat', 17:16:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:16:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:16:58 INFO - 'PWD': '/builds/slave/test', 17:16:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:16:58 INFO - 'SHELL': '/bin/bash', 17:16:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wYIK9oMPVk/Listeners', 17:16:58 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 17:16:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:16:58 INFO - 'USER': 'cltbld', 17:16:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:16:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:16:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:16:58 INFO - 'XPC_FLAGS': '0x0', 17:16:58 INFO - 'XPC_SERVICE_NAME': '0', 17:16:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:16:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:16:59 INFO - Downloading/unpacking psutil>=3.1.1 17:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:16:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:17:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:17:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:17:01 INFO - Installing collected packages: psutil 17:17:01 INFO - Running setup.py install for psutil 17:17:01 INFO - building 'psutil._psutil_osx' extension 17:17:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 17:17:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 17:17:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 17:17:01 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 17:17:02 INFO - building 'psutil._psutil_posix' extension 17:17:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 17:17:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:17:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:17:02 INFO - ^ 17:17:02 INFO - 1 warning generated. 17:17:02 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 17:17:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:17:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:17:02 INFO - Successfully installed psutil 17:17:02 INFO - Cleaning up... 17:17:02 INFO - Return code: 0 17:17:02 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:17:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:17:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10889bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10889e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee885ab7e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108679d30>, '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 0x1085a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10866ebe8>, '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.InBYSZR5Yj/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.wYIK9oMPVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 17:17:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 17:17:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:17:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.InBYSZR5Yj/Render', 17:17:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:02 INFO - 'HOME': '/Users/cltbld', 17:17:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:02 INFO - 'LOGNAME': 'cltbld', 17:17:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:02 INFO - 'MOZ_NO_REMOTE': '1', 17:17:02 INFO - 'NO_EM_RESTART': '1', 17:17:02 INFO - 'PAGER': '/bin/cat', 17:17:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:02 INFO - 'PWD': '/builds/slave/test', 17:17:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:02 INFO - 'SHELL': '/bin/bash', 17:17:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wYIK9oMPVk/Listeners', 17:17:02 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 17:17:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:02 INFO - 'USER': 'cltbld', 17:17:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:02 INFO - 'XPC_FLAGS': '0x0', 17:17:02 INFO - 'XPC_SERVICE_NAME': '0', 17:17:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:17:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:17:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:17:04 INFO - Installing collected packages: mozsystemmonitor 17:17:04 INFO - Running setup.py install for mozsystemmonitor 17:17:04 INFO - Successfully installed mozsystemmonitor 17:17:04 INFO - Cleaning up... 17:17:04 INFO - Return code: 0 17:17:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:17:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:17:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:17:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:04 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/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 0x10889bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10889e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee885ab7e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108679d30>, '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 0x1085a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10866ebe8>, '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.InBYSZR5Yj/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.wYIK9oMPVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 17:17:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 17:17:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 17:17:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.InBYSZR5Yj/Render', 17:17:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:04 INFO - 'HOME': '/Users/cltbld', 17:17:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:04 INFO - 'LOGNAME': 'cltbld', 17:17:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:04 INFO - 'MOZ_NO_REMOTE': '1', 17:17:04 INFO - 'NO_EM_RESTART': '1', 17:17:04 INFO - 'PAGER': '/bin/cat', 17:17:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:04 INFO - 'PWD': '/builds/slave/test', 17:17:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:04 INFO - 'SHELL': '/bin/bash', 17:17:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wYIK9oMPVk/Listeners', 17:17:04 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 17:17:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:04 INFO - 'USER': 'cltbld', 17:17:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:04 INFO - 'XPC_FLAGS': '0x0', 17:17:04 INFO - 'XPC_SERVICE_NAME': '0', 17:17:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:04 INFO - Downloading/unpacking jsonschema==2.5.1 17:17:04 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 17:17:04 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 17:17:04 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 17:17:04 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 17:17:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:17:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:17:05 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 17:17:05 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 17:17:05 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 17:17:05 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 17:17:06 INFO - Downloading functools32-3.2.3-2.tar.gz 17:17:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:17:06 INFO - warning: no files found matching '*.txt' 17:17:06 INFO - no previously-included directories found matching 'build' 17:17:06 INFO - no previously-included directories found matching 'dist' 17:17:06 INFO - no previously-included directories found matching '.git*' 17:17:06 INFO - Installing collected packages: jsonschema, functools32 17:17:06 INFO - Running setup.py install for functools32 17:17:06 INFO - warning: no files found matching '*.txt' 17:17:06 INFO - no previously-included directories found matching 'build' 17:17:06 INFO - no previously-included directories found matching 'dist' 17:17:06 INFO - no previously-included directories found matching '.git*' 17:17:06 INFO - Successfully installed jsonschema functools32 17:17:06 INFO - Cleaning up... 17:17:06 INFO - Return code: 0 17:17:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:17:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:17:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:17:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10889bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10889e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee885ab7e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108679d30>, '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 0x1085a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10866ebe8>, '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.InBYSZR5Yj/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.wYIK9oMPVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 17:17:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 17:17:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:17:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.InBYSZR5Yj/Render', 17:17:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:06 INFO - 'HOME': '/Users/cltbld', 17:17:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:06 INFO - 'LOGNAME': 'cltbld', 17:17:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:06 INFO - 'MOZ_NO_REMOTE': '1', 17:17:06 INFO - 'NO_EM_RESTART': '1', 17:17:06 INFO - 'PAGER': '/bin/cat', 17:17:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:06 INFO - 'PWD': '/builds/slave/test', 17:17:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:06 INFO - 'SHELL': '/bin/bash', 17:17:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wYIK9oMPVk/Listeners', 17:17:06 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 17:17:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:06 INFO - 'USER': 'cltbld', 17:17:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:06 INFO - 'XPC_FLAGS': '0x0', 17:17:06 INFO - 'XPC_SERVICE_NAME': '0', 17:17:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:17:06 INFO - Cleaning up... 17:17:06 INFO - Return code: 0 17:17:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:17:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:17:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:17:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10889bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10889e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee885ab7e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108679d30>, '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 0x1085a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10866ebe8>, '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.InBYSZR5Yj/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.wYIK9oMPVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 17:17:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 17:17:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:17:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.InBYSZR5Yj/Render', 17:17:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:06 INFO - 'HOME': '/Users/cltbld', 17:17:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:06 INFO - 'LOGNAME': 'cltbld', 17:17:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:06 INFO - 'MOZ_NO_REMOTE': '1', 17:17:06 INFO - 'NO_EM_RESTART': '1', 17:17:06 INFO - 'PAGER': '/bin/cat', 17:17:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:06 INFO - 'PWD': '/builds/slave/test', 17:17:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:06 INFO - 'SHELL': '/bin/bash', 17:17:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wYIK9oMPVk/Listeners', 17:17:06 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 17:17:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:06 INFO - 'USER': 'cltbld', 17:17:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:06 INFO - 'XPC_FLAGS': '0x0', 17:17:06 INFO - 'XPC_SERVICE_NAME': '0', 17:17:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:07 INFO - Downloading/unpacking blobuploader==1.2.4 17:17:07 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 17:17:07 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 17:17:07 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 17:17:07 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 17:17:08 INFO - Downloading blobuploader-1.2.4.tar.gz 17:17:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:17:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:17:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:17:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:09 INFO - Downloading docopt-0.6.1.tar.gz 17:17:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:17:09 INFO - Installing collected packages: blobuploader, requests, docopt 17:17:09 INFO - Running setup.py install for blobuploader 17:17:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:17:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:17:09 INFO - Running setup.py install for requests 17:17:10 INFO - Running setup.py install for docopt 17:17:10 INFO - Successfully installed blobuploader requests docopt 17:17:10 INFO - Cleaning up... 17:17:10 INFO - Return code: 0 17:17:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:17:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:17:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:10 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/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 0x10889bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10889e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee885ab7e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108679d30>, '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 0x1085a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10866ebe8>, '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.InBYSZR5Yj/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.wYIK9oMPVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 17:17:10 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:17:10 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:17:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.InBYSZR5Yj/Render', 17:17:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:10 INFO - 'HOME': '/Users/cltbld', 17:17:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:10 INFO - 'LOGNAME': 'cltbld', 17:17:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:10 INFO - 'MOZ_NO_REMOTE': '1', 17:17:10 INFO - 'NO_EM_RESTART': '1', 17:17:10 INFO - 'PAGER': '/bin/cat', 17:17:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:10 INFO - 'PWD': '/builds/slave/test', 17:17:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:10 INFO - 'SHELL': '/bin/bash', 17:17:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wYIK9oMPVk/Listeners', 17:17:10 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 17:17:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:10 INFO - 'USER': 'cltbld', 17:17:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:10 INFO - 'XPC_FLAGS': '0x0', 17:17:10 INFO - 'XPC_SERVICE_NAME': '0', 17:17:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:17:11 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-qu_a8v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:17:11 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-So71XX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:17:11 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-hrpUh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:17:11 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-zB6sio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:17:11 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-wIFhzR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:17:11 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-Cus3Mk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:17:11 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-i61sdk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:17:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:17:11 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-1oNOfe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:17:12 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-PHMeh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:17:12 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-O7DFlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:17:12 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-2X9NIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:17:12 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-3EyN_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:17:12 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-xwjBri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:17:12 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-Vc5A3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:17:12 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-Jn3B7A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:17:12 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-vnZh9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:17:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:17:12 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-0QHBpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:17:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:17:13 INFO - Running setup.py install for manifestparser 17:17:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:17:13 INFO - Running setup.py install for mozcrash 17:17:13 INFO - Running setup.py install for mozdebug 17:17:13 INFO - Running setup.py install for mozdevice 17:17:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:17:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:17:13 INFO - Running setup.py install for mozfile 17:17:13 INFO - Running setup.py install for mozhttpd 17:17:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:17:13 INFO - Running setup.py install for mozinfo 17:17:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:17:14 INFO - Running setup.py install for mozInstall 17:17:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:17:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:17:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:17:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:17:14 INFO - Running setup.py install for mozleak 17:17:14 INFO - Running setup.py install for mozlog 17:17:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:17:14 INFO - Running setup.py install for moznetwork 17:17:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:17:14 INFO - Running setup.py install for mozprocess 17:17:14 INFO - Running setup.py install for mozprofile 17:17:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:17:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:17:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:17:14 INFO - Running setup.py install for mozrunner 17:17:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:17:15 INFO - Running setup.py install for mozscreenshot 17:17:15 INFO - Running setup.py install for moztest 17:17:15 INFO - Running setup.py install for mozversion 17:17:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:17:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:17:15 INFO - Cleaning up... 17:17:15 INFO - Return code: 0 17:17:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:17:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:17:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:17:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:16 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/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 0x10889bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10889e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee885ab7e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108679d30>, '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 0x1085a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10866ebe8>, '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.InBYSZR5Yj/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.wYIK9oMPVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 17:17:16 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:17:16 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:17:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.InBYSZR5Yj/Render', 17:17:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:16 INFO - 'HOME': '/Users/cltbld', 17:17:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:16 INFO - 'LOGNAME': 'cltbld', 17:17:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:16 INFO - 'MOZ_NO_REMOTE': '1', 17:17:16 INFO - 'NO_EM_RESTART': '1', 17:17:16 INFO - 'PAGER': '/bin/cat', 17:17:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:16 INFO - 'PWD': '/builds/slave/test', 17:17:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:16 INFO - 'SHELL': '/bin/bash', 17:17:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wYIK9oMPVk/Listeners', 17:17:16 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 17:17:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:16 INFO - 'USER': 'cltbld', 17:17:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:16 INFO - 'XPC_FLAGS': '0x0', 17:17:16 INFO - 'XPC_SERVICE_NAME': '0', 17:17:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:17:16 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-EJ970D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:17:16 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)) 17:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:17:16 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-1K6Jmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:17:16 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)) 17:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:17:16 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-e1XOul-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:17:16 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)) 17:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:17:16 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-aN9aQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:17:16 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)) 17:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:17:16 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-WX_8cL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:17:16 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)) 17:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:17:16 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-TPNvrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:17:16 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)) 17:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:17:16 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-EKmQZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:17:17 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)) 17:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:17:17 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-BM9EXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:17:17 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)) 17:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:17:17 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-V4oRDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:17:17 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)) 17:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:17:17 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-VNXWIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:17:17 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)) 17:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:17:17 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-yQLM3d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:17:17 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)) 17:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:17:17 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-b4Hg0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:17:17 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)) 17:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:17:17 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-td1PSC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:17:17 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)) 17:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:17:17 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-rGV75L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:17:17 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)) 17:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:17:17 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-EuEiLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:17:17 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)) 17:17:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:17:17 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-La4VJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:17:18 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)) 17:17:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:17:18 INFO - Running setup.py (path:/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/pip-GGs1pX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:17:18 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)) 17:17:18 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)) 17:17:18 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)) 17:17:18 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)) 17:17:18 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)) 17:17:18 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)) 17:17:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:17:18 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 17:17:18 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 17:17:18 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 17:17:18 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 17:17:19 INFO - Downloading blessings-1.6.tar.gz 17:17:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:17:20 INFO - Installing collected packages: blessings 17:17:20 INFO - Running setup.py install for blessings 17:17:20 INFO - Successfully installed blessings 17:17:20 INFO - Cleaning up... 17:17:20 INFO - Return code: 0 17:17:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:17:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:17:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:17:20 INFO - Reading from file tmpfile_stdout 17:17:20 INFO - Current package versions: 17:17:20 INFO - blessings == 1.6 17:17:20 INFO - blobuploader == 1.2.4 17:17:20 INFO - docopt == 0.6.1 17:17:20 INFO - functools32 == 3.2.3-2 17:17:20 INFO - jsonschema == 2.5.1 17:17:20 INFO - manifestparser == 1.1 17:17:20 INFO - mozInstall == 1.12 17:17:20 INFO - mozcrash == 1.0 17:17:20 INFO - mozdebug == 0.1 17:17:20 INFO - mozdevice == 0.49 17:17:20 INFO - mozfile == 1.2 17:17:20 INFO - mozhttpd == 0.7 17:17:20 INFO - mozinfo == 0.9 17:17:20 INFO - mozleak == 0.1 17:17:20 INFO - mozlog == 3.4 17:17:20 INFO - moznetwork == 0.27 17:17:20 INFO - mozprocess == 0.25 17:17:20 INFO - mozprofile == 0.28 17:17:20 INFO - mozrunner == 6.13 17:17:20 INFO - mozscreenshot == 0.1 17:17:20 INFO - mozsystemmonitor == 0.3 17:17:20 INFO - moztest == 0.8 17:17:20 INFO - mozversion == 1.4 17:17:20 INFO - psutil == 3.1.1 17:17:20 INFO - requests == 1.2.3 17:17:20 INFO - wsgiref == 0.1.2 17:17:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:17:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:17:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:17:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:20 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/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 0x10889bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10889e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee885ab7e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108679d30>, '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 0x1085a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10866ebe8>, '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.InBYSZR5Yj/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.wYIK9oMPVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 17:17:20 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 17:17:20 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:17:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.InBYSZR5Yj/Render', 17:17:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:20 INFO - 'HOME': '/Users/cltbld', 17:17:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:20 INFO - 'LOGNAME': 'cltbld', 17:17:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:20 INFO - 'MOZ_NO_REMOTE': '1', 17:17:20 INFO - 'NO_EM_RESTART': '1', 17:17:20 INFO - 'PAGER': '/bin/cat', 17:17:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:20 INFO - 'PWD': '/builds/slave/test', 17:17:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:20 INFO - 'SHELL': '/bin/bash', 17:17:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wYIK9oMPVk/Listeners', 17:17:20 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 17:17:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:20 INFO - 'USER': 'cltbld', 17:17:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:20 INFO - 'XPC_FLAGS': '0x0', 17:17:20 INFO - 'XPC_SERVICE_NAME': '0', 17:17:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:20 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)) 17:17:20 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)) 17:17:20 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)) 17:17:20 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)) 17:17:20 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)) 17:17:20 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)) 17:17:20 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)) 17:17:20 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)) 17:17:20 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)) 17:17:20 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)) 17:17:20 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)) 17:17:20 INFO - Cleaning up... 17:17:20 INFO - Return code: 0 17:17:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:17:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:17:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:17:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10889bb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10889e768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee885ab7e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108679d30>, '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 0x1085a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10866ebe8>, '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.InBYSZR5Yj/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.wYIK9oMPVk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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 17:17:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:17:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:17:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.InBYSZR5Yj/Render', 17:17:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:20 INFO - 'HOME': '/Users/cltbld', 17:17:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:20 INFO - 'LOGNAME': 'cltbld', 17:17:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:20 INFO - 'MOZ_NO_REMOTE': '1', 17:17:20 INFO - 'NO_EM_RESTART': '1', 17:17:20 INFO - 'PAGER': '/bin/cat', 17:17:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:20 INFO - 'PWD': '/builds/slave/test', 17:17:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:20 INFO - 'SHELL': '/bin/bash', 17:17:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wYIK9oMPVk/Listeners', 17:17:20 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 17:17:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:20 INFO - 'USER': 'cltbld', 17:17:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:20 INFO - 'XPC_FLAGS': '0x0', 17:17:20 INFO - 'XPC_SERVICE_NAME': '0', 17:17:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:17:21 INFO - Cleaning up... 17:17:21 INFO - Return code: 0 17:17:21 INFO - Running post-action listener: _resource_record_post_action 17:17:21 INFO - Running post-action listener: _start_resource_monitoring 17:17:21 INFO - Starting resource monitoring. 17:17:21 INFO - [mozharness: 2017-05-26 00:17:21.068968Z] Finished create-virtualenv step (success) 17:17:21 INFO - [mozharness: 2017-05-26 00:17:21.069914Z] Running install step. 17:17:21 INFO - Running pre-action listener: _resource_record_pre_action 17:17:21 INFO - Running main action method: install 17:17:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:17:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:17:21 INFO - Reading from file tmpfile_stdout 17:17:21 INFO - Detecting whether we're running mozinstall >=1.0... 17:17:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:17:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:17:21 INFO - Reading from file tmpfile_stdout 17:17:21 INFO - Output received: 17:17:21 INFO - Usage: mozinstall [options] installer 17:17:21 INFO - Options: 17:17:21 INFO - -h, --help show this help message and exit 17:17:21 INFO - -d DEST, --destination=DEST 17:17:21 INFO - Directory to install application into. [default: 17:17:21 INFO - "/builds/slave/test"] 17:17:21 INFO - --app=APP Application being installed. [default: firefox] 17:17:21 INFO - mkdir: /builds/slave/test/build/application 17:17:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:17:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 17:17:36 INFO - Reading from file tmpfile_stdout 17:17:36 INFO - Output received: 17:17:36 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 17:17:36 INFO - Running post-action listener: _resource_record_post_action 17:17:36 INFO - [mozharness: 2017-05-26 00:17:36.683198Z] Finished install step (success) 17:17:36 INFO - [mozharness: 2017-05-26 00:17:36.683352Z] Running run-tests step. 17:17:36 INFO - Running pre-action listener: _resource_record_pre_action 17:17:36 INFO - Running main action method: run_tests 17:17:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:17:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:17:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:17:36 INFO - 2017-05-25 17:17:36.697 screenresolution[1626:7361] starting screenresolution argv=screenresolution get 17:17:36 INFO - 2017-05-25 17:17:36.700 screenresolution[1626:7361] Display 0: 1600x1200x32@60 17:17:36 INFO - 2017-05-25 17:17:36.706 screenresolution[1627:7363] starting screenresolution argv=screenresolution list 17:17:36 INFO - Available Modes on Display 0 17:17:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:17:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:17:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:17:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:17:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:17:36 INFO - Intel Iris: 17:17:36 INFO - Chipset Model: Intel Iris 17:17:36 INFO - Type: GPU 17:17:36 INFO - Bus: Built-In 17:17:36 INFO - VRAM (Dynamic, Max): 1536 MB 17:17:36 INFO - Vendor: Intel (0x8086) 17:17:36 INFO - Device ID: 0x0a2e 17:17:36 INFO - Revision ID: 0x0009 17:17:36 INFO - Displays: 17:17:36 INFO - Display: 17:17:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:17:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:17:36 INFO - Display Serial Number: 1600x1200 60 17:17:36 INFO - Main Display: Yes 17:17:36 INFO - Mirror: Off 17:17:36 INFO - Online: Yes 17:17:36 INFO - Rotation: Supported 17:17:36 INFO - Return code: 0 17:17:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:17:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:17:36 INFO - Python 2.7.3 17:17:36 INFO - Return code: 0 17:17:36 INFO - grabbing minidump binary from tooltool 17:17:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 17:17:36 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 0x108679d30>, '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 0x1085a0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10866ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:17:36 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 17:17:36 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 17:17:36 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 17:17:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:17:37 INFO - Return code: 0 17:17:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:17:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:17:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:17:37 INFO - ENV: RUST_BACKTRACE is now 1 17:17:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:17:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:17:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0321', '--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 17:17:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0321 --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 17:17:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.InBYSZR5Yj/Render', 17:17:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:37 INFO - 'HOME': '/Users/cltbld', 17:17:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:37 INFO - 'LOGNAME': 'cltbld', 17:17:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:17:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:17:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:37 INFO - 'MOZ_NO_REMOTE': '1', 17:17:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:17:37 INFO - 'NO_EM_RESTART': '1', 17:17:37 INFO - 'PAGER': '/bin/cat', 17:17:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:37 INFO - 'PWD': '/builds/slave/test', 17:17:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:17:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:37 INFO - 'RUST_BACKTRACE': '1', 17:17:37 INFO - 'SHELL': '/bin/bash', 17:17:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wYIK9oMPVk/Listeners', 17:17:37 INFO - 'TMPDIR': '/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/', 17:17:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:37 INFO - 'USER': 'cltbld', 17:17:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:37 INFO - 'XPC_FLAGS': '0x0', 17:17:37 INFO - 'XPC_SERVICE_NAME': '0', 17:17:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0321', '--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 17:17:37 INFO - mozversion application_buildid: 20170525155044 17:17:37 INFO - mozversion application_changeset: 51c849c595a0b5428d1d56e4ae668653437283e2 17:17:37 INFO - mozversion application_display_name: Firefox 17:17:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:17:37 INFO - mozversion application_name: Firefox 17:17:37 INFO - mozversion application_remotingname: firefox 17:17:37 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 17:17:37 INFO - mozversion application_vendor: Mozilla 17:17:37 INFO - mozversion application_version: 54.0 17:17:37 INFO - mozversion platform_buildid: 20170525155044 17:17:37 INFO - mozversion platform_changeset: 51c849c595a0b5428d1d56e4ae668653437283e2 17:17:37 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 17:17:37 INFO - mozversion platform_version: 54.0 17:17:37 INFO - using testdate: 1495757857 17:17:37 INFO - actual date: 1495757857 17:17:37 INFO - starting webserver on 'localhost:49218' 17:17:37 INFO - SUITE-START | Running 2 tests 17:17:37 INFO - TEST-START | dromaeo_css 17:17:37 INFO - operating with platform_type : mac_ 17:17:37 INFO - Initialising browser for dromaeo_css test... 17:17:37 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmpJIszWj/profile http://localhost:49218/getInfo.html) 17:17:40 INFO - PROCESS | 1637 | 2017-05-25 17:17:40.186 plugin-container[1640:7532] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 17:17:40 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:17:40 INFO - PROCESS | 1637 | 2017-05-25 17:17:40.187 plugin-container[1640:7532] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d1b, name = 'com.apple.CFPasteboardClient' 17:17:40 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:17:40 INFO - PROCESS | 1637 | 2017-05-25 17:17:40.187 plugin-container[1640:7532] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:17:40 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 17:17:40 INFO - PROCESS | 1637 | colorDepth:24 17:17:40 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 17:17:40 INFO - PROCESS | 1637 | __metrics 17:17:40 ERROR - PROCESS | 1637 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 17:17:41 INFO - TEST-INFO | 1637: exit 0 17:17:41 INFO - Browser initialized. 17:17:41 INFO - Running cycle 1/1 for dromaeo_css test... 17:17:41 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmpJIszWj/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:17:43 INFO - PROCESS | 1641 | 2017-05-25 17:17:43.394 plugin-container[1642:7664] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x854f, name = 'com.apple.tsm.portname' 17:17:43 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:17:43 INFO - PROCESS | 1641 | 2017-05-25 17:17:43.395 plugin-container[1642:7664] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 17:17:43 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:17:43 INFO - PROCESS | 1641 | 2017-05-25 17:17:43.395 plugin-container[1642:7664] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:17:53 INFO - PROCESS | 1641 | RSS: Main: 174288896 17:17:53 INFO - PROCESS | 1641 | 17:20:56 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49218/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49218/tests/dromaeo/cssquery-ext.html) 17:20:58 INFO - PROCESS | 1641 | RSS: Main: 189378560 17:20:58 INFO - PROCESS | 1641 | 17:24:00 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49218/tests/dromaeo/cssquery-ext.html (next: http://localhost:49218/tests/dromaeo/cssquery-jquery.html) 17:24:01 INFO - PROCESS | 1641 | RSS: Main: 198787072 17:24:01 INFO - PROCESS | 1641 | 17:27:03 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49218/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49218/tests/dromaeo/cssquery-mootools.html) 17:27:04 INFO - PROCESS | 1641 | RSS: Main: 203509760 17:27:04 INFO - PROCESS | 1641 | 17:30:05 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49218/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49218/tests/dromaeo/cssquery-prototype.html) 17:30:06 INFO - PROCESS | 1641 | RSS: Main: 206606336 17:30:06 INFO - PROCESS | 1641 | 17:33:09 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49218/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49218/tests/dromaeo/cssquery-yui.html) 17:33:10 INFO - PROCESS | 1641 | RSS: Main: 209850368 17:33:10 INFO - PROCESS | 1641 | 17:36:12 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49218/tests/dromaeo/cssquery-yui.html (next: http://localhost:49218/tests/dromaeo/cssquery-dojo.html) 17:36:13 INFO - PROCESS | 1641 | RSS: Main: 207912960 17:36:13 INFO - PROCESS | 1641 | 17:36:13 INFO - PROCESS | 1641 | __start_tp_report 17:36:13 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 17:36:13 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 17:36:13 INFO - PROCESS | 1641 | |i|pagename|runs| 17:36:13 INFO - PROCESS | 1641 | |0;dojo.html;3055.8777648894047;3398.2863598644285;3475.8262086895634;3622.040159357771;3652;5165;6002.309734380537;6074.69626518674;6170.56806023578;6242.750289988395;6026.789062382817;6169.444749972502;6179.227596550431;6233.968830155843;6241.781537646183;4527.366168736387;5035.571976382012;5547.0570003099365;5570.916436253459;5622.746976386052;5591.6365436246715;5597.076482380402;5597.356304025048;5600.715452678704;5633.070543360341;5327.067763141454;5358.196270559409;5423.83387571672;5579.46995035471;5580.609357344987;5529.612927095105;5544.500994910457;5566.749496272657;5576.86057848557;5608.383077861473;5444.128939369682;5529.419410961871;5547.694876781776;5549.694766787825;5599.524040456566;5945.256842894638;6189.916764566183;6219.626822390625;6256.405641464054;6280.905786413207;6298.811035668938;6300.905486417708;6303.991361382203;6329.968350158266;6332.968335158294;6473.902891456632;6491.415772580443;6499.6100233985635;6505.512086593524;6517.478601711851;5661.34894487131;6073.908891366633;6185.350538193425;6210.596311239845;6257.906131407987;6441.871162576722;6477.902831457532;6685.565438246498;6687.531872768956;6714.29499902513;6356.396142366468;6366.713497892606;6416.230052393743;6426.582272152295;6431.356864313531;6512.251091124486;6581.605103693793;6601.2738598754095;6607.273199948002;6627.03907933347;6263;6297.055441683784;6493.25327587324;6506.934930650727;6524.7276781028195;6288.52836037299;6346.30190679025;6360.586561873463;6380.553361264667;6380.872382552323;5194.4026436959475;5263.710495922761;5301.310829592128;5307.28351672527;5324.760385782572;4698.88252793676;4711.246200607886;4729.125111854318;4741.478437371887;4745.691530050535;5344.866378340573;6062.818115456544;6118.071005858243;6306.054091886253;6314.336994615591;6429.839254018593;6458.644774537445;6511.088447617338;6536.313687062885;6540.542162048706;6261.499080073583;6264.21697287839;6265.091561723524;6304.306526282106;6305;5632.04255276604;5813.302403711582;5814.447627475383;5817.88364232713;5822.184894114827;6613.206415230107;6726.932730672631;6750.3924646782025;6776.15298087739;6788.93211067883;5553.4724201200825;6471.126397936407;6604.537682362381;6607.108040414675;6614.570052946543;6826.658667066527;6837.931620683729;6852.417544508772;6862.348076932683;6882.7591034313555;5372.785088596413;6262.812115636538;6317.652529110943;6396.392342727433;6417.743290268337;5571.969185700658;6607.9669601651685;6616.338366163345;6626.668666566556;6641.767538136142;6349.460295874902;6360.077788720702;6393.6483493408305;6443.162388889415;6616.371444712745;6239.906401403891;6266.467350275096;6321.146645202839;6322.873542529215;6389.87220255583;6228.781992630236;6270.184875966278;6318.399752023551;6318.526110541636;6339.207599050119;6211.316755156839;6366.917624004023;6379.425851673372;6405.135306733531;6417.03744438338;6149.8007888461325;6214.25428948529;6219.036049412348;6286.402791734778;6292.465140463112 17:36:13 INFO - PROCESS | 1641 | |1;ext.html;765.7205120131154;818.2921772666643;840.4873027453252;978.403174063821;981.3621146254937;39481.60518394819;40527;41675.95810104749;41726.58273417265;41814.37278440826;31889.84055079727;39536.40695389571;39673;39691.40462893052;40030.7998460008;8318.875216871751;8479.406441549094;11249.60626378077;11458.770824583504;11671;11401.828972565418;11473.713157171054;11475.598354057567;11483.827742583868;11510.366929818856;11417.600383986563;11459.083273338112;11468.369239691814;11472.196946213768;11548.30710157389;11382.260153090065;11422.257553259053;11424.485898134604;11429.199956003084;11446;9647.758806029906;10958.671239862817;11137.610183643614;11153.665390038312;11202.607908723156;35070.82464587661;35246.295074098634;35455.11362215939;35532.46701299483;35557.82221088904;3872.7482713623526;3938.921221575581;3941.3299739044432;3946.092398748275;3953;26050.34874128162;26540.938362465477;26687.332816679544;26690.466190676078;27280.590791138147;3204.5513628092085;3220.855061522214;3264.4287249731437;3312.817795021273;3317.9004629861142;301564.98435015586;321440;322313;350470;369435.15282423684;44529.777351113036;44541.777291113336;44687;44704.5412502309;44704.55295447069;42710;47268;49026;49061.26408103881;49123.263151052764;14058.94557908161;14205.21871297068;14210.289485525682;14215.715685686228;14223.502177423741;12075.27548347102;12142.575009874789;12289.754204915851;12314.81527777084;12330.691732706575;12113.818292725617;12138.575149869712;12159.635210943687;12201.938990304992;12218.389080545938;5361.713188834729;5454.672719636835;5887.4406931341455;5889.558283128783;5919.496842768327;876.2332908975155;900.6667533012827;905.8958219804675;906.9501177435172;916.9358144929924;26869.731302687112;27718.722812771615;27907.162785116478;28161.014364497143;28256.8587157067;978.8923218445965;995.5171741705267;1031.4636389077787;1032.3031953431555;1036.4765793274498;12849.100562960502;14098.50655227062;14444.855551444562;14690.63273418173;14730.852691472948;420052;530247;539513.3024334932;667770;670630;47413;47820.760896195294;47831;47863;47894.5210547897;1470.867621914033;1484.5175318021686;1487.137460273039;1490.1282749591394;1503.361071544589;26241;27594.5860812092;28044.298892527033;28240.152795416172;28297.29256768515;41001.97495062647;41200.793996029824;41232.58767412287;41255;41293.58706412897;421689;422364;423726;424707;424839.875800619;29066;30117;30406.783728650607;31568.36863262676;32596.674033259365;39524;39835.20329593334;40540;40775.59224407718;40863.77408677744;28804.711952880203;28812;29078.27304317409;29462;29583.408331833674;28773;28849.42301154007;28858.567121493616;29513.409731804815;29647.703522964493;33459;33984.830075849466;34249.486257705656;34839;35355.82322088373;29592;29745.70254297516;29848;30679.539806902467;30749.538756918217;20361.694574581095;23141.305760827203;24542;24577.385565360295;25136.62295065612 17:36:13 INFO - PROCESS | 1641 | |2;jquery.html;389.5111634898202;420.82115101082053;422.4149552869279;431.4822213343986;457.75947183133707;20577.588448231047;21274.57450850982;21838;22081.33755987319;22338.10647574095;18498.075096245168;22578.88710556449;23262.651060234068;23302.41743956402;23574.93912773925;6743;6801.829954251159;7076.610786406745;7105.7157713691395;7112.253213412595;6848.315168483136;6885;6949.166100067999;6953.721851125949;6955.234924158339;6820.04519367289;6859.176898772154;6863.862722745542;6894.276101009397;6996.440284777205;6852.554583952052;6884.105066341343;6912.723491060377;6937.410320122771;7543.358814500719;7703.114141873701;7742.225777422213;7754.340881025119;7764.728234511821;7768.145503994556;25838.74161258382;26226.606600901003;26323.47353052928;26358;26542.86728566364;2996.4905965985836;3031.1512776422637;3039.0427015490272;3045.6497502787242;3047;14917.776233356508;16033;16969.3212271509;17115.486535403958;17392.739108913378;2149.365937048559;2151.580441813847;2195.2316689158824;2195.732447770808;2229.7770222977733;137137;156883;158057;160548.19725901296;167212;20613.58772824565;21943.341699749035;22050.669239961415;22109.005094770775;22195;32352.514712279335;32390.866319679168;32949;32961.67038329634;32982.175445614055;10632;10854.34873907568;11001.119910407147;11024.614138505114;11166.162537809534;7653.19641437641;9184.678536251198;9219.539023048956;9225.446473211628;9278.396904201205;9888.752781180385;9923.553440095213;9983;9996.800063998679;10125.240606954361;4237.915241695149;4319.308910574293;4333.198358303661;4335.479742430928;4336.371226172188;881.4094556647062;957.5068839547639;1007.345225603349;1041.770810421706;1070.6520380876245;20836;20976.05607747655;21043.47391315199;21075;21215.78784212169;960.9567569459251;1043.2644985285356;1065.4672663668166;1082.3235477826358;1085.1969542538466;8339.374546909006;10603.84094238587;11063.059639930696;11074.501647425725;11083.334999900031;634171;641031;641690;778248.1087594526;798708;168191;168714;170839;173878;175271;1284.556827894375;1298.1756584568677;1348.5347555093476;1350.3653282956996;1351.115019662123;19594.90202549035;20036;20365.389038328874;20379.286724964553;20450.795492045483;53880.730596346766;65820;65920.6703966477;66823;66898;76044;93244.53377733068;93489.06510934804;95661;96130.15479380747;11618.6514404568;11810.350430726392;11819.881801181878;11863.288202707865;12223.816642750187;12855.035872309802;13364.465421383036;13604.251766152956;13628.318584070956;13752;9470;10020.398776073458;10096.79806403853;11224.775504490028;11724.765504689687;10337.035963166974;10564.788704226026;10882.966118218757;10961.45192740344;11304.604338848101;10657.680269591925;10881.619143329945;10947.945260273966;10999.890001099886;11624.767504650028;11475.540978360772;11518.481668324777;11523.193376463567;11728.296302221894;11858.762824743284;9482.573284202366;10165.33925294883;10896.12830973536;11552;11658.53365865356 17:36:13 INFO - PROCESS | 1641 | |3;mootools.html;1388.3613537772624;1432.720619479202;1603.2144249317837;1635.1252080137122;1657.9668406631872;10820.837687434685;11132.443377831096;11417.657470275904;11429.199956003084;11484.540618375266;9508.76228094298;9627.13355797978;11406.372649504294;11423.314601123917;11447.59933402331;2483.4163971466737;2513.3088400689867;2523.659305993695;2524.066095544651;2529.468811549577;2445.9388515287083;2466.90132394704;2467.2968204061845;2479.690038745157;2479.9752002479927;2469.604863221885;2469.6295555666616;2479.7396273391305;2520.1305549585454;2521.35705395124;2431.8540887546715;2459.1761759810483;2474.2329877737957;2480.6403071554614;2498.912538061177;2447.339218411019;2451.2156110044793;2471.0856982916257;2476.0962248779247;2476.331390524548;11118.61084862026;11292.830607540893;11303.434828258636;11333.979941805277;11359.545618175263;5971.910421343683;6086.695665216722;6142.262928448615;6146.66193359365;6147.385261473862;3616.5298511193378;3683.336999340118;3734.0104872208885;3739.420389839579;3811.4092315691114;1222.370479203211;1232.7411243638846;1263.829383033297;1283.140296001681;1289.4133169407992;13973.510927117604;14245.857541424557;15174.24128793556;15606.297716602667;15771.053736775715;4074.246264441088;4773.7374444405195;4800.447948485899;4915.11527924977;4920.680155789933;11659.53361865516;11689.707757306136;11707.941460292644;11726.530938762355;11734.70663233409;5916.852078697981;5917.940820591739;5977.163197152402;5989.730462129215;6004;5351.224072509464;5409.83770486886;5451.4276001019325;5470.070088085035;5507.752151153208;3475.6176820549717;3556.626554211822;3575.5530558680166;3586.408242639935;3607.9458808117897;1019.3527110284874;1076.8316376731334;1080.502968634437;1084.6583326252205;1093.6117678224275;946.8153710026617;954.7231302922074;977.5112443778111;979.9608015679293;1009.0766948242423;5724.885502290014;6591.769288074895;6676.898311778502;6683;6757.222919364336;983.3902980152351;1011.1001208924064;1020.1736593359402;1032.2980373346197;1059.560282482761;3712.1833196696684;4223.97888010558;4427.468703755506;4435;4574.7941342639;13562.59312220635;14107.506237281646;14138.929305353613;14147;14187.64530886736;13738.381772820247;13891.436588715467;14009.159450433006;14088.43646254159;14115.153090814583;8765.868511972325;9008.099190080939;10431.321964072311;10450.477476126163;10477.580896764044;6172.321044684992;6930.653467326714;6957.991091291633;6986.580805151707;7081.221065682667;11817.704557386134;12048.638540843789;12054.337011464102;12111.576094836639;12116.091293152873;12014.33921134346;12176.33030183313;12395.318257495925;12574.748505030031;12600.747985040065;8578;8599.613017414105;8659.653613855628;8663.740087797376;8696.826063478822;10340.741481463023;10544.52549635253;10554.841677374692;10556.630517931835;10557.683269501927;8802.33982451306;9044.592993315186;9062.365634405529;9103.772405689911;9120.087991200828;8756.562171891508;8769.561521924006;8785.51679657625;8804.86792698123;8833.249173820297;8135.674573017013;8164.142765009588;8264.173582641688;8278.958605206935;8288.336933045463;5802.796902108575;6059.666718330534;6066.211392519121;6108.908366374597;6205.844853878689;5990.460858522754;6150.907736383869;6172.969135154475;6211.09939058843;6250.062490626442 17:36:13 INFO - PROCESS | 1641 | |4;prototype.html;376.787115279867;385.18725489217746;386.57669851512594;398.1737893870218;400.4613794446469;5272.630915835893;5303.6398802532885;5389.140746442476;5434.347878254605;5442.741262376109;4919.262110683399;5250.529851641547;5335.918022712628;5409.675419474835;5410.647338165459;3993.7204395692243;4029.6171863672903;4463.754493502856;4656.5343465653505;4675.322078298645;4377.789216445691;4504.504504504502;4618.468876079261;4790.425148982127;4817.686850354733;3946.9605303947023;4103.302438585432;4674.9247673035225;4753.409391881433;4792.418501894384;3903.863364782219;3960.4851369321946;4723.7638118093955;4724.905501889977;4775.808967641307;3686.8525258989607;4035.3341698619693;4420.419460237218;4471.306947423154;4543.295789152687;4941.975290123562;5310.362756469249;5331.333583302087;5347.582890534011;5398.4871437213405;4869;5119.897602047976;5202.358349155606;5359.81240656579;5579.776808927638;5166.9955784267495;5853.47318741311;5855.590108692393;5857.882842343172;5915.881682366372;3955.9802200989097;4282.379055037002;4761.047790441926;4849.369581954358;5015.7178483789785;5117.948820511821;5761.567882408836;6215.502759779252;6225.844353891143;6235.906461403082;5206.55744261742;5220.399654039834;5259.816541278182;5263.842084737464;5305.973470132624;4770.902692380702;5031.723255220998;5211.993382547671;5269.394019687708;5326.493983071602;4598.517155698603;5448.182772584065;5484.287042684506;5517.889642207214;5538.612297139242;5680.857978550569;5823.417658234227;5847.771967886792;5874.236349274653;6024.548158888101;1519.3770554072778;1696.2621259752048;1736.8350006749338;1793.506785633961;1823.717323814811;1428.314409083625;1436.6264771159574;1477.6822983058694;1480.052766229614;1480.2820631993475;5122.692638441706;5487.012337779238;5490.450954904478;5509.311336082989;5514.235444657671;5301.091607021469;6054.7909607906995;6059.7273122709585;6105.8473538161015;6129.785457509055;5049.065922804293;5081.923771143436;5399.352077750616;5467.617266791366;5531.225628412026;2743.8490883001627;2947.985260073729;3070.3398769264268;3127.2650927032128;3131.04309806371;5231.555317797954;5812.076848127497;6267.341929097471;6276.435120839146;6309.968450157719;5462.590305727007;5687.3459552152035;5967.433093856077;6049.727762250709;6250.6874656267;5657.943420565742;6047.971844786309;6291.874162516724;6363.268224154189;6398.520110991694;5517.531009864207;5571.888562228652;5926.31100136459;6064.0898117092975;6116.5412594056515;5219.791208351623;5637.295338082739;5674.687892165971;5938.851528711817;5941.940580594139;6316.589421687576;6318.273398559225;6328.734253149297;6360.141380913703;6367.777127800505;3976.5228172619472;3981.880543583697;4460.130274596422;4630.027694184124;4693.718376897397;5371.086915224343;5501.422350653123;5638;5698.800541981011;6044.4613262429;4838.588719958702;5737.647564498536;5776.00727748926;5836.912446313224;5918.852028699317;4687.414073240845;4875.634327425386;5185.481451854785;5809.390014048633;5847.006008978399;5043.319151914445;5311.734413279398;5746.626469279484;5810.82567522975;5820.563457740771;5161.793528258978;5536.806211782568;5558.666480011212;5865.384134665798;5914.3198532167635;4482.977585112054;5095;5456.454354564512;5710.971445142748;5735.483806457439 17:36:13 INFO - PROCESS | 1641 | |5;yui.html;758.0637912178455;776.3245976000876;807.903051633804;895.274827389814;904.1997831918754;14010.439582416704;16991.91504042481;17129.57176070599;17195.226214820334;17205.741913871287;15787.052776833369;15835.12906790126;15976.360945562163;16006.679866402665;16017.759733604005;925.1165137293901;943.5659596585579;973.0658567774944;1038.70396936873;1045.900639439252;1033.3696445168441;1035.244271681673;1038.6053299746086;1039.5633833789827;1040.1470793948965;1076.4994277660885;1078.4607696151925;1079.1474734959372;1080.8486811846349;1083.9187060970419;1053.062774131024;1073.0557109743454;1073.8711354637455;1074.6077681646182;1075.2365820267619;796.7052190689482;800.803803068252;801.4790386248926;801.6833350826449;802.9116797152309;16416.343346266134;16901.985880847187;17049.91475042629;17374;17617.471475855746;15622.531324060183;15784.68430631381;15801.288941997638;15871.841281587152;16008.759868601981;11807.645770626896;12050.638480845588;12110.939445302805;12222.205556638879;12378.504859805596;3960.356611296695;3980.960190398088;4000.7999600019884;4045.9885028742815;4071.0636553592544;106414;110276;113081;113822;115947;11763.176577639655;11839.230450020721;11876.524939002516;12112.333821639895;12229.388530573437;26065.498887829468;26736.866315668296;26815;26828.731712682624;26832.463350732876;8121.715739949192;8157.918420815835;8170.346372290322;8188.83622327562;8299.045609754834;4341.674374421931;4396.966712822484;4412.426384569985;4526.977365113154;4533.342665313511;3695.094701798023;3756.3238617048646;3793.79134147619;3832.616738326201;3862.613738626115;659.261626977788;661.8312330355825;668.8762578922915;680.9795706128725;681.1077488490093;588.3792598808213;675.770238119032;696.2271878215178;727.9408460689684;748.3489364253135;9142.497162656118;9547.236221102296;10544.420056896792;10949.288296260876;10953.123750099972;722.5664601239214;742.6917829100863;749.6701451361383;753.6420200404764;756.3911051603402;4886.486918873467;5235.6747223130715;5269.78920843162;5340.8664783379945;5383.865403364869;105629.47185264179;106083;107863.46068269608;108201;110109;51610.22584661158;56997;63904;68434.31565684378;74461.6276918612;70643;73635.63182184161;73773.89339159917;78690;79197.20802792013;11000.284981476178;11288.879558960756;11291.491882865126;11359.659210223706;11517.712057198638;48117.518824811305;48570.75714621404;48790.51209488002;49392.753036234586;50722.746386267834;49894;60632.09051864137;63194.36805632069;64102;64727.35272647402;13442.327883605585;13448.731025379633;13515.459381625018;13516.5269215577;13614.93192534031;14638.268086595415;14832.258387080386;15367.462138825087;15420.69158616799;15544.455944041903;13855.087805146104;14047;14379.640508987359;14557.199354035629;15278.083315001135;11981.700457488632;13889.652758681113;14165.787513187517;14391.928040359731;14421.1347319157;11953.581624643095;12390.876091239334;12408.441620126936;12461.813072803734;12813.615591532269;10159.84760228612;10462.267641265038;10580.735481613021;10776.730581735519;10817.188710846875;10315;11461;11643.359615221245;11692.824607630722;11730.882691173321 17:36:13 INFO - PROCESS | 1641 | __end_tp_report 17:36:13 INFO - PROCESS | 1641 | __start_cc_report 17:36:13 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,396 17:36:13 INFO - PROCESS | 1641 | __end_cc_report 17:36:13 INFO - PROCESS | 1641 | __startTimestamp1495758973189__endTimestamp 17:36:13 INFO - PROCESS | 1641 | 17:36:13 INFO - PROCESS | 1641 | ------- Summary: start ------- 17:36:13 INFO - PROCESS | 1641 | Number of tests: 6 17:36:13 INFO - PROCESS | 1641 | 17:36:13 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:6033.77 Median:6270.18 stddev:683.37 (10.9%) stddev-sans-first:644.30 17:36:13 INFO - PROCESS | 1641 | Values: 3055.9 3398.3 3475.8 3622.0 3652.0 5165.0 6002.3 6074.7 6170.6 6242.8 6026.8 6169.4 6179.2 6234.0 6241.8 4527.4 5035.6 5547.1 5570.9 5622.7 5591.6 5597.1 5597.4 5600.7 5633.1 5327.1 5358.2 5423.8 5579.5 5580.6 5529.6 5544.5 5566.7 5576.9 5608.4 5444.1 5529.4 5547.7 5549.7 5599.5 5945.3 6189.9 6219.6 6256.4 6280.9 6298.8 6300.9 6304.0 6330.0 6333.0 6473.9 6491.4 6499.6 6505.5 6517.5 5661.3 6073.9 6185.4 6210.6 6257.9 6441.9 6477.9 6685.6 6687.5 6714.3 6356.4 6366.7 6416.2 6426.6 6431.4 6512.3 6581.6 6601.3 6607.3 6627.0 6263.0 6297.1 6493.3 6506.9 6524.7 6288.5 6346.3 6360.6 6380.6 6380.9 5194.4 5263.7 5301.3 5307.3 5324.8 4698.9 4711.2 4729.1 4741.5 4745.7 5344.9 6062.8 6118.1 6306.1 6314.3 6429.8 6458.6 6511.1 6536.3 6540.5 6261.5 6264.2 6265.1 6304.3 6305.0 5632.0 5813.3 5814.4 5817.9 5822.2 6613.2 6726.9 6750.4 6776.2 6788.9 5553.5 6471.1 6604.5 6607.1 6614.6 6826.7 6837.9 6852.4 6862.3 6882.8 5372.8 6262.8 6317.7 6396.4 6417.7 5572.0 6608.0 6616.3 6626.7 6641.8 6349.5 6360.1 6393.6 6443.2 6616.4 6239.9 6266.5 6321.1 6322.9 6389.9 6228.8 6270.2 6318.4 6318.5 6339.2 6211.3 6366.9 6379.4 6405.1 6417.0 6149.8 6214.3 6219.0 6286.4 6292.5 17:36:13 INFO - PROCESS | 1641 | 17:36:13 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:56739.14 Median:17547.20 stddev:121325.14 (691.4%) stddev-sans-first:121592.71 17:36:13 INFO - PROCESS | 1641 | Values: 765.7 818.3 840.5 978.4 981.4 39481.6 40527.0 41676.0 41726.6 41814.4 31889.8 39536.4 39673.0 39691.4 40030.8 8318.9 8479.4 11249.6 11458.8 11671.0 11401.8 11473.7 11475.6 11483.8 11510.4 11417.6 11459.1 11468.4 11472.2 11548.3 11382.3 11422.3 11424.5 11429.2 11446.0 9647.8 10958.7 11137.6 11153.7 11202.6 35070.8 35246.3 35455.1 35532.5 35557.8 3872.7 3938.9 3941.3 3946.1 3953.0 26050.3 26540.9 26687.3 26690.5 27280.6 3204.6 3220.9 3264.4 3312.8 3317.9 301565.0 321440.0 322313.0 350470.0 369435.2 44529.8 44541.8 44687.0 44704.5 44704.6 42710.0 47268.0 49026.0 49061.3 49123.3 14058.9 14205.2 14210.3 14215.7 14223.5 12075.3 12142.6 12289.8 12314.8 12330.7 12113.8 12138.6 12159.6 12201.9 12218.4 5361.7 5454.7 5887.4 5889.6 5919.5 876.2 900.7 905.9 907.0 916.9 26869.7 27718.7 27907.2 28161.0 28256.9 978.9 995.5 1031.5 1032.3 1036.5 12849.1 14098.5 14444.9 14690.6 14730.9 420052.0 530247.0 539513.3 667770.0 670630.0 47413.0 47820.8 47831.0 47863.0 47894.5 1470.9 1484.5 1487.1 1490.1 1503.4 26241.0 27594.6 28044.3 28240.2 28297.3 41002.0 41200.8 41232.6 41255.0 41293.6 421689.0 422364.0 423726.0 424707.0 424839.9 29066.0 30117.0 30406.8 31568.4 32596.7 39524.0 39835.2 40540.0 40775.6 40863.8 28804.7 28812.0 29078.3 29462.0 29583.4 28773.0 28849.4 28858.6 29513.4 29647.7 33459.0 33984.8 34249.5 34839.0 35355.8 29592.0 29745.7 29848.0 30679.5 30749.5 20361.7 23141.3 24542.0 24577.4 25136.6 17:36:13 INFO - PROCESS | 1641 | 17:36:13 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:42565.53 Median:22066.00 stddev:118255.96 (535.9%) stddev-sans-first:118545.30 17:36:13 INFO - PROCESS | 1641 | Values: 389.5 420.8 422.4 431.5 457.8 20577.6 21274.6 21838.0 22081.3 22338.1 18498.1 22578.9 23262.7 23302.4 23574.9 6743.0 6801.8 7076.6 7105.7 7112.3 6848.3 6885.0 6949.2 6953.7 6955.2 6820.0 6859.2 6863.9 6894.3 6996.4 6852.6 6884.1 6912.7 6937.4 7543.4 7703.1 7742.2 7754.3 7764.7 7768.1 25838.7 26226.6 26323.5 26358.0 26542.9 2996.5 3031.2 3039.0 3045.6 3047.0 14917.8 16033.0 16969.3 17115.5 17392.7 2149.4 2151.6 2195.2 2195.7 2229.8 137137.0 156883.0 158057.0 160548.2 167212.0 20613.6 21943.3 22050.7 22109.0 22195.0 32352.5 32390.9 32949.0 32961.7 32982.2 10632.0 10854.3 11001.1 11024.6 11166.2 7653.2 9184.7 9219.5 9225.4 9278.4 9888.8 9923.6 9983.0 9996.8 10125.2 4237.9 4319.3 4333.2 4335.5 4336.4 881.4 957.5 1007.3 1041.8 1070.7 20836.0 20976.1 21043.5 21075.0 21215.8 961.0 1043.3 1065.5 1082.3 1085.2 8339.4 10603.8 11063.1 11074.5 11083.3 634171.0 641031.0 641690.0 778248.1 798708.0 168191.0 168714.0 170839.0 173878.0 175271.0 1284.6 1298.2 1348.5 1350.4 1351.1 19594.9 20036.0 20365.4 20379.3 20450.8 53880.7 65820.0 65920.7 66823.0 66898.0 76044.0 93244.5 93489.1 95661.0 96130.2 11618.7 11810.4 11819.9 11863.3 12223.8 12855.0 13364.5 13604.3 13628.3 13752.0 9470.0 10020.4 10096.8 11224.8 11724.8 10337.0 10564.8 10883.0 10961.5 11304.6 10657.7 10881.6 10947.9 10999.9 11624.8 11475.5 11518.5 11523.2 11728.3 11858.8 9482.6 10165.3 10896.1 11552.0 11658.5 17:36:13 INFO - PROCESS | 1641 | 17:36:13 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:6800.49 Median:2516.72 stddev:4208.31 (167.2%) stddev-sans-first:4200.46 17:36:13 INFO - PROCESS | 1641 | Values: 1388.4 1432.7 1603.2 1635.1 1658.0 10820.8 11132.4 11417.7 11429.2 11484.5 9508.8 9627.1 11406.4 11423.3 11447.6 2483.4 2513.3 2523.7 2524.1 2529.5 2445.9 2466.9 2467.3 2479.7 2480.0 2469.6 2469.6 2479.7 2520.1 2521.4 2431.9 2459.2 2474.2 2480.6 2498.9 2447.3 2451.2 2471.1 2476.1 2476.3 11118.6 11292.8 11303.4 11334.0 11359.5 5971.9 6086.7 6142.3 6146.7 6147.4 3616.5 3683.3 3734.0 3739.4 3811.4 1222.4 1232.7 1263.8 1283.1 1289.4 13973.5 14245.9 15174.2 15606.3 15771.1 4074.2 4773.7 4800.4 4915.1 4920.7 11659.5 11689.7 11707.9 11726.5 11734.7 5916.9 5917.9 5977.2 5989.7 6004.0 5351.2 5409.8 5451.4 5470.1 5507.8 3475.6 3556.6 3575.6 3586.4 3607.9 1019.4 1076.8 1080.5 1084.7 1093.6 946.8 954.7 977.5 980.0 1009.1 5724.9 6591.8 6676.9 6683.0 6757.2 983.4 1011.1 1020.2 1032.3 1059.6 3712.2 4224.0 4427.5 4435.0 4574.8 13562.6 14107.5 14138.9 14147.0 14187.6 13738.4 13891.4 14009.2 14088.4 14115.2 8765.9 9008.1 10431.3 10450.5 10477.6 6172.3 6930.7 6958.0 6986.6 7081.2 11817.7 12048.6 12054.3 12111.6 12116.1 12014.3 12176.3 12395.3 12574.7 12600.7 8578.0 8599.6 8659.7 8663.7 8696.8 10340.7 10544.5 10554.8 10556.6 10557.7 8802.3 9044.6 9062.4 9103.8 9120.1 8756.6 8769.6 8785.5 8804.9 8833.2 8135.7 8164.1 8264.2 8279.0 8288.3 5802.8 6059.7 6066.2 6108.9 6205.8 5990.5 6150.9 6173.0 6211.1 6250.1 17:36:13 INFO - PROCESS | 1641 | 17:36:13 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4928.50 Median:5319.11 stddev:1347.27 (25.3%) stddev-sans-first:1307.01 17:36:13 INFO - PROCESS | 1641 | Values: 376.8 385.2 386.6 398.2 400.5 5272.6 5303.6 5389.1 5434.3 5442.7 4919.3 5250.5 5335.9 5409.7 5410.6 3993.7 4029.6 4463.8 4656.5 4675.3 4377.8 4504.5 4618.5 4790.4 4817.7 3947.0 4103.3 4674.9 4753.4 4792.4 3903.9 3960.5 4723.8 4724.9 4775.8 3686.9 4035.3 4420.4 4471.3 4543.3 4942.0 5310.4 5331.3 5347.6 5398.5 4869.0 5119.9 5202.4 5359.8 5579.8 5167.0 5853.5 5855.6 5857.9 5915.9 3956.0 4282.4 4761.0 4849.4 5015.7 5117.9 5761.6 6215.5 6225.8 6235.9 5206.6 5220.4 5259.8 5263.8 5306.0 4770.9 5031.7 5212.0 5269.4 5326.5 4598.5 5448.2 5484.3 5517.9 5538.6 5680.9 5823.4 5847.8 5874.2 6024.5 1519.4 1696.3 1736.8 1793.5 1823.7 1428.3 1436.6 1477.7 1480.1 1480.3 5122.7 5487.0 5490.5 5509.3 5514.2 5301.1 6054.8 6059.7 6105.8 6129.8 5049.1 5081.9 5399.4 5467.6 5531.2 2743.8 2948.0 3070.3 3127.3 3131.0 5231.6 5812.1 6267.3 6276.4 6310.0 5462.6 5687.3 5967.4 6049.7 6250.7 5657.9 6048.0 6291.9 6363.3 6398.5 5517.5 5571.9 5926.3 6064.1 6116.5 5219.8 5637.3 5674.7 5938.9 5941.9 6316.6 6318.3 6328.7 6360.1 6367.8 3976.5 3981.9 4460.1 4630.0 4693.7 5371.1 5501.4 5638.0 5698.8 6044.5 4838.6 5737.6 5776.0 5836.9 5918.9 4687.4 4875.6 5185.5 5809.4 5847.0 5043.3 5311.7 5746.6 5810.8 5820.6 5161.8 5536.8 5558.7 5865.4 5914.3 4483.0 5095.0 5456.5 5711.0 5735.5 17:36:13 INFO - PROCESS | 1641 | 17:36:13 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:20269.71 Median:16217.05 stddev:28359.74 (174.9%) stddev-sans-first:28401.46 17:36:13 INFO - PROCESS | 1641 | Values: 758.1 776.3 807.9 895.3 904.2 14010.4 16991.9 17129.6 17195.2 17205.7 15787.1 15835.1 15976.4 16006.7 16017.8 925.1 943.6 973.1 1038.7 1045.9 1033.4 1035.2 1038.6 1039.6 1040.1 1076.5 1078.5 1079.1 1080.8 1083.9 1053.1 1073.1 1073.9 1074.6 1075.2 796.7 800.8 801.5 801.7 802.9 16416.3 16902.0 17049.9 17374.0 17617.5 15622.5 15784.7 15801.3 15871.8 16008.8 11807.6 12050.6 12110.9 12222.2 12378.5 3960.4 3981.0 4000.8 4046.0 4071.1 106414.0 110276.0 113081.0 113822.0 115947.0 11763.2 11839.2 11876.5 12112.3 12229.4 26065.5 26736.9 26815.0 26828.7 26832.5 8121.7 8157.9 8170.3 8188.8 8299.0 4341.7 4397.0 4412.4 4527.0 4533.3 3695.1 3756.3 3793.8 3832.6 3862.6 659.3 661.8 668.9 681.0 681.1 588.4 675.8 696.2 727.9 748.3 9142.5 9547.2 10544.4 10949.3 10953.1 722.6 742.7 749.7 753.6 756.4 4886.5 5235.7 5269.8 5340.9 5383.9 105629.5 106083.0 107863.5 108201.0 110109.0 51610.2 56997.0 63904.0 68434.3 74461.6 70643.0 73635.6 73773.9 78690.0 79197.2 11000.3 11288.9 11291.5 11359.7 11517.7 48117.5 48570.8 48790.5 49392.8 50722.7 49894.0 60632.1 63194.4 64102.0 64727.4 13442.3 13448.7 13515.5 13516.5 13614.9 14638.3 14832.3 15367.5 15420.7 15544.5 13855.1 14047.0 14379.6 14557.2 15278.1 11981.7 13889.7 14165.8 14391.9 14421.1 11953.6 12390.9 12408.4 12461.8 12813.6 10159.8 10462.3 10580.7 10776.7 10817.2 10315.0 11461.0 11643.4 11692.8 11730.9 17:36:13 INFO - PROCESS | 1641 | -------- Summary: end -------- 17:36:13 INFO - PROCESS | 1641 | 17:36:13 INFO - TEST-INFO | 1641: exit 0 17:36:13 INFO - TEST-OK | dromaeo_css | took 1116437ms 17:36:13 INFO - TEST-START | kraken 17:36:13 INFO - operating with platform_type : mac_ 17:36:13 INFO - Initialising browser for kraken test... 17:36:13 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmptB7_ne/profile http://localhost:49218/getInfo.html) 17:36:16 INFO - PROCESS | 1653 | 2017-05-25 17:36:16.247 plugin-container[1655:10147] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x453f, name = 'com.apple.tsm.portname' 17:36:16 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:36:16 INFO - PROCESS | 1653 | 2017-05-25 17:36:16.248 plugin-container[1655:10147] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 17:36:16 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:36:16 INFO - PROCESS | 1653 | 2017-05-25 17:36:16.248 plugin-container[1655:10147] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:36:16 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 17:36:16 INFO - PROCESS | 1653 | colorDepth:24 17:36:16 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 17:36:16 INFO - PROCESS | 1653 | __metrics 17:36:16 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 17:36:17 INFO - TEST-INFO | 1653: exit 0 17:36:17 INFO - Browser initialized. 17:36:17 INFO - Running cycle 1/1 for kraken test... 17:36:17 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f_/784x9wv55zl7jxv09s004psc00000w/T/tmptB7_ne/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:36:19 INFO - PROCESS | 1656 | 2017-05-25 17:36:19.410 plugin-container[1657:10283] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x424b, name = 'com.apple.tsm.portname' 17:36:19 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:36:19 INFO - PROCESS | 1656 | 2017-05-25 17:36:19.411 plugin-container[1657:10283] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b03, name = 'com.apple.CFPasteboardClient' 17:36:19 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:36:19 INFO - PROCESS | 1656 | 2017-05-25 17:36:19.411 plugin-container[1657:10283] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:36:29 INFO - PROCESS | 1656 | RSS: Main: 174399488 17:36:29 INFO - PROCESS | 1656 | 17:36:32 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49218/tests/kraken/driver.html?testName=audio-beat-detection) 17:36:33 INFO - PROCESS | 1656 | RSS: Main: 187899904 17:36:33 INFO - PROCESS | 1656 | 17:36:35 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49218/tests/kraken/driver.html?testName=audio-dft) 17:36:36 INFO - PROCESS | 1656 | RSS: Main: 195915776 17:36:36 INFO - PROCESS | 1656 | 17:36:39 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49218/tests/kraken/driver.html?testName=audio-fft) 17:36:40 INFO - PROCESS | 1656 | RSS: Main: 199577600 17:36:40 INFO - PROCESS | 1656 | 17:36:42 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49218/tests/kraken/driver.html?testName=audio-oscillator) 17:36:42 INFO - PROCESS | 1656 | RSS: Main: 200937472 17:36:42 INFO - PROCESS | 1656 | 17:36:45 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49218/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:36:45 INFO - PROCESS | 1656 | RSS: Main: 201297920 17:36:45 INFO - PROCESS | 1656 | 17:36:48 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49218/tests/kraken/driver.html?testName=imaging-darkroom) 17:36:49 INFO - PROCESS | 1656 | RSS: Main: 205529088 17:36:49 INFO - PROCESS | 1656 | 17:36:52 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49218/tests/kraken/driver.html?testName=imaging-desaturate) 17:36:53 INFO - PROCESS | 1656 | RSS: Main: 204390400 17:36:53 INFO - PROCESS | 1656 | 17:36:56 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49218/tests/kraken/driver.html?testName=json-parse-financial) 17:36:57 INFO - PROCESS | 1656 | RSS: Main: 204529664 17:36:57 INFO - PROCESS | 1656 | 17:36:58 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49218/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:36:59 INFO - PROCESS | 1656 | RSS: Main: 204083200 17:36:59 INFO - PROCESS | 1656 | 17:37:01 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:37:01 INFO - PROCESS | 1656 | RSS: Main: 204132352 17:37:01 INFO - PROCESS | 1656 | 17:37:03 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:37:04 INFO - PROCESS | 1656 | RSS: Main: 204439552 17:37:04 INFO - PROCESS | 1656 | 17:37:07 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:37:08 INFO - PROCESS | 1656 | RSS: Main: 204832768 17:37:08 INFO - PROCESS | 1656 | 17:37:11 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:37:11 INFO - PROCESS | 1656 | RSS: Main: 204521472 17:37:11 INFO - PROCESS | 1656 | 17:37:13 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49218/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49218/tests/kraken/driver.html?testName=ai-astar) 17:37:13 INFO - PROCESS | 1656 | RSS: Main: 206675968 17:37:13 INFO - PROCESS | 1656 | 17:37:13 INFO - PROCESS | 1656 | __start_tp_report 17:37:13 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 17:37:13 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 17:37:13 INFO - PROCESS | 1656 | |i|pagename|runs| 17:37:13 INFO - PROCESS | 1656 | |0;ai-astar;110;176;178;118;120;120;119;118;118;120 17:37:13 INFO - PROCESS | 1656 | |1;audio-beat-detection;141;136;137;140;110;114;111;110;127;111 17:37:13 INFO - PROCESS | 1656 | |2;audio-dft;196;202;194;198;195;194;208;240;271;210 17:37:13 INFO - PROCESS | 1656 | |3;audio-fft;80;66;69;112;52;70;82;86;62;69 17:37:13 INFO - PROCESS | 1656 | |4;audio-oscillator;84;72;113;61;93;100;109;100;74;75 17:37:13 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;91;91;91;92;92;89;137;91;92;136 17:37:13 INFO - PROCESS | 1656 | |6;imaging-darkroom;98;99;97;98;98;98;98;97;100;152 17:37:13 INFO - PROCESS | 1656 | |7;imaging-desaturate;82;145;146;111;90;149;94;145;147;130 17:37:13 INFO - PROCESS | 1656 | |8;json-parse-financial;68;64;66;66;70;70;68;69;69;69 17:37:13 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;60;59;73;72;68;65;60;58;61;60 17:37:13 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;85;84;91;88;81;84;85;87;82;88 17:37:13 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;143;136;143;137;146;155;149;150;237;149 17:37:13 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;164;168;164;165;169;165;165;168;224;175 17:37:13 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;67;65;65;65;63;76;74;63;59;78 17:37:13 INFO - PROCESS | 1656 | __end_tp_report 17:37:13 INFO - PROCESS | 1656 | __start_cc_report 17:37:13 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,999 17:37:13 INFO - PROCESS | 1656 | __end_cc_report 17:37:13 INFO - PROCESS | 1656 | __startTimestamp1495759033721__endTimestamp 17:37:13 INFO - PROCESS | 1656 | 17:37:13 INFO - PROCESS | 1656 | ------- Summary: start ------- 17:37:13 INFO - PROCESS | 1656 | Number of tests: 14 17:37:13 INFO - PROCESS | 1656 | 17:37:13 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:129.70 Median:120.00 stddev:25.10 (20.9%) stddev-sans-first:25.60 17:37:13 INFO - PROCESS | 1656 | Values: 110.0 176.0 178.0 118.0 120.0 120.0 119.0 118.0 118.0 120.0 17:37:13 INFO - PROCESS | 1656 | 17:37:13 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:123.70 Median:131.50 stddev:13.73 (10.4%) stddev-sans-first:13.06 17:37:13 INFO - PROCESS | 1656 | Values: 141.0 136.0 137.0 140.0 110.0 114.0 111.0 110.0 127.0 111.0 17:37:13 INFO - PROCESS | 1656 | 17:37:13 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:210.80 Median:205.00 stddev:25.30 (12.3%) stddev-sans-first:26.26 17:37:13 INFO - PROCESS | 1656 | Values: 196.0 202.0 194.0 198.0 195.0 194.0 208.0 240.0 271.0 210.0 17:37:13 INFO - PROCESS | 1656 | 17:37:13 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:74.80 Median:69.50 stddev:16.46 (23.7%) stddev-sans-first:17.35 17:37:13 INFO - PROCESS | 1656 | Values: 80.0 66.0 69.0 112.0 52.0 70.0 82.0 86.0 62.0 69.0 17:37:13 INFO - PROCESS | 1656 | 17:37:13 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:88.10 Median:73.00 stddev:17.46 (23.9%) stddev-sans-first:18.46 17:37:13 INFO - PROCESS | 1656 | Values: 84.0 72.0 113.0 61.0 93.0 100.0 109.0 100.0 74.0 75.0 17:37:13 INFO - PROCESS | 1656 | 17:37:13 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:100.20 Median:91.00 stddev:19.15 (21.0%) stddev-sans-first:20.02 17:37:13 INFO - PROCESS | 1656 | Values: 91.0 91.0 91.0 92.0 92.0 89.0 137.0 91.0 92.0 136.0 17:37:13 INFO - PROCESS | 1656 | 17:37:13 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:103.50 Median:98.00 stddev:17.06 (17.4%) stddev-sans-first:17.98 17:37:13 INFO - PROCESS | 1656 | Values: 98.0 99.0 97.0 98.0 98.0 98.0 98.0 97.0 100.0 152.0 17:37:13 INFO - PROCESS | 1656 | 17:37:13 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:123.90 Median:148.00 stddev:26.97 (18.2%) stddev-sans-first:23.96 17:37:13 INFO - PROCESS | 1656 | Values: 82.0 145.0 146.0 111.0 90.0 149.0 94.0 145.0 147.0 130.0 17:37:13 INFO - PROCESS | 1656 | 17:37:13 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:67.90 Median:69.00 stddev:1.97 (2.9%) stddev-sans-first:2.09 17:37:13 INFO - PROCESS | 1656 | Values: 68.0 64.0 66.0 66.0 70.0 70.0 68.0 69.0 69.0 69.0 17:37:13 INFO - PROCESS | 1656 | 17:37:13 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:63.60 Median:63.00 stddev:5.56 (8.8%) stddev-sans-first:5.74 17:37:13 INFO - PROCESS | 1656 | Values: 60.0 59.0 73.0 72.0 68.0 65.0 60.0 58.0 61.0 60.0 17:37:13 INFO - PROCESS | 1656 | 17:37:13 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:85.50 Median:86.00 stddev:3.03 (3.5%) stddev-sans-first:3.21 17:37:13 INFO - PROCESS | 1656 | Values: 85.0 84.0 91.0 88.0 81.0 84.0 85.0 87.0 82.0 88.0 17:37:13 INFO - PROCESS | 1656 | 17:37:13 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:154.50 Median:149.00 stddev:29.58 (19.8%) stddev-sans-first:31.08 17:37:13 INFO - PROCESS | 1656 | Values: 143.0 136.0 143.0 137.0 146.0 155.0 149.0 150.0 237.0 149.0 17:37:13 INFO - PROCESS | 1656 | 17:37:13 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.70 Median:168.00 stddev:18.33 (10.9%) stddev-sans-first:19.17 17:37:13 INFO - PROCESS | 1656 | Values: 164.0 168.0 164.0 165.0 169.0 165.0 165.0 168.0 224.0 175.0 17:37:13 INFO - PROCESS | 1656 | 17:37:13 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:66.00 stddev:6.29 (9.5%) stddev-sans-first:6.67 17:37:13 INFO - PROCESS | 1656 | Values: 67.0 65.0 65.0 65.0 63.0 76.0 74.0 63.0 59.0 78.0 17:37:13 INFO - PROCESS | 1656 | -------- Summary: end -------- 17:37:13 INFO - PROCESS | 1656 | 17:37:14 INFO - TEST-INFO | 1656: exit 0 17:37:14 INFO - TEST-OK | kraken | took 60798ms 17:37:14 INFO - SUITE-END | took 1177s 17:37:14 INFO - Completed test suite (00:19:37) 17:37:14 INFO - javascript benchmark 17:37:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7929.83319304285, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3055.8777648894047, 3398.2863598644285, 3475.8262086895634, 3622.040159357771, 3652.0, 5165.0, 6002.309734380537, 6074.69626518674, 6170.56806023578, 6242.750289988395, 6026.789062382817, 6169.444749972502, 6179.227596550431, 6233.968830155843, 6241.781537646183, 4527.366168736387, 5035.571976382012, 5547.0570003099365, 5570.916436253459, 5622.746976386052, 5591.6365436246715, 5597.076482380402, 5597.356304025048, 5600.715452678704, 5633.070543360341, 5327.067763141454, 5358.196270559409, 5423.83387571672, 5579.46995035471, 5580.609357344987, 5529.612927095105, 5544.500994910457, 5566.749496272657, 5576.86057848557, 5608.383077861473, 5444.128939369682, 5529.419410961871, 5547.694876781776, 5549.694766787825, 5599.524040456566, 5945.256842894638, 6189.916764566183, 6219.626822390625, 6256.405641464054, 6280.905786413207, 6298.811035668938, 6300.905486417708, 6303.991361382203, 6329.968350158266, 6332.968335158294, 6473.902891456632, 6491.415772580443, 6499.6100233985635, 6505.512086593524, 6517.478601711851, 5661.34894487131, 6073.908891366633, 6185.350538193425, 6210.596311239845, 6257.906131407987, 6441.871162576722, 6477.902831457532, 6685.565438246498, 6687.531872768956, 6714.29499902513, 6356.396142366468, 6366.713497892606, 6416.230052393743, 6426.582272152295, 6431.356864313531, 6512.251091124486, 6581.605103693793, 6601.2738598754095, 6607.273199948002, 6627.03907933347, 6263.0, 6297.055441683784, 6493.25327587324, 6506.934930650727, 6524.7276781028195, 6288.52836037299, 6346.30190679025, 6360.586561873463, 6380.553361264667, 6380.872382552323, 5194.4026436959475, 5263.710495922761, 5301.310829592128, 5307.28351672527, 5324.760385782572, 4698.88252793676, 4711.246200607886, 4729.125111854318, 4741.478437371887, 4745.691530050535, 5344.866378340573, 6062.818115456544, 6118.071005858243, 6306.054091886253, 6314.336994615591, 6429.839254018593, 6458.644774537445, 6511.088447617338, 6536.313687062885, 6540.542162048706, 6261.499080073583, 6264.21697287839, 6265.091561723524, 6304.306526282106, 6305.0, 5632.04255276604, 5813.302403711582, 5814.447627475383, 5817.88364232713, 5822.184894114827, 6613.206415230107, 6726.932730672631, 6750.3924646782025, 6776.15298087739, 6788.93211067883, 5553.4724201200825, 6471.126397936407, 6604.537682362381, 6607.108040414675, 6614.570052946543, 6826.658667066527, 6837.931620683729, 6852.417544508772, 6862.348076932683, 6882.7591034313555, 5372.785088596413, 6262.812115636538, 6317.652529110943, 6396.392342727433, 6417.743290268337, 5571.969185700658, 6607.9669601651685, 6616.338366163345, 6626.668666566556, 6641.767538136142, 6349.460295874902, 6360.077788720702, 6393.6483493408305, 6443.162388889415, 6616.371444712745, 6239.906401403891, 6266.467350275096, 6321.146645202839, 6322.873542529215, 6389.87220255583, 6228.781992630236, 6270.184875966278, 6318.399752023551, 6318.526110541636, 6339.207599050119, 6211.316755156839, 6366.917624004023, 6379.425851673372, 6405.135306733531, 6417.03744438338, 6149.8007888461325, 6214.25428948529, 6219.036049412348, 6286.402791734778, 6292.465140463112], "value": 5990.449497081936, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [765.7205120131154, 818.2921772666643, 840.4873027453252, 978.403174063821, 981.3621146254937, 39481.60518394819, 40527.0, 41675.95810104749, 41726.58273417265, 41814.37278440826, 31889.84055079727, 39536.40695389571, 39673.0, 39691.40462893052, 40030.7998460008, 8318.875216871751, 8479.406441549094, 11249.60626378077, 11458.770824583504, 11671.0, 11401.828972565418, 11473.713157171054, 11475.598354057567, 11483.827742583868, 11510.366929818856, 11417.600383986563, 11459.083273338112, 11468.369239691814, 11472.196946213768, 11548.30710157389, 11382.260153090065, 11422.257553259053, 11424.485898134604, 11429.199956003084, 11446.0, 9647.758806029906, 10958.671239862817, 11137.610183643614, 11153.665390038312, 11202.607908723156, 35070.82464587661, 35246.295074098634, 35455.11362215939, 35532.46701299483, 35557.82221088904, 3872.7482713623526, 3938.921221575581, 3941.3299739044432, 3946.092398748275, 3953.0, 26050.34874128162, 26540.938362465477, 26687.332816679544, 26690.466190676078, 27280.590791138147, 3204.5513628092085, 3220.855061522214, 3264.4287249731437, 3312.817795021273, 3317.9004629861142, 301564.98435015586, 321440.0, 322313.0, 350470.0, 369435.15282423684, 44529.777351113036, 44541.777291113336, 44687.0, 44704.5412502309, 44704.55295447069, 42710.0, 47268.0, 49026.0, 49061.26408103881, 49123.263151052764, 14058.94557908161, 14205.21871297068, 14210.289485525682, 14215.715685686228, 14223.502177423741, 12075.27548347102, 12142.575009874789, 12289.754204915851, 12314.81527777084, 12330.691732706575, 12113.818292725617, 12138.575149869712, 12159.635210943687, 12201.938990304992, 12218.389080545938, 5361.713188834729, 5454.672719636835, 5887.4406931341455, 5889.558283128783, 5919.496842768327, 876.2332908975155, 900.6667533012827, 905.8958219804675, 906.9501177435172, 916.9358144929924, 26869.731302687112, 27718.722812771615, 27907.162785116478, 28161.014364497143, 28256.8587157067, 978.8923218445965, 995.5171741705267, 1031.4636389077787, 1032.3031953431555, 1036.4765793274498, 12849.100562960502, 14098.50655227062, 14444.855551444562, 14690.63273418173, 14730.852691472948, 420052.0, 530247.0, 539513.3024334932, 667770.0, 670630.0, 47413.0, 47820.760896195294, 47831.0, 47863.0, 47894.5210547897, 1470.867621914033, 1484.5175318021686, 1487.137460273039, 1490.1282749591394, 1503.361071544589, 26241.0, 27594.5860812092, 28044.298892527033, 28240.152795416172, 28297.29256768515, 41001.97495062647, 41200.793996029824, 41232.58767412287, 41255.0, 41293.58706412897, 421689.0, 422364.0, 423726.0, 424707.0, 424839.875800619, 29066.0, 30117.0, 30406.783728650607, 31568.36863262676, 32596.674033259365, 39524.0, 39835.20329593334, 40540.0, 40775.59224407718, 40863.77408677744, 28804.711952880203, 28812.0, 29078.27304317409, 29462.0, 29583.408331833674, 28773.0, 28849.42301154007, 28858.567121493616, 29513.409731804815, 29647.703522964493, 33459.0, 33984.830075849466, 34249.486257705656, 34839.0, 35355.82322088373, 29592.0, 29745.70254297516, 29848.0, 30679.539806902467, 30749.538756918217, 20361.694574581095, 23141.305760827203, 24542.0, 24577.385565360295, 25136.62295065612], "value": 18508.838669488996, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.5111634898202, 420.82115101082053, 422.4149552869279, 431.4822213343986, 457.75947183133707, 20577.588448231047, 21274.57450850982, 21838.0, 22081.33755987319, 22338.10647574095, 18498.075096245168, 22578.88710556449, 23262.651060234068, 23302.41743956402, 23574.93912773925, 6743.0, 6801.829954251159, 7076.610786406745, 7105.7157713691395, 7112.253213412595, 6848.315168483136, 6885.0, 6949.166100067999, 6953.721851125949, 6955.234924158339, 6820.04519367289, 6859.176898772154, 6863.862722745542, 6894.276101009397, 6996.440284777205, 6852.554583952052, 6884.105066341343, 6912.723491060377, 6937.410320122771, 7543.358814500719, 7703.114141873701, 7742.225777422213, 7754.340881025119, 7764.728234511821, 7768.145503994556, 25838.74161258382, 26226.606600901003, 26323.47353052928, 26358.0, 26542.86728566364, 2996.4905965985836, 3031.1512776422637, 3039.0427015490272, 3045.6497502787242, 3047.0, 14917.776233356508, 16033.0, 16969.3212271509, 17115.486535403958, 17392.739108913378, 2149.365937048559, 2151.580441813847, 2195.2316689158824, 2195.732447770808, 2229.7770222977733, 137137.0, 156883.0, 158057.0, 160548.19725901296, 167212.0, 20613.58772824565, 21943.341699749035, 22050.669239961415, 22109.005094770775, 22195.0, 32352.514712279335, 32390.866319679168, 32949.0, 32961.67038329634, 32982.175445614055, 10632.0, 10854.34873907568, 11001.119910407147, 11024.614138505114, 11166.162537809534, 7653.19641437641, 9184.678536251198, 9219.539023048956, 9225.446473211628, 9278.396904201205, 9888.752781180385, 9923.553440095213, 9983.0, 9996.800063998679, 10125.240606954361, 4237.915241695149, 4319.308910574293, 4333.198358303661, 4335.479742430928, 4336.371226172188, 881.4094556647062, 957.5068839547639, 1007.345225603349, 1041.770810421706, 1070.6520380876245, 20836.0, 20976.05607747655, 21043.47391315199, 21075.0, 21215.78784212169, 960.9567569459251, 1043.2644985285356, 1065.4672663668166, 1082.3235477826358, 1085.1969542538466, 8339.374546909006, 10603.84094238587, 11063.059639930696, 11074.501647425725, 11083.334999900031, 634171.0, 641031.0, 641690.0, 778248.1087594526, 798708.0, 168191.0, 168714.0, 170839.0, 173878.0, 175271.0, 1284.556827894375, 1298.1756584568677, 1348.5347555093476, 1350.3653282956996, 1351.115019662123, 19594.90202549035, 20036.0, 20365.389038328874, 20379.286724964553, 20450.795492045483, 53880.730596346766, 65820.0, 65920.6703966477, 66823.0, 66898.0, 76044.0, 93244.53377733068, 93489.06510934804, 95661.0, 96130.15479380747, 11618.6514404568, 11810.350430726392, 11819.881801181878, 11863.288202707865, 12223.816642750187, 12855.035872309802, 13364.465421383036, 13604.251766152956, 13628.318584070956, 13752.0, 9470.0, 10020.398776073458, 10096.79806403853, 11224.775504490028, 11724.765504689687, 10337.035963166974, 10564.788704226026, 10882.966118218757, 10961.45192740344, 11304.604338848101, 10657.680269591925, 10881.619143329945, 10947.945260273966, 10999.890001099886, 11624.767504650028, 11475.540978360772, 11518.481668324777, 11523.193376463567, 11728.296302221894, 11858.762824743284, 9482.573284202366, 10165.33925294883, 10896.12830973536, 11552.0, 11658.53365865356], "value": 11899.991429448886, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1388.3613537772624, 1432.720619479202, 1603.2144249317837, 1635.1252080137122, 1657.9668406631872, 10820.837687434685, 11132.443377831096, 11417.657470275904, 11429.199956003084, 11484.540618375266, 9508.76228094298, 9627.13355797978, 11406.372649504294, 11423.314601123917, 11447.59933402331, 2483.4163971466737, 2513.3088400689867, 2523.659305993695, 2524.066095544651, 2529.468811549577, 2445.9388515287083, 2466.90132394704, 2467.2968204061845, 2479.690038745157, 2479.9752002479927, 2469.604863221885, 2469.6295555666616, 2479.7396273391305, 2520.1305549585454, 2521.35705395124, 2431.8540887546715, 2459.1761759810483, 2474.2329877737957, 2480.6403071554614, 2498.912538061177, 2447.339218411019, 2451.2156110044793, 2471.0856982916257, 2476.0962248779247, 2476.331390524548, 11118.61084862026, 11292.830607540893, 11303.434828258636, 11333.979941805277, 11359.545618175263, 5971.910421343683, 6086.695665216722, 6142.262928448615, 6146.66193359365, 6147.385261473862, 3616.5298511193378, 3683.336999340118, 3734.0104872208885, 3739.420389839579, 3811.4092315691114, 1222.370479203211, 1232.7411243638846, 1263.829383033297, 1283.140296001681, 1289.4133169407992, 13973.510927117604, 14245.857541424557, 15174.24128793556, 15606.297716602667, 15771.053736775715, 4074.246264441088, 4773.7374444405195, 4800.447948485899, 4915.11527924977, 4920.680155789933, 11659.53361865516, 11689.707757306136, 11707.941460292644, 11726.530938762355, 11734.70663233409, 5916.852078697981, 5917.940820591739, 5977.163197152402, 5989.730462129215, 6004.0, 5351.224072509464, 5409.83770486886, 5451.4276001019325, 5470.070088085035, 5507.752151153208, 3475.6176820549717, 3556.626554211822, 3575.5530558680166, 3586.408242639935, 3607.9458808117897, 1019.3527110284874, 1076.8316376731334, 1080.502968634437, 1084.6583326252205, 1093.6117678224275, 946.8153710026617, 954.7231302922074, 977.5112443778111, 979.9608015679293, 1009.0766948242423, 5724.885502290014, 6591.769288074895, 6676.898311778502, 6683.0, 6757.222919364336, 983.3902980152351, 1011.1001208924064, 1020.1736593359402, 1032.2980373346197, 1059.560282482761, 3712.1833196696684, 4223.97888010558, 4427.468703755506, 4435.0, 4574.7941342639, 13562.59312220635, 14107.506237281646, 14138.929305353613, 14147.0, 14187.64530886736, 13738.381772820247, 13891.436588715467, 14009.159450433006, 14088.43646254159, 14115.153090814583, 8765.868511972325, 9008.099190080939, 10431.321964072311, 10450.477476126163, 10477.580896764044, 6172.321044684992, 6930.653467326714, 6957.991091291633, 6986.580805151707, 7081.221065682667, 11817.704557386134, 12048.638540843789, 12054.337011464102, 12111.576094836639, 12116.091293152873, 12014.33921134346, 12176.33030183313, 12395.318257495925, 12574.748505030031, 12600.747985040065, 8578.0, 8599.613017414105, 8659.653613855628, 8663.740087797376, 8696.826063478822, 10340.741481463023, 10544.52549635253, 10554.841677374692, 10556.630517931835, 10557.683269501927, 8802.33982451306, 9044.592993315186, 9062.365634405529, 9103.772405689911, 9120.087991200828, 8756.562171891508, 8769.561521924006, 8785.51679657625, 8804.86792698123, 8833.249173820297, 8135.674573017013, 8164.142765009588, 8264.173582641688, 8278.958605206935, 8288.336933045463, 5802.796902108575, 6059.666718330534, 6066.211392519121, 6108.908366374597, 6205.844853878689, 5990.460858522754, 6150.907736383869, 6172.969135154475, 6211.09939058843, 6250.062490626442], "value": 5218.966904214666, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.787115279867, 385.18725489217746, 386.57669851512594, 398.1737893870218, 400.4613794446469, 5272.630915835893, 5303.6398802532885, 5389.140746442476, 5434.347878254605, 5442.741262376109, 4919.262110683399, 5250.529851641547, 5335.918022712628, 5409.675419474835, 5410.647338165459, 3993.7204395692243, 4029.6171863672903, 4463.754493502856, 4656.5343465653505, 4675.322078298645, 4377.789216445691, 4504.504504504502, 4618.468876079261, 4790.425148982127, 4817.686850354733, 3946.9605303947023, 4103.302438585432, 4674.9247673035225, 4753.409391881433, 4792.418501894384, 3903.863364782219, 3960.4851369321946, 4723.7638118093955, 4724.905501889977, 4775.808967641307, 3686.8525258989607, 4035.3341698619693, 4420.419460237218, 4471.306947423154, 4543.295789152687, 4941.975290123562, 5310.362756469249, 5331.333583302087, 5347.582890534011, 5398.4871437213405, 4869.0, 5119.897602047976, 5202.358349155606, 5359.81240656579, 5579.776808927638, 5166.9955784267495, 5853.47318741311, 5855.590108692393, 5857.882842343172, 5915.881682366372, 3955.9802200989097, 4282.379055037002, 4761.047790441926, 4849.369581954358, 5015.7178483789785, 5117.948820511821, 5761.567882408836, 6215.502759779252, 6225.844353891143, 6235.906461403082, 5206.55744261742, 5220.399654039834, 5259.816541278182, 5263.842084737464, 5305.973470132624, 4770.902692380702, 5031.723255220998, 5211.993382547671, 5269.394019687708, 5326.493983071602, 4598.517155698603, 5448.182772584065, 5484.287042684506, 5517.889642207214, 5538.612297139242, 5680.857978550569, 5823.417658234227, 5847.771967886792, 5874.236349274653, 6024.548158888101, 1519.3770554072778, 1696.2621259752048, 1736.8350006749338, 1793.506785633961, 1823.717323814811, 1428.314409083625, 1436.6264771159574, 1477.6822983058694, 1480.052766229614, 1480.2820631993475, 5122.692638441706, 5487.012337779238, 5490.450954904478, 5509.311336082989, 5514.235444657671, 5301.091607021469, 6054.7909607906995, 6059.7273122709585, 6105.8473538161015, 6129.785457509055, 5049.065922804293, 5081.923771143436, 5399.352077750616, 5467.617266791366, 5531.225628412026, 2743.8490883001627, 2947.985260073729, 3070.3398769264268, 3127.2650927032128, 3131.04309806371, 5231.555317797954, 5812.076848127497, 6267.341929097471, 6276.435120839146, 6309.968450157719, 5462.590305727007, 5687.3459552152035, 5967.433093856077, 6049.727762250709, 6250.6874656267, 5657.943420565742, 6047.971844786309, 6291.874162516724, 6363.268224154189, 6398.520110991694, 5517.531009864207, 5571.888562228652, 5926.31100136459, 6064.0898117092975, 6116.5412594056515, 5219.791208351623, 5637.295338082739, 5674.687892165971, 5938.851528711817, 5941.940580594139, 6316.589421687576, 6318.273398559225, 6328.734253149297, 6360.141380913703, 6367.777127800505, 3976.5228172619472, 3981.880543583697, 4460.130274596422, 4630.027694184124, 4693.718376897397, 5371.086915224343, 5501.422350653123, 5638.0, 5698.800541981011, 6044.4613262429, 4838.588719958702, 5737.647564498536, 5776.00727748926, 5836.912446313224, 5918.852028699317, 4687.414073240845, 4875.634327425386, 5185.481451854785, 5809.390014048633, 5847.006008978399, 5043.319151914445, 5311.734413279398, 5746.626469279484, 5810.82567522975, 5820.563457740771, 5161.793528258978, 5536.806211782568, 5558.666480011212, 5865.384134665798, 5914.3198532167635, 4482.977585112054, 5095.0, 5456.454354564512, 5710.971445142748, 5735.483806457439], "value": 4543.634416058903, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [758.0637912178455, 776.3245976000876, 807.903051633804, 895.274827389814, 904.1997831918754, 14010.439582416704, 16991.91504042481, 17129.57176070599, 17195.226214820334, 17205.741913871287, 15787.052776833369, 15835.12906790126, 15976.360945562163, 16006.679866402665, 16017.759733604005, 925.1165137293901, 943.5659596585579, 973.0658567774944, 1038.70396936873, 1045.900639439252, 1033.3696445168441, 1035.244271681673, 1038.6053299746086, 1039.5633833789827, 1040.1470793948965, 1076.4994277660885, 1078.4607696151925, 1079.1474734959372, 1080.8486811846349, 1083.9187060970419, 1053.062774131024, 1073.0557109743454, 1073.8711354637455, 1074.6077681646182, 1075.2365820267619, 796.7052190689482, 800.803803068252, 801.4790386248926, 801.6833350826449, 802.9116797152309, 16416.343346266134, 16901.985880847187, 17049.91475042629, 17374.0, 17617.471475855746, 15622.531324060183, 15784.68430631381, 15801.288941997638, 15871.841281587152, 16008.759868601981, 11807.645770626896, 12050.638480845588, 12110.939445302805, 12222.205556638879, 12378.504859805596, 3960.356611296695, 3980.960190398088, 4000.7999600019884, 4045.9885028742815, 4071.0636553592544, 106414.0, 110276.0, 113081.0, 113822.0, 115947.0, 11763.176577639655, 11839.230450020721, 11876.524939002516, 12112.333821639895, 12229.388530573437, 26065.498887829468, 26736.866315668296, 26815.0, 26828.731712682624, 26832.463350732876, 8121.715739949192, 8157.918420815835, 8170.346372290322, 8188.83622327562, 8299.045609754834, 4341.674374421931, 4396.966712822484, 4412.426384569985, 4526.977365113154, 4533.342665313511, 3695.094701798023, 3756.3238617048646, 3793.79134147619, 3832.616738326201, 3862.613738626115, 659.261626977788, 661.8312330355825, 668.8762578922915, 680.9795706128725, 681.1077488490093, 588.3792598808213, 675.770238119032, 696.2271878215178, 727.9408460689684, 748.3489364253135, 9142.497162656118, 9547.236221102296, 10544.420056896792, 10949.288296260876, 10953.123750099972, 722.5664601239214, 742.6917829100863, 749.6701451361383, 753.6420200404764, 756.3911051603402, 4886.486918873467, 5235.6747223130715, 5269.78920843162, 5340.8664783379945, 5383.865403364869, 105629.47185264179, 106083.0, 107863.46068269608, 108201.0, 110109.0, 51610.22584661158, 56997.0, 63904.0, 68434.31565684378, 74461.6276918612, 70643.0, 73635.63182184161, 73773.89339159917, 78690.0, 79197.20802792013, 11000.284981476178, 11288.879558960756, 11291.491882865126, 11359.659210223706, 11517.712057198638, 48117.518824811305, 48570.75714621404, 48790.51209488002, 49392.753036234586, 50722.746386267834, 49894.0, 60632.09051864137, 63194.36805632069, 64102.0, 64727.35272647402, 13442.327883605585, 13448.731025379633, 13515.459381625018, 13516.5269215577, 13614.93192534031, 14638.268086595415, 14832.258387080386, 15367.462138825087, 15420.69158616799, 15544.455944041903, 13855.087805146104, 14047.0, 14379.640508987359, 14557.199354035629, 15278.083315001135, 11981.700457488632, 13889.652758681113, 14165.787513187517, 14391.928040359731, 14421.1347319157, 11953.581624643095, 12390.876091239334, 12408.441620126936, 12461.813072803734, 12813.615591532269, 10159.84760228612, 10462.267641265038, 10580.735481613021, 10776.730581735519, 10817.188710846875, 10315.0, 11461.0, 11643.359615221245, 11692.824607630722, 11730.882691173321], "value": 7946.462952994286, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1566.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 176.0, 178.0, 118.0, 120.0, 120.0, 119.0, 118.0, 118.0, 120.0], "value": 129.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 136.0, 137.0, 140.0, 110.0, 114.0, 111.0, 110.0, 127.0, 111.0], "value": 123.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 202.0, 194.0, 198.0, 195.0, 194.0, 208.0, 240.0, 271.0, 210.0], "value": 210.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 66.0, 69.0, 112.0, 52.0, 70.0, 82.0, 86.0, 62.0, 69.0], "value": 74.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 72.0, 113.0, 61.0, 93.0, 100.0, 109.0, 100.0, 74.0, 75.0], "value": 88.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 91.0, 92.0, 92.0, 89.0, 137.0, 91.0, 92.0, 136.0], "value": 100.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 99.0, 97.0, 98.0, 98.0, 98.0, 98.0, 97.0, 100.0, 152.0], "value": 103.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 145.0, 146.0, 111.0, 90.0, 149.0, 94.0, 145.0, 147.0, 130.0], "value": 123.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 64.0, 66.0, 66.0, 70.0, 70.0, 68.0, 69.0, 69.0, 69.0], "value": 67.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 73.0, 72.0, 68.0, 65.0, 60.0, 58.0, 61.0, 60.0], "value": 63.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 91.0, 88.0, 81.0, 84.0, 85.0, 87.0, 82.0, 88.0], "value": 85.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 136.0, 143.0, 137.0, 146.0, 155.0, 149.0, 150.0, 237.0, 149.0], "value": 154.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 168.0, 164.0, 165.0, 169.0, 165.0, 165.0, 168.0, 224.0, 175.0], "value": 172.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 65.0, 65.0, 63.0, 76.0, 74.0, 63.0, 59.0, 78.0], "value": 67.5, "unit": "score"}]}]} 17:37:14 INFO - Return code: 0 17:37:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:37:15 ERROR - # TBPL SUCCESS # 17:37:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:37:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:37:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:37:15 INFO - 2017-05-25 17:37:15.046 screenresolution[1661:10662] starting screenresolution argv=screenresolution get 17:37:15 INFO - 2017-05-25 17:37:15.048 screenresolution[1661:10662] Display 0: 1600x1200x32@60 17:37:15 INFO - 2017-05-25 17:37:15.054 screenresolution[1662:10665] starting screenresolution argv=screenresolution list 17:37:15 INFO - Available Modes on Display 0 17:37:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:37:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:37:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:37:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:37:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:37:15 INFO - Intel Iris: 17:37:15 INFO - Chipset Model: Intel Iris 17:37:15 INFO - Type: GPU 17:37:15 INFO - Bus: Built-In 17:37:15 INFO - VRAM (Dynamic, Max): 1536 MB 17:37:15 INFO - Vendor: Intel (0x8086) 17:37:15 INFO - Device ID: 0x0a2e 17:37:15 INFO - Revision ID: 0x0009 17:37:15 INFO - Displays: 17:37:15 INFO - Display: 17:37:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:37:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:37:15 INFO - Display Serial Number: 1600x1200 60 17:37:15 INFO - Main Display: Yes 17:37:15 INFO - Mirror: Off 17:37:15 INFO - Online: Yes 17:37:15 INFO - Rotation: Supported 17:37:15 INFO - Return code: 0 17:37:15 INFO - Running post-action listener: _resource_record_post_action 17:37:15 INFO - [mozharness: 2017-05-26 00:37:15.290745Z] Finished run-tests step (success) 17:37:15 INFO - Running post-run listener: _resource_record_post_run 17:37:15 INFO - Total resource usage - Wall time: 1193s; CPU: 55.0%; Read bytes: 40044544; Write bytes: 427336704; Read time: 257; Write time: 1434 17:37:15 INFO - TinderboxPrint: CPU usage
54.8% 17:37:15 INFO - TinderboxPrint: I/O read bytes / time
40,044,544 / 257 17:37:15 INFO - TinderboxPrint: I/O write bytes / time
427,336,704 / 1,434 17:37:15 INFO - TinderboxPrint: CPU idle
2,159.5 (45.2%) 17:37:15 INFO - TinderboxPrint: CPU system
964.9 (20.2%) 17:37:15 INFO - TinderboxPrint: CPU user
1,649.3 (34.6%) 17:37:15 INFO - TinderboxPrint: Swap in / out
162,422,784 / 0 17:37:15 INFO - install - Wall time: 16s; CPU: 54.0%; Read bytes: 115583488; Write bytes: 113180672; Read time: 10617; Write time: 242 17:37:15 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 38250496; Write bytes: 303363072; Read time: 242; Write time: 1156 17:37:15 INFO - Running post-run listener: _upload_blobber_files 17:37:15 INFO - Blob upload gear active. 17:37:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:37:15 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 17:37:15 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:37:15 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:37:16 INFO - (blobuploader) - INFO - Open directory for files ... 17:37:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:37:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:37:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:37:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:37:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:37:17 INFO - (blobuploader) - INFO - Done attempting. 17:37:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:37:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:37:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:37:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:37:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:37:18 INFO - (blobuploader) - INFO - Done attempting. 17:37:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:37:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:37:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:37:19 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:37:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:37:19 INFO - (blobuploader) - INFO - Done attempting. 17:37:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:37:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:37:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:37:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:37:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:37:21 INFO - (blobuploader) - INFO - Done attempting. 17:37:21 INFO - (blobuploader) - INFO - Iteration through files over. 17:37:21 INFO - Return code: 0 17:37:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:37:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:37:21 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/60488ae0e458ac87880dd9d2065074b9e3b2a23f3777b57c3aa65282d2859b6e7dca734305d035cff820796af0d34814542d555d2927dc62373f265b01d3f74c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c8b805790baee951905ab30df02774c48c74f36cd590bcb13a9f6002cb407bfed02f80110f048ce88fcaea7b5c49edbe68ff554324666c0bfe92cd09a49fb633", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7d9cad9bae26518decfdba2543f8f5f29f0f831fbfaaedc8aaa3253e275188281f042f62df574a49805d156e3496f6f58e99e68818fa8af6c396222441ad8d1a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a456a3dfc800c55e27739e451053b82d0e53b06de98db63109408118e74308c9b2fbf338bd05aa3275a46bca824cfb05cef4e53dd9158a5d4b201ec8dbf2e5b1"} 17:37:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:37:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:37:21 INFO - Contents: 17:37:21 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/60488ae0e458ac87880dd9d2065074b9e3b2a23f3777b57c3aa65282d2859b6e7dca734305d035cff820796af0d34814542d555d2927dc62373f265b01d3f74c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c8b805790baee951905ab30df02774c48c74f36cd590bcb13a9f6002cb407bfed02f80110f048ce88fcaea7b5c49edbe68ff554324666c0bfe92cd09a49fb633", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7d9cad9bae26518decfdba2543f8f5f29f0f831fbfaaedc8aaa3253e275188281f042f62df574a49805d156e3496f6f58e99e68818fa8af6c396222441ad8d1a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a456a3dfc800c55e27739e451053b82d0e53b06de98db63109408118e74308c9b2fbf338bd05aa3275a46bca824cfb05cef4e53dd9158a5d4b201ec8dbf2e5b1"} 17:37:21 INFO - Running post-run listener: copy_logs_to_upload_dir 17:37:21 INFO - Copying logs to upload dir... 17:37:21 INFO - mkdir: /builds/slave/test/build/upload/logs 17:37:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.912595 ========= master_lag: 0.63 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-25 17:37:21.837065) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 17:37:21.840726) ========= 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.InBYSZR5Yj/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.wYIK9oMPVk/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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-Beta/sha512/60488ae0e458ac87880dd9d2065074b9e3b2a23f3777b57c3aa65282d2859b6e7dca734305d035cff820796af0d34814542d555d2927dc62373f265b01d3f74c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c8b805790baee951905ab30df02774c48c74f36cd590bcb13a9f6002cb407bfed02f80110f048ce88fcaea7b5c49edbe68ff554324666c0bfe92cd09a49fb633", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7d9cad9bae26518decfdba2543f8f5f29f0f831fbfaaedc8aaa3253e275188281f042f62df574a49805d156e3496f6f58e99e68818fa8af6c396222441ad8d1a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a456a3dfc800c55e27739e451053b82d0e53b06de98db63109408118e74308c9b2fbf338bd05aa3275a46bca824cfb05cef4e53dd9158a5d4b201ec8dbf2e5b1"} build_url:https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011084 build_url: 'https://queue.taskcluster.net/v1/task/fPE3ES3kTfGaKV53poYgSg/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/60488ae0e458ac87880dd9d2065074b9e3b2a23f3777b57c3aa65282d2859b6e7dca734305d035cff820796af0d34814542d555d2927dc62373f265b01d3f74c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c8b805790baee951905ab30df02774c48c74f36cd590bcb13a9f6002cb407bfed02f80110f048ce88fcaea7b5c49edbe68ff554324666c0bfe92cd09a49fb633", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7d9cad9bae26518decfdba2543f8f5f29f0f831fbfaaedc8aaa3253e275188281f042f62df574a49805d156e3496f6f58e99e68818fa8af6c396222441ad8d1a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a456a3dfc800c55e27739e451053b82d0e53b06de98db63109408118e74308c9b2fbf338bd05aa3275a46bca824cfb05cef4e53dd9158a5d4b201ec8dbf2e5b1"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 17:37:21.877751) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:37:21.878054) ========= 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.InBYSZR5Yj/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.wYIK9oMPVk/Listeners TMPDIR=/var/folders/f_/784x9wv55zl7jxv09s004psc00000w/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.005027 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 17:37:21.927243) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 17:37:21.927705) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 17:37:21.928127) ========= ========= Total master_lag: 0.77 =========