builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0221 starttime: 1496913186.96 results: success (0) buildid: 20170608011525 builduid: 808df44897e6480c95c84513207ff0f4 revision: 2125aa846c90398be9d3b6c01ff48de8265aeba3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-08 02:13:06.961026) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-08 02:13:06.961535) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-08 02:13:06.961861) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-08 02:13:07.031048) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 02:13:07.031494) ========= 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.K2frq1lAgX/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.sJMlsenDon/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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-06-08 02:13:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.5M=0.001s 2017-06-08 02:13:07 (18.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.155614 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 02:13:07.210776) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 02:13:07.211133) ========= 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.K2frq1lAgX/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.sJMlsenDon/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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.027117 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 02:13:07.259900) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-08 02:13:07.260298) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2125aa846c90398be9d3b6c01ff48de8265aeba3 --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 2125aa846c90398be9d3b6c01ff48de8265aeba3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.K2frq1lAgX/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.sJMlsenDon/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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-06-08 02:13:07,362 truncating revision to first 12 chars 2017-06-08 02:13:07,362 Setting DEBUG logging. 2017-06-08 02:13:07,363 attempt 1/10 2017-06-08 02:13:07,363 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/2125aa846c90?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-08 02:13:09,554 unpacking tar archive at: mozilla-beta-2125aa846c90/testing/mozharness/ program finished with exit code 0 elapsedTime=2.472944 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-08 02:13:09.755708) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-08 02:13:09.756048) ========= script_repo_revision: 2125aa846c90398be9d3b6c01ff48de8265aeba3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-08 02:13:09.756433) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-08 02:13:09.756699) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-08 02:13:09.775342) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-06-08 02:13:09.775684) ========= /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.K2frq1lAgX/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.sJMlsenDon/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 02:13:09 INFO - MultiFileLogger online at 20170608 02:13:09 in /builds/slave/test 02:13:09 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 02:13:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:13:09 INFO - {'append_to_log': False, 02:13:09 INFO - 'base_work_dir': '/builds/slave/test', 02:13:09 INFO - 'blob_upload_branch': 'Mozilla-Beta', 02:13:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:13:09 INFO - 'branch': 'Mozilla-Beta', 02:13:09 INFO - 'buildbot_json_path': 'buildprops.json', 02:13:09 INFO - 'config_files': ('talos/mac_config.py',), 02:13:09 INFO - 'default_actions': ('clobber', 02:13:09 INFO - 'read-buildbot-config', 02:13:09 INFO - 'download-and-extract', 02:13:09 INFO - 'populate-webroot', 02:13:09 INFO - 'create-virtualenv', 02:13:09 INFO - 'install', 02:13:09 INFO - 'run-tests'), 02:13:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:13:09 INFO - 'download_minidump_stackwalk': True, 02:13:09 INFO - 'download_symbols': 'ondemand', 02:13:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:13:09 INFO - 'tooltool.py': '/tools/tooltool.py', 02:13:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:13:09 INFO - '/tools/misc-python/virtualenv.py')}, 02:13:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:13:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:13:09 INFO - 'gecko_profile': False, 02:13:09 INFO - 'gecko_profile_interval': 0, 02:13:09 INFO - 'installer_path': 'installer.exe', 02:13:09 INFO - 'log_level': 'info', 02:13:09 INFO - 'log_name': 'talos', 02:13:09 INFO - 'log_to_console': True, 02:13:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:13:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:13:09 INFO - 'opt_config_files': (), 02:13:09 INFO - 'pip_index': False, 02:13:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:13:09 INFO - 'cmd': ('bash', 02:13:09 INFO - '-c', 02:13:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:13:09 INFO - 'enabled': True, 02:13:09 INFO - 'halt_on_failure': False, 02:13:09 INFO - 'name': 'check_screen_resolution'},), 02:13:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:13:09 INFO - 'cmd': ('bash', 02:13:09 INFO - '-c', 02:13:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:13:09 INFO - 'enabled': True, 02:13:09 INFO - 'halt_on_failure': False, 02:13:09 INFO - 'name': 'check_screen_resolution'},), 02:13:09 INFO - 'run_cmd_checks_enabled': True, 02:13:09 INFO - 'suite': 'dromaeojs-e10s', 02:13:09 INFO - 'system_bits': '32', 02:13:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:13:09 INFO - 'title': 't-yosemite-r7-0221', 02:13:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:13:09 INFO - 'use_talos_json': True, 02:13:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:13:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:13:09 INFO - 'work_dir': 'build'} 02:13:09 INFO - [mozharness: 2017-06-08 09:13:09.986146Z] Running clobber step. 02:13:09 INFO - Running pre-action listener: _resource_record_pre_action 02:13:09 INFO - Running main action method: clobber 02:13:09 INFO - rmtree: /builds/slave/test/build 02:13:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:13:12 INFO - Running post-action listener: _resource_record_post_action 02:13:12 INFO - [mozharness: 2017-06-08 09:13:12.449938Z] Finished clobber step (success) 02:13:12 INFO - [mozharness: 2017-06-08 09:13:12.450076Z] Running read-buildbot-config step. 02:13:12 INFO - Running pre-action listener: _resource_record_pre_action 02:13:12 INFO - Running main action method: read_buildbot_config 02:13:12 INFO - Using buildbot properties: 02:13:12 INFO - { 02:13:12 INFO - "project": "", 02:13:12 INFO - "product": "firefox", 02:13:12 INFO - "script_repo_revision": "production", 02:13:12 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 02:13:12 INFO - "repository": "", 02:13:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 02:13:12 INFO - "buildid": "20170608011525", 02:13:12 INFO - "pgo_build": "False", 02:13:12 INFO - "basedir": "/builds/slave/test", 02:13:12 INFO - "buildnumber": 12, 02:13:12 INFO - "slavename": "t-yosemite-r7-0221", 02:13:12 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 02:13:12 INFO - "platform": "yosemite_r7_devedition", 02:13:12 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 02:13:12 INFO - "branch": "mozilla-beta", 02:13:12 INFO - "repo_path": "releases/mozilla-beta", 02:13:12 INFO - "revision": "2125aa846c90398be9d3b6c01ff48de8265aeba3", 02:13:12 INFO - "stage_platform": "macosx64-devedition", 02:13:12 INFO - "builduid": "808df44897e6480c95c84513207ff0f4", 02:13:12 INFO - "slavebuilddir": "test" 02:13:12 INFO - } 02:13:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/PciA_TQOQl-ieW8oGe_nrA/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 02:13:12 INFO - Running post-action listener: _resource_record_post_action 02:13:12 INFO - [mozharness: 2017-06-08 09:13:12.453371Z] Finished read-buildbot-config step (success) 02:13:12 INFO - [mozharness: 2017-06-08 09:13:12.453470Z] Running download-and-extract step. 02:13:12 INFO - Running pre-action listener: _resource_record_pre_action 02:13:12 INFO - Running main action method: download_and_extract 02:13:12 INFO - mkdir: /builds/slave/test/build/tests 02:13:12 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')]} 02:13:12 INFO - trying https://queue.taskcluster.net/v1/task/PciA_TQOQl-ieW8oGe_nrA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 02:13:12 INFO - Downloading https://queue.taskcluster.net/v1/task/PciA_TQOQl-ieW8oGe_nrA/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 02:13:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PciA_TQOQl-ieW8oGe_nrA/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 02:13:13 INFO - Downloaded 1287 bytes. 02:13:13 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 02:13:13 INFO - Using the following test package requirements: 02:13:13 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:13:13 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 02:13:13 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 02:13:13 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:13:13 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 02:13:13 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:13:13 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 02:13:13 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 02:13:13 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:13:13 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 02:13:13 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 02:13:13 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:13:13 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 02:13:13 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:13:13 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 02:13:13 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:13:13 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 02:13:13 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 02:13:13 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 02:13:13 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 02:13:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PciA_TQOQl-ieW8oGe_nrA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 02:13:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PciA_TQOQl-ieW8oGe_nrA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 02:13:13 INFO - Fetch https://queue.taskcluster.net/v1/task/PciA_TQOQl-ieW8oGe_nrA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 02:13:15 INFO - Expected file size: 51965551 02:13:15 INFO - Obtained file size: 51965551 02:13:30 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 02:13:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PciA_TQOQl-ieW8oGe_nrA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 02:13:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PciA_TQOQl-ieW8oGe_nrA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 02:13:30 INFO - Fetch https://queue.taskcluster.net/v1/task/PciA_TQOQl-ieW8oGe_nrA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 02:13:32 INFO - Expected file size: 51965551 02:13:32 INFO - Obtained file size: 51965551 02:13:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PciA_TQOQl-ieW8oGe_nrA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 02:13:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PciA_TQOQl-ieW8oGe_nrA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 02:13:47 INFO - Fetch https://queue.taskcluster.net/v1/task/PciA_TQOQl-ieW8oGe_nrA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 02:13:48 INFO - Expected file size: 14404461 02:13:48 INFO - Obtained file size: 14404461 02:13:48 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')]} 02:13:48 INFO - trying https://queue.taskcluster.net/v1/task/PciA_TQOQl-ieW8oGe_nrA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 02:13:48 INFO - Downloading https://queue.taskcluster.net/v1/task/PciA_TQOQl-ieW8oGe_nrA/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 02:13:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PciA_TQOQl-ieW8oGe_nrA/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 02:13:50 INFO - Downloaded 74885757 bytes. 02:13:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PciA_TQOQl-ieW8oGe_nrA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 02:13:50 INFO - mkdir: /builds/slave/test/properties 02:13:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:13:50 INFO - Writing to file /builds/slave/test/properties/build_url 02:13:50 INFO - Contents: 02:13:50 INFO - build_url:https://queue.taskcluster.net/v1/task/PciA_TQOQl-ieW8oGe_nrA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 02:13:51 INFO - Running post-action listener: _resource_record_post_action 02:13:51 INFO - Running post-action listener: set_extra_try_arguments 02:13:51 INFO - [mozharness: 2017-06-08 09:13:51.207093Z] Finished download-and-extract step (success) 02:13:51 INFO - [mozharness: 2017-06-08 09:13:51.207196Z] Running populate-webroot step. 02:13:51 INFO - Running pre-action listener: _resource_record_pre_action 02:13:51 INFO - Running main action method: populate_webroot 02:13:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:13:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:13:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:13:51 INFO - '--tppagecycles', 02:13:51 INFO - '7'], 02:13:51 INFO - 'tests': ['tsvgm']}}, 02:13:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:13:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:13:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:13:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:13:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:13:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:13:51 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:13:51 INFO - 'talos_options': ['--disable-e10s'], 02:13:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:13:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:13:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:13:51 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:13:51 INFO - 'talos_options': ['--disable-e10s'], 02:13:51 INFO - 'tests': ['damp', 'tps']}, 02:13:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:13:51 INFO - 'tests': ['damp', 'tps']}, 02:13:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:13:51 INFO - 'tests': ['dromaeo_dom']}, 02:13:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:13:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:13:51 INFO - 'tests': ['basic_compositor_video']}, 02:13:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:13:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:13:51 INFO - 'tests': ['a11yr', 02:13:51 INFO - 'ts_paint', 02:13:51 INFO - 'tpaint', 02:13:51 INFO - 'sessionrestore', 02:13:51 INFO - 'sessionrestore_no_auto_restore', 02:13:51 INFO - 'tabpaint']}, 02:13:51 INFO - 'other-e10s': {'tests': ['a11yr', 02:13:51 INFO - 'ts_paint', 02:13:51 INFO - 'tpaint', 02:13:51 INFO - 'sessionrestore', 02:13:51 INFO - 'sessionrestore_no_auto_restore', 02:13:51 INFO - 'tabpaint']}, 02:13:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:13:51 INFO - 'ts_paint', 02:13:51 INFO - 'tpaint', 02:13:51 INFO - 'sessionrestore', 02:13:51 INFO - 'sessionrestore_no_auto_restore', 02:13:51 INFO - 'tabpaint']}, 02:13:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:13:51 INFO - 'ts_paint', 02:13:51 INFO - 'tpaint', 02:13:51 INFO - 'sessionrestore', 02:13:51 INFO - 'sessionrestore_no_auto_restore', 02:13:51 INFO - 'tabpaint']}, 02:13:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:13:51 INFO - 'tests': ['a11yr', 02:13:51 INFO - 'ts_paint', 02:13:51 INFO - 'tpaint', 02:13:51 INFO - 'sessionrestore', 02:13:51 INFO - 'sessionrestore_no_auto_restore', 02:13:51 INFO - 'tabpaint']}, 02:13:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:13:51 INFO - 'tests': ['a11yr', 02:13:51 INFO - 'ts_paint', 02:13:51 INFO - 'tpaint', 02:13:51 INFO - 'sessionrestore', 02:13:51 INFO - 'sessionrestore_no_auto_restore', 02:13:51 INFO - 'tabpaint']}, 02:13:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:13:51 INFO - 'tests': ['tsvgx', 02:13:51 INFO - 'tsvgr_opacity', 02:13:51 INFO - 'tart', 02:13:51 INFO - 'tscrollx', 02:13:51 INFO - 'cart', 02:13:51 INFO - 'tsvg_static']}, 02:13:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:13:51 INFO - 'tsvgr_opacity', 02:13:51 INFO - 'tart', 02:13:51 INFO - 'tscrollx', 02:13:51 INFO - 'cart', 02:13:51 INFO - 'tsvg_static']}, 02:13:51 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:13:51 INFO - 'talos_options': ['--disable-e10s'], 02:13:51 INFO - 'tests': ['tp5o']}, 02:13:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:13:51 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:13:51 INFO - 'talos_options': ['--disable-e10s', 02:13:51 INFO - '--xperf_path', 02:13:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:13:51 INFO - 'tests': ['tp5n']}, 02:13:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:13:51 INFO - 'talos_options': ['--xperf_path', 02:13:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:13:51 INFO - 'tests': ['tp5n']}}, 02:13:51 INFO - 'talos.zip': {'path': '', 02:13:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:13:51 INFO - Running post-action listener: _resource_record_post_action 02:13:51 INFO - [mozharness: 2017-06-08 09:13:51.225601Z] Finished populate-webroot step (success) 02:13:51 INFO - [mozharness: 2017-06-08 09:13:51.225730Z] Running create-virtualenv step. 02:13:51 INFO - Running pre-action listener: _resource_record_pre_action 02:13:51 INFO - Running main action method: create_virtualenv 02:13:51 INFO - Creating virtualenv /builds/slave/test/build/venv 02:13:51 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 02:13:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:13:51 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 02:13:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:13:51 INFO - Using real prefix '/tools/python27' 02:13:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:13:52 INFO - Installing distribute.............................................................................................................................................................................................done. 02:13:54 INFO - Installing pip.................done. 02:13:54 INFO - Return code: 0 02:13:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:13:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:13:55 INFO - Reading from file tmpfile_stdout 02:13:55 INFO - Output received: 02:13:55 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) 02:13:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:13:55 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')]} 02:13:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:13:55 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')]} 02:13:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:13:55 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 0x109632b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109635768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6dbd74fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410d30>, '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 0x109337db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109405be8>, '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.K2frq1lAgX/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.sJMlsenDon/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 02:13:55 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 02:13:55 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 02:13:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K2frq1lAgX/Render', 02:13:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:13:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:13:55 INFO - 'HOME': '/Users/cltbld', 02:13:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:13:55 INFO - 'LOGNAME': 'cltbld', 02:13:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:55 INFO - 'MOZ_NO_REMOTE': '1', 02:13:55 INFO - 'NO_EM_RESTART': '1', 02:13:55 INFO - 'PAGER': '/bin/cat', 02:13:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:13:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:55 INFO - 'PWD': '/builds/slave/test', 02:13:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:13:55 INFO - 'SHELL': '/bin/bash', 02:13:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sJMlsenDon/Listeners', 02:13:55 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 02:13:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:13:55 INFO - 'USER': 'cltbld', 02:13:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:13:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:13:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:55 INFO - 'XPC_FLAGS': '0x0', 02:13:55 INFO - 'XPC_SERVICE_NAME': '0', 02:13:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:13:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:13:56 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 02:13:56 INFO - Cleaning up... 02:13:56 INFO - Return code: 0 02:13:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:13:56 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')]} 02:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:13:56 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')]} 02:13:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:13:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x109632b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109635768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6dbd74fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410d30>, '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 0x109337db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109405be8>, '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.K2frq1lAgX/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.sJMlsenDon/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 02:13:56 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 02:13:56 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 02:13:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K2frq1lAgX/Render', 02:13:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:13:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:13:56 INFO - 'HOME': '/Users/cltbld', 02:13:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:13:56 INFO - 'LOGNAME': 'cltbld', 02:13:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:13:56 INFO - 'MOZ_NO_REMOTE': '1', 02:13:56 INFO - 'NO_EM_RESTART': '1', 02:13:56 INFO - 'PAGER': '/bin/cat', 02:13:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:13:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:13:56 INFO - 'PWD': '/builds/slave/test', 02:13:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:13:56 INFO - 'SHELL': '/bin/bash', 02:13:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sJMlsenDon/Listeners', 02:13:56 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 02:13:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:13:56 INFO - 'USER': 'cltbld', 02:13:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:13:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:13:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:13:56 INFO - 'XPC_FLAGS': '0x0', 02:13:56 INFO - 'XPC_SERVICE_NAME': '0', 02:13:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:13:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:13:56 INFO - Downloading/unpacking psutil>=3.1.1 02:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:13:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:13:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:13:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:13:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:13:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:13:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:13:59 INFO - Installing collected packages: psutil 02:13:59 INFO - Running setup.py install for psutil 02:13:59 INFO - building 'psutil._psutil_osx' extension 02:13:59 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 02:13:59 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 02:13:59 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 02:13:59 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 02:14:00 INFO - building 'psutil._psutil_posix' extension 02:14:00 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 02:14:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:14:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:14:00 INFO - ^ 02:14:00 INFO - 1 warning generated. 02:14:00 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 02:14:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:14:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:14:00 INFO - Successfully installed psutil 02:14:00 INFO - Cleaning up... 02:14:00 INFO - Return code: 0 02:14:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:14:00 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')]} 02:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:00 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')]} 02:14:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:00 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 0x109632b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109635768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6dbd74fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410d30>, '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 0x109337db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109405be8>, '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.K2frq1lAgX/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.sJMlsenDon/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 02:14:00 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 02:14:00 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 02:14:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K2frq1lAgX/Render', 02:14:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:14:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:14:00 INFO - 'HOME': '/Users/cltbld', 02:14:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:14:00 INFO - 'LOGNAME': 'cltbld', 02:14:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:00 INFO - 'MOZ_NO_REMOTE': '1', 02:14:00 INFO - 'NO_EM_RESTART': '1', 02:14:00 INFO - 'PAGER': '/bin/cat', 02:14:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:14:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:00 INFO - 'PWD': '/builds/slave/test', 02:14:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:14:00 INFO - 'SHELL': '/bin/bash', 02:14:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sJMlsenDon/Listeners', 02:14:00 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 02:14:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:14:00 INFO - 'USER': 'cltbld', 02:14:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:14:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:14:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:00 INFO - 'XPC_FLAGS': '0x0', 02:14:00 INFO - 'XPC_SERVICE_NAME': '0', 02:14:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:14:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:14:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:14:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:14:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:14:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:14:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:14:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:14:02 INFO - Installing collected packages: mozsystemmonitor 02:14:02 INFO - Running setup.py install for mozsystemmonitor 02:14:02 INFO - Successfully installed mozsystemmonitor 02:14:02 INFO - Cleaning up... 02:14:02 INFO - Return code: 0 02:14:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:14: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')]} 02:14:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14: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')]} 02:14:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14: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', '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 0x109632b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109635768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6dbd74fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410d30>, '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 0x109337db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109405be8>, '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.K2frq1lAgX/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.sJMlsenDon/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 02:14: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:14: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 jsonschema==2.5.1 02:14:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K2frq1lAgX/Render', 02:14:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:14:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:14:02 INFO - 'HOME': '/Users/cltbld', 02:14:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:14:02 INFO - 'LOGNAME': 'cltbld', 02:14:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:02 INFO - 'MOZ_NO_REMOTE': '1', 02:14:02 INFO - 'NO_EM_RESTART': '1', 02:14:02 INFO - 'PAGER': '/bin/cat', 02:14:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:14:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:02 INFO - 'PWD': '/builds/slave/test', 02:14:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:14:02 INFO - 'SHELL': '/bin/bash', 02:14:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sJMlsenDon/Listeners', 02:14:02 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 02:14:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:14:02 INFO - 'USER': 'cltbld', 02:14:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:14:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:14:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:02 INFO - 'XPC_FLAGS': '0x0', 02:14:02 INFO - 'XPC_SERVICE_NAME': '0', 02:14:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:14:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:02 INFO - Downloading/unpacking jsonschema==2.5.1 02:14: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 02:14: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 02:14: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 02:14: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 02:14:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:14:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:14: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 02:14: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 02:14: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 02:14: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 02:14:04 INFO - Downloading functools32-3.2.3-2.tar.gz 02:14:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:14:04 INFO - warning: no files found matching '*.txt' 02:14:04 INFO - no previously-included directories found matching 'build' 02:14:04 INFO - no previously-included directories found matching 'dist' 02:14:04 INFO - no previously-included directories found matching '.git*' 02:14:04 INFO - Installing collected packages: jsonschema, functools32 02:14:04 INFO - Running setup.py install for functools32 02:14:04 INFO - warning: no files found matching '*.txt' 02:14:04 INFO - no previously-included directories found matching 'build' 02:14:04 INFO - no previously-included directories found matching 'dist' 02:14:04 INFO - no previously-included directories found matching '.git*' 02:14:04 INFO - Successfully installed jsonschema functools32 02:14:04 INFO - Cleaning up... 02:14:04 INFO - Return code: 0 02:14:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:14: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')]} 02:14:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14: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')]} 02:14:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14: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', '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 0x109632b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109635768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6dbd74fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410d30>, '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 0x109337db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109405be8>, '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.K2frq1lAgX/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.sJMlsenDon/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 02:14: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:14: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 functools32==3.2.3-2 02:14:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K2frq1lAgX/Render', 02:14:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:14:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:14:04 INFO - 'HOME': '/Users/cltbld', 02:14:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:14:04 INFO - 'LOGNAME': 'cltbld', 02:14:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:04 INFO - 'MOZ_NO_REMOTE': '1', 02:14:04 INFO - 'NO_EM_RESTART': '1', 02:14:04 INFO - 'PAGER': '/bin/cat', 02:14:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:14:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:04 INFO - 'PWD': '/builds/slave/test', 02:14:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:14:04 INFO - 'SHELL': '/bin/bash', 02:14:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sJMlsenDon/Listeners', 02:14:04 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 02:14:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:14:04 INFO - 'USER': 'cltbld', 02:14:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:14:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:14:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:04 INFO - 'XPC_FLAGS': '0x0', 02:14:04 INFO - 'XPC_SERVICE_NAME': '0', 02:14:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:14:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:14:04 INFO - Cleaning up... 02:14:04 INFO - Return code: 0 02:14:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:14: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')]} 02:14:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14: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')]} 02:14:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14: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', '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 0x109632b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109635768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6dbd74fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410d30>, '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 0x109337db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109405be8>, '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.K2frq1lAgX/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.sJMlsenDon/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 02:14: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:14: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 blobuploader==1.2.4 02:14:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K2frq1lAgX/Render', 02:14:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:14:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:14:04 INFO - 'HOME': '/Users/cltbld', 02:14:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:14:04 INFO - 'LOGNAME': 'cltbld', 02:14:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:04 INFO - 'MOZ_NO_REMOTE': '1', 02:14:04 INFO - 'NO_EM_RESTART': '1', 02:14:04 INFO - 'PAGER': '/bin/cat', 02:14:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:14:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:04 INFO - 'PWD': '/builds/slave/test', 02:14:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:14:04 INFO - 'SHELL': '/bin/bash', 02:14:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sJMlsenDon/Listeners', 02:14:04 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 02:14:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:14:04 INFO - 'USER': 'cltbld', 02:14:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:14:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:14:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:04 INFO - 'XPC_FLAGS': '0x0', 02:14:04 INFO - 'XPC_SERVICE_NAME': '0', 02:14:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:14:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:05 INFO - Downloading/unpacking blobuploader==1.2.4 02:14: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 02:14: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 02:14: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 02:14: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 02:14:06 INFO - Downloading blobuploader-1.2.4.tar.gz 02:14:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:14:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:14:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:14:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:14:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:14:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:14:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:14:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:14: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 02:14: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 02:14: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 02:14: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 02:14:07 INFO - Downloading docopt-0.6.1.tar.gz 02:14:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:14:07 INFO - Installing collected packages: blobuploader, requests, docopt 02:14:07 INFO - Running setup.py install for blobuploader 02:14:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:14:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:14:07 INFO - Running setup.py install for requests 02:14:08 INFO - Running setup.py install for docopt 02:14:08 INFO - Successfully installed blobuploader requests docopt 02:14:08 INFO - Cleaning up... 02:14:08 INFO - Return code: 0 02:14:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:14:08 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')]} 02:14:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:08 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')]} 02:14:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:08 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 0x109632b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109635768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6dbd74fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410d30>, '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 0x109337db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109405be8>, '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.K2frq1lAgX/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.sJMlsenDon/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 02:14:08 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 02:14:08 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 02:14:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K2frq1lAgX/Render', 02:14:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:14:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:14:08 INFO - 'HOME': '/Users/cltbld', 02:14:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:14:08 INFO - 'LOGNAME': 'cltbld', 02:14:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:08 INFO - 'MOZ_NO_REMOTE': '1', 02:14:08 INFO - 'NO_EM_RESTART': '1', 02:14:08 INFO - 'PAGER': '/bin/cat', 02:14:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:14:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:08 INFO - 'PWD': '/builds/slave/test', 02:14:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:14:08 INFO - 'SHELL': '/bin/bash', 02:14:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sJMlsenDon/Listeners', 02:14:08 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 02:14:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:14:08 INFO - 'USER': 'cltbld', 02:14:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:14:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:14:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:08 INFO - 'XPC_FLAGS': '0x0', 02:14:08 INFO - 'XPC_SERVICE_NAME': '0', 02:14:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:14:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:14:08 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-k_N7Uh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:14:08 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-69jdkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:14:08 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-Xz8R21-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:14:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:14:08 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-YmDwUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:14:09 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-n8G1zW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:14:09 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-MFMYQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:14:09 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-rmJXZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:14:09 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-ZTd_gp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:14:09 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-R6TK3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:14:09 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-o4P59e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:14:09 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-rPZreY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:14:09 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-IF_Pj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:14:09 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-o_AEFo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:14:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:14:09 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-6kV_xc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:14:10 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-YN1ZOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:14:10 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-6Ij37u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:14:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:14:10 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-OEiFh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:14:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:14:10 INFO - Running setup.py install for manifestparser 02:14:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:14:10 INFO - Running setup.py install for mozcrash 02:14:10 INFO - Running setup.py install for mozdebug 02:14:10 INFO - Running setup.py install for mozdevice 02:14:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:14:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:14:11 INFO - Running setup.py install for mozfile 02:14:11 INFO - Running setup.py install for mozhttpd 02:14:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:14:11 INFO - Running setup.py install for mozinfo 02:14:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:14:11 INFO - Running setup.py install for mozInstall 02:14:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:14:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:14:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:14:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:14:11 INFO - Running setup.py install for mozleak 02:14:11 INFO - Running setup.py install for mozlog 02:14:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:14:11 INFO - Running setup.py install for moznetwork 02:14:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:14:12 INFO - Running setup.py install for mozprocess 02:14:12 INFO - Running setup.py install for mozprofile 02:14:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:14:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:14:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:14:12 INFO - Running setup.py install for mozrunner 02:14:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:14:12 INFO - Running setup.py install for mozscreenshot 02:14:12 INFO - Running setup.py install for moztest 02:14:12 INFO - Running setup.py install for mozversion 02:14:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:14:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:14:12 INFO - Cleaning up... 02:14:12 INFO - Return code: 0 02:14:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:14:12 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')]} 02:14:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:12 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')]} 02:14:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:12 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 0x109632b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109635768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6dbd74fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410d30>, '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 0x109337db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109405be8>, '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.K2frq1lAgX/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.sJMlsenDon/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 02:14:12 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 02:14:12 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 02:14:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K2frq1lAgX/Render', 02:14:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:14:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:14:12 INFO - 'HOME': '/Users/cltbld', 02:14:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:14:12 INFO - 'LOGNAME': 'cltbld', 02:14:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:12 INFO - 'MOZ_NO_REMOTE': '1', 02:14:12 INFO - 'NO_EM_RESTART': '1', 02:14:12 INFO - 'PAGER': '/bin/cat', 02:14:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:14:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:12 INFO - 'PWD': '/builds/slave/test', 02:14:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:14:12 INFO - 'SHELL': '/bin/bash', 02:14:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sJMlsenDon/Listeners', 02:14:12 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 02:14:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:14:12 INFO - 'USER': 'cltbld', 02:14:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:14:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:14:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:12 INFO - 'XPC_FLAGS': '0x0', 02:14:12 INFO - 'XPC_SERVICE_NAME': '0', 02:14:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:14:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:14:13 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-Qsr_Cd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:14:13 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)) 02:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:14:13 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-82pwGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:14:13 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)) 02:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:14:13 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-q80q0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:14:13 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)) 02:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:14:13 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-4DawDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:14:13 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)) 02:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:14:13 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-HeYMl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:14:13 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)) 02:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:14:13 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-RINhd4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:14:13 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)) 02:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:14:13 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-gowlV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:14:13 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)) 02:14:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:14:13 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-wVcmnH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:14:14 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)) 02:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:14:14 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-hH7hhZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:14:14 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)) 02:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:14:14 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-6ZRS2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:14:14 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)) 02:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:14:14 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-Wc8VvU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:14:14 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)) 02:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:14:14 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-B3oBRz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:14:14 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)) 02:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:14:14 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-1vhwxO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:14:14 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)) 02:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:14:14 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-jiraVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:14:14 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)) 02:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:14:14 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-wfFT9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:14:14 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)) 02:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:14:14 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-Ln3RIb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:14:14 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)) 02:14:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:14:14 INFO - Running setup.py (path:/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/pip-e0HSgX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:14:15 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)) 02:14:15 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)) 02:14:15 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)) 02:14:15 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)) 02:14:15 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)) 02:14:15 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)) 02:14:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:14:15 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 02:14:15 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 02:14:15 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 02:14:15 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 02:14:16 INFO - Downloading blessings-1.6.tar.gz 02:14:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:14:16 INFO - Installing collected packages: blessings 02:14:16 INFO - Running setup.py install for blessings 02:14:16 INFO - Successfully installed blessings 02:14:16 INFO - Cleaning up... 02:14:16 INFO - Return code: 0 02:14:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:14:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:14:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:14:17 INFO - Reading from file tmpfile_stdout 02:14:17 INFO - Current package versions: 02:14:17 INFO - blessings == 1.6 02:14:17 INFO - blobuploader == 1.2.4 02:14:17 INFO - docopt == 0.6.1 02:14:17 INFO - functools32 == 3.2.3-2 02:14:17 INFO - jsonschema == 2.5.1 02:14:17 INFO - manifestparser == 1.1 02:14:17 INFO - mozInstall == 1.12 02:14:17 INFO - mozcrash == 1.0 02:14:17 INFO - mozdebug == 0.1 02:14:17 INFO - mozdevice == 0.49 02:14:17 INFO - mozfile == 1.2 02:14:17 INFO - mozhttpd == 0.7 02:14:17 INFO - mozinfo == 0.9 02:14:17 INFO - mozleak == 0.1 02:14:17 INFO - mozlog == 3.4 02:14:17 INFO - moznetwork == 0.27 02:14:17 INFO - mozprocess == 0.25 02:14:17 INFO - mozprofile == 0.28 02:14:17 INFO - mozrunner == 6.13 02:14:17 INFO - mozscreenshot == 0.1 02:14:17 INFO - mozsystemmonitor == 0.3 02:14:17 INFO - moztest == 0.8 02:14:17 INFO - mozversion == 1.4 02:14:17 INFO - psutil == 3.1.1 02:14:17 INFO - requests == 1.2.3 02:14:17 INFO - wsgiref == 0.1.2 02:14:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:14:17 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')]} 02:14:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:17 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')]} 02:14:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:17 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 0x109632b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109635768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6dbd74fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410d30>, '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 0x109337db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109405be8>, '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.K2frq1lAgX/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.sJMlsenDon/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 02:14:17 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 02:14:17 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 02:14:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K2frq1lAgX/Render', 02:14:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:14:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:14:17 INFO - 'HOME': '/Users/cltbld', 02:14:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:14:17 INFO - 'LOGNAME': 'cltbld', 02:14:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:17 INFO - 'MOZ_NO_REMOTE': '1', 02:14:17 INFO - 'NO_EM_RESTART': '1', 02:14:17 INFO - 'PAGER': '/bin/cat', 02:14:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:14:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:17 INFO - 'PWD': '/builds/slave/test', 02:14:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:14:17 INFO - 'SHELL': '/bin/bash', 02:14:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sJMlsenDon/Listeners', 02:14:17 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 02:14:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:14:17 INFO - 'USER': 'cltbld', 02:14:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:14:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:14:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:17 INFO - 'XPC_FLAGS': '0x0', 02:14:17 INFO - 'XPC_SERVICE_NAME': '0', 02:14:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:14:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:17 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)) 02:14:17 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)) 02:14:17 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)) 02:14:17 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)) 02:14:17 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)) 02:14:17 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)) 02:14:17 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)) 02:14:17 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)) 02:14:17 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)) 02:14:17 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)) 02:14:17 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)) 02:14:17 INFO - Cleaning up... 02:14:17 INFO - Return code: 0 02:14:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:14:17 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')]} 02:14:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:14:17 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')]} 02:14:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:14:17 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 0x109632b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109635768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe6dbd74fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109410d30>, '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 0x109337db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109405be8>, '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.K2frq1lAgX/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.sJMlsenDon/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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 02:14:17 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 02:14:17 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 02:14:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K2frq1lAgX/Render', 02:14:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:14:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:14:17 INFO - 'HOME': '/Users/cltbld', 02:14:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:14:17 INFO - 'LOGNAME': 'cltbld', 02:14:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:17 INFO - 'MOZ_NO_REMOTE': '1', 02:14:17 INFO - 'NO_EM_RESTART': '1', 02:14:17 INFO - 'PAGER': '/bin/cat', 02:14:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:14:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:17 INFO - 'PWD': '/builds/slave/test', 02:14:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:14:17 INFO - 'SHELL': '/bin/bash', 02:14:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sJMlsenDon/Listeners', 02:14:17 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 02:14:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:14:17 INFO - 'USER': 'cltbld', 02:14:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:14:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:14:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:17 INFO - 'XPC_FLAGS': '0x0', 02:14:17 INFO - 'XPC_SERVICE_NAME': '0', 02:14:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:14:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:14:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:14:17 INFO - Cleaning up... 02:14:17 INFO - Return code: 0 02:14:17 INFO - Running post-action listener: _resource_record_post_action 02:14:17 INFO - Running post-action listener: _start_resource_monitoring 02:14:17 INFO - Starting resource monitoring. 02:14:17 INFO - [mozharness: 2017-06-08 09:14:17.625266Z] Finished create-virtualenv step (success) 02:14:17 INFO - [mozharness: 2017-06-08 09:14:17.626243Z] Running install step. 02:14:17 INFO - Running pre-action listener: _resource_record_pre_action 02:14:17 INFO - Running main action method: install 02:14:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:14:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:14:17 INFO - Reading from file tmpfile_stdout 02:14:17 INFO - Detecting whether we're running mozinstall >=1.0... 02:14:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:14:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:14:17 INFO - Reading from file tmpfile_stdout 02:14:17 INFO - Output received: 02:14:17 INFO - Usage: mozinstall [options] installer 02:14:17 INFO - Options: 02:14:17 INFO - -h, --help show this help message and exit 02:14:17 INFO - -d DEST, --destination=DEST 02:14:17 INFO - Directory to install application into. [default: 02:14:17 INFO - "/builds/slave/test"] 02:14:17 INFO - --app=APP Application being installed. [default: firefox] 02:14:17 INFO - mkdir: /builds/slave/test/build/application 02:14:17 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'] 02:14:17 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 02:14:37 INFO - Reading from file tmpfile_stdout 02:14:37 INFO - Output received: 02:14:37 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:14:37 INFO - Running post-action listener: _resource_record_post_action 02:14:37 INFO - [mozharness: 2017-06-08 09:14:37.872957Z] Finished install step (success) 02:14:37 INFO - [mozharness: 2017-06-08 09:14:37.873086Z] Running run-tests step. 02:14:37 INFO - Running pre-action listener: _resource_record_pre_action 02:14:37 INFO - Running main action method: run_tests 02:14:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:14:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:14:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:14:37 INFO - 2017-06-08 02:14:37.887 screenresolution[1617:7484] starting screenresolution argv=screenresolution get 02:14:37 INFO - 2017-06-08 02:14:37.890 screenresolution[1617:7484] Display 0: 1600x1200x32@60 02:14:37 INFO - 2017-06-08 02:14:37.896 screenresolution[1618:7487] starting screenresolution argv=screenresolution list 02:14:37 INFO - Available Modes on Display 0 02:14:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:14:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:14:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:14:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:14:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:14:38 INFO - Intel Iris: 02:14:38 INFO - Chipset Model: Intel Iris 02:14:38 INFO - Type: GPU 02:14:38 INFO - Bus: Built-In 02:14:38 INFO - VRAM (Dynamic, Max): 1536 MB 02:14:38 INFO - Vendor: Intel (0x8086) 02:14:38 INFO - Device ID: 0x0a2e 02:14:38 INFO - Revision ID: 0x0009 02:14:38 INFO - Displays: 02:14:38 INFO - Display: 02:14:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:14:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:14:38 INFO - Display Serial Number: 1600x1200 60 02:14:38 INFO - Main Display: Yes 02:14:38 INFO - Mirror: Off 02:14:38 INFO - Online: Yes 02:14:38 INFO - Rotation: Supported 02:14:38 INFO - Return code: 0 02:14:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:14:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:14:38 INFO - Python 2.7.3 02:14:38 INFO - Return code: 0 02:14:38 INFO - grabbing minidump binary from tooltool 02:14:38 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')]} 02:14:38 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 0x109410d30>, '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 0x109337db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109405be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:14:38 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 02:14:38 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 02:14:38 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 02:14:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:14:38 INFO - Return code: 0 02:14:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:14:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:14:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:14:38 INFO - ENV: RUST_BACKTRACE is now 1 02:14:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:14:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:14:38 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PciA_TQOQl-ieW8oGe_nrA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0221', '--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 02:14:38 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PciA_TQOQl-ieW8oGe_nrA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0221 --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 02:14:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K2frq1lAgX/Render', 02:14:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:14:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:14:38 INFO - 'HOME': '/Users/cltbld', 02:14:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:14:38 INFO - 'LOGNAME': 'cltbld', 02:14:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:14:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:14:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:14:38 INFO - 'MOZ_NO_REMOTE': '1', 02:14:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:14:38 INFO - 'NO_EM_RESTART': '1', 02:14:38 INFO - 'PAGER': '/bin/cat', 02:14:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:14:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:14:38 INFO - 'PWD': '/builds/slave/test', 02:14:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:14:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:14:38 INFO - 'RUST_BACKTRACE': '1', 02:14:38 INFO - 'SHELL': '/bin/bash', 02:14:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.sJMlsenDon/Listeners', 02:14:38 INFO - 'TMPDIR': '/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/', 02:14:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:14:38 INFO - 'USER': 'cltbld', 02:14:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:14:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:14:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:14:38 INFO - 'XPC_FLAGS': '0x0', 02:14:38 INFO - 'XPC_SERVICE_NAME': '0', 02:14:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:14:38 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PciA_TQOQl-ieW8oGe_nrA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0221', '--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 02:14:38 INFO - mozversion application_buildid: 20170608011525 02:14:38 INFO - mozversion application_changeset: 2125aa846c90398be9d3b6c01ff48de8265aeba3 02:14:38 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:14:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:14:38 INFO - mozversion application_name: Firefox 02:14:38 INFO - mozversion application_remotingname: firefox-dev 02:14:38 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 02:14:38 INFO - mozversion application_vendor: Mozilla 02:14:38 INFO - mozversion application_version: 54.0 02:14:38 INFO - mozversion platform_buildid: 20170608011525 02:14:38 INFO - mozversion platform_changeset: 2125aa846c90398be9d3b6c01ff48de8265aeba3 02:14:38 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 02:14:38 INFO - mozversion platform_version: 54.0 02:14:38 INFO - using testdate: 1496913278 02:14:38 INFO - actual date: 1496913278 02:14:38 INFO - starting webserver on 'localhost:49222' 02:14:38 INFO - SUITE-START | Running 2 tests 02:14:38 INFO - TEST-START | dromaeo_css 02:14:38 INFO - operating with platform_type : mac_ 02:14:38 INFO - Initialising browser for dromaeo_css test... 02:14:38 INFO - TEST-INFO | started process 1628 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpUblZVZ/profile http://localhost:49222/getInfo.html) 02:14:41 INFO - PROCESS | 1628 | 2017-06-08 02:14:41.392 plugin-container[1632:7679] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d43, name = 'com.apple.tsm.portname' 02:14:41 INFO - PROCESS | 1628 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:14:41 INFO - PROCESS | 1628 | 2017-06-08 02:14:41.392 plugin-container[1632:7679] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c07, name = 'com.apple.CFPasteboardClient' 02:14:41 INFO - PROCESS | 1628 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:14:41 INFO - PROCESS | 1628 | 2017-06-08 02:14:41.392 plugin-container[1632:7679] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:14:41 INFO - PROCESS | 1628 | __metrics Screen width/height:1600/1200 02:14:41 INFO - PROCESS | 1628 | colorDepth:24 02:14:41 INFO - PROCESS | 1628 | Browser inner width/height: 1024/689 02:14:41 INFO - PROCESS | 1628 | __metrics 02:14:41 ERROR - PROCESS | 1628 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3381: TypeError: this.tabs is undefined 02:14:41 INFO - TEST-INFO | 1628: exit 0 02:14:41 INFO - Browser initialized. 02:14:41 INFO - Running cycle 1/1 for dromaeo_css test... 02:14:41 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpUblZVZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:14:44 INFO - PROCESS | 1633 | 2017-06-08 02:14:44.515 plugin-container[1637:7873] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 02:14:44 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:14:44 INFO - PROCESS | 1633 | 2017-06-08 02:14:44.516 plugin-container[1637:7873] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a1f, name = 'com.apple.CFPasteboardClient' 02:14:44 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:14:44 INFO - PROCESS | 1633 | 2017-06-08 02:14:44.516 plugin-container[1637:7873] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:14:54 INFO - PROCESS | 1633 | RSS: Main: 202817536 02:14:54 INFO - PROCESS | 1633 | 02:17:57 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49222/tests/dromaeo/cssquery-ext.html) 02:17:59 INFO - PROCESS | 1633 | RSS: Main: 216088576 02:17:59 INFO - PROCESS | 1633 | 02:21:01 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-ext.html (next: http://localhost:49222/tests/dromaeo/cssquery-jquery.html) 02:21:02 INFO - PROCESS | 1633 | RSS: Main: 229912576 02:21:02 INFO - PROCESS | 1633 | 02:24:04 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49222/tests/dromaeo/cssquery-mootools.html) 02:24:04 INFO - PROCESS | 1633 | RSS: Main: 231706624 02:24:04 INFO - PROCESS | 1633 | 02:27:07 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49222/tests/dromaeo/cssquery-prototype.html) 02:27:07 INFO - PROCESS | 1633 | RSS: Main: 235868160 02:27:07 INFO - PROCESS | 1633 | 02:30:10 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49222/tests/dromaeo/cssquery-yui.html) 02:30:11 INFO - PROCESS | 1633 | RSS: Main: 237998080 02:30:11 INFO - PROCESS | 1633 | 02:33:14 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-yui.html (next: http://localhost:49222/tests/dromaeo/cssquery-dojo.html) 02:33:14 INFO - PROCESS | 1633 | RSS: Main: 235978752 02:33:14 INFO - PROCESS | 1633 | 02:33:14 INFO - PROCESS | 1633 | __start_tp_report 02:33:14 INFO - PROCESS | 1633 | _x_x_mozilla_page_load 02:33:14 INFO - PROCESS | 1633 | _x_x_mozilla_page_load_details 02:33:14 INFO - PROCESS | 1633 | |i|pagename|runs| 02:33:14 INFO - PROCESS | 1633 | |0;dojo.html;3239.8704051837926;3501.369753444379;3513.7891726496414;3514.80668563229;3520.806355650438;6005.339412664609;6029.336772954977;6056.8788624227545;6079.392060793918;6094.847628809271;6077.848053798657;6079.969600151993;6111.174991376163;6143.201383820097;6152.354002829706;5432.348118225818;5489.39616642169;5496.862578435531;5506.284183056197;5580.888382232353;4763;5266.631335806495;5437.6465529740635;5572.5263352614875;5610.635308704942;3938.995556133182;5344.198370244475;5358.169483730008;5432.75552600132;5437.592180586452;5526.778928842861;5540.612157148961;5556.86107847303;5581.358143813472;5599;5431.646942948696;5463.125899856043;5476.7535460904355;5500.202470641734;5517.310336207974;5761.193432919395;5820.86493133839;6013.398660133952;6083.513318934474;6182.103594978747;6299.559030867841;6312.116303717483;6314.747410103636;6322.241331040304;6431.807045788634;6519.739210431577;6527.804165875032;6539.803805885831;6542.672866356711;6561.2782593914635;6120.540959427972;6175.135481032659;6237.126802247689;6239.50083993284;6255.968720156416;6272.905906411407;6350.396712312323;6352.301246862841;6539.346065393518;6613.801585952429;6289.182406287154;6319.115323854664;6335.3981371769605;6340.651264180422;6346.365363463709;5299.5760339172775;6482.67586620667;6493.967530162414;6498;6506.869862602722;6253.312135665074;6286.591371560926;6297.118403423525;6414.743410263631;6429.485641148697;6211.472024877936;6278.372162783685;6385.521085918482;6416.101745755598;6454.580452270588;4916.041371932439;4920.212765957501;4962.379702537183;4965.677230979974;4976.0794253063295;4432.711873728197;4435.689501734848;4459.620932220798;4473.910521789546;4513.638908887281;5705.71471426427;5801.245838041028;5982.46157845796;5996.040633498619;6019.578629495981;6330.8100756977365;6332.778352757631;6401.167848179707;6415.967920160369;6465.838354041187;6006.639601623917;6010.729517171738;6012.729427175787;6039.094135879565;6049.758009679564;5661.688607126647;5671.602987790815;5676.290463692038;5680.5455563554815;5682.176084467729;6312.715927783169;6312.873742525032;6492.870142597028;6525.3800888915475;6654.434373078246;6430.935690643034;6477.838054048687;6505.41451269388;6508.902366464602;6518.445932095636;5422.9186562202385;6652.135222421054;6658;6663.733450661919;6666.633335166613;6251.78118765841;6261.812145635638;6315.5894866833505;6405.8718825624155;6436.613803171825;5410.97294513525;6313.116163737172;6420.7431702731365;6514.120593719787;6525.477961762953;6307.211598550181;6325.240971083499;6325.272593651789;6326.3041065483685;6361.522885783492;6230.034344676583;6254.030625253093;6255.37446255389;6270;6334.239891212899;6202.317745048132;6226.719797609209;6269.529785266214;6275.497960163268;6320.5575609706875;6311.810645680637;6328.0507923809955;6333.461655759312;6334.3665633436285;6336.081268216175;6129.389354848411;6136.907946380718;6193.628382297076;6261.373862613702;6290.685465726787 02:33:14 INFO - PROCESS | 1633 | |1;ext.html;782.4718315137285;825.8637324841402;845.2097289034755;882.8984666763316;965.4979410706433;39555.813325600204;40272.19455610886;41644.95887602811;41935.79032104843;42110.36834447486;40490.38038478457;40674.77975660728;40731.79634101833;40803;40857.79571102148;8119.715809946668;11000.779984400308;11325.490352934137;11529.423528823567;11533.538658453652;11055.22613417061;11102.333859968387;11117.444127793618;11178.608748693798;11182.552697892075;9389.483578403186;11128.777424451506;11208.831867521994;11208.887911120866;11224.887751122465;11095.833562496488;11129.387883666395;11152.498137583829;11162.888371116265;11166.274192177483;9140.131687489678;10880.183986201067;10925.235233533656;10944.945275273572;10974.561017559288;27649;34397.82801086004;34730;34913.30173396543;35022.649773502446;3868.2456920900436;3905.531336239641;3936.3898595717424;3941.1920556286177;3966.5240171179353;23567.41081472977;25500.872495637403;25527.48945021089;26968.190954271402;27111.457770844474;3186.3786561620495;3195.6964088411796;3239.2387788869596;3319.0872510059685;3328.3176948725454;255872.72063639748;306268;341182;343411;357981;40775;41068.794656026526;41092.38361424581;41260.381094283606;41270.587294127276;43733.56266437295;43761.78119109448;43784.56215437805;43845.34231986523;43916.78041609772;13438.328083595781;13561.915046796232;13570.185788852701;13581.524646637321;13583.932080339733;11864.644060678194;11915.940420298017;12103.515859365527;12117.091218158497;12148;11452.568428946382;11466.770664586662;11483.598074067368;11492.195546311845;11586.304821710724;4710.222813235846;5581.553475722014;5675.347335056439;5677.801276955287;5690.260266165372;1013.6249587652616;1103.5971870267456;1161.280006396023;1175.747214348902;1180.3508070561156;21005.68491472629;26694.5995810063;26783.929642214516;27145.864270678914;27156.72843271542;1222.4865556466304;1234.6419538333962;1246.1526162209602;1273.9681507962375;1283.9101262911506;11629.47667354971;12789.61631151067;13705.588832335046;14098.788518172236;14357.78463323051;365820;366312;366406;368444;368488;54300.45699542954;54596.18105728417;54804;54849;54943.725281374136;1428.6999730056805;1473.3517252408906;1476.512750792236;1486.31629450454;1489.0098084773722;22829.88585057064;24967.375815604755;25065.49869002573;26620.866895665396;26621.866890665424;41436.58563414448;41858.790706046275;41948.95127621834;41970.95072623209;41977;339274.6072539342;339789;339868;340001;340977;29288.457620971385;29900.401991960473;31061.68938310588;31103.688963110984;31259.84370078135;38693.22613547769;39842.60157398505;39849;40008.599699010396;41026.2816028787;27697.584536232374;28227.71772282333;28565.42869142564;28985.42029159363;29436.558451622815;21953.560928781655;26817.061402850806;28574.71413786343;28647.5702864453;29402.411951760416;33196.621718796734;33458.49812252867;33581.83209083939;33626.32747344991;33888.15279618029;28798;28883.711162888943;29101.272468188465;29565.556516652698;29658.703412966457;22213.77786222117;23223.187188448323;23402.07824203782;23507.294781156594;23584.17455389053 02:33:14 INFO - PROCESS | 1633 | |2;jquery.html;408.53427093113856;417.75978812183;421.5868448920056;430.0925048148407;454.727163701779;18970.90514547427;20516;21283.36149915503;21865;21893.452663683416;21590.028448719808;21914.780852191474;22386;22570.322890313277;22702.091916323327;5217.37391513019;6991;6996.3003699630135;7035.894461583055;7042.6126563039015;6522.771702990396;6902.344277293649;6945.374916257536;6956.721731130749;6957.7216911323485;6598.637074960875;6869.622170780605;6918.896216556731;6953.443724502028;6964.094667693193;6906.930930690679;6951;6961.930380696179;6970.651467426608;6993.230744618088;7064.752733654349;7414.443916706268;7494.925050749477;7535.9246407535775;7540.3213710766295;25165.62251566228;25502.23493295204;26273.21180364592;26521;26784.464310713873;3266.3140740444537;3339.6493368196475;3395.7962522248745;3428.8856121760277;3440.2431465077643;15886.36454541817;16025.519234422869;16048.598785030352;16152.434664786677;16163;1948.0551932312958;2026.280670362015;2107.4942013916702;2134.253011445997;2167.7723838996835;132839;147888.2605586976;158972;172193.1390343053;173808;16917.83082169187;21028.47428814292;21466.892665536572;21512.78487215139;21794.78205217959;31246.53130203049;31508.684913151035;31788.84105579457;31792.68207317897;32002.519962200586;9733.853992190123;9963.402195868272;9975.25185611082;10042.799144017079;10147;8249.917500824915;8282.875756863652;8286.70996515119;8358.164183581715;8438.409311348147;9450.102240287162;9607.790208252141;9623.807523849484;9649.179819715402;9697.369670971364;4149.33610622299;4294.914101717948;4311;4311.585512113226;4313.719608225455;1090.8909109089186;1091.6452153050132;1106.468894930438;1115.548202977779;1167.9124065695107;18326;21973.340799776033;22035.66946495804;23197.652035219486;24191.39521511941;965.6475386484024;967.1875624475474;972.3048020665258;1004.598160735697;1013.2856336282903;10429.582816687247;10977.12183025356;11032.889671103347;11073.335599864035;11078.279911805868;781591;786524;790722;791066;799104.0044799739;150992;161295;171835;172588;177486;1290.3354772292278;1314.2640121531972;1345.0382976172084;1345.4281930179634;1346.703725180439;14064.578062658136;17799.73300400521;18599.628007439504;18889.90555047216;19015.049247537845;47826;64540;65905.01142482771;66050.66974665096;67912;98913.50543247238;99305.50347248217;99801;99836.00163998558;102243;9629.662961796303;10928.01647851697;11508.309501429942;11745.88254117482;11987.580434684744;11289.879468723137;13052;13093.083484156017;13208.27354495512;13497.86502134966;9655.517224138905;11089;11497.252678575866;11590.362530060927;11669.59156429521;10645.89354106449;10717;10803.567857285621;10853.186011049362;11107.278026928223;11499.655010349703;11562;11765.705687390877;11803.586874459354;11846.466908989289;11560.884391155982;11625.47685354178;11663.825042624538;11783.351915644378;11903.642890713292;11317.830232546683;11330.60342887995;11594.94202528982;11625.67486502686;11692.123090768056 02:33:14 INFO - PROCESS | 1633 | |3;mootools.html;1601.511538980612;1627.7232870412029;1631.747079202724;1632.8040635123784;1642.0722291905076;10698.732531686703;11213.327200367983;11399.829002564968;11419.371934543607;11562.942185289061;11153.553857845696;11217;11397.316161030323;11403.429828508562;11404.600838970639;2276.7950884420397;2304.1704986204954;2322.036354912709;2326.6044772388736;2326.732425771041;1725.4219836354837;1775.937842175524;2101.8528702990798;2150.7204063471727;2293.9885300573555;2036.5417780999305;2194.670799380098;2237.284069097889;2237.3735354100877;2240.10395841663;1922.9134688939032;2274.1471948019494;2274.1812947338944;2279.521300526883;2281.235786011688;2249.3926639807323;2278.7493375728654;2280.8859557022083;2296.632538793785;2301.3786277704926;11039.72400689981;11325.603603873826;11352.94323528377;11354.772904541946;11360.261582997078;5388.515033646951;5513.035218836728;5538.584606154514;5555.416681248492;5557.3886872444;3035.7723170762283;3269.885554005622;3368.932621347584;3401.693847553707;3402.7447941404494;1061.6921092883056;1092.1099304067238;1102.608573956242;1146.4668928947997;1146.696125526736;15461.613459663486;15464;15611.609709757233;15612.4535641252;15614.531564053097;4169.60388763067;4293.205756934977;4383.517813040563;4386.385905973167;4391.692581519263;11665.125115616363;11799.646010619696;11804.527818887149;11819.940900295616;11828.822567661491;5389.434109418533;5419.701916394636;5423.5932305076485;5441.374241962146;5446.700431476228;4936.259561065869;4938.506149385033;4975.104481193348;5049.8737531562;5067.923981140286;2998.670146283907;3051.343961048385;3631.2737452509077;3719.423489359154;3730.813459327023;920.9079092090738;923.2798417234568;923.8060007398522;931.7996630724426;936.7892224249489;830.2652152844776;941.2940294778916;942.4298299528823;960.8078384323163;1017.9287449878437;6500.9349906501275;6559.278479367265;6588.73645054202;6802.14973128359;7014.158301003912;962.2398305338704;962.7448726087592;1007.49882674814;1080.7946490166844;1113.8050841102936;4226.682998775104;4369;4433.933490997638;4590.517995610479;4591.334256532851;10054.49727513636;13405.329733513481;13423.798643020362;14003.50987715425;14021.719565608631;11860.288382697066;11906.690264070945;13627.727445451234;13937.88496920244;13942.302884855717;9989;10185.694429167137;10392.42841643717;10455.424951627581;10495.632652857113;6870.862582748217;6955.652217389212;7183.353498185188;7204.423646108404;7250.746223882139;10953.780924381308;12145.757084858076;12176.330301833485;12233.204841685261;12334.629961101182;12514.18657787241;12675.492980280686;12677.619671409873;12839.293838838954;12864.421101050662;9215.216706579748;9449.622015119594;9481.241500680067;9494.192993595621;9503.809923801347;10147.64483243083;10370.429626370622;10465.372077675363;10550.366977981346;10631.149508039493;9019.909800902169;9108.726738197865;9180.44917304964;9191.172794448532;9283.860742088738;8820.29437644974;8856.955715221382;8891.977422596485;8917.331200159884;8968.10318968098;7478.7382441616455;7999.120096789232;8078.111407745261;8085.878711819445;8093.19068093186;5884.35272120075;5934.821955341346;5937.34689184198;6049.153118563317;6126.877462450637;5823.126531020381;5968.074948383008;6096.390360963868;6232.719527621359;6300.117983482224 02:33:14 INFO - PROCESS | 1633 | |4;prototype.html;389.37310929403674;396.11468368197075;398.0983073338887;399.7981019585108;401.58636604297567;5344.412114667389;5359.311816777712;5398.622096453255;5404.702741349224;5429.267048948391;5030.2394162043265;5267.155735902608;5283.049051170788;5283.392409872866;5329.413764485904;3930;3986.083200863803;4112;4127.277726397883;4134.255833949888;4141.834326626931;4179.623833854953;4203.07532342884;4221.24017676818;4240.024794297298;4023.396490526415;4130.621541166373;4166.146065001076;4215.78921053946;4237.936430953539;4080.8367665293354;4135.483064616923;4140.358244472111;4146.470652901728;4151.612822626971;3366.309906469168;3405.650101430005;3424;3693.279810436966;3713.9442908356395;4441.111777644452;4812.975935120337;4987.376577927759;5021.648484606079;5123.385193776733;4333.414988976479;4444.355628384726;4618.2982954971385;4733.763311834395;4763.952360476385;4601.596513063514;4911.4106307243;5185.818496352647;5587.49137732814;5709.486146246816;4131.8967025824295;4135.875923722293;4144.668426525871;4171.89444797129;4176.0395109124765;4561.361409402686;5015.172496538067;5781.53747700183;5854.765809367577;5967.910481342783;4024.778637174925;4531.546845315508;4687.132880417065;4694.295855621615;4732.503087175797;4119.876403707894;4703.118752499027;4733.881652958635;4737.928931066037;4757.5718185363485;4553.383548840114;4749.786259618326;5077.019962214739;5102.719350435762;5120.590352771769;4895.510448955076;4944.9258261126115;5479.356228108263;5521.5306698930235;5688.345840228427;1495.2822645130182;1519.8176218853807;1587.3015873015836;1610.2914717524252;1630.1197353429252;983.9963237497712;1176.4823477669797;1214.2350319298962;1218.524775337619;1235.086036333125;4193.748375097504;4875.926861097087;4954.777035033432;5081;5211.609129315317;5080.974595127074;5451.48210919962;5837.190470954018;5861.325947516005;5933.495652869468;4666.463356714022;4699.342092107107;4724.212714498462;4737.768180273154;4815.711057336571;2780.4290575824593;2801.817881837674;3039.680833512494;3092.2015575903843;3122.750179985596;5366.087765079946;5635.915461268084;5993.520518358608;6205.47253483459;6206.014075495888;5006.499350065037;5286.775067209;5415.377231618336;6091.269047714302;6103.572749907553;5448.809291674773;5556.583256255797;6197;6213.477697964027;6239.812805615838;4952.289647527548;5523.861903452445;5556.078726810312;5925.377835327401;6011.977963746086;5264.419715102004;5613.831585052455;5726.914096288642;5774.538036957118;5851.912221316598;5005.6996580205305;5330.360356757214;5709.28721383575;5898.705064746659;5899.439553242435;3969.8412063517135;4166.479190101238;4599.93100103492;4643.3963584733765;4693.389859318267;5027.59779217669;5650.85872853182;5735.311762588516;5804.593678442468;5920.496757775637;4914.533119353656;4929.778159982738;5302.939412117669;5569.994801871242;5713.314402271754;4674.439067311944;5478.014482107182;5490.533304669148;5666.773329066791;5724.914126288026;5114.872128196676;5230.97384513075;5234.398044224963;5348.839534813962;5506.476884695948;4983.526564976321;5049.8737531562;5106.7701953412925;5637.8843444285085;5783.652980821164;4839.830605928775;5390.353157621111;5588.664680119044;5592.356878958972;5594.489487838342 02:33:14 INFO - PROCESS | 1633 | |5;yui.html;726.9563826170427;751.8496300739854;783.4241832253293;839.8992120945487;854.5684429363171;15882.36470541177;16510.174491275433;16889.83110168898;17107.572310692238;17271.82728172718;15307;15310.923445382785;15705;15809.130497822614;15853.84146158538;977.9168770654485;993.1210878372656;1000.8498725191207;1001.0390025575456;1004.1715584642699;969.6606187834237;994.9602015919354;997.0727223682009;1006.5923301063044;1007.269729446153;1059.3326204491198;1061.3631820907472;1063.3407287481773;1064.132731823565;1065.424670677833;1052.5368837711383;1052.63157894737;1056.418969566739;1057.5928267616946;1059.9417032063272;787.7361084036854;789.5617932047711;790.3084800799301;791.8930944322558;792.675003248671;16815.327386904508;16948.983061016253;16949.406770762966;17233.741493877602;17406.7388989164;11585.942070289679;14212.715745685131;14278;14280.92859535706;14318;11913;11923.801964480586;11996.760064798742;12058.879411205864;12063.577774777841;3635.9272814543824;3665.816709164558;3721.3115573618697;3735.402335626287;3879.3405121129476;108295.99999999921;111212.88787112106;114841;115842;117857;10871.565137394573;10945.726356840983;10981.835272470918;11031.944840275908;11106;22775.202867899545;22989.655155172684;23293.53412931732;23382.532349352918;23412;7818.765437036898;7908.881366779503;7925.643346049442;7945.404094692921;7957.045154581371;4175.185838761473;4413.823447062082;4450.732956022649;4473.373727678128;4496.100779844044;3454.6199918008997;3475.1485885957595;3503.702185314277;3526.9118272043406;3536.45184996326;626.9342118398649;630.6337522708253;642.2774378823823;643.334149155631;655.944244739193;641.1985018726592;661.9735210591523;673.8382788130813;677.7153595489807;689.6276010954028;8890.64437422496;9819.509024548744;9868.407895526292;9880.357776744633;9959.950200249097;638.1066506890298;639.68015992004;659.475716805141;681.2574294019444;690.3717616968535;4828.66199366048;4831;4860.3195552622665;4905.632077594195;4920.803167873245;84471;97974.51012744891;98653;103766;108491.45754271178;59072;74626.62686686532;83639;90070;91787.54106229426;57643.13535296974;66171.6765664684;77629.2237077622;79873;81338;10912.618058368236;10994.625671791026;11374.090072794323;11374.54501819918;11375.1468639854;49836.501634983186;50258.497415025384;51489.940402384986;51619.70950726262;52589.7370513145;64695;66947.66526167338;67481.3251867475;68104;68437.65781171063;11209.840752948756;14362.210078445785;14480.34838432252;14629.341679624229;14685.706285874008;13361.599152025456;14204.857951420354;15027.323770430137;15282;15560.455384061503;13828.239446830521;14137.222452765196;14438.205898675253;15512.301946412665;15685;14099.295035248402;14503.564893053226;14550.85449145495;14634.634134146305;15043.548693539211;13710.588682339545;14100.858991409954;14237.715245695235;14438.061526000776;14625.780613291023;9564.634297777226;9861.605535778488;10256.538455769658;10286.434246116536;10699.5185216667;9948.701538953843;11653.6503904883;11687.88312116902;12071.57749478764;12128.332941687939 02:33:14 INFO - PROCESS | 1633 | __end_tp_report 02:33:14 INFO - PROCESS | 1633 | __start_cc_report 02:33:14 INFO - PROCESS | 1633 | _x_x_mozilla_cycle_collect,380 02:33:14 INFO - PROCESS | 1633 | __end_cc_report 02:33:14 INFO - PROCESS | 1633 | __startTimestamp1496914394257__endTimestamp 02:33:14 INFO - PROCESS | 1633 | 02:33:14 INFO - PROCESS | 1633 | ------- Summary: start ------- 02:33:14 INFO - PROCESS | 1633 | Number of tests: 6 02:33:14 INFO - PROCESS | 1633 | 02:33:14 INFO - PROCESS | 1633 | [#0] dojo.html Cycles:165 Average:5941.27 Median:6237.13 stddev:687.46 (11.0%) stddev-sans-first:656.10 02:33:14 INFO - PROCESS | 1633 | Values: 3239.9 3501.4 3513.8 3514.8 3520.8 6005.3 6029.3 6056.9 6079.4 6094.8 6077.8 6080.0 6111.2 6143.2 6152.4 5432.3 5489.4 5496.9 5506.3 5580.9 4763.0 5266.6 5437.6 5572.5 5610.6 3939.0 5344.2 5358.2 5432.8 5437.6 5526.8 5540.6 5556.9 5581.4 5599.0 5431.6 5463.1 5476.8 5500.2 5517.3 5761.2 5820.9 6013.4 6083.5 6182.1 6299.6 6312.1 6314.7 6322.2 6431.8 6519.7 6527.8 6539.8 6542.7 6561.3 6120.5 6175.1 6237.1 6239.5 6256.0 6272.9 6350.4 6352.3 6539.3 6613.8 6289.2 6319.1 6335.4 6340.7 6346.4 5299.6 6482.7 6494.0 6498.0 6506.9 6253.3 6286.6 6297.1 6414.7 6429.5 6211.5 6278.4 6385.5 6416.1 6454.6 4916.0 4920.2 4962.4 4965.7 4976.1 4432.7 4435.7 4459.6 4473.9 4513.6 5705.7 5801.2 5982.5 5996.0 6019.6 6330.8 6332.8 6401.2 6416.0 6465.8 6006.6 6010.7 6012.7 6039.1 6049.8 5661.7 5671.6 5676.3 5680.5 5682.2 6312.7 6312.9 6492.9 6525.4 6654.4 6430.9 6477.8 6505.4 6508.9 6518.4 5422.9 6652.1 6658.0 6663.7 6666.6 6251.8 6261.8 6315.6 6405.9 6436.6 5411.0 6313.1 6420.7 6514.1 6525.5 6307.2 6325.2 6325.3 6326.3 6361.5 6230.0 6254.0 6255.4 6270.0 6334.2 6202.3 6226.7 6269.5 6275.5 6320.6 6311.8 6328.1 6333.5 6334.4 6336.1 6129.4 6136.9 6193.6 6261.4 6290.7 02:33:14 INFO - PROCESS | 1633 | 02:33:14 INFO - PROCESS | 1633 | [#1] ext.html Cycles:180 Average:48207.09 Median:29345.43 stddev:90578.46 (308.7%) stddev-sans-first:90762.57 02:33:14 INFO - PROCESS | 1633 | Values: 782.5 825.9 845.2 882.9 965.5 39555.8 40272.2 41645.0 41935.8 42110.4 40490.4 40674.8 40731.8 40803.0 40857.8 8119.7 11000.8 11325.5 11529.4 11533.5 11055.2 11102.3 11117.4 11178.6 11182.6 9389.5 11128.8 11208.8 11208.9 11224.9 11095.8 11129.4 11152.5 11162.9 11166.3 9140.1 10880.2 10925.2 10944.9 10974.6 27649.0 34397.8 34730.0 34913.3 35022.6 3868.2 3905.5 3936.4 3941.2 3966.5 23567.4 25500.9 25527.5 26968.2 27111.5 3186.4 3195.7 3239.2 3319.1 3328.3 255872.7 306268.0 341182.0 343411.0 357981.0 40775.0 41068.8 41092.4 41260.4 41270.6 43733.6 43761.8 43784.6 43845.3 43916.8 13438.3 13561.9 13570.2 13581.5 13583.9 11864.6 11915.9 12103.5 12117.1 12148.0 11452.6 11466.8 11483.6 11492.2 11586.3 4710.2 5581.6 5675.3 5677.8 5690.3 1013.6 1103.6 1161.3 1175.7 1180.4 21005.7 26694.6 26783.9 27145.9 27156.7 1222.5 1234.6 1246.2 1274.0 1283.9 11629.5 12789.6 13705.6 14098.8 14357.8 365820.0 366312.0 366406.0 368444.0 368488.0 54300.5 54596.2 54804.0 54849.0 54943.7 1428.7 1473.4 1476.5 1486.3 1489.0 22829.9 24967.4 25065.5 26620.9 26621.9 41436.6 41858.8 41949.0 41971.0 41977.0 339274.6 339789.0 339868.0 340001.0 340977.0 29288.5 29900.4 31061.7 31103.7 31259.8 38693.2 39842.6 39849.0 40008.6 41026.3 27697.6 28227.7 28565.4 28985.4 29436.6 21953.6 26817.1 28574.7 28647.6 29402.4 33196.6 33458.5 33581.8 33626.3 33888.2 28798.0 28883.7 29101.3 29565.6 29658.7 22213.8 23223.2 23402.1 23507.3 23584.2 02:33:14 INFO - PROCESS | 1633 | 02:33:14 INFO - PROCESS | 1633 | [#2] jquery.html Cycles:180 Average:45035.88 Median:21944.06 stddev:131951.02 (601.3%) stddev-sans-first:132278.62 02:33:14 INFO - PROCESS | 1633 | Values: 408.5 417.8 421.6 430.1 454.7 18970.9 20516.0 21283.4 21865.0 21893.5 21590.0 21914.8 22386.0 22570.3 22702.1 5217.4 6991.0 6996.3 7035.9 7042.6 6522.8 6902.3 6945.4 6956.7 6957.7 6598.6 6869.6 6918.9 6953.4 6964.1 6906.9 6951.0 6961.9 6970.7 6993.2 7064.8 7414.4 7494.9 7535.9 7540.3 25165.6 25502.2 26273.2 26521.0 26784.5 3266.3 3339.6 3395.8 3428.9 3440.2 15886.4 16025.5 16048.6 16152.4 16163.0 1948.1 2026.3 2107.5 2134.3 2167.8 132839.0 147888.3 158972.0 172193.1 173808.0 16917.8 21028.5 21466.9 21512.8 21794.8 31246.5 31508.7 31788.8 31792.7 32002.5 9733.9 9963.4 9975.3 10042.8 10147.0 8249.9 8282.9 8286.7 8358.2 8438.4 9450.1 9607.8 9623.8 9649.2 9697.4 4149.3 4294.9 4311.0 4311.6 4313.7 1090.9 1091.6 1106.5 1115.5 1167.9 18326.0 21973.3 22035.7 23197.7 24191.4 965.6 967.2 972.3 1004.6 1013.3 10429.6 10977.1 11032.9 11073.3 11078.3 781591.0 786524.0 790722.0 791066.0 799104.0 150992.0 161295.0 171835.0 172588.0 177486.0 1290.3 1314.3 1345.0 1345.4 1346.7 14064.6 17799.7 18599.6 18889.9 19015.0 47826.0 64540.0 65905.0 66050.7 67912.0 98913.5 99305.5 99801.0 99836.0 102243.0 9629.7 10928.0 11508.3 11745.9 11987.6 11289.9 13052.0 13093.1 13208.3 13497.9 9655.5 11089.0 11497.3 11590.4 11669.6 10645.9 10717.0 10803.6 10853.2 11107.3 11499.7 11562.0 11765.7 11803.6 11846.5 11560.9 11625.5 11663.8 11783.4 11903.6 11317.8 11330.6 11594.9 11625.7 11692.1 02:33:14 INFO - PROCESS | 1633 | 02:33:14 INFO - PROCESS | 1633 | [#3] mootools.html Cycles:180 Average:6721.60 Median:2324.32 stddev:4290.29 (184.6%) stddev-sans-first:4285.08 02:33:14 INFO - PROCESS | 1633 | Values: 1601.5 1627.7 1631.7 1632.8 1642.1 10698.7 11213.3 11399.8 11419.4 11562.9 11153.6 11217.0 11397.3 11403.4 11404.6 2276.8 2304.2 2322.0 2326.6 2326.7 1725.4 1775.9 2101.9 2150.7 2294.0 2036.5 2194.7 2237.3 2237.4 2240.1 1922.9 2274.1 2274.2 2279.5 2281.2 2249.4 2278.7 2280.9 2296.6 2301.4 11039.7 11325.6 11352.9 11354.8 11360.3 5388.5 5513.0 5538.6 5555.4 5557.4 3035.8 3269.9 3368.9 3401.7 3402.7 1061.7 1092.1 1102.6 1146.5 1146.7 15461.6 15464.0 15611.6 15612.5 15614.5 4169.6 4293.2 4383.5 4386.4 4391.7 11665.1 11799.6 11804.5 11819.9 11828.8 5389.4 5419.7 5423.6 5441.4 5446.7 4936.3 4938.5 4975.1 5049.9 5067.9 2998.7 3051.3 3631.3 3719.4 3730.8 920.9 923.3 923.8 931.8 936.8 830.3 941.3 942.4 960.8 1017.9 6500.9 6559.3 6588.7 6802.1 7014.2 962.2 962.7 1007.5 1080.8 1113.8 4226.7 4369.0 4433.9 4590.5 4591.3 10054.5 13405.3 13423.8 14003.5 14021.7 11860.3 11906.7 13627.7 13937.9 13942.3 9989.0 10185.7 10392.4 10455.4 10495.6 6870.9 6955.7 7183.4 7204.4 7250.7 10953.8 12145.8 12176.3 12233.2 12334.6 12514.2 12675.5 12677.6 12839.3 12864.4 9215.2 9449.6 9481.2 9494.2 9503.8 10147.6 10370.4 10465.4 10550.4 10631.1 9019.9 9108.7 9180.4 9191.2 9283.9 8820.3 8857.0 8892.0 8917.3 8968.1 7478.7 7999.1 8078.1 8085.9 8093.2 5884.4 5934.8 5937.3 6049.2 6126.9 5823.1 5968.1 6096.4 6232.7 6300.1 02:33:14 INFO - PROCESS | 1633 | 02:33:14 INFO - PROCESS | 1633 | [#4] prototype.html Cycles:180 Average:4626.77 Median:4969.15 stddev:1307.69 (26.3%) stddev-sans-first:1272.10 02:33:14 INFO - PROCESS | 1633 | Values: 389.4 396.1 398.1 399.8 401.6 5344.4 5359.3 5398.6 5404.7 5429.3 5030.2 5267.2 5283.0 5283.4 5329.4 3930.0 3986.1 4112.0 4127.3 4134.3 4141.8 4179.6 4203.1 4221.2 4240.0 4023.4 4130.6 4166.1 4215.8 4237.9 4080.8 4135.5 4140.4 4146.5 4151.6 3366.3 3405.7 3424.0 3693.3 3713.9 4441.1 4813.0 4987.4 5021.6 5123.4 4333.4 4444.4 4618.3 4733.8 4764.0 4601.6 4911.4 5185.8 5587.5 5709.5 4131.9 4135.9 4144.7 4171.9 4176.0 4561.4 5015.2 5781.5 5854.8 5967.9 4024.8 4531.5 4687.1 4694.3 4732.5 4119.9 4703.1 4733.9 4737.9 4757.6 4553.4 4749.8 5077.0 5102.7 5120.6 4895.5 4944.9 5479.4 5521.5 5688.3 1495.3 1519.8 1587.3 1610.3 1630.1 984.0 1176.5 1214.2 1218.5 1235.1 4193.7 4875.9 4954.8 5081.0 5211.6 5081.0 5451.5 5837.2 5861.3 5933.5 4666.5 4699.3 4724.2 4737.8 4815.7 2780.4 2801.8 3039.7 3092.2 3122.8 5366.1 5635.9 5993.5 6205.5 6206.0 5006.5 5286.8 5415.4 6091.3 6103.6 5448.8 5556.6 6197.0 6213.5 6239.8 4952.3 5523.9 5556.1 5925.4 6012.0 5264.4 5613.8 5726.9 5774.5 5851.9 5005.7 5330.4 5709.3 5898.7 5899.4 3969.8 4166.5 4599.9 4643.4 4693.4 5027.6 5650.9 5735.3 5804.6 5920.5 4914.5 4929.8 5302.9 5570.0 5713.3 4674.4 5478.0 5490.5 5666.8 5724.9 5114.9 5231.0 5234.4 5348.8 5506.5 4983.5 5049.9 5106.8 5637.9 5783.7 4839.8 5390.4 5588.7 5592.4 5594.5 02:33:14 INFO - PROCESS | 1633 | 02:33:14 INFO - PROCESS | 1633 | [#5] yui.html Cycles:180 Average:20524.72 Median:23141.59 stddev:28941.64 (125.1%) stddev-sans-first:28984.65 02:33:14 INFO - PROCESS | 1633 | Values: 727.0 751.8 783.4 839.9 854.6 15882.4 16510.2 16889.8 17107.6 17271.8 15307.0 15310.9 15705.0 15809.1 15853.8 977.9 993.1 1000.8 1001.0 1004.2 969.7 995.0 997.1 1006.6 1007.3 1059.3 1061.4 1063.3 1064.1 1065.4 1052.5 1052.6 1056.4 1057.6 1059.9 787.7 789.6 790.3 791.9 792.7 16815.3 16949.0 16949.4 17233.7 17406.7 11585.9 14212.7 14278.0 14280.9 14318.0 11913.0 11923.8 11996.8 12058.9 12063.6 3635.9 3665.8 3721.3 3735.4 3879.3 108296.0 111212.9 114841.0 115842.0 117857.0 10871.6 10945.7 10981.8 11031.9 11106.0 22775.2 22989.7 23293.5 23382.5 23412.0 7818.8 7908.9 7925.6 7945.4 7957.0 4175.2 4413.8 4450.7 4473.4 4496.1 3454.6 3475.1 3503.7 3526.9 3536.5 626.9 630.6 642.3 643.3 655.9 641.2 662.0 673.8 677.7 689.6 8890.6 9819.5 9868.4 9880.4 9960.0 638.1 639.7 659.5 681.3 690.4 4828.7 4831.0 4860.3 4905.6 4920.8 84471.0 97974.5 98653.0 103766.0 108491.5 59072.0 74626.6 83639.0 90070.0 91787.5 57643.1 66171.7 77629.2 79873.0 81338.0 10912.6 10994.6 11374.1 11374.5 11375.1 49836.5 50258.5 51489.9 51619.7 52589.7 64695.0 66947.7 67481.3 68104.0 68437.7 11209.8 14362.2 14480.3 14629.3 14685.7 13361.6 14204.9 15027.3 15282.0 15560.5 13828.2 14137.2 14438.2 15512.3 15685.0 14099.3 14503.6 14550.9 14634.6 15043.5 13710.6 14100.9 14237.7 14438.1 14625.8 9564.6 9861.6 10256.5 10286.4 10699.5 9948.7 11653.7 11687.9 12071.6 12128.3 02:33:14 INFO - PROCESS | 1633 | -------- Summary: end -------- 02:33:14 INFO - PROCESS | 1633 | 02:33:14 INFO - TEST-INFO | 1633: exit 0 02:33:14 INFO - TEST-OK | dromaeo_css | took 1116225ms 02:33:14 INFO - TEST-START | kraken 02:33:14 INFO - operating with platform_type : mac_ 02:33:14 INFO - Initialising browser for kraken test... 02:33:14 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpi0sOnk/profile http://localhost:49222/getInfo.html) 02:33:17 INFO - PROCESS | 1646 | 2017-06-08 02:33:17.248 plugin-container[1648:10288] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 02:33:17 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:33:17 INFO - PROCESS | 1646 | 2017-06-08 02:33:17.249 plugin-container[1648:10288] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 02:33:17 INFO - PROCESS | 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:33:17 INFO - PROCESS | 1646 | 2017-06-08 02:33:17.249 plugin-container[1648:10288] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:33:17 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 02:33:17 INFO - PROCESS | 1646 | colorDepth:24 02:33:17 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 02:33:17 INFO - PROCESS | 1646 | __metrics 02:33:17 ERROR - PROCESS | 1646 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3381: TypeError: this.tabs is undefined 02:33:18 INFO - TEST-INFO | 1646: exit 0 02:33:18 INFO - Browser initialized. 02:33:18 INFO - Running cycle 1/1 for kraken test... 02:33:18 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/T/tmpi0sOnk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:33:20 INFO - PROCESS | 1649 | 2017-06-08 02:33:20.498 plugin-container[1650:10409] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x884b, name = 'com.apple.tsm.portname' 02:33:20 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:33:20 INFO - PROCESS | 1649 | 2017-06-08 02:33:20.499 plugin-container[1650:10409] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 02:33:20 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:33:20 INFO - PROCESS | 1649 | 2017-06-08 02:33:20.499 plugin-container[1650:10409] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:33:30 INFO - PROCESS | 1649 | RSS: Main: 202838016 02:33:30 INFO - PROCESS | 1649 | 02:33:33 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-beat-detection) 02:33:34 INFO - PROCESS | 1649 | RSS: Main: 216420352 02:33:34 INFO - PROCESS | 1649 | 02:33:36 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-dft) 02:33:37 INFO - PROCESS | 1649 | RSS: Main: 224333824 02:33:37 INFO - PROCESS | 1649 | 02:33:40 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-fft) 02:33:41 INFO - PROCESS | 1649 | RSS: Main: 227885056 02:33:41 INFO - PROCESS | 1649 | 02:33:43 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-oscillator) 02:33:44 INFO - PROCESS | 1649 | RSS: Main: 228245504 02:33:44 INFO - PROCESS | 1649 | 02:33:46 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:33:46 INFO - PROCESS | 1649 | RSS: Main: 229527552 02:33:46 INFO - PROCESS | 1649 | 02:33:49 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-darkroom) 02:33:50 INFO - PROCESS | 1649 | RSS: Main: 233353216 02:33:50 INFO - PROCESS | 1649 | 02:33:53 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-desaturate) 02:33:53 INFO - PROCESS | 1649 | RSS: Main: 232706048 02:33:53 INFO - PROCESS | 1649 | 02:33:57 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49222/tests/kraken/driver.html?testName=json-parse-financial) 02:33:57 INFO - PROCESS | 1649 | RSS: Main: 232869888 02:33:57 INFO - PROCESS | 1649 | 02:33:59 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49222/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:34:00 INFO - PROCESS | 1649 | RSS: Main: 232570880 02:34:00 INFO - PROCESS | 1649 | 02:34:02 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:34:02 INFO - PROCESS | 1649 | RSS: Main: 232595456 02:34:02 INFO - PROCESS | 1649 | 02:34:04 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:34:05 INFO - PROCESS | 1649 | RSS: Main: 232628224 02:34:05 INFO - PROCESS | 1649 | 02:34:08 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:34:09 INFO - PROCESS | 1649 | RSS: Main: 232988672 02:34:09 INFO - PROCESS | 1649 | 02:34:12 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:34:12 INFO - PROCESS | 1649 | RSS: Main: 232734720 02:34:12 INFO - PROCESS | 1649 | 02:34:14 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49222/tests/kraken/driver.html?testName=ai-astar) 02:34:14 INFO - PROCESS | 1649 | RSS: Main: 234622976 02:34:14 INFO - PROCESS | 1649 | 02:34:14 INFO - PROCESS | 1649 | __start_tp_report 02:34:14 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 02:34:14 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 02:34:14 INFO - PROCESS | 1649 | |i|pagename|runs| 02:34:14 INFO - PROCESS | 1649 | |0;ai-astar;117;120;121;118;123;169;175;145;120;121 02:34:14 INFO - PROCESS | 1649 | |1;audio-beat-detection;162;150;111;150;94;138;169;113;131;127 02:34:14 INFO - PROCESS | 1649 | |2;audio-dft;229;262;200;197;199;198;223;229;196;196 02:34:14 INFO - PROCESS | 1649 | |3;audio-fft;86;63;63;91;61;69;57;69;99;54 02:34:14 INFO - PROCESS | 1649 | |4;audio-oscillator;88;72;86;74;71;72;81;71;86;106 02:34:14 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;89;89;97;88;103;88;89;117;133;90 02:34:14 INFO - PROCESS | 1649 | |6;imaging-darkroom;97;97;97;102;96;96;99;97;140;97 02:34:14 INFO - PROCESS | 1649 | |7;imaging-desaturate;127;140;117;91;92;86;90;94;123;91 02:34:14 INFO - PROCESS | 1649 | |8;json-parse-financial;64;64;64;66;67;68;67;68;66;69 02:34:14 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;57;76;98;58;75;82;87;84;85;84 02:34:14 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;89;82;87;88;82;84;85;89;89;105 02:34:14 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;138;138;144;128;141;130;144;150;162;143 02:34:14 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;166;167;193;172;170;169;164;167;166;169 02:34:14 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;66;66;68;66;68;63;72;76;85;85 02:34:14 INFO - PROCESS | 1649 | __end_tp_report 02:34:14 INFO - PROCESS | 1649 | __start_cc_report 02:34:14 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1019 02:34:14 INFO - PROCESS | 1649 | __end_cc_report 02:34:14 INFO - PROCESS | 1649 | __startTimestamp1496914454668__endTimestamp 02:34:14 INFO - PROCESS | 1649 | 02:34:14 INFO - PROCESS | 1649 | ------- Summary: start ------- 02:34:14 INFO - PROCESS | 1649 | Number of tests: 14 02:34:14 INFO - PROCESS | 1649 | 02:34:14 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:132.90 Median:122.00 stddev:22.14 (18.1%) stddev-sans-first:22.72 02:34:14 INFO - PROCESS | 1649 | Values: 117.0 120.0 121.0 118.0 123.0 169.0 175.0 145.0 120.0 121.0 02:34:14 INFO - PROCESS | 1649 | 02:34:14 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:134.50 Median:150.00 stddev:23.95 (16.0%) stddev-sans-first:23.24 02:34:14 INFO - PROCESS | 1649 | Values: 162.0 150.0 111.0 150.0 94.0 138.0 169.0 113.0 131.0 127.0 02:34:14 INFO - PROCESS | 1649 | 02:34:14 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:212.90 Median:211.50 stddev:22.20 (10.5%) stddev-sans-first:22.77 02:34:14 INFO - PROCESS | 1649 | Values: 229.0 262.0 200.0 197.0 199.0 198.0 223.0 229.0 196.0 196.0 02:34:14 INFO - PROCESS | 1649 | 02:34:14 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:71.20 Median:69.00 stddev:15.38 (22.3%) stddev-sans-first:15.36 02:34:14 INFO - PROCESS | 1649 | Values: 86.0 63.0 63.0 91.0 61.0 69.0 57.0 69.0 99.0 54.0 02:34:14 INFO - PROCESS | 1649 | 02:34:14 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:80.70 Median:77.50 stddev:11.23 (14.5%) stddev-sans-first:11.59 02:34:14 INFO - PROCESS | 1649 | Values: 88.0 72.0 86.0 74.0 71.0 72.0 81.0 71.0 86.0 106.0 02:34:14 INFO - PROCESS | 1649 | 02:34:14 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:98.30 Median:89.00 stddev:15.34 (17.2%) stddev-sans-first:15.90 02:34:14 INFO - PROCESS | 1649 | Values: 89.0 89.0 97.0 88.0 103.0 88.0 89.0 117.0 133.0 90.0 02:34:14 INFO - PROCESS | 1649 | 02:34:14 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:101.80 Median:97.00 stddev:13.54 (14.0%) stddev-sans-first:14.25 02:34:14 INFO - PROCESS | 1649 | Values: 97.0 97.0 97.0 102.0 96.0 96.0 99.0 97.0 140.0 97.0 02:34:14 INFO - PROCESS | 1649 | 02:34:14 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:105.10 Median:90.50 stddev:19.56 (21.6%) stddev-sans-first:19.08 02:34:14 INFO - PROCESS | 1649 | Values: 127.0 140.0 117.0 91.0 92.0 86.0 90.0 94.0 123.0 91.0 02:34:14 INFO - PROCESS | 1649 | 02:34:14 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:66.30 Median:67.00 stddev:1.83 (2.7%) stddev-sans-first:1.74 02:34:14 INFO - PROCESS | 1649 | Values: 64.0 64.0 64.0 66.0 67.0 68.0 67.0 68.0 66.0 69.0 02:34:14 INFO - PROCESS | 1649 | 02:34:14 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:78.60 Median:84.00 stddev:12.77 (15.2%) stddev-sans-first:10.90 02:34:14 INFO - PROCESS | 1649 | Values: 57.0 76.0 98.0 58.0 75.0 82.0 87.0 84.0 85.0 84.0 02:34:14 INFO - PROCESS | 1649 | 02:34:14 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:88.00 Median:87.50 stddev:6.58 (7.5%) stddev-sans-first:6.97 02:34:14 INFO - PROCESS | 1649 | Values: 89.0 82.0 87.0 88.0 82.0 84.0 85.0 89.0 89.0 105.0 02:34:14 INFO - PROCESS | 1649 | 02:34:14 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:141.80 Median:143.50 stddev:9.69 (6.8%) stddev-sans-first:10.18 02:34:14 INFO - PROCESS | 1649 | Values: 138.0 138.0 144.0 128.0 141.0 130.0 144.0 150.0 162.0 143.0 02:34:14 INFO - PROCESS | 1649 | 02:34:14 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.30 Median:169.00 stddev:8.30 (4.9%) stddev-sans-first:8.66 02:34:14 INFO - PROCESS | 1649 | Values: 166.0 167.0 193.0 172.0 170.0 169.0 164.0 167.0 166.0 169.0 02:34:14 INFO - PROCESS | 1649 | 02:34:14 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.50 Median:70.00 stddev:7.98 (11.4%) stddev-sans-first:8.21 02:34:14 INFO - PROCESS | 1649 | Values: 66.0 66.0 68.0 66.0 68.0 63.0 72.0 76.0 85.0 85.0 02:34:14 INFO - PROCESS | 1649 | -------- Summary: end -------- 02:34:14 INFO - PROCESS | 1649 | 02:34:15 INFO - TEST-INFO | 1649: exit 0 02:34:15 INFO - TEST-OK | kraken | took 60616ms 02:34:15 INFO - SUITE-END | took 1176s 02:34:15 INFO - Completed test suite (00:19:37) 02:34:15 INFO - javascript benchmark 02:34:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7717.817043570059, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3239.8704051837926, 3501.369753444379, 3513.7891726496414, 3514.80668563229, 3520.806355650438, 6005.339412664609, 6029.336772954977, 6056.8788624227545, 6079.392060793918, 6094.847628809271, 6077.848053798657, 6079.969600151993, 6111.174991376163, 6143.201383820097, 6152.354002829706, 5432.348118225818, 5489.39616642169, 5496.862578435531, 5506.284183056197, 5580.888382232353, 4763.0, 5266.631335806495, 5437.6465529740635, 5572.5263352614875, 5610.635308704942, 3938.995556133182, 5344.198370244475, 5358.169483730008, 5432.75552600132, 5437.592180586452, 5526.778928842861, 5540.612157148961, 5556.86107847303, 5581.358143813472, 5599.0, 5431.646942948696, 5463.125899856043, 5476.7535460904355, 5500.202470641734, 5517.310336207974, 5761.193432919395, 5820.86493133839, 6013.398660133952, 6083.513318934474, 6182.103594978747, 6299.559030867841, 6312.116303717483, 6314.747410103636, 6322.241331040304, 6431.807045788634, 6519.739210431577, 6527.804165875032, 6539.803805885831, 6542.672866356711, 6561.2782593914635, 6120.540959427972, 6175.135481032659, 6237.126802247689, 6239.50083993284, 6255.968720156416, 6272.905906411407, 6350.396712312323, 6352.301246862841, 6539.346065393518, 6613.801585952429, 6289.182406287154, 6319.115323854664, 6335.3981371769605, 6340.651264180422, 6346.365363463709, 5299.5760339172775, 6482.67586620667, 6493.967530162414, 6498.0, 6506.869862602722, 6253.312135665074, 6286.591371560926, 6297.118403423525, 6414.743410263631, 6429.485641148697, 6211.472024877936, 6278.372162783685, 6385.521085918482, 6416.101745755598, 6454.580452270588, 4916.041371932439, 4920.212765957501, 4962.379702537183, 4965.677230979974, 4976.0794253063295, 4432.711873728197, 4435.689501734848, 4459.620932220798, 4473.910521789546, 4513.638908887281, 5705.71471426427, 5801.245838041028, 5982.46157845796, 5996.040633498619, 6019.578629495981, 6330.8100756977365, 6332.778352757631, 6401.167848179707, 6415.967920160369, 6465.838354041187, 6006.639601623917, 6010.729517171738, 6012.729427175787, 6039.094135879565, 6049.758009679564, 5661.688607126647, 5671.602987790815, 5676.290463692038, 5680.5455563554815, 5682.176084467729, 6312.715927783169, 6312.873742525032, 6492.870142597028, 6525.3800888915475, 6654.434373078246, 6430.935690643034, 6477.838054048687, 6505.41451269388, 6508.902366464602, 6518.445932095636, 5422.9186562202385, 6652.135222421054, 6658.0, 6663.733450661919, 6666.633335166613, 6251.78118765841, 6261.812145635638, 6315.5894866833505, 6405.8718825624155, 6436.613803171825, 5410.97294513525, 6313.116163737172, 6420.7431702731365, 6514.120593719787, 6525.477961762953, 6307.211598550181, 6325.240971083499, 6325.272593651789, 6326.3041065483685, 6361.522885783492, 6230.034344676583, 6254.030625253093, 6255.37446255389, 6270.0, 6334.239891212899, 6202.317745048132, 6226.719797609209, 6269.529785266214, 6275.497960163268, 6320.5575609706875, 6311.810645680637, 6328.0507923809955, 6333.461655759312, 6334.3665633436285, 6336.081268216175, 6129.389354848411, 6136.907946380718, 6193.628382297076, 6261.373862613702, 6290.685465726787], "value": 5897.127686320648, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [782.4718315137285, 825.8637324841402, 845.2097289034755, 882.8984666763316, 965.4979410706433, 39555.813325600204, 40272.19455610886, 41644.95887602811, 41935.79032104843, 42110.36834447486, 40490.38038478457, 40674.77975660728, 40731.79634101833, 40803.0, 40857.79571102148, 8119.715809946668, 11000.779984400308, 11325.490352934137, 11529.423528823567, 11533.538658453652, 11055.22613417061, 11102.333859968387, 11117.444127793618, 11178.608748693798, 11182.552697892075, 9389.483578403186, 11128.777424451506, 11208.831867521994, 11208.887911120866, 11224.887751122465, 11095.833562496488, 11129.387883666395, 11152.498137583829, 11162.888371116265, 11166.274192177483, 9140.131687489678, 10880.183986201067, 10925.235233533656, 10944.945275273572, 10974.561017559288, 27649.0, 34397.82801086004, 34730.0, 34913.30173396543, 35022.649773502446, 3868.2456920900436, 3905.531336239641, 3936.3898595717424, 3941.1920556286177, 3966.5240171179353, 23567.41081472977, 25500.872495637403, 25527.48945021089, 26968.190954271402, 27111.457770844474, 3186.3786561620495, 3195.6964088411796, 3239.2387788869596, 3319.0872510059685, 3328.3176948725454, 255872.72063639748, 306268.0, 341182.0, 343411.0, 357981.0, 40775.0, 41068.794656026526, 41092.38361424581, 41260.381094283606, 41270.587294127276, 43733.56266437295, 43761.78119109448, 43784.56215437805, 43845.34231986523, 43916.78041609772, 13438.328083595781, 13561.915046796232, 13570.185788852701, 13581.524646637321, 13583.932080339733, 11864.644060678194, 11915.940420298017, 12103.515859365527, 12117.091218158497, 12148.0, 11452.568428946382, 11466.770664586662, 11483.598074067368, 11492.195546311845, 11586.304821710724, 4710.222813235846, 5581.553475722014, 5675.347335056439, 5677.801276955287, 5690.260266165372, 1013.6249587652616, 1103.5971870267456, 1161.280006396023, 1175.747214348902, 1180.3508070561156, 21005.68491472629, 26694.5995810063, 26783.929642214516, 27145.864270678914, 27156.72843271542, 1222.4865556466304, 1234.6419538333962, 1246.1526162209602, 1273.9681507962375, 1283.9101262911506, 11629.47667354971, 12789.61631151067, 13705.588832335046, 14098.788518172236, 14357.78463323051, 365820.0, 366312.0, 366406.0, 368444.0, 368488.0, 54300.45699542954, 54596.18105728417, 54804.0, 54849.0, 54943.725281374136, 1428.6999730056805, 1473.3517252408906, 1476.512750792236, 1486.31629450454, 1489.0098084773722, 22829.88585057064, 24967.375815604755, 25065.49869002573, 26620.866895665396, 26621.866890665424, 41436.58563414448, 41858.790706046275, 41948.95127621834, 41970.95072623209, 41977.0, 339274.6072539342, 339789.0, 339868.0, 340001.0, 340977.0, 29288.457620971385, 29900.401991960473, 31061.68938310588, 31103.688963110984, 31259.84370078135, 38693.22613547769, 39842.60157398505, 39849.0, 40008.599699010396, 41026.2816028787, 27697.584536232374, 28227.71772282333, 28565.42869142564, 28985.42029159363, 29436.558451622815, 21953.560928781655, 26817.061402850806, 28574.71413786343, 28647.5702864453, 29402.411951760416, 33196.621718796734, 33458.49812252867, 33581.83209083939, 33626.32747344991, 33888.15279618029, 28798.0, 28883.711162888943, 29101.272468188465, 29565.556516652698, 29658.703412966457, 22213.77786222117, 23223.187188448323, 23402.07824203782, 23507.294781156594, 23584.17455389053], "value": 18004.543663025626, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.53427093113856, 417.75978812183, 421.5868448920056, 430.0925048148407, 454.727163701779, 18970.90514547427, 20516.0, 21283.36149915503, 21865.0, 21893.452663683416, 21590.028448719808, 21914.780852191474, 22386.0, 22570.322890313277, 22702.091916323327, 5217.37391513019, 6991.0, 6996.3003699630135, 7035.894461583055, 7042.6126563039015, 6522.771702990396, 6902.344277293649, 6945.374916257536, 6956.721731130749, 6957.7216911323485, 6598.637074960875, 6869.622170780605, 6918.896216556731, 6953.443724502028, 6964.094667693193, 6906.930930690679, 6951.0, 6961.930380696179, 6970.651467426608, 6993.230744618088, 7064.752733654349, 7414.443916706268, 7494.925050749477, 7535.9246407535775, 7540.3213710766295, 25165.62251566228, 25502.23493295204, 26273.21180364592, 26521.0, 26784.464310713873, 3266.3140740444537, 3339.6493368196475, 3395.7962522248745, 3428.8856121760277, 3440.2431465077643, 15886.36454541817, 16025.519234422869, 16048.598785030352, 16152.434664786677, 16163.0, 1948.0551932312958, 2026.280670362015, 2107.4942013916702, 2134.253011445997, 2167.7723838996835, 132839.0, 147888.2605586976, 158972.0, 172193.1390343053, 173808.0, 16917.83082169187, 21028.47428814292, 21466.892665536572, 21512.78487215139, 21794.78205217959, 31246.53130203049, 31508.684913151035, 31788.84105579457, 31792.68207317897, 32002.519962200586, 9733.853992190123, 9963.402195868272, 9975.25185611082, 10042.799144017079, 10147.0, 8249.917500824915, 8282.875756863652, 8286.70996515119, 8358.164183581715, 8438.409311348147, 9450.102240287162, 9607.790208252141, 9623.807523849484, 9649.179819715402, 9697.369670971364, 4149.33610622299, 4294.914101717948, 4311.0, 4311.585512113226, 4313.719608225455, 1090.8909109089186, 1091.6452153050132, 1106.468894930438, 1115.548202977779, 1167.9124065695107, 18326.0, 21973.340799776033, 22035.66946495804, 23197.652035219486, 24191.39521511941, 965.6475386484024, 967.1875624475474, 972.3048020665258, 1004.598160735697, 1013.2856336282903, 10429.582816687247, 10977.12183025356, 11032.889671103347, 11073.335599864035, 11078.279911805868, 781591.0, 786524.0, 790722.0, 791066.0, 799104.0044799739, 150992.0, 161295.0, 171835.0, 172588.0, 177486.0, 1290.3354772292278, 1314.2640121531972, 1345.0382976172084, 1345.4281930179634, 1346.703725180439, 14064.578062658136, 17799.73300400521, 18599.628007439504, 18889.90555047216, 19015.049247537845, 47826.0, 64540.0, 65905.01142482771, 66050.66974665096, 67912.0, 98913.50543247238, 99305.50347248217, 99801.0, 99836.00163998558, 102243.0, 9629.662961796303, 10928.01647851697, 11508.309501429942, 11745.88254117482, 11987.580434684744, 11289.879468723137, 13052.0, 13093.083484156017, 13208.27354495512, 13497.86502134966, 9655.517224138905, 11089.0, 11497.252678575866, 11590.362530060927, 11669.59156429521, 10645.89354106449, 10717.0, 10803.567857285621, 10853.186011049362, 11107.278026928223, 11499.655010349703, 11562.0, 11765.705687390877, 11803.586874459354, 11846.466908989289, 11560.884391155982, 11625.47685354178, 11663.825042624538, 11783.351915644378, 11903.642890713292, 11317.830232546683, 11330.60342887995, 11594.94202528982, 11625.67486502686, 11692.123090768056], "value": 11851.473931061017, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1601.511538980612, 1627.7232870412029, 1631.747079202724, 1632.8040635123784, 1642.0722291905076, 10698.732531686703, 11213.327200367983, 11399.829002564968, 11419.371934543607, 11562.942185289061, 11153.553857845696, 11217.0, 11397.316161030323, 11403.429828508562, 11404.600838970639, 2276.7950884420397, 2304.1704986204954, 2322.036354912709, 2326.6044772388736, 2326.732425771041, 1725.4219836354837, 1775.937842175524, 2101.8528702990798, 2150.7204063471727, 2293.9885300573555, 2036.5417780999305, 2194.670799380098, 2237.284069097889, 2237.3735354100877, 2240.10395841663, 1922.9134688939032, 2274.1471948019494, 2274.1812947338944, 2279.521300526883, 2281.235786011688, 2249.3926639807323, 2278.7493375728654, 2280.8859557022083, 2296.632538793785, 2301.3786277704926, 11039.72400689981, 11325.603603873826, 11352.94323528377, 11354.772904541946, 11360.261582997078, 5388.515033646951, 5513.035218836728, 5538.584606154514, 5555.416681248492, 5557.3886872444, 3035.7723170762283, 3269.885554005622, 3368.932621347584, 3401.693847553707, 3402.7447941404494, 1061.6921092883056, 1092.1099304067238, 1102.608573956242, 1146.4668928947997, 1146.696125526736, 15461.613459663486, 15464.0, 15611.609709757233, 15612.4535641252, 15614.531564053097, 4169.60388763067, 4293.205756934977, 4383.517813040563, 4386.385905973167, 4391.692581519263, 11665.125115616363, 11799.646010619696, 11804.527818887149, 11819.940900295616, 11828.822567661491, 5389.434109418533, 5419.701916394636, 5423.5932305076485, 5441.374241962146, 5446.700431476228, 4936.259561065869, 4938.506149385033, 4975.104481193348, 5049.8737531562, 5067.923981140286, 2998.670146283907, 3051.343961048385, 3631.2737452509077, 3719.423489359154, 3730.813459327023, 920.9079092090738, 923.2798417234568, 923.8060007398522, 931.7996630724426, 936.7892224249489, 830.2652152844776, 941.2940294778916, 942.4298299528823, 960.8078384323163, 1017.9287449878437, 6500.9349906501275, 6559.278479367265, 6588.73645054202, 6802.14973128359, 7014.158301003912, 962.2398305338704, 962.7448726087592, 1007.49882674814, 1080.7946490166844, 1113.8050841102936, 4226.682998775104, 4369.0, 4433.933490997638, 4590.517995610479, 4591.334256532851, 10054.49727513636, 13405.329733513481, 13423.798643020362, 14003.50987715425, 14021.719565608631, 11860.288382697066, 11906.690264070945, 13627.727445451234, 13937.88496920244, 13942.302884855717, 9989.0, 10185.694429167137, 10392.42841643717, 10455.424951627581, 10495.632652857113, 6870.862582748217, 6955.652217389212, 7183.353498185188, 7204.423646108404, 7250.746223882139, 10953.780924381308, 12145.757084858076, 12176.330301833485, 12233.204841685261, 12334.629961101182, 12514.18657787241, 12675.492980280686, 12677.619671409873, 12839.293838838954, 12864.421101050662, 9215.216706579748, 9449.622015119594, 9481.241500680067, 9494.192993595621, 9503.809923801347, 10147.64483243083, 10370.429626370622, 10465.372077675363, 10550.366977981346, 10631.149508039493, 9019.909800902169, 9108.726738197865, 9180.44917304964, 9191.172794448532, 9283.860742088738, 8820.29437644974, 8856.955715221382, 8891.977422596485, 8917.331200159884, 8968.10318968098, 7478.7382441616455, 7999.120096789232, 8078.111407745261, 8085.878711819445, 8093.19068093186, 5884.35272120075, 5934.821955341346, 5937.34689184198, 6049.153118563317, 6126.877462450637, 5823.126531020381, 5968.074948383008, 6096.390360963868, 6232.719527621359, 6300.117983482224], "value": 5048.889185241358, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.37310929403674, 396.11468368197075, 398.0983073338887, 399.7981019585108, 401.58636604297567, 5344.412114667389, 5359.311816777712, 5398.622096453255, 5404.702741349224, 5429.267048948391, 5030.2394162043265, 5267.155735902608, 5283.049051170788, 5283.392409872866, 5329.413764485904, 3930.0, 3986.083200863803, 4112.0, 4127.277726397883, 4134.255833949888, 4141.834326626931, 4179.623833854953, 4203.07532342884, 4221.24017676818, 4240.024794297298, 4023.396490526415, 4130.621541166373, 4166.146065001076, 4215.78921053946, 4237.936430953539, 4080.8367665293354, 4135.483064616923, 4140.358244472111, 4146.470652901728, 4151.612822626971, 3366.309906469168, 3405.650101430005, 3424.0, 3693.279810436966, 3713.9442908356395, 4441.111777644452, 4812.975935120337, 4987.376577927759, 5021.648484606079, 5123.385193776733, 4333.414988976479, 4444.355628384726, 4618.2982954971385, 4733.763311834395, 4763.952360476385, 4601.596513063514, 4911.4106307243, 5185.818496352647, 5587.49137732814, 5709.486146246816, 4131.8967025824295, 4135.875923722293, 4144.668426525871, 4171.89444797129, 4176.0395109124765, 4561.361409402686, 5015.172496538067, 5781.53747700183, 5854.765809367577, 5967.910481342783, 4024.778637174925, 4531.546845315508, 4687.132880417065, 4694.295855621615, 4732.503087175797, 4119.876403707894, 4703.118752499027, 4733.881652958635, 4737.928931066037, 4757.5718185363485, 4553.383548840114, 4749.786259618326, 5077.019962214739, 5102.719350435762, 5120.590352771769, 4895.510448955076, 4944.9258261126115, 5479.356228108263, 5521.5306698930235, 5688.345840228427, 1495.2822645130182, 1519.8176218853807, 1587.3015873015836, 1610.2914717524252, 1630.1197353429252, 983.9963237497712, 1176.4823477669797, 1214.2350319298962, 1218.524775337619, 1235.086036333125, 4193.748375097504, 4875.926861097087, 4954.777035033432, 5081.0, 5211.609129315317, 5080.974595127074, 5451.48210919962, 5837.190470954018, 5861.325947516005, 5933.495652869468, 4666.463356714022, 4699.342092107107, 4724.212714498462, 4737.768180273154, 4815.711057336571, 2780.4290575824593, 2801.817881837674, 3039.680833512494, 3092.2015575903843, 3122.750179985596, 5366.087765079946, 5635.915461268084, 5993.520518358608, 6205.47253483459, 6206.014075495888, 5006.499350065037, 5286.775067209, 5415.377231618336, 6091.269047714302, 6103.572749907553, 5448.809291674773, 5556.583256255797, 6197.0, 6213.477697964027, 6239.812805615838, 4952.289647527548, 5523.861903452445, 5556.078726810312, 5925.377835327401, 6011.977963746086, 5264.419715102004, 5613.831585052455, 5726.914096288642, 5774.538036957118, 5851.912221316598, 5005.6996580205305, 5330.360356757214, 5709.28721383575, 5898.705064746659, 5899.439553242435, 3969.8412063517135, 4166.479190101238, 4599.93100103492, 4643.3963584733765, 4693.389859318267, 5027.59779217669, 5650.85872853182, 5735.311762588516, 5804.593678442468, 5920.496757775637, 4914.533119353656, 4929.778159982738, 5302.939412117669, 5569.994801871242, 5713.314402271754, 4674.439067311944, 5478.014482107182, 5490.533304669148, 5666.773329066791, 5724.914126288026, 5114.872128196676, 5230.97384513075, 5234.398044224963, 5348.839534813962, 5506.476884695948, 4983.526564976321, 5049.8737531562, 5106.7701953412925, 5637.8843444285085, 5783.652980821164, 4839.830605928775, 5390.353157621111, 5588.664680119044, 5592.356878958972, 5594.489487838342], "value": 4252.7264338709965, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [726.9563826170427, 751.8496300739854, 783.4241832253293, 839.8992120945487, 854.5684429363171, 15882.36470541177, 16510.174491275433, 16889.83110168898, 17107.572310692238, 17271.82728172718, 15307.0, 15310.923445382785, 15705.0, 15809.130497822614, 15853.84146158538, 977.9168770654485, 993.1210878372656, 1000.8498725191207, 1001.0390025575456, 1004.1715584642699, 969.6606187834237, 994.9602015919354, 997.0727223682009, 1006.5923301063044, 1007.269729446153, 1059.3326204491198, 1061.3631820907472, 1063.3407287481773, 1064.132731823565, 1065.424670677833, 1052.5368837711383, 1052.63157894737, 1056.418969566739, 1057.5928267616946, 1059.9417032063272, 787.7361084036854, 789.5617932047711, 790.3084800799301, 791.8930944322558, 792.675003248671, 16815.327386904508, 16948.983061016253, 16949.406770762966, 17233.741493877602, 17406.7388989164, 11585.942070289679, 14212.715745685131, 14278.0, 14280.92859535706, 14318.0, 11913.0, 11923.801964480586, 11996.760064798742, 12058.879411205864, 12063.577774777841, 3635.9272814543824, 3665.816709164558, 3721.3115573618697, 3735.402335626287, 3879.3405121129476, 108295.99999999921, 111212.88787112106, 114841.0, 115842.0, 117857.0, 10871.565137394573, 10945.726356840983, 10981.835272470918, 11031.944840275908, 11106.0, 22775.202867899545, 22989.655155172684, 23293.53412931732, 23382.532349352918, 23412.0, 7818.765437036898, 7908.881366779503, 7925.643346049442, 7945.404094692921, 7957.045154581371, 4175.185838761473, 4413.823447062082, 4450.732956022649, 4473.373727678128, 4496.100779844044, 3454.6199918008997, 3475.1485885957595, 3503.702185314277, 3526.9118272043406, 3536.45184996326, 626.9342118398649, 630.6337522708253, 642.2774378823823, 643.334149155631, 655.944244739193, 641.1985018726592, 661.9735210591523, 673.8382788130813, 677.7153595489807, 689.6276010954028, 8890.64437422496, 9819.509024548744, 9868.407895526292, 9880.357776744633, 9959.950200249097, 638.1066506890298, 639.68015992004, 659.475716805141, 681.2574294019444, 690.3717616968535, 4828.66199366048, 4831.0, 4860.3195552622665, 4905.632077594195, 4920.803167873245, 84471.0, 97974.51012744891, 98653.0, 103766.0, 108491.45754271178, 59072.0, 74626.62686686532, 83639.0, 90070.0, 91787.54106229426, 57643.13535296974, 66171.6765664684, 77629.2237077622, 79873.0, 81338.0, 10912.618058368236, 10994.625671791026, 11374.090072794323, 11374.54501819918, 11375.1468639854, 49836.501634983186, 50258.497415025384, 51489.940402384986, 51619.70950726262, 52589.7370513145, 64695.0, 66947.66526167338, 67481.3251867475, 68104.0, 68437.65781171063, 11209.840752948756, 14362.210078445785, 14480.34838432252, 14629.341679624229, 14685.706285874008, 13361.599152025456, 14204.857951420354, 15027.323770430137, 15282.0, 15560.455384061503, 13828.239446830521, 14137.222452765196, 14438.205898675253, 15512.301946412665, 15685.0, 14099.295035248402, 14503.564893053226, 14550.85449145495, 14634.634134146305, 15043.548693539211, 13710.588682339545, 14100.858991409954, 14237.715245695235, 14438.061526000776, 14625.780613291023, 9564.634297777226, 9861.605535778488, 10256.538455769658, 10286.434246116536, 10699.5185216667, 9948.701538953843, 11653.6503904883, 11687.88312116902, 12071.57749478764, 12128.332941687939], "value": 7821.105344546913, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1553.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 120.0, 121.0, 118.0, 123.0, 169.0, 175.0, 145.0, 120.0, 121.0], "value": 132.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 150.0, 111.0, 150.0, 94.0, 138.0, 169.0, 113.0, 131.0, 127.0], "value": 134.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [229.0, 262.0, 200.0, 197.0, 199.0, 198.0, 223.0, 229.0, 196.0, 196.0], "value": 212.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 63.0, 63.0, 91.0, 61.0, 69.0, 57.0, 69.0, 99.0, 54.0], "value": 71.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 72.0, 86.0, 74.0, 71.0, 72.0, 81.0, 71.0, 86.0, 106.0], "value": 80.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 97.0, 88.0, 103.0, 88.0, 89.0, 117.0, 133.0, 90.0], "value": 98.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 97.0, 102.0, 96.0, 96.0, 99.0, 97.0, 140.0, 97.0], "value": 101.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 140.0, 117.0, 91.0, 92.0, 86.0, 90.0, 94.0, 123.0, 91.0], "value": 105.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 66.0, 67.0, 68.0, 67.0, 68.0, 66.0, 69.0], "value": 66.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 76.0, 98.0, 58.0, 75.0, 82.0, 87.0, 84.0, 85.0, 84.0], "value": 78.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 82.0, 87.0, 88.0, 82.0, 84.0, 85.0, 89.0, 89.0, 105.0], "value": 88.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 138.0, 144.0, 128.0, 141.0, 130.0, 144.0, 150.0, 162.0, 143.0], "value": 141.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 167.0, 193.0, 172.0, 170.0, 169.0, 164.0, 167.0, 166.0, 169.0], "value": 170.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 68.0, 66.0, 68.0, 63.0, 72.0, 76.0, 85.0, 85.0], "value": 71.5, "unit": "score"}]}]} 02:34:15 INFO - Return code: 0 02:34:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:34:15 ERROR - # TBPL SUCCESS # 02:34:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:34:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:34:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:34:15 INFO - 2017-06-08 02:34:15.964 screenresolution[1654:10797] starting screenresolution argv=screenresolution get 02:34:15 INFO - 2017-06-08 02:34:15.966 screenresolution[1654:10797] Display 0: 1600x1200x32@60 02:34:15 INFO - 2017-06-08 02:34:15.972 screenresolution[1655:10800] starting screenresolution argv=screenresolution list 02:34:15 INFO - Available Modes on Display 0 02:34:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:34:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:34:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:34:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:34:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:34:16 INFO - Intel Iris: 02:34:16 INFO - Chipset Model: Intel Iris 02:34:16 INFO - Type: GPU 02:34:16 INFO - Bus: Built-In 02:34:16 INFO - VRAM (Dynamic, Max): 1536 MB 02:34:16 INFO - Vendor: Intel (0x8086) 02:34:16 INFO - Device ID: 0x0a2e 02:34:16 INFO - Revision ID: 0x0009 02:34:16 INFO - Displays: 02:34:16 INFO - Display: 02:34:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:34:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:34:16 INFO - Display Serial Number: 1600x1200 60 02:34:16 INFO - Main Display: Yes 02:34:16 INFO - Mirror: Off 02:34:16 INFO - Online: Yes 02:34:16 INFO - Rotation: Supported 02:34:16 INFO - Return code: 0 02:34:16 INFO - Running post-action listener: _resource_record_post_action 02:34:16 INFO - [mozharness: 2017-06-08 09:34:16.214464Z] Finished run-tests step (success) 02:34:16 INFO - Running post-run listener: _resource_record_post_run 02:34:16 INFO - Total resource usage - Wall time: 1198s; CPU: 55.0%; Read bytes: 40094208; Write bytes: 516966400; Read time: 257; Write time: 1636 02:34:16 INFO - TinderboxPrint: CPU usage
54.5% 02:34:16 INFO - TinderboxPrint: I/O read bytes / time
40,094,208 / 257 02:34:16 INFO - TinderboxPrint: I/O write bytes / time
516,966,400 / 1,636 02:34:16 INFO - TinderboxPrint: CPU idle
2,179.9 (45.5%) 02:34:16 INFO - TinderboxPrint: CPU system
960.5 (20.0%) 02:34:16 INFO - TinderboxPrint: CPU user
1,653.3 (34.5%) 02:34:16 INFO - TinderboxPrint: Swap in / out
263,553,024 / 0 02:34:16 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208472576; Write bytes: 200761344; Read time: 15831; Write time: 430 02:34:16 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 37960192; Write bytes: 306178048; Read time: 235; Write time: 1172 02:34:16 INFO - Running post-run listener: _upload_blobber_files 02:34:16 INFO - Blob upload gear active. 02:34:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:34:16 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 02:34:16 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'] 02:34:16 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 02:34:17 INFO - (blobuploader) - INFO - Open directory for files ... 02:34:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:34:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:34:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:34:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:34:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:34:17 INFO - (blobuploader) - INFO - Done attempting. 02:34:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:34:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:34:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:34:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:34:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:34:19 INFO - (blobuploader) - INFO - Done attempting. 02:34:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 02:34:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:34:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:34:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 02:34:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:34:20 INFO - (blobuploader) - INFO - Done attempting. 02:34:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:34:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:34:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:34:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:34:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:34:22 INFO - (blobuploader) - INFO - Done attempting. 02:34:22 INFO - (blobuploader) - INFO - Iteration through files over. 02:34:22 INFO - Return code: 0 02:34:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:34:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:34:22 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/33974b0c0c351b4ff19d2ee8d86933d1cf4ca08ce37d969e7fd3e7fc92579f65970caffdbb41f97b40c508b8923cc1bfcc17ba440cd5fb30a55a5e3a52a156e1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ea533caaa7bcdf55e0d378b730565a5172ed7ae428f60acb3a7204d907ef990d2a77a9a7dbc8c53773f959c28ea20eba36f4015029544bf586c7af1056168ef6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/49d05ad3b8573668838ceed5044bd7a2276dee7ca4204f741e701734498663bd7f72b1278baf707615452b66a2c2e52dcb95065eeefb54021623eca9c3d6e6a3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9f320b37792ae9c185be2d4e12e1e0f1b17bc8014203d3b3553d98bb91733b78d2ca673a71336590717df0805e065fcc0af19b5c4f91008f8c304f6db4733e70"} 02:34:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:34:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:34:22 INFO - Contents: 02:34:22 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/33974b0c0c351b4ff19d2ee8d86933d1cf4ca08ce37d969e7fd3e7fc92579f65970caffdbb41f97b40c508b8923cc1bfcc17ba440cd5fb30a55a5e3a52a156e1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ea533caaa7bcdf55e0d378b730565a5172ed7ae428f60acb3a7204d907ef990d2a77a9a7dbc8c53773f959c28ea20eba36f4015029544bf586c7af1056168ef6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/49d05ad3b8573668838ceed5044bd7a2276dee7ca4204f741e701734498663bd7f72b1278baf707615452b66a2c2e52dcb95065eeefb54021623eca9c3d6e6a3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9f320b37792ae9c185be2d4e12e1e0f1b17bc8014203d3b3553d98bb91733b78d2ca673a71336590717df0805e065fcc0af19b5c4f91008f8c304f6db4733e70"} 02:34:22 INFO - Running post-run listener: copy_logs_to_upload_dir 02:34:22 INFO - Copying logs to upload dir... 02:34:22 INFO - mkdir: /builds/slave/test/build/upload/logs 02:34:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.395104 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-06-08 02:34:22.207043) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-08 02:34:22.210377) ========= 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.K2frq1lAgX/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.sJMlsenDon/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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/33974b0c0c351b4ff19d2ee8d86933d1cf4ca08ce37d969e7fd3e7fc92579f65970caffdbb41f97b40c508b8923cc1bfcc17ba440cd5fb30a55a5e3a52a156e1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ea533caaa7bcdf55e0d378b730565a5172ed7ae428f60acb3a7204d907ef990d2a77a9a7dbc8c53773f959c28ea20eba36f4015029544bf586c7af1056168ef6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/49d05ad3b8573668838ceed5044bd7a2276dee7ca4204f741e701734498663bd7f72b1278baf707615452b66a2c2e52dcb95065eeefb54021623eca9c3d6e6a3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9f320b37792ae9c185be2d4e12e1e0f1b17bc8014203d3b3553d98bb91733b78d2ca673a71336590717df0805e065fcc0af19b5c4f91008f8c304f6db4733e70"} build_url:https://queue.taskcluster.net/v1/task/PciA_TQOQl-ieW8oGe_nrA/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011259 build_url: 'https://queue.taskcluster.net/v1/task/PciA_TQOQl-ieW8oGe_nrA/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/33974b0c0c351b4ff19d2ee8d86933d1cf4ca08ce37d969e7fd3e7fc92579f65970caffdbb41f97b40c508b8923cc1bfcc17ba440cd5fb30a55a5e3a52a156e1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ea533caaa7bcdf55e0d378b730565a5172ed7ae428f60acb3a7204d907ef990d2a77a9a7dbc8c53773f959c28ea20eba36f4015029544bf586c7af1056168ef6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/49d05ad3b8573668838ceed5044bd7a2276dee7ca4204f741e701734498663bd7f72b1278baf707615452b66a2c2e52dcb95065eeefb54021623eca9c3d6e6a3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9f320b37792ae9c185be2d4e12e1e0f1b17bc8014203d3b3553d98bb91733b78d2ca673a71336590717df0805e065fcc0af19b5c4f91008f8c304f6db4733e70"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-08 02:34:22.243256) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 02:34:22.243546) ========= 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.K2frq1lAgX/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.sJMlsenDon/Listeners TMPDIR=/var/folders/71/7903fjj12g39x3jmfxdbc1h400000w/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.005222 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 02:34:22.293262) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-08 02:34:22.293586) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-08 02:34:22.293897) ========= ========= Total master_lag: 0.17 =========