builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0180 starttime: 1496156664.79 results: success (0) buildid: 20170530071016 builduid: 4e3ce2e1f8244f70a1c4180497b38b11 revision: 3524778fe631486d5a9cd4f5392177f297b337dc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:24.785436) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:24.785932) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:24.786219) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:24.989234) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:24.989592) ========= 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.ulAPCGdced/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.wgKISAwlWF/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-30 08:04:25-- 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% 149M=0s 2017-05-30 08:04:25 (149 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.518535 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:25.537338) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:25.537641) ========= 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.ulAPCGdced/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.wgKISAwlWF/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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.030962 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:25.592762) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-30 08:04:25.593078) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3524778fe631486d5a9cd4f5392177f297b337dc --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 3524778fe631486d5a9cd4f5392177f297b337dc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ulAPCGdced/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.wgKISAwlWF/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-30 08:04:25,686 truncating revision to first 12 chars 2017-05-30 08:04:25,686 Setting DEBUG logging. 2017-05-30 08:04:25,686 attempt 1/10 2017-05-30 08:04:25,686 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/3524778fe631?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-30 08:04:26,539 unpacking tar archive at: mozilla-beta-3524778fe631/testing/mozharness/ program finished with exit code 0 elapsedTime=1.130983 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-30 08:04:26.746578) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:26.746879) ========= script_repo_revision: 3524778fe631486d5a9cd4f5392177f297b337dc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:26.747269) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:26.747533) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 08:04:26.766362) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-05-30 08:04:26.766656) ========= /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.ulAPCGdced/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.wgKISAwlWF/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 08:04:26 INFO - MultiFileLogger online at 20170530 08:04:26 in /builds/slave/test 08:04:26 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 08:04:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:04:26 INFO - {'append_to_log': False, 08:04:26 INFO - 'base_work_dir': '/builds/slave/test', 08:04:26 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:04:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:04:26 INFO - 'branch': 'Mozilla-Beta', 08:04:26 INFO - 'buildbot_json_path': 'buildprops.json', 08:04:26 INFO - 'config_files': ('talos/mac_config.py',), 08:04:26 INFO - 'default_actions': ('clobber', 08:04:26 INFO - 'read-buildbot-config', 08:04:26 INFO - 'download-and-extract', 08:04:26 INFO - 'populate-webroot', 08:04:26 INFO - 'create-virtualenv', 08:04:26 INFO - 'install', 08:04:26 INFO - 'run-tests'), 08:04:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:04:26 INFO - 'download_minidump_stackwalk': True, 08:04:26 INFO - 'download_symbols': 'ondemand', 08:04:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:04:26 INFO - 'tooltool.py': '/tools/tooltool.py', 08:04:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:04:26 INFO - '/tools/misc-python/virtualenv.py')}, 08:04:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:04:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:04:26 INFO - 'gecko_profile': False, 08:04:26 INFO - 'gecko_profile_interval': 0, 08:04:26 INFO - 'installer_path': 'installer.exe', 08:04:26 INFO - 'log_level': 'info', 08:04:26 INFO - 'log_name': 'talos', 08:04:26 INFO - 'log_to_console': True, 08:04:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:04:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:04:26 INFO - 'opt_config_files': (), 08:04:26 INFO - 'pip_index': False, 08:04:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:04:26 INFO - 'cmd': ('bash', 08:04:26 INFO - '-c', 08:04:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:04:26 INFO - 'enabled': True, 08:04:26 INFO - 'halt_on_failure': False, 08:04:26 INFO - 'name': 'check_screen_resolution'},), 08:04:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:04:26 INFO - 'cmd': ('bash', 08:04:26 INFO - '-c', 08:04:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:04:26 INFO - 'enabled': True, 08:04:26 INFO - 'halt_on_failure': False, 08:04:26 INFO - 'name': 'check_screen_resolution'},), 08:04:26 INFO - 'run_cmd_checks_enabled': True, 08:04:26 INFO - 'suite': 'dromaeojs-e10s', 08:04:26 INFO - 'system_bits': '32', 08:04:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:04:26 INFO - 'title': 't-yosemite-r7-0180', 08:04:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:04:26 INFO - 'use_talos_json': True, 08:04:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:04:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:04:26 INFO - 'work_dir': 'build'} 08:04:26 INFO - [mozharness: 2017-05-30 15:04:26.974198Z] Running clobber step. 08:04:26 INFO - Running pre-action listener: _resource_record_pre_action 08:04:26 INFO - Running main action method: clobber 08:04:26 INFO - rmtree: /builds/slave/test/build 08:04:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:04:31 INFO - Running post-action listener: _resource_record_post_action 08:04:31 INFO - [mozharness: 2017-05-30 15:04:31.734735Z] Finished clobber step (success) 08:04:31 INFO - [mozharness: 2017-05-30 15:04:31.734857Z] Running read-buildbot-config step. 08:04:31 INFO - Running pre-action listener: _resource_record_pre_action 08:04:31 INFO - Running main action method: read_buildbot_config 08:04:31 INFO - Using buildbot properties: 08:04:31 INFO - { 08:04:31 INFO - "project": "", 08:04:31 INFO - "product": "firefox", 08:04:31 INFO - "script_repo_revision": "production", 08:04:31 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 08:04:31 INFO - "repository": "", 08:04:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 08:04:31 INFO - "buildid": "20170530071016", 08:04:31 INFO - "pgo_build": "False", 08:04:31 INFO - "basedir": "/builds/slave/test", 08:04:31 INFO - "buildnumber": 2, 08:04:31 INFO - "slavename": "t-yosemite-r7-0180", 08:04:31 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:04:31 INFO - "platform": "yosemite_r7_devedition", 08:04:31 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 08:04:31 INFO - "branch": "mozilla-beta", 08:04:31 INFO - "repo_path": "releases/mozilla-beta", 08:04:31 INFO - "revision": "3524778fe631486d5a9cd4f5392177f297b337dc", 08:04:31 INFO - "stage_platform": "macosx64-devedition", 08:04:31 INFO - "builduid": "4e3ce2e1f8244f70a1c4180497b38b11", 08:04:31 INFO - "slavebuilddir": "test" 08:04:31 INFO - } 08:04:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 08:04:31 INFO - Running post-action listener: _resource_record_post_action 08:04:31 INFO - [mozharness: 2017-05-30 15:04:31.738148Z] Finished read-buildbot-config step (success) 08:04:31 INFO - [mozharness: 2017-05-30 15:04:31.738279Z] Running download-and-extract step. 08:04:31 INFO - Running pre-action listener: _resource_record_pre_action 08:04:31 INFO - Running main action method: download_and_extract 08:04:31 INFO - mkdir: /builds/slave/test/build/tests 08:04:31 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')]} 08:04:31 INFO - trying https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 08:04:31 INFO - Downloading https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/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 08:04:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/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 08:04:33 INFO - Downloaded 1287 bytes. 08:04:33 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 08:04:33 INFO - Using the following test package requirements: 08:04:33 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:04:33 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 08:04:33 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 08:04:33 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:04:33 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 08:04:33 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:04:33 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 08:04:33 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 08:04:33 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:04:33 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 08:04:33 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 08:04:33 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:04:33 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 08:04:33 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:04:33 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 08:04:33 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:04:33 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 08:04:33 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:04:33 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 08:04:33 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 08:04:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 08:04:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 08:04:33 INFO - Fetch https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 08:04:34 INFO - Expected file size: 51964297 08:04:34 INFO - Obtained file size: 51964297 08:04:50 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 08:04:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 08:04:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 08:04:50 INFO - Fetch https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 08:04:52 INFO - Expected file size: 51964297 08:04:52 INFO - Obtained file size: 51964297 08:05:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 08:05:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 08:05:08 INFO - Fetch https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 08:05:08 INFO - Expected file size: 14404461 08:05:08 INFO - Obtained file size: 14404461 08:05:09 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')]} 08:05:09 INFO - trying https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 08:05:09 INFO - Downloading https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 08:05:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/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 08:05:11 INFO - Downloaded 74893626 bytes. 08:05:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 08:05:11 INFO - mkdir: /builds/slave/test/properties 08:05:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:05:11 INFO - Writing to file /builds/slave/test/properties/build_url 08:05:11 INFO - Contents: 08:05:11 INFO - build_url:https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 08:05:12 INFO - Running post-action listener: _resource_record_post_action 08:05:12 INFO - Running post-action listener: set_extra_try_arguments 08:05:12 INFO - [mozharness: 2017-05-30 15:05:12.164166Z] Finished download-and-extract step (success) 08:05:12 INFO - [mozharness: 2017-05-30 15:05:12.164322Z] Running populate-webroot step. 08:05:12 INFO - Running pre-action listener: _resource_record_pre_action 08:05:12 INFO - Running main action method: populate_webroot 08:05:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:05:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:05:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:05:12 INFO - '--tppagecycles', 08:05:12 INFO - '7'], 08:05:12 INFO - 'tests': ['tsvgm']}}, 08:05:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:05:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:05:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:05:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:05:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:05:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:05:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:05:12 INFO - 'talos_options': ['--disable-e10s'], 08:05:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:05:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:05:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:05:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:05:12 INFO - 'talos_options': ['--disable-e10s'], 08:05:12 INFO - 'tests': ['damp', 'tps']}, 08:05:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:05:12 INFO - 'tests': ['damp', 'tps']}, 08:05:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:05:12 INFO - 'tests': ['dromaeo_dom']}, 08:05:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:05:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:05:12 INFO - 'tests': ['basic_compositor_video']}, 08:05:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:05:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:05:12 INFO - 'tests': ['a11yr', 08:05:12 INFO - 'ts_paint', 08:05:12 INFO - 'tpaint', 08:05:12 INFO - 'sessionrestore', 08:05:12 INFO - 'sessionrestore_no_auto_restore', 08:05:12 INFO - 'tabpaint']}, 08:05:12 INFO - 'other-e10s': {'tests': ['a11yr', 08:05:12 INFO - 'ts_paint', 08:05:12 INFO - 'tpaint', 08:05:12 INFO - 'sessionrestore', 08:05:12 INFO - 'sessionrestore_no_auto_restore', 08:05:12 INFO - 'tabpaint']}, 08:05:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:05:12 INFO - 'ts_paint', 08:05:12 INFO - 'tpaint', 08:05:12 INFO - 'sessionrestore', 08:05:12 INFO - 'sessionrestore_no_auto_restore', 08:05:12 INFO - 'tabpaint']}, 08:05:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:05:12 INFO - 'ts_paint', 08:05:12 INFO - 'tpaint', 08:05:12 INFO - 'sessionrestore', 08:05:12 INFO - 'sessionrestore_no_auto_restore', 08:05:12 INFO - 'tabpaint']}, 08:05:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:05:12 INFO - 'tests': ['a11yr', 08:05:12 INFO - 'ts_paint', 08:05:12 INFO - 'tpaint', 08:05:12 INFO - 'sessionrestore', 08:05:12 INFO - 'sessionrestore_no_auto_restore', 08:05:12 INFO - 'tabpaint']}, 08:05:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:05:12 INFO - 'tests': ['a11yr', 08:05:12 INFO - 'ts_paint', 08:05:12 INFO - 'tpaint', 08:05:12 INFO - 'sessionrestore', 08:05:12 INFO - 'sessionrestore_no_auto_restore', 08:05:12 INFO - 'tabpaint']}, 08:05:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:05:12 INFO - 'tests': ['tsvgx', 08:05:12 INFO - 'tsvgr_opacity', 08:05:12 INFO - 'tart', 08:05:12 INFO - 'tscrollx', 08:05:12 INFO - 'cart', 08:05:12 INFO - 'tsvg_static']}, 08:05:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:05:12 INFO - 'tsvgr_opacity', 08:05:12 INFO - 'tart', 08:05:12 INFO - 'tscrollx', 08:05:12 INFO - 'cart', 08:05:12 INFO - 'tsvg_static']}, 08:05:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:05:12 INFO - 'talos_options': ['--disable-e10s'], 08:05:12 INFO - 'tests': ['tp5o']}, 08:05:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:05:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:05:12 INFO - 'talos_options': ['--disable-e10s', 08:05:12 INFO - '--xperf_path', 08:05:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:05:12 INFO - 'tests': ['tp5n']}, 08:05:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:05:12 INFO - 'talos_options': ['--xperf_path', 08:05:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:05:12 INFO - 'tests': ['tp5n']}}, 08:05:12 INFO - 'talos.zip': {'path': '', 08:05:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:05:12 INFO - Running post-action listener: _resource_record_post_action 08:05:12 INFO - [mozharness: 2017-05-30 15:05:12.182108Z] Finished populate-webroot step (success) 08:05:12 INFO - [mozharness: 2017-05-30 15:05:12.182247Z] Running create-virtualenv step. 08:05:12 INFO - Running pre-action listener: _resource_record_pre_action 08:05:12 INFO - Running main action method: create_virtualenv 08:05:12 INFO - Creating virtualenv /builds/slave/test/build/venv 08:05:12 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 08:05:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:05:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:05:12 INFO - Using real prefix '/tools/python27' 08:05:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:05:13 INFO - Installing distribute.............................................................................................................................................................................................done. 08:05:15 INFO - Installing pip.................done. 08:05:15 INFO - Return code: 0 08:05:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:05:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:05:16 INFO - Reading from file tmpfile_stdout 08:05:16 INFO - Output received: 08:05:16 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) 08:05:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:05:16 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')]} 08:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:16 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')]} 08:05:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:16 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 0x108857b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10885a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef5ace2650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108635d30>, '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 0x10855cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10862abe8>, '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.ulAPCGdced/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.wgKISAwlWF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 08:05:16 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 08:05:16 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 08:05:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ulAPCGdced/Render', 08:05:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:16 INFO - 'HOME': '/Users/cltbld', 08:05:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:16 INFO - 'LOGNAME': 'cltbld', 08:05:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:16 INFO - 'MOZ_NO_REMOTE': '1', 08:05:16 INFO - 'NO_EM_RESTART': '1', 08:05:16 INFO - 'PAGER': '/bin/cat', 08:05:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:16 INFO - 'PWD': '/builds/slave/test', 08:05:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:16 INFO - 'SHELL': '/bin/bash', 08:05:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wgKISAwlWF/Listeners', 08:05:16 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 08:05:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:16 INFO - 'USER': 'cltbld', 08:05:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:16 INFO - 'XPC_FLAGS': '0x0', 08:05:16 INFO - 'XPC_SERVICE_NAME': '0', 08:05:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:16 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 08:05:16 INFO - Cleaning up... 08:05:16 INFO - Return code: 0 08:05:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:05:16 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')]} 08:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:16 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')]} 08:05:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:16 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 0x108857b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10885a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef5ace2650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108635d30>, '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 0x10855cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10862abe8>, '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.ulAPCGdced/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.wgKISAwlWF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 08:05:16 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 08:05:16 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 08:05:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ulAPCGdced/Render', 08:05:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:16 INFO - 'HOME': '/Users/cltbld', 08:05:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:16 INFO - 'LOGNAME': 'cltbld', 08:05:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:16 INFO - 'MOZ_NO_REMOTE': '1', 08:05:16 INFO - 'NO_EM_RESTART': '1', 08:05:16 INFO - 'PAGER': '/bin/cat', 08:05:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:16 INFO - 'PWD': '/builds/slave/test', 08:05:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:16 INFO - 'SHELL': '/bin/bash', 08:05:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wgKISAwlWF/Listeners', 08:05:16 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 08:05:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:16 INFO - 'USER': 'cltbld', 08:05:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:16 INFO - 'XPC_FLAGS': '0x0', 08:05:16 INFO - 'XPC_SERVICE_NAME': '0', 08:05:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:16 INFO - Downloading/unpacking psutil>=3.1.1 08:05:16 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 08:05:16 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 08:05:16 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 08:05:16 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 08:05:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:05:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:05:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:05:19 INFO - Installing collected packages: psutil 08:05:19 INFO - Running setup.py install for psutil 08:05:19 INFO - building 'psutil._psutil_osx' extension 08:05:19 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 08:05:19 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 08:05:19 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 08:05:19 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 08:05:20 INFO - building 'psutil._psutil_posix' extension 08:05:20 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 08:05:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:05:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:05:20 INFO - ^ 08:05:20 INFO - 1 warning generated. 08:05:20 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 08:05:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:05:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:05:20 INFO - Successfully installed psutil 08:05:20 INFO - Cleaning up... 08:05:20 INFO - Return code: 0 08:05:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:05:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:05:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:05:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108857b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10885a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef5ace2650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108635d30>, '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 0x10855cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10862abe8>, '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.ulAPCGdced/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.wgKISAwlWF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 08:05:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:05:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:05:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ulAPCGdced/Render', 08:05:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:20 INFO - 'HOME': '/Users/cltbld', 08:05:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:20 INFO - 'LOGNAME': 'cltbld', 08:05:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:20 INFO - 'MOZ_NO_REMOTE': '1', 08:05:20 INFO - 'NO_EM_RESTART': '1', 08:05:20 INFO - 'PAGER': '/bin/cat', 08:05:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:20 INFO - 'PWD': '/builds/slave/test', 08:05:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:20 INFO - 'SHELL': '/bin/bash', 08:05:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wgKISAwlWF/Listeners', 08:05:20 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 08:05:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:20 INFO - 'USER': 'cltbld', 08:05:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:20 INFO - 'XPC_FLAGS': '0x0', 08:05:20 INFO - 'XPC_SERVICE_NAME': '0', 08:05:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:05:20 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 08:05:20 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 08:05:20 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 08:05:20 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 08:05:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:05:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:05:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:05:22 INFO - Installing collected packages: mozsystemmonitor 08:05:22 INFO - Running setup.py install for mozsystemmonitor 08:05:22 INFO - Successfully installed mozsystemmonitor 08:05:22 INFO - Cleaning up... 08:05:22 INFO - Return code: 0 08:05:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:05:22 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')]} 08:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:22 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')]} 08:05:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:22 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 0x108857b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10885a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef5ace2650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108635d30>, '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 0x10855cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10862abe8>, '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.ulAPCGdced/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.wgKISAwlWF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 08:05:22 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 08:05:22 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 08:05:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ulAPCGdced/Render', 08:05:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:22 INFO - 'HOME': '/Users/cltbld', 08:05:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:22 INFO - 'LOGNAME': 'cltbld', 08:05:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:22 INFO - 'MOZ_NO_REMOTE': '1', 08:05:22 INFO - 'NO_EM_RESTART': '1', 08:05:22 INFO - 'PAGER': '/bin/cat', 08:05:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:22 INFO - 'PWD': '/builds/slave/test', 08:05:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:22 INFO - 'SHELL': '/bin/bash', 08:05:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wgKISAwlWF/Listeners', 08:05:22 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 08:05:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:22 INFO - 'USER': 'cltbld', 08:05:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:22 INFO - 'XPC_FLAGS': '0x0', 08:05:22 INFO - 'XPC_SERVICE_NAME': '0', 08:05:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:22 INFO - Downloading/unpacking jsonschema==2.5.1 08:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:05:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:05:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:05:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:05:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:05:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:05:23 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 08:05:23 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 08:05:23 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 08:05:23 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 08:05:24 INFO - Downloading functools32-3.2.3-2.tar.gz 08:05:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:05:24 INFO - warning: no files found matching '*.txt' 08:05:24 INFO - no previously-included directories found matching 'build' 08:05:24 INFO - no previously-included directories found matching 'dist' 08:05:24 INFO - no previously-included directories found matching '.git*' 08:05:24 INFO - Installing collected packages: jsonschema, functools32 08:05:24 INFO - Running setup.py install for functools32 08:05:24 INFO - warning: no files found matching '*.txt' 08:05:24 INFO - no previously-included directories found matching 'build' 08:05:24 INFO - no previously-included directories found matching 'dist' 08:05:24 INFO - no previously-included directories found matching '.git*' 08:05:24 INFO - Successfully installed jsonschema functools32 08:05:24 INFO - Cleaning up... 08:05:24 INFO - Return code: 0 08:05:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:05:24 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')]} 08:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:24 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')]} 08:05:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:24 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 0x108857b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10885a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef5ace2650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108635d30>, '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 0x10855cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10862abe8>, '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.ulAPCGdced/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.wgKISAwlWF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 08:05:24 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 08:05:24 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 08:05:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ulAPCGdced/Render', 08:05:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:24 INFO - 'HOME': '/Users/cltbld', 08:05:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:24 INFO - 'LOGNAME': 'cltbld', 08:05:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:24 INFO - 'MOZ_NO_REMOTE': '1', 08:05:24 INFO - 'NO_EM_RESTART': '1', 08:05:24 INFO - 'PAGER': '/bin/cat', 08:05:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:24 INFO - 'PWD': '/builds/slave/test', 08:05:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:24 INFO - 'SHELL': '/bin/bash', 08:05:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wgKISAwlWF/Listeners', 08:05:24 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 08:05:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:24 INFO - 'USER': 'cltbld', 08:05:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:24 INFO - 'XPC_FLAGS': '0x0', 08:05:24 INFO - 'XPC_SERVICE_NAME': '0', 08:05:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:05:24 INFO - Cleaning up... 08:05:24 INFO - Return code: 0 08:05:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:05:24 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')]} 08:05:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:24 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')]} 08:05:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:24 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 0x108857b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10885a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef5ace2650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108635d30>, '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 0x10855cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10862abe8>, '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.ulAPCGdced/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.wgKISAwlWF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 08:05:24 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 08:05:24 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 08:05:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ulAPCGdced/Render', 08:05:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:24 INFO - 'HOME': '/Users/cltbld', 08:05:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:24 INFO - 'LOGNAME': 'cltbld', 08:05:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:24 INFO - 'MOZ_NO_REMOTE': '1', 08:05:24 INFO - 'NO_EM_RESTART': '1', 08:05:24 INFO - 'PAGER': '/bin/cat', 08:05:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:24 INFO - 'PWD': '/builds/slave/test', 08:05:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:24 INFO - 'SHELL': '/bin/bash', 08:05:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wgKISAwlWF/Listeners', 08:05:24 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 08:05:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:24 INFO - 'USER': 'cltbld', 08:05:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:24 INFO - 'XPC_FLAGS': '0x0', 08:05:24 INFO - 'XPC_SERVICE_NAME': '0', 08:05:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:25 INFO - Downloading/unpacking blobuploader==1.2.4 08:05:25 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 08:05:25 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 08:05:25 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 08:05:25 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 08:05:26 INFO - Downloading blobuploader-1.2.4.tar.gz 08:05:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:05:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:05:26 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 08:05:26 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 08:05:26 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 08:05:26 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 08:05:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:05:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:05:27 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 08:05:27 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 08:05:27 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 08:05:27 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 08:05:27 INFO - Downloading docopt-0.6.1.tar.gz 08:05:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:05:27 INFO - Installing collected packages: blobuploader, requests, docopt 08:05:27 INFO - Running setup.py install for blobuploader 08:05:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:05:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:05:27 INFO - Running setup.py install for requests 08:05:28 INFO - Running setup.py install for docopt 08:05:28 INFO - Successfully installed blobuploader requests docopt 08:05:28 INFO - Cleaning up... 08:05:28 INFO - Return code: 0 08:05:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:05:28 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')]} 08:05:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:28 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')]} 08:05:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:28 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 0x108857b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10885a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef5ace2650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108635d30>, '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 0x10855cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10862abe8>, '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.ulAPCGdced/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.wgKISAwlWF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 08:05:28 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 08:05:28 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 08:05:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ulAPCGdced/Render', 08:05:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:28 INFO - 'HOME': '/Users/cltbld', 08:05:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:28 INFO - 'LOGNAME': 'cltbld', 08:05:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:28 INFO - 'MOZ_NO_REMOTE': '1', 08:05:28 INFO - 'NO_EM_RESTART': '1', 08:05:28 INFO - 'PAGER': '/bin/cat', 08:05:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:28 INFO - 'PWD': '/builds/slave/test', 08:05:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:28 INFO - 'SHELL': '/bin/bash', 08:05:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wgKISAwlWF/Listeners', 08:05:28 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 08:05:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:28 INFO - 'USER': 'cltbld', 08:05:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:28 INFO - 'XPC_FLAGS': '0x0', 08:05:28 INFO - 'XPC_SERVICE_NAME': '0', 08:05:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:05:28 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-onJknk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:05:28 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-ckjM2A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:05:28 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-jXw7SA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:05:28 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-xNiFFP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:05:28 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-Py5teJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:05:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-Ldl27a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:05:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-e11wTq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:05:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-kFZFW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:05:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-9W4n0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:05:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-a_MNWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:05:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-8N1jno-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:05:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-8r5nI9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:05:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-iNP2yG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:05:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-sQZbYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:05:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-x7XEPs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:05:30 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-vP1x75-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:05:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:05:30 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-MnTKs_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:05:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:05:30 INFO - Running setup.py install for manifestparser 08:05:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:05:30 INFO - Running setup.py install for mozcrash 08:05:30 INFO - Running setup.py install for mozdebug 08:05:30 INFO - Running setup.py install for mozdevice 08:05:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:05:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:05:30 INFO - Running setup.py install for mozfile 08:05:31 INFO - Running setup.py install for mozhttpd 08:05:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:05:31 INFO - Running setup.py install for mozinfo 08:05:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:05:31 INFO - Running setup.py install for mozInstall 08:05:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:05:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:05:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:05:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:05:31 INFO - Running setup.py install for mozleak 08:05:31 INFO - Running setup.py install for mozlog 08:05:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:05:31 INFO - Running setup.py install for moznetwork 08:05:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:05:31 INFO - Running setup.py install for mozprocess 08:05:32 INFO - Running setup.py install for mozprofile 08:05:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:05:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:05:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:05:32 INFO - Running setup.py install for mozrunner 08:05:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:05:32 INFO - Running setup.py install for mozscreenshot 08:05:32 INFO - Running setup.py install for moztest 08:05:32 INFO - Running setup.py install for mozversion 08:05:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:05:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:05:32 INFO - Cleaning up... 08:05:32 INFO - Return code: 0 08:05:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:05:32 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')]} 08:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:32 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')]} 08:05:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:32 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 0x108857b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10885a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef5ace2650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108635d30>, '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 0x10855cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10862abe8>, '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.ulAPCGdced/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.wgKISAwlWF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 08:05:32 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 08:05:32 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 08:05:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ulAPCGdced/Render', 08:05:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:32 INFO - 'HOME': '/Users/cltbld', 08:05:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:32 INFO - 'LOGNAME': 'cltbld', 08:05:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:32 INFO - 'MOZ_NO_REMOTE': '1', 08:05:32 INFO - 'NO_EM_RESTART': '1', 08:05:32 INFO - 'PAGER': '/bin/cat', 08:05:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:32 INFO - 'PWD': '/builds/slave/test', 08:05:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:32 INFO - 'SHELL': '/bin/bash', 08:05:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wgKISAwlWF/Listeners', 08:05:32 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 08:05:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:32 INFO - 'USER': 'cltbld', 08:05:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:32 INFO - 'XPC_FLAGS': '0x0', 08:05:32 INFO - 'XPC_SERVICE_NAME': '0', 08:05:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:05:33 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-3tSQVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:05:33 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)) 08:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:05:33 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-5LgxD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:05:33 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)) 08:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:05:33 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-weQKz7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:05:33 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)) 08:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:05:33 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-t3BH0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:05:33 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)) 08:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:05:33 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-aq5FlQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:05:33 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)) 08:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:05:33 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-i5_7Rk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:05:33 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)) 08:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:05:33 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-q3sUNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:05:33 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)) 08:05:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:05:33 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-xJvd2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:05:34 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)) 08:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:05:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-hDrldh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:05:34 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)) 08:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:05:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-gHCYJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:05:34 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)) 08:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:05:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-hM152e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:05:34 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)) 08:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:05:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-4Nq1bw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:05:34 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)) 08:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:05:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-iIEgtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:05:34 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)) 08:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:05:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-Surj56-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:05:34 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)) 08:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:05:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-cRD8Nl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:05:34 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)) 08:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:05:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-uvMVzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:05:34 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)) 08:05:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:05:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-QBIfiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:05:34 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)) 08:05:34 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)) 08:05:34 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)) 08:05:34 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)) 08:05:34 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)) 08:05:34 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)) 08:05:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:05:35 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 08:05:35 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 08:05:35 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 08:05:35 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 08:05:36 INFO - Downloading blessings-1.6.tar.gz 08:05:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:05:36 INFO - Installing collected packages: blessings 08:05:36 INFO - Running setup.py install for blessings 08:05:36 INFO - Successfully installed blessings 08:05:36 INFO - Cleaning up... 08:05:36 INFO - Return code: 0 08:05:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:05:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:05:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:05:36 INFO - Reading from file tmpfile_stdout 08:05:36 INFO - Current package versions: 08:05:36 INFO - blessings == 1.6 08:05:36 INFO - blobuploader == 1.2.4 08:05:36 INFO - docopt == 0.6.1 08:05:36 INFO - functools32 == 3.2.3-2 08:05:36 INFO - jsonschema == 2.5.1 08:05:36 INFO - manifestparser == 1.1 08:05:36 INFO - mozInstall == 1.12 08:05:36 INFO - mozcrash == 1.0 08:05:36 INFO - mozdebug == 0.1 08:05:36 INFO - mozdevice == 0.49 08:05:36 INFO - mozfile == 1.2 08:05:36 INFO - mozhttpd == 0.7 08:05:36 INFO - mozinfo == 0.9 08:05:36 INFO - mozleak == 0.1 08:05:36 INFO - mozlog == 3.4 08:05:36 INFO - moznetwork == 0.27 08:05:36 INFO - mozprocess == 0.25 08:05:36 INFO - mozprofile == 0.28 08:05:36 INFO - mozrunner == 6.13 08:05:36 INFO - mozscreenshot == 0.1 08:05:36 INFO - mozsystemmonitor == 0.3 08:05:36 INFO - moztest == 0.8 08:05:36 INFO - mozversion == 1.4 08:05:36 INFO - psutil == 3.1.1 08:05:36 INFO - requests == 1.2.3 08:05:36 INFO - wsgiref == 0.1.2 08:05:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:05:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:36 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 0x108857b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10885a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef5ace2650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108635d30>, '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 0x10855cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10862abe8>, '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.ulAPCGdced/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.wgKISAwlWF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 08:05:36 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 08:05:36 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 08:05:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ulAPCGdced/Render', 08:05:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:36 INFO - 'HOME': '/Users/cltbld', 08:05:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:36 INFO - 'LOGNAME': 'cltbld', 08:05:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:36 INFO - 'MOZ_NO_REMOTE': '1', 08:05:36 INFO - 'NO_EM_RESTART': '1', 08:05:36 INFO - 'PAGER': '/bin/cat', 08:05:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:36 INFO - 'PWD': '/builds/slave/test', 08:05:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:36 INFO - 'SHELL': '/bin/bash', 08:05:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wgKISAwlWF/Listeners', 08:05:36 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 08:05:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:36 INFO - 'USER': 'cltbld', 08:05:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:36 INFO - 'XPC_FLAGS': '0x0', 08:05:36 INFO - 'XPC_SERVICE_NAME': '0', 08:05:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:37 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)) 08:05:37 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)) 08:05:37 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)) 08:05:37 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)) 08:05:37 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)) 08:05:37 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)) 08:05:37 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)) 08:05:37 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)) 08:05:37 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)) 08:05:37 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)) 08:05:37 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)) 08:05:37 INFO - Cleaning up... 08:05:37 INFO - Return code: 0 08:05:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:05:37 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')]} 08:05:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:37 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')]} 08:05:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:37 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 0x108857b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10885a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef5ace2650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108635d30>, '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 0x10855cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10862abe8>, '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.ulAPCGdced/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.wgKISAwlWF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 08:05:37 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 08:05:37 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 08:05:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ulAPCGdced/Render', 08:05:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:37 INFO - 'HOME': '/Users/cltbld', 08:05:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:37 INFO - 'LOGNAME': 'cltbld', 08:05:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:37 INFO - 'MOZ_NO_REMOTE': '1', 08:05:37 INFO - 'NO_EM_RESTART': '1', 08:05:37 INFO - 'PAGER': '/bin/cat', 08:05:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:37 INFO - 'PWD': '/builds/slave/test', 08:05:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:37 INFO - 'SHELL': '/bin/bash', 08:05:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wgKISAwlWF/Listeners', 08:05:37 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 08:05:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:37 INFO - 'USER': 'cltbld', 08:05:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:37 INFO - 'XPC_FLAGS': '0x0', 08:05:37 INFO - 'XPC_SERVICE_NAME': '0', 08:05:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:05:37 INFO - Cleaning up... 08:05:37 INFO - Return code: 0 08:05:37 INFO - Running post-action listener: _resource_record_post_action 08:05:37 INFO - Running post-action listener: _start_resource_monitoring 08:05:37 INFO - Starting resource monitoring. 08:05:37 INFO - [mozharness: 2017-05-30 15:05:37.539063Z] Finished create-virtualenv step (success) 08:05:37 INFO - [mozharness: 2017-05-30 15:05:37.540006Z] Running install step. 08:05:37 INFO - Running pre-action listener: _resource_record_pre_action 08:05:37 INFO - Running main action method: install 08:05:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:05:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:05:37 INFO - Reading from file tmpfile_stdout 08:05:37 INFO - Detecting whether we're running mozinstall >=1.0... 08:05:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:05:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:05:37 INFO - Reading from file tmpfile_stdout 08:05:37 INFO - Output received: 08:05:37 INFO - Usage: mozinstall [options] installer 08:05:37 INFO - Options: 08:05:37 INFO - -h, --help show this help message and exit 08:05:37 INFO - -d DEST, --destination=DEST 08:05:37 INFO - Directory to install application into. [default: 08:05:37 INFO - "/builds/slave/test"] 08:05:37 INFO - --app=APP Application being installed. [default: firefox] 08:05:37 INFO - mkdir: /builds/slave/test/build/application 08:05:37 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'] 08:05:37 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 08:05:58 INFO - Reading from file tmpfile_stdout 08:05:58 INFO - Output received: 08:05:58 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:05:58 INFO - Running post-action listener: _resource_record_post_action 08:05:58 INFO - [mozharness: 2017-05-30 15:05:58.150571Z] Finished install step (success) 08:05:58 INFO - [mozharness: 2017-05-30 15:05:58.150715Z] Running run-tests step. 08:05:58 INFO - Running pre-action listener: _resource_record_pre_action 08:05:58 INFO - Running main action method: run_tests 08:05:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:05:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:05:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:05:58 INFO - 2017-05-30 08:05:58.165 screenresolution[1646:9304] starting screenresolution argv=screenresolution get 08:05:58 INFO - 2017-05-30 08:05:58.168 screenresolution[1646:9304] Display 0: 1600x1200x32@60 08:05:58 INFO - 2017-05-30 08:05:58.174 screenresolution[1647:9307] starting screenresolution argv=screenresolution list 08:05:58 INFO - Available Modes on Display 0 08:05:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:05:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:05:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:05:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:05:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:05:58 INFO - Intel Iris: 08:05:58 INFO - Chipset Model: Intel Iris 08:05:58 INFO - Type: GPU 08:05:58 INFO - Bus: Built-In 08:05:58 INFO - VRAM (Dynamic, Max): 1536 MB 08:05:58 INFO - Vendor: Intel (0x8086) 08:05:58 INFO - Device ID: 0x0a2e 08:05:58 INFO - Revision ID: 0x0009 08:05:58 INFO - Displays: 08:05:58 INFO - Display: 08:05:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:05:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:05:58 INFO - Display Serial Number: 1600x1200 60 08:05:58 INFO - Main Display: Yes 08:05:58 INFO - Mirror: Off 08:05:58 INFO - Online: Yes 08:05:58 INFO - Rotation: Supported 08:05:58 INFO - Return code: 0 08:05:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:05:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:05:58 INFO - Python 2.7.3 08:05:58 INFO - Return code: 0 08:05:58 INFO - grabbing minidump binary from tooltool 08:05:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:05:58 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 0x108635d30>, '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 0x10855cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10862abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:05:58 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 08:05:58 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 08:05:58 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 08:05:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:05:58 INFO - Return code: 0 08:05:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:05:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:05:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:05:58 INFO - ENV: RUST_BACKTRACE is now 1 08:05:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:05:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:05:58 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/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0180', '--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 08:05:58 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/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0180 --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 08:05:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ulAPCGdced/Render', 08:05:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:58 INFO - 'HOME': '/Users/cltbld', 08:05:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:58 INFO - 'LOGNAME': 'cltbld', 08:05:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:05:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:05:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:58 INFO - 'MOZ_NO_REMOTE': '1', 08:05:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:05:58 INFO - 'NO_EM_RESTART': '1', 08:05:58 INFO - 'PAGER': '/bin/cat', 08:05:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:58 INFO - 'PWD': '/builds/slave/test', 08:05:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:05:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:58 INFO - 'RUST_BACKTRACE': '1', 08:05:58 INFO - 'SHELL': '/bin/bash', 08:05:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wgKISAwlWF/Listeners', 08:05:58 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 08:05:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:58 INFO - 'USER': 'cltbld', 08:05:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:58 INFO - 'XPC_FLAGS': '0x0', 08:05:58 INFO - 'XPC_SERVICE_NAME': '0', 08:05:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:58 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/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0180', '--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 08:05:58 INFO - mozversion application_buildid: 20170530071016 08:05:58 INFO - mozversion application_changeset: 3524778fe631486d5a9cd4f5392177f297b337dc 08:05:58 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:05:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:05:58 INFO - mozversion application_name: Firefox 08:05:58 INFO - mozversion application_remotingname: firefox-dev 08:05:58 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:05:58 INFO - mozversion application_vendor: Mozilla 08:05:58 INFO - mozversion application_version: 54.0 08:05:58 INFO - mozversion platform_buildid: 20170530071016 08:05:58 INFO - mozversion platform_changeset: 3524778fe631486d5a9cd4f5392177f297b337dc 08:05:58 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:05:58 INFO - mozversion platform_version: 54.0 08:05:58 INFO - using testdate: 1496156758 08:05:58 INFO - actual date: 1496156758 08:05:58 INFO - starting webserver on 'localhost:49225' 08:05:58 INFO - SUITE-START | Running 2 tests 08:05:58 INFO - TEST-START | dromaeo_css 08:05:58 INFO - operating with platform_type : mac_ 08:05:58 INFO - Initialising browser for dromaeo_css test... 08:05:58 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpCbyWT_/profile http://localhost:49225/getInfo.html) 08:06:01 INFO - PROCESS | 1657 | 2017-05-30 08:06:01.653 plugin-container[1661:9472] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d43, name = 'com.apple.tsm.portname' 08:06:01 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:06:01 INFO - PROCESS | 1657 | 2017-05-30 08:06:01.654 plugin-container[1661:9472] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 08:06:01 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:06:01 INFO - PROCESS | 1657 | 2017-05-30 08:06:01.654 plugin-container[1661:9472] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:06:01 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 08:06:01 INFO - PROCESS | 1657 | colorDepth:24 08:06:01 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 08:06:01 INFO - PROCESS | 1657 | __metrics 08:06:01 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 08:06:02 INFO - TEST-INFO | 1657: exit 0 08:06:02 INFO - Browser initialized. 08:06:02 INFO - Running cycle 1/1 for dromaeo_css test... 08:06:02 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpCbyWT_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:06:04 INFO - PROCESS | 1662 | 2017-05-30 08:06:04.688 plugin-container[1663:9588] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 08:06:04 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:06:04 INFO - PROCESS | 1662 | 2017-05-30 08:06:04.689 plugin-container[1663:9588] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x430f, name = 'com.apple.CFPasteboardClient' 08:06:04 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:06:04 INFO - PROCESS | 1662 | 2017-05-30 08:06:04.689 plugin-container[1663:9588] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:06:14 INFO - PROCESS | 1662 | RSS: Main: 203067392 08:06:14 INFO - PROCESS | 1662 | 08:09:17 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49225/tests/dromaeo/cssquery-ext.html) 08:09:19 INFO - PROCESS | 1662 | RSS: Main: 220999680 08:09:19 INFO - PROCESS | 1662 | 08:12:21 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-ext.html (next: http://localhost:49225/tests/dromaeo/cssquery-jquery.html) 08:12:22 INFO - PROCESS | 1662 | RSS: Main: 232689664 08:12:22 INFO - PROCESS | 1662 | 08:15:24 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49225/tests/dromaeo/cssquery-mootools.html) 08:15:24 INFO - PROCESS | 1662 | RSS: Main: 235311104 08:15:24 INFO - PROCESS | 1662 | 08:18:26 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49225/tests/dromaeo/cssquery-prototype.html) 08:18:27 INFO - PROCESS | 1662 | RSS: Main: 238043136 08:18:27 INFO - PROCESS | 1662 | 08:21:30 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49225/tests/dromaeo/cssquery-yui.html) 08:21:30 INFO - PROCESS | 1662 | RSS: Main: 240119808 08:21:30 INFO - PROCESS | 1662 | 08:24:33 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-yui.html (next: http://localhost:49225/tests/dromaeo/cssquery-dojo.html) 08:24:33 INFO - PROCESS | 1662 | RSS: Main: 238329856 08:24:33 INFO - PROCESS | 1662 | 08:24:33 INFO - PROCESS | 1662 | __start_tp_report 08:24:33 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 08:24:33 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 08:24:33 INFO - PROCESS | 1662 | |i|pagename|runs| 08:24:33 INFO - PROCESS | 1662 | |0;dojo.html;3141.3874294512566;3436.759426840122;3503.896272674106;3533.8056406897626;3543.805090720011;5524.613277070606;5982.461578457944;5994.040953447438;6009.978303688373;6024.1566180734735;4910.754462276881;5973.253343332083;5994.07091900756;6016.187814645022;6058.363871793454;5369.463053694629;5433.103537916259;5460.235567020621;5478.041342765;5481.068218402881;5467.45325467454;5481.671099734009;5495.697736624484;5496.230527726121;5500.889982200353;5341.252224688546;5358.91961620576;5476.7535460904155;5494.1758736189695;5517.862053448675;5410.2966614340085;5430.131179011359;5467.507924286793;5476.315460567429;5513.558915286808;5361.169018802092;5494.011078005958;5499.120140777456;5516.310461192351;5535.58483113764;6090.725917333686;6091.969540152315;6126.17296664949;6128.111423843562;6147.538934579924;6160.383961603849;6255.843603909939;6334.2398912130375;6361.459275961549;6387.169667943138;6395.20059992501;6426.1003459515705;6438.774642887476;6448.097266382711;6459.6447195404235;6198.535109866778;6198.659073750942;6200.844978875519;6213.968930155365;6216.2540495140875;6269.153664255267;6304.747810087637;6481.578697384655;6692.163479565054;6708.429783468362;6269.7492100316385;6279.780207692709;6284.340144284784;6296.590721603081;6305.274893387227;6429.646369449725;6451.032345148265;6456.289808121103;6462.256840463313;6463.321351258144;6201.565890387629;6320.209973753281;6377.840553986095;6379.681015949184;6383.234011918599;5401.6218864679095;6243.063540468966;6275.184226050585;6289.056641503719;6302.464290535356;4808.014357056795;4857.80568777252;4873.780679869415;4966.354373931365;4979.103761322996;4432.734035957853;4438.334249862546;4453.22068637987;4479.148961697267;4494.595486406239;5257.842264732064;5556;5844.6785426801935;5975.312839023481;5987.251593550806;6278.717457714414;6289.0880822280515;6303.401176888188;6402.807915762534;6426.389492998158;5978.133170690226;5992.101184822224;5995.820125396245;6011.458968692838;6022.247029742452;5585.218069470277;5588.413216612278;5609;5612.270404847343;5617.662940223518;6282.748690052438;6339.144215530844;6456.483481321577;6469.902951455638;6613.5370524062855;5449.918251226156;6378.840528986812;6399.8080057598345;6433.453156481752;6440.774572889926;5562.694051827189;6478.060681201106;6610.471162306908;6614.404703576702;6629.502787290876;6243.094751261133;6273.215848018997;6277.654728989767;6348.333424990495;6361.491080713625;6415.871682566416;6417.839554011187;6417.935820641734;6432.2281326241155;6450.677466126581;6299.370062993848;6305.148804911279;6306.558540902093;6310.148130002391;6321.209848768904;6180.042093475518;6180.351063138485;6264.68676566179;6271.090691849565;6297.622142671455;6153.84615384619;6171.166892469639;6266.717997690023;6275.309715931336;6279.686015699288;4885.364902562644;6261.655608941552;6284.088807123033;6296.401841825203;6350.491960643045;5995.550333724901;6063.454289114001;6097.054956481752;6122.295935967242;6257.311695713377 08:24:33 INFO - PROCESS | 1662 | |1;ext.html;757.5909009135067;800.1958032177661;823.0740417030838;960.7598100474877;972.9854052189214;38364.23271534568;39253.803730981344;41110.7944460278;41119.58880411203;41388.37917431237;39431.80284098582;39526.01184970377;39702.801485992604;39719;39807.60192398075;8059.838803223917;8183.71357002505;10838.241323107386;11120.110391168728;11247.66257012291;10964.835527467094;11078.833817492745;11082.501287442085;11084.224104312701;11102.389368584725;9491.715248542554;10922.453877306143;11089.500972456219;11112.388818615012;11125.777484450306;10056.145227655657;11017;11023.007929286363;11037.392943388111;11047.447627618667;9075.954620226923;10817.567297308098;10830.40432776197;10839.67480975572;10844;33172;33279.16802079943;33301.66793328264;33380;33393.332133357195;3850.903727406809;3870.574236833946;3895.2988462076814;3908.355121404965;3963.128111815396;19809;24757.752422475904;24826.875865620557;26281.08016219423;26377.868110659514;2783.7216278372202;3129.937401251985;3148.338848841746;3280.5899262592175;3292.27569934614;330616.3469182639;333680;345948.9999999975;349507;446083.99999999674;42173;42287.94280143021;42351.57648423538;42356.57643423588;42357.15285694269;43326.35010474845;43506.56493435025;43523.347149792775;43528.78235608801;43546.12907741827;13243.867561324263;13317.800232996513;13345.199288042748;13354.599362019155;13371.398287077105;11697.532098716127;11751.706207344714;11759.000484958704;11795.05639548834;11818.822717659243;11442.256253343507;11444.656660300205;11458.828117578243;11475.71310717222;11536.884631153582;4890.853274401774;5608.9439105609235;5621.943780562142;5679.375268720438;5698;972.8394814855469;1125.5610311978335;1134.8070827959266;1167;1175.9083169874423;25691.743082569308;25706.48587028249;25774.48451030969;25958.480830383287;26104;1112.9833052504218;1238.96283111505;1290.6257185416312;1324.8145259663654;1325.9469621215044;13055.086143969831;13378.063535552612;13616.251106189056;13624.386902589407;13624.795628065587;389291;401095;419908;423293.88353058655;423827;52971.205431918555;53344.73327633337;53435.732821335645;53465.73267133717;53525;1282.7498637765732;1456.3664805809506;1488.8213414390343;1498.6628008697955;1501.6696326808085;24796.884140213373;25376.731163442124;25614.487710246063;25750.484990299716;25814.09650662218;44636.33045504385;44800.65598032064;44818.77590612026;44841;44876.77561612171;401738;401794;402317;402346.97653024265;402403;27968.860155699906;28277.434451310448;29784.702152979058;30225.848870755504;30370.54444183295;36927.11557988042;37161.814190928875;37866.67466638654;37914.62085379111;38029.42955855609;23262.3021309361;26471;26485;27510.724892751616;27653.893844246006;26527.60208596834;26869.86565067162;27191.72808271892;27617;27740.58389124205;30914;31013;31148.68851311458;31706.20734481564;31792.841035794674;27300.59049114225;27386;27492.862535687193;27938.441231175668;28028.57957130604;21834.89082554577;22565.210217642303;22681.659775103715;22757.772422276226;23074.9616267265 08:24:33 INFO - PROCESS | 1662 | |2;jquery.html;361.9348517266892;397.7951355052149;408.41800434380997;408.51182836510384;418.8502560471504;16684;17468.21393037313;17875.37436189732;20026.799732002713;21153.259635912746;20881.477963050933;20927.895360523213;20938.37184884456;21501.892490537524;21547.13811447544;5952.19645347879;6545.149130613026;6888.621125838077;6919.8962015569805;6931.064306318633;6595.868082638344;6808.6936087876165;6812.080369150151;6846;6848.623325717083;6816.352446517573;6844.7946561602985;6847.075644787965;6870.209925858515;6884.655767211644;6675.365840245193;6758.628275444848;6821.965890170567;6888.483363747739;6889;6418.486521078303;6602.669866506655;6675.499337549703;6678.699458524378;6681.8663626727675;24505;25124.874375628187;25365.492690146093;25487.362815929566;25513;2921.1382642120625;2974.851257437141;2989.17797605658;2991.760659147263;3049.1614805928325;15690.686186276325;16348.509544713677;16354.100524471149;16650.50048498547;16977.74533381988;2026.3515674984012;2098.328534868843;2110.472381904524;2112.904919278636;2113.968290475644;162349;162912.3708762909;168447.15776421162;171982;172616;19988.700169497468;20500.69248961267;20502.627005214068;20889.686654700192;20975.580488390147;30608.08175754731;30889.536656950164;30954;30987.845060775002;31037.913673021336;8681.826363472821;10125.392476451436;10243.334183278062;10342.948285258675;10430.634927777492;8571.002831172751;8709.825803484022;8756;8910.73267801954;8988.191062804248;9294.814103717888;9346.485943273185;9436.11277744454;9477.478738669439;9517.381370210915;3469.548958635361;3997.900052498711;4089.284375234322;4094.0583665757044;4118.485189351331;887.7114937645291;908.40953380302;910.5037754423855;917.7155081924625;925.5881132895931;20664.793352066587;21068.367948961557;21070.894645526674;21131.8943405282;21191.046402911907;891.4428482198626;899.6941040046415;924.8381533231658;946.6592026870321;951.7668171298076;8863.512506812187;9346.953265233631;10303.793924121475;10945.616903408501;11020.724481888017;757487;758047;762650;771880;782044;130390.34804825915;157961.8407631864;165668;167454;171091;1181.9349935753617;1214.5566868093083;1251.9374031298398;1280.9359532023361;1289.7356042011365;18623.53441163982;19010.95439750827;19450.513737156685;19819.30632427858;19857;49906.50093499019;64151.679241603495;64688.353116468235;64903.05290841282;66663.33336666765;63759.68120159555;87579;93848.06151938393;95504;95617.52191238999;10207.234457415576;11409.144314176636;11440.427978601203;11477.94261028723;11771.174918019786;12841.101122921305;12911.289879056741;12944.870551294744;13299.401526931118;13305.733885322046;10735.248532602644;11096.278741881752;11101.500432480396;11124.888751112385;11337.319760814378;9842.360246583949;10704.197185210984;10750.139988801033;10768.892311076788;11058.502367393647;11590.78296778826;11657.18399712012;11757.588484403004;11896.583619573274;11942.223755455867;10247.644750980953;11109.722256943642;11646.883531164918;11722.765544688888;11738.882611173778;11391.373474458984;11406.973372396524;11653.6503904883;11690.298582085103;11780.52877884875 08:24:33 INFO - PROCESS | 1662 | |3;mootools.html;1568.3648122510374;1582.6201711589222;1598.4405458089664;1600.7198740220463;1601.3914712409282;9958.502074896254;10462.424566648831;10477.26659133861;10952.890471095287;11315.717107072327;9791.559379827908;11019.559217631286;11229.045531129843;11243.325400475956;11269.26749761265;2009.6483115454773;2014.5265862522297;2276;2324.407276144581;2336.6261398176293;2244.9887750561306;2284.7829456201634;2296.4258935266184;2314.525522267931;2323.9302820915314;2151.5051538146236;2177.934661960144;2259.378670865517;2321.5008773113777;2335.684682567857;2229.8104661103825;2301.4361481437154;2302.896369663369;2305.4697419593504;2309.0186670664984;2305.319930620471;2322.442613772699;2334.1947028275217;2351.5179388225374;2351.659009443621;10311.381317120997;10752.784944301071;11203.60787372438;11277;11287.54849806015;5037.697738135724;5451.890962180734;5496.697681627509;5502.284702988626;5512.779488820443;3148.354587309619;3246.5617141686043;3351.346487434951;3413.8805141819917;3418.282160746246;1108.7394462301352;1120.238237998169;1158.3918442817503;1161.134954458925;1168.5968340922368;13670.384832682454;14186;14666.19335936523;15265.313060912285;15287.770683439758;4315.805788739514;4423.871912662315;4439.1121775645;4445.73325600465;4446.444194475691;11675.591354302558;11687.298762074302;11711.765764684658;11730.589429369931;11731.76536469266;5428.83713488596;5428.972855135699;5437.483439073281;5439.374471935778;5453.700046497481;4787.473377928425;5014.724190169576;5022.171341728647;5043.621728370387;5069.1635880079375;3532.894013179609;3632.3824949758605;3685.815709214582;3712.3688972874675;3719.7210209234418;822.43663090783;910.7449914023952;914.401067300919;927.1562877781187;929.3680297397834;927.7263207353711;936.1621348892703;952.2049089010616;959.4147569982305;1007.0885848307294;6500.642464664489;6514.967425162844;6749.358810912955;6812.829679258059;6819.5908245505425;898.9706785730433;943.6980166346704;955.8040601676639;987.9308448408686;1010.8989101089979;4267.871963841089;4291.721038132511;4354.651627869763;4413.977930110394;4418.270985287456;13196.274204918629;13719.1082579632;13809.654758630913;13813.930930345485;13851.030427870153;13260.204387736767;13291.268980206181;13637.727245455233;13658.590242292747;13751;9945.353552019096;10005.399676019317;10067.093961543496;10152.086312231935;10223.335483193569;6588.341165883373;6625.4699624029;6708.563943343862;6709.463242940651;6791.830204244933;11957.701057473632;12193.51225950952;12209.511619535118;12231.204971676812;12236.265824050586;11139.554417823196;12136.817947730968;12729.618111456672;12731.745365092462;13076.607701768962;6847.6576171190245;8378.03652579961;8458.28104611115;8463.57682115904;8477.109903460048;10092.747681308027;10210.846837297595;10223.591056357662;10277.9486102569;10317.329373590692;7249.782506524813;8705.998810136914;8736.301095912184;8739.38824282294;8856.690015849414;8504;8601.440906341068;8608.268297194809;8734.737957861274;8792.648294068422;7804.9219507806465;7845.960770196112;7927.128015918129;7929.603519824101;7934.087579928382;5905.173275741486;5941.37615550361;5969.791057312973;5990.46085852258;6005.468605505568;5071.315372424676;5860;5995.250593675791;6122.112293717475;6273.839339722166 08:24:33 INFO - PROCESS | 1662 | |4;prototype.html;299.57161259399055;351.37455329513466;354.4948448460944;384.9153186299013;385.7396257526171;4932.062908047468;5101.923471147931;5145.871353216166;5188.584913206943;5199.350081239845;4353.216421044211;4522.68341216115;5043.974780126095;5059.898802023958;5084.796608135679;4120.835166593332;4213.7893105344765;4220.831166753326;4230.280852255109;4272.829086836523;3543.94684079739;3837.637638638285;4277.2087163874785;4278.197780453807;4286.185624731306;3657.487951686766;3713.57293911201;4231.132617813356;4269.3595960606;4282.892927676818;3548.290341931624;3772.7547709398937;4197.958020419787;4277.294246449348;4277.401163837066;3576.123849656851;3722.088088418328;3925.60743925608;3928.4696565963513;3929.332013557702;4094.508658960914;4271.765052922088;4947.579455746266;5011.949880501184;5108.003939231851;4434.84478043267;4832.420109586837;4931.4821943696115;4952.1181950858445;5004.449510553836;4624.606908412789;5421.349438067417;5473;5491.148871924858;5527.281453411031;3741.7567858089137;3828.8704832074604;4032.669219157671;4369.759663218521;4441.289393696993;4874.09829181599;5668.295314888674;5761.251037365116;5810.2569229231485;5811.709414529257;4260.676672195692;4385.320275357325;4427.690061695715;4434.955650443454;4439.356293337448;3934.449177115206;4130.359794231899;4491.573300536444;4496.910061798746;4542.591796543083;3968.543617484026;4072.572379900127;4316.3093904975685;4434.312681534368;4540.79566419512;5340.359156901119;5362.897383501624;5381.784728610812;5391.16436952273;5436.700981446058;1309.2340980526308;1414.7877818327333;1432.3411230833724;1527.4882914223638;1561.687662467511;952.8375382033907;1103.9724006899892;1118.869941359233;1141.1897552737482;1165.3998190931677;4513.074819662027;4801.735904525215;4885.925096478769;4894.402614078649;4943.456219815817;4451.221036318631;5100.846974590768;5219.686818790885;5451.48210919962;5651.745671444794;4468.664850136252;4594.323072078667;4901;4910.926336104962;4914.434839993375;2546.981207517008;2944.8674809633617;2949.7640188784844;2956.2410365832297;2957.6894426085382;5167.741612919414;5236.816711415082;5269.1832765921345;5850.53195744348;5873.590338318772;4612.2703986005245;5066.974665126724;5533.612647114664;5843.94156058434;5847.8012007538355;5549.667019978814;5826.388229235869;5876.970615147068;5905.675187864623;5965.733021451821;5141.742912854417;5285.392179899207;5335.67985920846;5340.626156169031;5449.945500544944;4655.902805531111;4987.675801072919;5449.7002664852225;5538.944610553843;5589.071770239261;5149.639525233198;5173.40505841818;5513.145462453326;5878.529717622494;5897.557683173865;3726.329260733094;4203.961078876681;4219.936700949427;4349.565043495625;4503.639708823221;4897.657163998629;5211.296474975829;5365.892682146257;5620.903923734996;5686.033374326457;4689.366935463805;4825.9758701206265;4902;5060;5506.339239291311;4378.912421751611;4805.543473370024;5287.52412282881;5305.018571564273;5447;4827.203511420576;5057.367829021372;5382.946170538245;5422.457754224546;5424.965637885793;4797.112534181188;4806.375171227718;5387.329927722419;5395.514403703529;5488.475301361418;4985.277134815359;5128.94784154701;5362.624616276823;5385.973070134624;5411.161270003156 08:24:33 INFO - PROCESS | 1662 | |5;yui.html;728.2717282717283;757.2881491398089;781.7413963518734;836.3267569606467;860.3332417376535;15609.765853512192;16100.59748506285;16623.584410389747;16654.500364989053;17123.74314385285;15114;15459.149746763924;15630.531084067496;15786.526404207863;15798.368065277404;978.7455261631956;995.2784231432227;1009.0918173643743;1017.4607458047246;1021.2187676427538;976.3653625143678;1004.7287232447235;1006.0492834271616;1008.596561375452;1010.1918465227825;1045.4406892312613;1046.9685909422728;1047.5600247895864;1048.1667074675652;1051.747580580659;1041.515695201731;1042.1142029275131;1043.5929987304958;1045.5817672930812;1046.5342922399536;782.5187509681562;785.2030189357806;787.8266781308103;788.0936922539125;790.549386849496;16394.344226230936;16531;16855.494335169842;17021.23404446803;17379.47861564155;13997.3701183447;14011;14251.748460014958;14327.641808954755;14455.855441445661;11689.649310520697;11814.291142531476;11830.940845295718;11919.225250358699;11930.105242106876;3620.1130722972775;3644.8542058317635;3687.0966613179676;3813.9809300953316;3875.011871972657;103881;108627;109215.90784092137;110548.4472577632;114034.42982785033;10534.578616855393;10602.840957385646;10721.463926803628;10850.620228291971;10997.61508347204;20372.388828335173;21491.03290351938;21800.236991705213;21959.780402196095;21988.560228795654;7952.920470795218;8142.307903828122;8147.307478864244;8153.388495862834;8239.835203295901;4334.881862230894;4356.847510337187;4461.174682683714;4512.062465541158;4536.3422303765765;3340.0981734931433;3385.322935412927;3398.677125673057;3407.522946787452;3418.8974330770116;622.2222222222222;635.9268684101295;636.3223167326782;636.5353292096795;645.2740666749906;653.6927644007309;676.5061505101205;686.8797960356918;695.3741632530763;698.9825254368579;10221.596765242128;10398.96010398954;10627.89372106269;10679.359238445719;10934.797172311039;618.6937465954381;707.6744697439235;713.2795876165111;720.6036679826075;726.9600171990486;4807.855764327076;4999.275105109738;5019.347484826949;5027.522385373383;5040.143175660147;99093;100030;100258.49870750746;101714;103044;65590;74352;77880;80237;80407;60149.12841835856;69361;70238.64880675563;71955.28044719486;73803.89294160593;10710.839337409789;10757.731056723644;11259.549618015188;11411;11443.48504317291;43298.567014329456;43988.34017489676;46983.82540436379;47155;50190.99618007692;59593.70203148956;61243.46891327574;67711;68768.65615671889;69031;11749.00133488663;12279.938600306941;12836.16564923277;12874.742505150032;12919.547815826401;13962.301884905917;14366.856331436551;14426.927865360605;14664.266786660412;14781.187034712764;13064.76708406459;13914.86085139176;13993.090449120775;14050.859491404955;14339.426422943383;13396.732065358834;13653.385597647932;13742.519011834538;13894.444222230997;14050.929745351208;12946.741065178832;13586.86413135856;13751.587452376445;13840.723185536433;14002.509912153026;10015.599376024877;10202.28583999113;10215.387076775418;10292.279540432099;10419.374837509773;8654.913450865412;9240.907590924005;10152.707769473227;10410.583576657153;10459.686209413425 08:24:33 INFO - PROCESS | 1662 | __end_tp_report 08:24:33 INFO - PROCESS | 1662 | __start_cc_report 08:24:33 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,359 08:24:33 INFO - PROCESS | 1662 | __end_cc_report 08:24:33 INFO - PROCESS | 1662 | __startTimestamp1496157873957__endTimestamp 08:24:33 INFO - PROCESS | 1662 | 08:24:33 INFO - PROCESS | 1662 | ------- Summary: start ------- 08:24:33 INFO - PROCESS | 1662 | Number of tests: 6 08:24:33 INFO - PROCESS | 1662 | 08:24:33 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5904.39 Median:6180.35 stddev:671.17 (10.9%) stddev-sans-first:637.27 08:24:33 INFO - PROCESS | 1662 | Values: 3141.4 3436.8 3503.9 3533.8 3543.8 5524.6 5982.5 5994.0 6010.0 6024.2 4910.8 5973.3 5994.1 6016.2 6058.4 5369.5 5433.1 5460.2 5478.0 5481.1 5467.5 5481.7 5495.7 5496.2 5500.9 5341.3 5358.9 5476.8 5494.2 5517.9 5410.3 5430.1 5467.5 5476.3 5513.6 5361.2 5494.0 5499.1 5516.3 5535.6 6090.7 6092.0 6126.2 6128.1 6147.5 6160.4 6255.8 6334.2 6361.5 6387.2 6395.2 6426.1 6438.8 6448.1 6459.6 6198.5 6198.7 6200.8 6214.0 6216.3 6269.2 6304.7 6481.6 6692.2 6708.4 6269.7 6279.8 6284.3 6296.6 6305.3 6429.6 6451.0 6456.3 6462.3 6463.3 6201.6 6320.2 6377.8 6379.7 6383.2 5401.6 6243.1 6275.2 6289.1 6302.5 4808.0 4857.8 4873.8 4966.4 4979.1 4432.7 4438.3 4453.2 4479.1 4494.6 5257.8 5556.0 5844.7 5975.3 5987.3 6278.7 6289.1 6303.4 6402.8 6426.4 5978.1 5992.1 5995.8 6011.5 6022.2 5585.2 5588.4 5609.0 5612.3 5617.7 6282.7 6339.1 6456.5 6469.9 6613.5 5449.9 6378.8 6399.8 6433.5 6440.8 5562.7 6478.1 6610.5 6614.4 6629.5 6243.1 6273.2 6277.7 6348.3 6361.5 6415.9 6417.8 6417.9 6432.2 6450.7 6299.4 6305.1 6306.6 6310.1 6321.2 6180.0 6180.4 6264.7 6271.1 6297.6 6153.8 6171.2 6266.7 6275.3 6279.7 4885.4 6261.7 6284.1 6296.4 6350.5 5995.6 6063.5 6097.1 6122.3 6257.3 08:24:33 INFO - PROCESS | 1662 | 08:24:33 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:51938.03 Median:27998.72 stddev:104154.55 (372.0%) stddev-sans-first:104375.85 08:24:33 INFO - PROCESS | 1662 | Values: 757.6 800.2 823.1 960.8 973.0 38364.2 39253.8 41110.8 41119.6 41388.4 39431.8 39526.0 39702.8 39719.0 39807.6 8059.8 8183.7 10838.2 11120.1 11247.7 10964.8 11078.8 11082.5 11084.2 11102.4 9491.7 10922.5 11089.5 11112.4 11125.8 10056.1 11017.0 11023.0 11037.4 11047.4 9076.0 10817.6 10830.4 10839.7 10844.0 33172.0 33279.2 33301.7 33380.0 33393.3 3850.9 3870.6 3895.3 3908.4 3963.1 19809.0 24757.8 24826.9 26281.1 26377.9 2783.7 3129.9 3148.3 3280.6 3292.3 330616.3 333680.0 345949.0 349507.0 446084.0 42173.0 42287.9 42351.6 42356.6 42357.2 43326.4 43506.6 43523.3 43528.8 43546.1 13243.9 13317.8 13345.2 13354.6 13371.4 11697.5 11751.7 11759.0 11795.1 11818.8 11442.3 11444.7 11458.8 11475.7 11536.9 4890.9 5608.9 5621.9 5679.4 5698.0 972.8 1125.6 1134.8 1167.0 1175.9 25691.7 25706.5 25774.5 25958.5 26104.0 1113.0 1239.0 1290.6 1324.8 1325.9 13055.1 13378.1 13616.3 13624.4 13624.8 389291.0 401095.0 419908.0 423293.9 423827.0 52971.2 53344.7 53435.7 53465.7 53525.0 1282.7 1456.4 1488.8 1498.7 1501.7 24796.9 25376.7 25614.5 25750.5 25814.1 44636.3 44800.7 44818.8 44841.0 44876.8 401738.0 401794.0 402317.0 402347.0 402403.0 27968.9 28277.4 29784.7 30225.8 30370.5 36927.1 37161.8 37866.7 37914.6 38029.4 23262.3 26471.0 26485.0 27510.7 27653.9 26527.6 26869.9 27191.7 27617.0 27740.6 30914.0 31013.0 31148.7 31706.2 31792.8 27300.6 27386.0 27492.9 27938.4 28028.6 21834.9 22565.2 22681.7 22757.8 23075.0 08:24:33 INFO - PROCESS | 1662 | 08:24:33 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:43897.54 Median:23026.07 stddev:128234.66 (556.9%) stddev-sans-first:128552.72 08:24:33 INFO - PROCESS | 1662 | Values: 361.9 397.8 408.4 408.5 418.9 16684.0 17468.2 17875.4 20026.8 21153.3 20881.5 20927.9 20938.4 21501.9 21547.1 5952.2 6545.1 6888.6 6919.9 6931.1 6595.9 6808.7 6812.1 6846.0 6848.6 6816.4 6844.8 6847.1 6870.2 6884.7 6675.4 6758.6 6822.0 6888.5 6889.0 6418.5 6602.7 6675.5 6678.7 6681.9 24505.0 25124.9 25365.5 25487.4 25513.0 2921.1 2974.9 2989.2 2991.8 3049.2 15690.7 16348.5 16354.1 16650.5 16977.7 2026.4 2098.3 2110.5 2112.9 2114.0 162349.0 162912.4 168447.2 171982.0 172616.0 19988.7 20500.7 20502.6 20889.7 20975.6 30608.1 30889.5 30954.0 30987.8 31037.9 8681.8 10125.4 10243.3 10342.9 10430.6 8571.0 8709.8 8756.0 8910.7 8988.2 9294.8 9346.5 9436.1 9477.5 9517.4 3469.5 3997.9 4089.3 4094.1 4118.5 887.7 908.4 910.5 917.7 925.6 20664.8 21068.4 21070.9 21131.9 21191.0 891.4 899.7 924.8 946.7 951.8 8863.5 9347.0 10303.8 10945.6 11020.7 757487.0 758047.0 762650.0 771880.0 782044.0 130390.3 157961.8 165668.0 167454.0 171091.0 1181.9 1214.6 1251.9 1280.9 1289.7 18623.5 19011.0 19450.5 19819.3 19857.0 49906.5 64151.7 64688.4 64903.1 66663.3 63759.7 87579.0 93848.1 95504.0 95617.5 10207.2 11409.1 11440.4 11477.9 11771.2 12841.1 12911.3 12944.9 13299.4 13305.7 10735.2 11096.3 11101.5 11124.9 11337.3 9842.4 10704.2 10750.1 10768.9 11058.5 11590.8 11657.2 11757.6 11896.6 11942.2 10247.6 11109.7 11646.9 11722.8 11738.9 11391.4 11407.0 11653.7 11690.3 11780.5 08:24:33 INFO - PROCESS | 1662 | 08:24:33 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:6601.15 Median:3298.95 stddev:4182.95 (126.8%) stddev-sans-first:4177.59 08:24:33 INFO - PROCESS | 1662 | Values: 1568.4 1582.6 1598.4 1600.7 1601.4 9958.5 10462.4 10477.3 10952.9 11315.7 9791.6 11019.6 11229.0 11243.3 11269.3 2009.6 2014.5 2276.0 2324.4 2336.6 2245.0 2284.8 2296.4 2314.5 2323.9 2151.5 2177.9 2259.4 2321.5 2335.7 2229.8 2301.4 2302.9 2305.5 2309.0 2305.3 2322.4 2334.2 2351.5 2351.7 10311.4 10752.8 11203.6 11277.0 11287.5 5037.7 5451.9 5496.7 5502.3 5512.8 3148.4 3246.6 3351.3 3413.9 3418.3 1108.7 1120.2 1158.4 1161.1 1168.6 13670.4 14186.0 14666.2 15265.3 15287.8 4315.8 4423.9 4439.1 4445.7 4446.4 11675.6 11687.3 11711.8 11730.6 11731.8 5428.8 5429.0 5437.5 5439.4 5453.7 4787.5 5014.7 5022.2 5043.6 5069.2 3532.9 3632.4 3685.8 3712.4 3719.7 822.4 910.7 914.4 927.2 929.4 927.7 936.2 952.2 959.4 1007.1 6500.6 6515.0 6749.4 6812.8 6819.6 899.0 943.7 955.8 987.9 1010.9 4267.9 4291.7 4354.7 4414.0 4418.3 13196.3 13719.1 13809.7 13813.9 13851.0 13260.2 13291.3 13637.7 13658.6 13751.0 9945.4 10005.4 10067.1 10152.1 10223.3 6588.3 6625.5 6708.6 6709.5 6791.8 11957.7 12193.5 12209.5 12231.2 12236.3 11139.6 12136.8 12729.6 12731.7 13076.6 6847.7 8378.0 8458.3 8463.6 8477.1 10092.7 10210.8 10223.6 10277.9 10317.3 7249.8 8706.0 8736.3 8739.4 8856.7 8504.0 8601.4 8608.3 8734.7 8792.6 7804.9 7846.0 7927.1 7929.6 7934.1 5905.2 5941.4 5969.8 5990.5 6005.5 5071.3 5860.0 5995.3 6122.1 6273.8 08:24:33 INFO - PROCESS | 1662 | 08:24:33 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:4483.96 Median:4880.01 stddev:1263.86 (25.9%) stddev-sans-first:1227.76 08:24:33 INFO - PROCESS | 1662 | Values: 299.6 351.4 354.5 384.9 385.7 4932.1 5101.9 5145.9 5188.6 5199.4 4353.2 4522.7 5044.0 5059.9 5084.8 4120.8 4213.8 4220.8 4230.3 4272.8 3543.9 3837.6 4277.2 4278.2 4286.2 3657.5 3713.6 4231.1 4269.4 4282.9 3548.3 3772.8 4198.0 4277.3 4277.4 3576.1 3722.1 3925.6 3928.5 3929.3 4094.5 4271.8 4947.6 5011.9 5108.0 4434.8 4832.4 4931.5 4952.1 5004.4 4624.6 5421.3 5473.0 5491.1 5527.3 3741.8 3828.9 4032.7 4369.8 4441.3 4874.1 5668.3 5761.3 5810.3 5811.7 4260.7 4385.3 4427.7 4435.0 4439.4 3934.4 4130.4 4491.6 4496.9 4542.6 3968.5 4072.6 4316.3 4434.3 4540.8 5340.4 5362.9 5381.8 5391.2 5436.7 1309.2 1414.8 1432.3 1527.5 1561.7 952.8 1104.0 1118.9 1141.2 1165.4 4513.1 4801.7 4885.9 4894.4 4943.5 4451.2 5100.8 5219.7 5451.5 5651.7 4468.7 4594.3 4901.0 4910.9 4914.4 2547.0 2944.9 2949.8 2956.2 2957.7 5167.7 5236.8 5269.2 5850.5 5873.6 4612.3 5067.0 5533.6 5843.9 5847.8 5549.7 5826.4 5877.0 5905.7 5965.7 5141.7 5285.4 5335.7 5340.6 5449.9 4655.9 4987.7 5449.7 5538.9 5589.1 5149.6 5173.4 5513.1 5878.5 5897.6 3726.3 4204.0 4219.9 4349.6 4503.6 4897.7 5211.3 5365.9 5620.9 5686.0 4689.4 4826.0 4902.0 5060.0 5506.3 4378.9 4805.5 5287.5 5305.0 5447.0 4827.2 5057.4 5382.9 5422.5 5425.0 4797.1 4806.4 5387.3 5395.5 5488.5 4985.3 5128.9 5362.6 5386.0 5411.2 08:24:33 INFO - PROCESS | 1662 | 08:24:33 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:19885.53 Median:16247.47 stddev:28014.34 (172.4%) stddev-sans-first:28056.00 08:24:33 INFO - PROCESS | 1662 | Values: 728.3 757.3 781.7 836.3 860.3 15609.8 16100.6 16623.6 16654.5 17123.7 15114.0 15459.1 15630.5 15786.5 15798.4 978.7 995.3 1009.1 1017.5 1021.2 976.4 1004.7 1006.0 1008.6 1010.2 1045.4 1047.0 1047.6 1048.2 1051.7 1041.5 1042.1 1043.6 1045.6 1046.5 782.5 785.2 787.8 788.1 790.5 16394.3 16531.0 16855.5 17021.2 17379.5 13997.4 14011.0 14251.7 14327.6 14455.9 11689.6 11814.3 11830.9 11919.2 11930.1 3620.1 3644.9 3687.1 3814.0 3875.0 103881.0 108627.0 109215.9 110548.4 114034.4 10534.6 10602.8 10721.5 10850.6 10997.6 20372.4 21491.0 21800.2 21959.8 21988.6 7952.9 8142.3 8147.3 8153.4 8239.8 4334.9 4356.8 4461.2 4512.1 4536.3 3340.1 3385.3 3398.7 3407.5 3418.9 622.2 635.9 636.3 636.5 645.3 653.7 676.5 686.9 695.4 699.0 10221.6 10399.0 10627.9 10679.4 10934.8 618.7 707.7 713.3 720.6 727.0 4807.9 4999.3 5019.3 5027.5 5040.1 99093.0 100030.0 100258.5 101714.0 103044.0 65590.0 74352.0 77880.0 80237.0 80407.0 60149.1 69361.0 70238.6 71955.3 73803.9 10710.8 10757.7 11259.5 11411.0 11443.5 43298.6 43988.3 46983.8 47155.0 50191.0 59593.7 61243.5 67711.0 68768.7 69031.0 11749.0 12279.9 12836.2 12874.7 12919.5 13962.3 14366.9 14426.9 14664.3 14781.2 13064.8 13914.9 13993.1 14050.9 14339.4 13396.7 13653.4 13742.5 13894.4 14050.9 12946.7 13586.9 13751.6 13840.7 14002.5 10015.6 10202.3 10215.4 10292.3 10419.4 8654.9 9240.9 10152.7 10410.6 10459.7 08:24:33 INFO - PROCESS | 1662 | -------- Summary: end -------- 08:24:33 INFO - PROCESS | 1662 | 08:24:34 INFO - TEST-INFO | 1662: exit 0 08:24:34 INFO - TEST-OK | dromaeo_css | took 1115678ms 08:24:34 INFO - TEST-START | kraken 08:24:34 INFO - operating with platform_type : mac_ 08:24:34 INFO - Initialising browser for kraken test... 08:24:34 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpCOKxAr/profile http://localhost:49225/getInfo.html) 08:24:36 INFO - PROCESS | 1669 | 2017-05-30 08:24:36.948 plugin-container[1671:11660] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 08:24:36 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:24:36 INFO - PROCESS | 1669 | 2017-05-30 08:24:36.949 plugin-container[1671:11660] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431b, name = 'com.apple.CFPasteboardClient' 08:24:36 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:24:36 INFO - PROCESS | 1669 | 2017-05-30 08:24:36.949 plugin-container[1671:11660] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:24:37 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 08:24:37 INFO - PROCESS | 1669 | colorDepth:24 08:24:37 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 08:24:37 INFO - PROCESS | 1669 | __metrics 08:24:37 ERROR - PROCESS | 1669 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 08:24:37 INFO - TEST-INFO | 1669: exit 0 08:24:37 INFO - Browser initialized. 08:24:37 INFO - Running cycle 1/1 for kraken test... 08:24:37 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpCOKxAr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:24:40 INFO - PROCESS | 1672 | 2017-05-30 08:24:40.164 plugin-container[1673:11783] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4537, name = 'com.apple.tsm.portname' 08:24:40 INFO - PROCESS | 1672 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:24:40 INFO - PROCESS | 1672 | 2017-05-30 08:24:40.164 plugin-container[1673:11783] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 08:24:40 INFO - PROCESS | 1672 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:24:40 INFO - PROCESS | 1672 | 2017-05-30 08:24:40.164 plugin-container[1673:11783] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:24:49 INFO - PROCESS | 1672 | RSS: Main: 202727424 08:24:49 INFO - PROCESS | 1672 | 08:24:53 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection) 08:24:54 INFO - PROCESS | 1672 | RSS: Main: 216596480 08:24:54 INFO - PROCESS | 1672 | 08:24:56 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-dft) 08:24:57 INFO - PROCESS | 1672 | RSS: Main: 224501760 08:24:57 INFO - PROCESS | 1672 | 08:25:00 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-fft) 08:25:01 INFO - PROCESS | 1672 | RSS: Main: 225959936 08:25:01 INFO - PROCESS | 1672 | 08:25:03 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator) 08:25:04 INFO - PROCESS | 1672 | RSS: Main: 227749888 08:25:04 INFO - PROCESS | 1672 | 08:25:06 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:25:06 INFO - PROCESS | 1672 | RSS: Main: 229449728 08:25:06 INFO - PROCESS | 1672 | 08:25:10 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom) 08:25:10 INFO - PROCESS | 1672 | RSS: Main: 233627648 08:25:10 INFO - PROCESS | 1672 | 08:25:13 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate) 08:25:14 INFO - PROCESS | 1672 | RSS: Main: 233320448 08:25:14 INFO - PROCESS | 1672 | 08:25:17 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial) 08:25:18 INFO - PROCESS | 1672 | RSS: Main: 233455616 08:25:18 INFO - PROCESS | 1672 | 08:25:20 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:25:20 INFO - PROCESS | 1672 | RSS: Main: 232517632 08:25:20 INFO - PROCESS | 1672 | 08:25:22 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:25:23 INFO - PROCESS | 1672 | RSS: Main: 232517632 08:25:23 INFO - PROCESS | 1672 | 08:25:25 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:25:25 INFO - PROCESS | 1672 | RSS: Main: 232648704 08:25:25 INFO - PROCESS | 1672 | 08:25:28 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:25:29 INFO - PROCESS | 1672 | RSS: Main: 232947712 08:25:29 INFO - PROCESS | 1672 | 08:25:32 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:25:33 INFO - PROCESS | 1672 | RSS: Main: 232927232 08:25:33 INFO - PROCESS | 1672 | 08:25:34 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49225/tests/kraken/driver.html?testName=ai-astar) 08:25:35 INFO - PROCESS | 1672 | RSS: Main: 236089344 08:25:35 INFO - PROCESS | 1672 | 08:25:35 INFO - PROCESS | 1672 | __start_tp_report 08:25:35 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 08:25:35 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 08:25:35 INFO - PROCESS | 1672 | |i|pagename|runs| 08:25:35 INFO - PROCESS | 1672 | |0;ai-astar;111;151;131;174;177;119;119;118;120;119 08:25:35 INFO - PROCESS | 1672 | |1;audio-beat-detection;142;113;121;148;103;104;133;105;125;139 08:25:35 INFO - PROCESS | 1672 | |2;audio-dft;197;200;178;193;267;208;273;281;223;200 08:25:35 INFO - PROCESS | 1672 | |3;audio-fft;78;68;86;108;86;60;65;75;95;83 08:25:35 INFO - PROCESS | 1672 | |4;audio-oscillator;89;70;84;74;70;70;82;71;72;82 08:25:35 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;135;135;96;139;90;133;137;95;94;119 08:25:35 INFO - PROCESS | 1672 | |6;imaging-darkroom;97;99;96;97;97;121;97;99;96;96 08:25:35 INFO - PROCESS | 1672 | |7;imaging-desaturate;147;143;94;146;139;95;91;91;143;90 08:25:35 INFO - PROCESS | 1672 | |8;json-parse-financial;66;54;58;102;99;69;69;71;69;71 08:25:35 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;59;64;56;69;60;57;57;59;60;59 08:25:35 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;84;100;108;79;83;82;82;89;87;88 08:25:35 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;159;157;145;149;148;138;146;145;188;142 08:25:35 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;168;229;214;168;165;170;167;168;168;169 08:25:35 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;70;81;86;67;67;65;65;66;65;66 08:25:35 INFO - PROCESS | 1672 | __end_tp_report 08:25:35 INFO - PROCESS | 1672 | __start_cc_report 08:25:35 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,997 08:25:35 INFO - PROCESS | 1672 | __end_cc_report 08:25:35 INFO - PROCESS | 1672 | __startTimestamp1496157935217__endTimestamp 08:25:35 INFO - PROCESS | 1672 | 08:25:35 INFO - PROCESS | 1672 | ------- Summary: start ------- 08:25:35 INFO - PROCESS | 1672 | Number of tests: 14 08:25:35 INFO - PROCESS | 1672 | 08:25:35 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:133.90 Median:125.50 stddev:24.50 (19.5%) stddev-sans-first:24.55 08:25:35 INFO - PROCESS | 1672 | Values: 111.0 151.0 131.0 174.0 177.0 119.0 119.0 118.0 120.0 119.0 08:25:35 INFO - PROCESS | 1672 | 08:25:35 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:123.30 Median:129.00 stddev:16.78 (13.0%) stddev-sans-first:16.38 08:25:35 INFO - PROCESS | 1672 | Values: 142.0 113.0 121.0 148.0 103.0 104.0 133.0 105.0 125.0 139.0 08:25:35 INFO - PROCESS | 1672 | 08:25:35 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:222.00 Median:215.50 stddev:37.53 (17.4%) stddev-sans-first:38.70 08:25:35 INFO - PROCESS | 1672 | Values: 197.0 200.0 178.0 193.0 267.0 208.0 273.0 281.0 223.0 200.0 08:25:35 INFO - PROCESS | 1672 | 08:25:35 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:80.40 Median:80.50 stddev:14.48 (18.0%) stddev-sans-first:15.33 08:25:35 INFO - PROCESS | 1672 | Values: 78.0 68.0 86.0 108.0 86.0 60.0 65.0 75.0 95.0 83.0 08:25:35 INFO - PROCESS | 1672 | 08:25:35 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:76.40 Median:78.00 stddev:7.12 (9.1%) stddev-sans-first:5.92 08:25:35 INFO - PROCESS | 1672 | Values: 89.0 70.0 84.0 74.0 70.0 70.0 82.0 71.0 72.0 82.0 08:25:35 INFO - PROCESS | 1672 | 08:25:35 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:114.50 stddev:21.01 (18.4%) stddev-sans-first:21.29 08:25:35 INFO - PROCESS | 1672 | Values: 135.0 135.0 96.0 139.0 90.0 133.0 137.0 95.0 94.0 119.0 08:25:35 INFO - PROCESS | 1672 | 08:25:35 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:99.50 Median:97.00 stddev:7.63 (7.9%) stddev-sans-first:8.04 08:25:35 INFO - PROCESS | 1672 | Values: 97.0 99.0 96.0 97.0 97.0 121.0 97.0 99.0 96.0 96.0 08:25:35 INFO - PROCESS | 1672 | 08:25:35 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:117.90 Median:90.50 stddev:27.21 (30.1%) stddev-sans-first:26.74 08:25:35 INFO - PROCESS | 1672 | Values: 147.0 143.0 94.0 146.0 139.0 95.0 91.0 91.0 143.0 90.0 08:25:35 INFO - PROCESS | 1672 | 08:25:35 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:72.80 Median:69.00 stddev:15.66 (22.7%) stddev-sans-first:16.42 08:25:35 INFO - PROCESS | 1672 | Values: 66.0 54.0 58.0 102.0 99.0 69.0 69.0 71.0 69.0 71.0 08:25:35 INFO - PROCESS | 1672 | 08:25:35 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:59.50 stddev:3.86 (6.5%) stddev-sans-first:4.08 08:25:35 INFO - PROCESS | 1672 | Values: 59.0 64.0 56.0 69.0 60.0 57.0 57.0 59.0 60.0 59.0 08:25:35 INFO - PROCESS | 1672 | 08:25:35 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:88.20 Median:83.50 stddev:9.07 (10.9%) stddev-sans-first:9.49 08:25:35 INFO - PROCESS | 1672 | Values: 84.0 100.0 108.0 79.0 83.0 82.0 82.0 89.0 87.0 88.0 08:25:35 INFO - PROCESS | 1672 | 08:25:35 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:151.70 Median:148.50 stddev:14.24 (9.6%) stddev-sans-first:14.85 08:25:35 INFO - PROCESS | 1672 | Values: 159.0 157.0 145.0 149.0 148.0 138.0 146.0 145.0 188.0 142.0 08:25:35 INFO - PROCESS | 1672 | 08:25:35 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.60 Median:168.50 stddev:22.92 (13.6%) stddev-sans-first:23.99 08:25:35 INFO - PROCESS | 1672 | Values: 168.0 229.0 214.0 168.0 165.0 170.0 167.0 168.0 168.0 169.0 08:25:35 INFO - PROCESS | 1672 | 08:25:35 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:67.00 stddev:7.47 (11.1%) stddev-sans-first:7.92 08:25:35 INFO - PROCESS | 1672 | Values: 70.0 81.0 86.0 67.0 67.0 65.0 65.0 66.0 65.0 66.0 08:25:35 INFO - PROCESS | 1672 | -------- Summary: end -------- 08:25:35 INFO - PROCESS | 1672 | 08:25:35 INFO - TEST-INFO | 1672: exit 0 08:25:35 INFO - TEST-OK | kraken | took 61451ms 08:25:35 INFO - SUITE-END | took 1177s 08:25:36 INFO - Completed test suite (00:19:37) 08:25:36 INFO - javascript benchmark 08:25:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7585.75144690347, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3141.3874294512566, 3436.759426840122, 3503.896272674106, 3533.8056406897626, 3543.805090720011, 5524.613277070606, 5982.461578457944, 5994.040953447438, 6009.978303688373, 6024.1566180734735, 4910.754462276881, 5973.253343332083, 5994.07091900756, 6016.187814645022, 6058.363871793454, 5369.463053694629, 5433.103537916259, 5460.235567020621, 5478.041342765, 5481.068218402881, 5467.45325467454, 5481.671099734009, 5495.697736624484, 5496.230527726121, 5500.889982200353, 5341.252224688546, 5358.91961620576, 5476.7535460904155, 5494.1758736189695, 5517.862053448675, 5410.2966614340085, 5430.131179011359, 5467.507924286793, 5476.315460567429, 5513.558915286808, 5361.169018802092, 5494.011078005958, 5499.120140777456, 5516.310461192351, 5535.58483113764, 6090.725917333686, 6091.969540152315, 6126.17296664949, 6128.111423843562, 6147.538934579924, 6160.383961603849, 6255.843603909939, 6334.2398912130375, 6361.459275961549, 6387.169667943138, 6395.20059992501, 6426.1003459515705, 6438.774642887476, 6448.097266382711, 6459.6447195404235, 6198.535109866778, 6198.659073750942, 6200.844978875519, 6213.968930155365, 6216.2540495140875, 6269.153664255267, 6304.747810087637, 6481.578697384655, 6692.163479565054, 6708.429783468362, 6269.7492100316385, 6279.780207692709, 6284.340144284784, 6296.590721603081, 6305.274893387227, 6429.646369449725, 6451.032345148265, 6456.289808121103, 6462.256840463313, 6463.321351258144, 6201.565890387629, 6320.209973753281, 6377.840553986095, 6379.681015949184, 6383.234011918599, 5401.6218864679095, 6243.063540468966, 6275.184226050585, 6289.056641503719, 6302.464290535356, 4808.014357056795, 4857.80568777252, 4873.780679869415, 4966.354373931365, 4979.103761322996, 4432.734035957853, 4438.334249862546, 4453.22068637987, 4479.148961697267, 4494.595486406239, 5257.842264732064, 5556.0, 5844.6785426801935, 5975.312839023481, 5987.251593550806, 6278.717457714414, 6289.0880822280515, 6303.401176888188, 6402.807915762534, 6426.389492998158, 5978.133170690226, 5992.101184822224, 5995.820125396245, 6011.458968692838, 6022.247029742452, 5585.218069470277, 5588.413216612278, 5609.0, 5612.270404847343, 5617.662940223518, 6282.748690052438, 6339.144215530844, 6456.483481321577, 6469.902951455638, 6613.5370524062855, 5449.918251226156, 6378.840528986812, 6399.8080057598345, 6433.453156481752, 6440.774572889926, 5562.694051827189, 6478.060681201106, 6610.471162306908, 6614.404703576702, 6629.502787290876, 6243.094751261133, 6273.215848018997, 6277.654728989767, 6348.333424990495, 6361.491080713625, 6415.871682566416, 6417.839554011187, 6417.935820641734, 6432.2281326241155, 6450.677466126581, 6299.370062993848, 6305.148804911279, 6306.558540902093, 6310.148130002391, 6321.209848768904, 6180.042093475518, 6180.351063138485, 6264.68676566179, 6271.090691849565, 6297.622142671455, 6153.84615384619, 6171.166892469639, 6266.717997690023, 6275.309715931336, 6279.686015699288, 4885.364902562644, 6261.655608941552, 6284.088807123033, 6296.401841825203, 6350.491960643045, 5995.550333724901, 6063.454289114001, 6097.054956481752, 6122.295935967242, 6257.311695713377], "value": 5862.067696555104, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [757.5909009135067, 800.1958032177661, 823.0740417030838, 960.7598100474877, 972.9854052189214, 38364.23271534568, 39253.803730981344, 41110.7944460278, 41119.58880411203, 41388.37917431237, 39431.80284098582, 39526.01184970377, 39702.801485992604, 39719.0, 39807.60192398075, 8059.838803223917, 8183.71357002505, 10838.241323107386, 11120.110391168728, 11247.66257012291, 10964.835527467094, 11078.833817492745, 11082.501287442085, 11084.224104312701, 11102.389368584725, 9491.715248542554, 10922.453877306143, 11089.500972456219, 11112.388818615012, 11125.777484450306, 10056.145227655657, 11017.0, 11023.007929286363, 11037.392943388111, 11047.447627618667, 9075.954620226923, 10817.567297308098, 10830.40432776197, 10839.67480975572, 10844.0, 33172.0, 33279.16802079943, 33301.66793328264, 33380.0, 33393.332133357195, 3850.903727406809, 3870.574236833946, 3895.2988462076814, 3908.355121404965, 3963.128111815396, 19809.0, 24757.752422475904, 24826.875865620557, 26281.08016219423, 26377.868110659514, 2783.7216278372202, 3129.937401251985, 3148.338848841746, 3280.5899262592175, 3292.27569934614, 330616.3469182639, 333680.0, 345948.9999999975, 349507.0, 446083.99999999674, 42173.0, 42287.94280143021, 42351.57648423538, 42356.57643423588, 42357.15285694269, 43326.35010474845, 43506.56493435025, 43523.347149792775, 43528.78235608801, 43546.12907741827, 13243.867561324263, 13317.800232996513, 13345.199288042748, 13354.599362019155, 13371.398287077105, 11697.532098716127, 11751.706207344714, 11759.000484958704, 11795.05639548834, 11818.822717659243, 11442.256253343507, 11444.656660300205, 11458.828117578243, 11475.71310717222, 11536.884631153582, 4890.853274401774, 5608.9439105609235, 5621.943780562142, 5679.375268720438, 5698.0, 972.8394814855469, 1125.5610311978335, 1134.8070827959266, 1167.0, 1175.9083169874423, 25691.743082569308, 25706.48587028249, 25774.48451030969, 25958.480830383287, 26104.0, 1112.9833052504218, 1238.96283111505, 1290.6257185416312, 1324.8145259663654, 1325.9469621215044, 13055.086143969831, 13378.063535552612, 13616.251106189056, 13624.386902589407, 13624.795628065587, 389291.0, 401095.0, 419908.0, 423293.88353058655, 423827.0, 52971.205431918555, 53344.73327633337, 53435.732821335645, 53465.73267133717, 53525.0, 1282.7498637765732, 1456.3664805809506, 1488.8213414390343, 1498.6628008697955, 1501.6696326808085, 24796.884140213373, 25376.731163442124, 25614.487710246063, 25750.484990299716, 25814.09650662218, 44636.33045504385, 44800.65598032064, 44818.77590612026, 44841.0, 44876.77561612171, 401738.0, 401794.0, 402317.0, 402346.97653024265, 402403.0, 27968.860155699906, 28277.434451310448, 29784.702152979058, 30225.848870755504, 30370.54444183295, 36927.11557988042, 37161.814190928875, 37866.67466638654, 37914.62085379111, 38029.42955855609, 23262.3021309361, 26471.0, 26485.0, 27510.724892751616, 27653.893844246006, 26527.60208596834, 26869.86565067162, 27191.72808271892, 27617.0, 27740.58389124205, 30914.0, 31013.0, 31148.68851311458, 31706.20734481564, 31792.841035794674, 27300.59049114225, 27386.0, 27492.862535687193, 27938.441231175668, 28028.57957130604, 21834.89082554577, 22565.210217642303, 22681.659775103715, 22757.772422276226, 23074.9616267265], "value": 17918.390228236207, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.9348517266892, 397.7951355052149, 408.41800434380997, 408.51182836510384, 418.8502560471504, 16684.0, 17468.21393037313, 17875.37436189732, 20026.799732002713, 21153.259635912746, 20881.477963050933, 20927.895360523213, 20938.37184884456, 21501.892490537524, 21547.13811447544, 5952.19645347879, 6545.149130613026, 6888.621125838077, 6919.8962015569805, 6931.064306318633, 6595.868082638344, 6808.6936087876165, 6812.080369150151, 6846.0, 6848.623325717083, 6816.352446517573, 6844.7946561602985, 6847.075644787965, 6870.209925858515, 6884.655767211644, 6675.365840245193, 6758.628275444848, 6821.965890170567, 6888.483363747739, 6889.0, 6418.486521078303, 6602.669866506655, 6675.499337549703, 6678.699458524378, 6681.8663626727675, 24505.0, 25124.874375628187, 25365.492690146093, 25487.362815929566, 25513.0, 2921.1382642120625, 2974.851257437141, 2989.17797605658, 2991.760659147263, 3049.1614805928325, 15690.686186276325, 16348.509544713677, 16354.100524471149, 16650.50048498547, 16977.74533381988, 2026.3515674984012, 2098.328534868843, 2110.472381904524, 2112.904919278636, 2113.968290475644, 162349.0, 162912.3708762909, 168447.15776421162, 171982.0, 172616.0, 19988.700169497468, 20500.69248961267, 20502.627005214068, 20889.686654700192, 20975.580488390147, 30608.08175754731, 30889.536656950164, 30954.0, 30987.845060775002, 31037.913673021336, 8681.826363472821, 10125.392476451436, 10243.334183278062, 10342.948285258675, 10430.634927777492, 8571.002831172751, 8709.825803484022, 8756.0, 8910.73267801954, 8988.191062804248, 9294.814103717888, 9346.485943273185, 9436.11277744454, 9477.478738669439, 9517.381370210915, 3469.548958635361, 3997.900052498711, 4089.284375234322, 4094.0583665757044, 4118.485189351331, 887.7114937645291, 908.40953380302, 910.5037754423855, 917.7155081924625, 925.5881132895931, 20664.793352066587, 21068.367948961557, 21070.894645526674, 21131.8943405282, 21191.046402911907, 891.4428482198626, 899.6941040046415, 924.8381533231658, 946.6592026870321, 951.7668171298076, 8863.512506812187, 9346.953265233631, 10303.793924121475, 10945.616903408501, 11020.724481888017, 757487.0, 758047.0, 762650.0, 771880.0, 782044.0, 130390.34804825915, 157961.8407631864, 165668.0, 167454.0, 171091.0, 1181.9349935753617, 1214.5566868093083, 1251.9374031298398, 1280.9359532023361, 1289.7356042011365, 18623.53441163982, 19010.95439750827, 19450.513737156685, 19819.30632427858, 19857.0, 49906.50093499019, 64151.679241603495, 64688.353116468235, 64903.05290841282, 66663.33336666765, 63759.68120159555, 87579.0, 93848.06151938393, 95504.0, 95617.52191238999, 10207.234457415576, 11409.144314176636, 11440.427978601203, 11477.94261028723, 11771.174918019786, 12841.101122921305, 12911.289879056741, 12944.870551294744, 13299.401526931118, 13305.733885322046, 10735.248532602644, 11096.278741881752, 11101.500432480396, 11124.888751112385, 11337.319760814378, 9842.360246583949, 10704.197185210984, 10750.139988801033, 10768.892311076788, 11058.502367393647, 11590.78296778826, 11657.18399712012, 11757.588484403004, 11896.583619573274, 11942.223755455867, 10247.644750980953, 11109.722256943642, 11646.883531164918, 11722.765544688888, 11738.882611173778, 11391.373474458984, 11406.973372396524, 11653.6503904883, 11690.298582085103, 11780.52877884875], "value": 11490.251795830847, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1568.3648122510374, 1582.6201711589222, 1598.4405458089664, 1600.7198740220463, 1601.3914712409282, 9958.502074896254, 10462.424566648831, 10477.26659133861, 10952.890471095287, 11315.717107072327, 9791.559379827908, 11019.559217631286, 11229.045531129843, 11243.325400475956, 11269.26749761265, 2009.6483115454773, 2014.5265862522297, 2276.0, 2324.407276144581, 2336.6261398176293, 2244.9887750561306, 2284.7829456201634, 2296.4258935266184, 2314.525522267931, 2323.9302820915314, 2151.5051538146236, 2177.934661960144, 2259.378670865517, 2321.5008773113777, 2335.684682567857, 2229.8104661103825, 2301.4361481437154, 2302.896369663369, 2305.4697419593504, 2309.0186670664984, 2305.319930620471, 2322.442613772699, 2334.1947028275217, 2351.5179388225374, 2351.659009443621, 10311.381317120997, 10752.784944301071, 11203.60787372438, 11277.0, 11287.54849806015, 5037.697738135724, 5451.890962180734, 5496.697681627509, 5502.284702988626, 5512.779488820443, 3148.354587309619, 3246.5617141686043, 3351.346487434951, 3413.8805141819917, 3418.282160746246, 1108.7394462301352, 1120.238237998169, 1158.3918442817503, 1161.134954458925, 1168.5968340922368, 13670.384832682454, 14186.0, 14666.19335936523, 15265.313060912285, 15287.770683439758, 4315.805788739514, 4423.871912662315, 4439.1121775645, 4445.73325600465, 4446.444194475691, 11675.591354302558, 11687.298762074302, 11711.765764684658, 11730.589429369931, 11731.76536469266, 5428.83713488596, 5428.972855135699, 5437.483439073281, 5439.374471935778, 5453.700046497481, 4787.473377928425, 5014.724190169576, 5022.171341728647, 5043.621728370387, 5069.1635880079375, 3532.894013179609, 3632.3824949758605, 3685.815709214582, 3712.3688972874675, 3719.7210209234418, 822.43663090783, 910.7449914023952, 914.401067300919, 927.1562877781187, 929.3680297397834, 927.7263207353711, 936.1621348892703, 952.2049089010616, 959.4147569982305, 1007.0885848307294, 6500.642464664489, 6514.967425162844, 6749.358810912955, 6812.829679258059, 6819.5908245505425, 898.9706785730433, 943.6980166346704, 955.8040601676639, 987.9308448408686, 1010.8989101089979, 4267.871963841089, 4291.721038132511, 4354.651627869763, 4413.977930110394, 4418.270985287456, 13196.274204918629, 13719.1082579632, 13809.654758630913, 13813.930930345485, 13851.030427870153, 13260.204387736767, 13291.268980206181, 13637.727245455233, 13658.590242292747, 13751.0, 9945.353552019096, 10005.399676019317, 10067.093961543496, 10152.086312231935, 10223.335483193569, 6588.341165883373, 6625.4699624029, 6708.563943343862, 6709.463242940651, 6791.830204244933, 11957.701057473632, 12193.51225950952, 12209.511619535118, 12231.204971676812, 12236.265824050586, 11139.554417823196, 12136.817947730968, 12729.618111456672, 12731.745365092462, 13076.607701768962, 6847.6576171190245, 8378.03652579961, 8458.28104611115, 8463.57682115904, 8477.109903460048, 10092.747681308027, 10210.846837297595, 10223.591056357662, 10277.9486102569, 10317.329373590692, 7249.782506524813, 8705.998810136914, 8736.301095912184, 8739.38824282294, 8856.690015849414, 8504.0, 8601.440906341068, 8608.268297194809, 8734.737957861274, 8792.648294068422, 7804.9219507806465, 7845.960770196112, 7927.128015918129, 7929.603519824101, 7934.087579928382, 5905.173275741486, 5941.37615550361, 5969.791057312973, 5990.46085852258, 6005.468605505568, 5071.315372424676, 5860.0, 5995.250593675791, 6122.112293717475, 6273.839339722166], "value": 4996.428087405592, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.57161259399055, 351.37455329513466, 354.4948448460944, 384.9153186299013, 385.7396257526171, 4932.062908047468, 5101.923471147931, 5145.871353216166, 5188.584913206943, 5199.350081239845, 4353.216421044211, 4522.68341216115, 5043.974780126095, 5059.898802023958, 5084.796608135679, 4120.835166593332, 4213.7893105344765, 4220.831166753326, 4230.280852255109, 4272.829086836523, 3543.94684079739, 3837.637638638285, 4277.2087163874785, 4278.197780453807, 4286.185624731306, 3657.487951686766, 3713.57293911201, 4231.132617813356, 4269.3595960606, 4282.892927676818, 3548.290341931624, 3772.7547709398937, 4197.958020419787, 4277.294246449348, 4277.401163837066, 3576.123849656851, 3722.088088418328, 3925.60743925608, 3928.4696565963513, 3929.332013557702, 4094.508658960914, 4271.765052922088, 4947.579455746266, 5011.949880501184, 5108.003939231851, 4434.84478043267, 4832.420109586837, 4931.4821943696115, 4952.1181950858445, 5004.449510553836, 4624.606908412789, 5421.349438067417, 5473.0, 5491.148871924858, 5527.281453411031, 3741.7567858089137, 3828.8704832074604, 4032.669219157671, 4369.759663218521, 4441.289393696993, 4874.09829181599, 5668.295314888674, 5761.251037365116, 5810.2569229231485, 5811.709414529257, 4260.676672195692, 4385.320275357325, 4427.690061695715, 4434.955650443454, 4439.356293337448, 3934.449177115206, 4130.359794231899, 4491.573300536444, 4496.910061798746, 4542.591796543083, 3968.543617484026, 4072.572379900127, 4316.3093904975685, 4434.312681534368, 4540.79566419512, 5340.359156901119, 5362.897383501624, 5381.784728610812, 5391.16436952273, 5436.700981446058, 1309.2340980526308, 1414.7877818327333, 1432.3411230833724, 1527.4882914223638, 1561.687662467511, 952.8375382033907, 1103.9724006899892, 1118.869941359233, 1141.1897552737482, 1165.3998190931677, 4513.074819662027, 4801.735904525215, 4885.925096478769, 4894.402614078649, 4943.456219815817, 4451.221036318631, 5100.846974590768, 5219.686818790885, 5451.48210919962, 5651.745671444794, 4468.664850136252, 4594.323072078667, 4901.0, 4910.926336104962, 4914.434839993375, 2546.981207517008, 2944.8674809633617, 2949.7640188784844, 2956.2410365832297, 2957.6894426085382, 5167.741612919414, 5236.816711415082, 5269.1832765921345, 5850.53195744348, 5873.590338318772, 4612.2703986005245, 5066.974665126724, 5533.612647114664, 5843.94156058434, 5847.8012007538355, 5549.667019978814, 5826.388229235869, 5876.970615147068, 5905.675187864623, 5965.733021451821, 5141.742912854417, 5285.392179899207, 5335.67985920846, 5340.626156169031, 5449.945500544944, 4655.902805531111, 4987.675801072919, 5449.7002664852225, 5538.944610553843, 5589.071770239261, 5149.639525233198, 5173.40505841818, 5513.145462453326, 5878.529717622494, 5897.557683173865, 3726.329260733094, 4203.961078876681, 4219.936700949427, 4349.565043495625, 4503.639708823221, 4897.657163998629, 5211.296474975829, 5365.892682146257, 5620.903923734996, 5686.033374326457, 4689.366935463805, 4825.9758701206265, 4902.0, 5060.0, 5506.339239291311, 4378.912421751611, 4805.543473370024, 5287.52412282881, 5305.018571564273, 5447.0, 4827.203511420576, 5057.367829021372, 5382.946170538245, 5422.457754224546, 5424.965637885793, 4797.112534181188, 4806.375171227718, 5387.329927722419, 5395.514403703529, 5488.475301361418, 4985.277134815359, 5128.94784154701, 5362.624616276823, 5385.973070134624, 5411.161270003156], "value": 4110.940465593109, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.2717282717283, 757.2881491398089, 781.7413963518734, 836.3267569606467, 860.3332417376535, 15609.765853512192, 16100.59748506285, 16623.584410389747, 16654.500364989053, 17123.74314385285, 15114.0, 15459.149746763924, 15630.531084067496, 15786.526404207863, 15798.368065277404, 978.7455261631956, 995.2784231432227, 1009.0918173643743, 1017.4607458047246, 1021.2187676427538, 976.3653625143678, 1004.7287232447235, 1006.0492834271616, 1008.596561375452, 1010.1918465227825, 1045.4406892312613, 1046.9685909422728, 1047.5600247895864, 1048.1667074675652, 1051.747580580659, 1041.515695201731, 1042.1142029275131, 1043.5929987304958, 1045.5817672930812, 1046.5342922399536, 782.5187509681562, 785.2030189357806, 787.8266781308103, 788.0936922539125, 790.549386849496, 16394.344226230936, 16531.0, 16855.494335169842, 17021.23404446803, 17379.47861564155, 13997.3701183447, 14011.0, 14251.748460014958, 14327.641808954755, 14455.855441445661, 11689.649310520697, 11814.291142531476, 11830.940845295718, 11919.225250358699, 11930.105242106876, 3620.1130722972775, 3644.8542058317635, 3687.0966613179676, 3813.9809300953316, 3875.011871972657, 103881.0, 108627.0, 109215.90784092137, 110548.4472577632, 114034.42982785033, 10534.578616855393, 10602.840957385646, 10721.463926803628, 10850.620228291971, 10997.61508347204, 20372.388828335173, 21491.03290351938, 21800.236991705213, 21959.780402196095, 21988.560228795654, 7952.920470795218, 8142.307903828122, 8147.307478864244, 8153.388495862834, 8239.835203295901, 4334.881862230894, 4356.847510337187, 4461.174682683714, 4512.062465541158, 4536.3422303765765, 3340.0981734931433, 3385.322935412927, 3398.677125673057, 3407.522946787452, 3418.8974330770116, 622.2222222222222, 635.9268684101295, 636.3223167326782, 636.5353292096795, 645.2740666749906, 653.6927644007309, 676.5061505101205, 686.8797960356918, 695.3741632530763, 698.9825254368579, 10221.596765242128, 10398.96010398954, 10627.89372106269, 10679.359238445719, 10934.797172311039, 618.6937465954381, 707.6744697439235, 713.2795876165111, 720.6036679826075, 726.9600171990486, 4807.855764327076, 4999.275105109738, 5019.347484826949, 5027.522385373383, 5040.143175660147, 99093.0, 100030.0, 100258.49870750746, 101714.0, 103044.0, 65590.0, 74352.0, 77880.0, 80237.0, 80407.0, 60149.12841835856, 69361.0, 70238.64880675563, 71955.28044719486, 73803.89294160593, 10710.839337409789, 10757.731056723644, 11259.549618015188, 11411.0, 11443.48504317291, 43298.567014329456, 43988.34017489676, 46983.82540436379, 47155.0, 50190.99618007692, 59593.70203148956, 61243.46891327574, 67711.0, 68768.65615671889, 69031.0, 11749.00133488663, 12279.938600306941, 12836.16564923277, 12874.742505150032, 12919.547815826401, 13962.301884905917, 14366.856331436551, 14426.927865360605, 14664.266786660412, 14781.187034712764, 13064.76708406459, 13914.86085139176, 13993.090449120775, 14050.859491404955, 14339.426422943383, 13396.732065358834, 13653.385597647932, 13742.519011834538, 13894.444222230997, 14050.929745351208, 12946.741065178832, 13586.86413135856, 13751.587452376445, 13840.723185536433, 14002.509912153026, 10015.599376024877, 10202.28583999113, 10215.387076775418, 10292.279540432099, 10419.374837509773, 8654.913450865412, 9240.907590924005, 10152.707769473227, 10410.583576657153, 10459.686209413425], "value": 7685.458535002746, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1591.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 151.0, 131.0, 174.0, 177.0, 119.0, 119.0, 118.0, 120.0, 119.0], "value": 133.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 113.0, 121.0, 148.0, 103.0, 104.0, 133.0, 105.0, 125.0, 139.0], "value": 123.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 200.0, 178.0, 193.0, 267.0, 208.0, 273.0, 281.0, 223.0, 200.0], "value": 222.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 68.0, 86.0, 108.0, 86.0, 60.0, 65.0, 75.0, 95.0, 83.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 70.0, 84.0, 74.0, 70.0, 70.0, 82.0, 71.0, 72.0, 82.0], "value": 76.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 135.0, 96.0, 139.0, 90.0, 133.0, 137.0, 95.0, 94.0, 119.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 99.0, 96.0, 97.0, 97.0, 121.0, 97.0, 99.0, 96.0, 96.0], "value": 99.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 143.0, 94.0, 146.0, 139.0, 95.0, 91.0, 91.0, 143.0, 90.0], "value": 117.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 54.0, 58.0, 102.0, 99.0, 69.0, 69.0, 71.0, 69.0, 71.0], "value": 72.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 56.0, 69.0, 60.0, 57.0, 57.0, 59.0, 60.0, 59.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 100.0, 108.0, 79.0, 83.0, 82.0, 82.0, 89.0, 87.0, 88.0], "value": 88.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 157.0, 145.0, 149.0, 148.0, 138.0, 146.0, 145.0, 188.0, 142.0], "value": 151.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 229.0, 214.0, 168.0, 165.0, 170.0, 167.0, 168.0, 168.0, 169.0], "value": 178.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 81.0, 86.0, 67.0, 67.0, 65.0, 65.0, 66.0, 65.0, 66.0], "value": 69.8, "unit": "score"}]}]} 08:25:36 INFO - Return code: 0 08:25:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:25:36 ERROR - # TBPL SUCCESS # 08:25:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:25:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:25:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:25:36 INFO - 2017-05-30 08:25:36.563 screenresolution[1677:12180] starting screenresolution argv=screenresolution get 08:25:36 INFO - 2017-05-30 08:25:36.565 screenresolution[1677:12180] Display 0: 1600x1200x32@60 08:25:36 INFO - 2017-05-30 08:25:36.571 screenresolution[1678:12183] starting screenresolution argv=screenresolution list 08:25:36 INFO - Available Modes on Display 0 08:25:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:25:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:25:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:25:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:25:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:25:36 INFO - Intel Iris: 08:25:36 INFO - Chipset Model: Intel Iris 08:25:36 INFO - Type: GPU 08:25:36 INFO - Bus: Built-In 08:25:36 INFO - VRAM (Dynamic, Max): 1536 MB 08:25:36 INFO - Vendor: Intel (0x8086) 08:25:36 INFO - Device ID: 0x0a2e 08:25:36 INFO - Revision ID: 0x0009 08:25:36 INFO - Displays: 08:25:36 INFO - Display: 08:25:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:25:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:25:36 INFO - Display Serial Number: 1600x1200 60 08:25:36 INFO - Main Display: Yes 08:25:36 INFO - Mirror: Off 08:25:36 INFO - Online: Yes 08:25:36 INFO - Rotation: Supported 08:25:36 INFO - Return code: 0 08:25:36 INFO - Running post-action listener: _resource_record_post_action 08:25:36 INFO - [mozharness: 2017-05-30 15:25:36.808306Z] Finished run-tests step (success) 08:25:36 INFO - Running post-run listener: _resource_record_post_run 08:25:36 INFO - Total resource usage - Wall time: 1198s; CPU: 55.0%; Read bytes: 35438592; Write bytes: 515336192; Read time: 329; Write time: 1629 08:25:36 INFO - TinderboxPrint: CPU usage
54.8% 08:25:36 INFO - TinderboxPrint: I/O read bytes / time
35,438,592 / 329 08:25:36 INFO - TinderboxPrint: I/O write bytes / time
515,336,192 / 1,629 08:25:36 INFO - TinderboxPrint: CPU idle
2,166.8 (45.2%) 08:25:36 INFO - TinderboxPrint: CPU system
970.2 (20.2%) 08:25:36 INFO - TinderboxPrint: CPU user
1,656.7 (34.6%) 08:25:36 INFO - TinderboxPrint: Swap in / out
240,472,064 / 0 08:25:36 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 202164736; Write bytes: 203638784; Read time: 15943; Write time: 390 08:25:36 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 33583104; Write bytes: 300838912; Read time: 305; Write time: 1204 08:25:37 INFO - Running post-run listener: _upload_blobber_files 08:25:37 INFO - Blob upload gear active. 08:25:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:25:37 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 08:25:37 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'] 08:25:37 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 08:25:37 INFO - (blobuploader) - INFO - Open directory for files ... 08:25:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:25:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:25:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:25:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:25:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:25:38 INFO - (blobuploader) - INFO - Done attempting. 08:25:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:25:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:25:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:25:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:25:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:25:39 INFO - (blobuploader) - INFO - Done attempting. 08:25:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:25:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:25:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:25:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:25:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:25:40 INFO - (blobuploader) - INFO - Done attempting. 08:25:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:25:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:25:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:25:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:25:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:25:42 INFO - (blobuploader) - INFO - Done attempting. 08:25:42 INFO - (blobuploader) - INFO - Iteration through files over. 08:25:42 INFO - Return code: 0 08:25:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:25:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:25:42 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e4d48b3127e9c862371c0315445840684ca95dcc22848b600ca26d052d6c61b9375e4123ec206810ac3eee59c9916a1751e363d7e4316a2bcf87103b967641f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3e1a405aa79f38982164316b75549a5262b6e1f5d112ab3bbe5e691a2a4fc27a6fa0a6912354d9db50d8025adb35df6f0ffc574c19a1523225e5a77e9cbc4cee", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b89160aa612dcae423685f2be52c60d7f8adf3aae9e2e8e5169acd2742a7f850dd73d7695a4b8ab7d67570ca7540004a6802e226235295469be57f8f80c4ee1d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0199f9dbf310895989dbbbafa009c2902410eacbae15d2903dcd7098455977edc9b2d08f8804360580a7da8e2e81d6276f9fb8c3cc8350c0f66b8fd5765da391"} 08:25:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:25:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:25:42 INFO - Contents: 08:25:42 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e4d48b3127e9c862371c0315445840684ca95dcc22848b600ca26d052d6c61b9375e4123ec206810ac3eee59c9916a1751e363d7e4316a2bcf87103b967641f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3e1a405aa79f38982164316b75549a5262b6e1f5d112ab3bbe5e691a2a4fc27a6fa0a6912354d9db50d8025adb35df6f0ffc574c19a1523225e5a77e9cbc4cee", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b89160aa612dcae423685f2be52c60d7f8adf3aae9e2e8e5169acd2742a7f850dd73d7695a4b8ab7d67570ca7540004a6802e226235295469be57f8f80c4ee1d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0199f9dbf310895989dbbbafa009c2902410eacbae15d2903dcd7098455977edc9b2d08f8804360580a7da8e2e81d6276f9fb8c3cc8350c0f66b8fd5765da391"} 08:25:42 INFO - Running post-run listener: copy_logs_to_upload_dir 08:25:42 INFO - Copying logs to upload dir... 08:25:42 INFO - mkdir: /builds/slave/test/build/upload/logs 08:25:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.843517 ========= master_lag: 2.13 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-05-30 08:25:44.742413) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 08:25:44.746448) ========= 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.ulAPCGdced/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.wgKISAwlWF/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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/e4d48b3127e9c862371c0315445840684ca95dcc22848b600ca26d052d6c61b9375e4123ec206810ac3eee59c9916a1751e363d7e4316a2bcf87103b967641f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3e1a405aa79f38982164316b75549a5262b6e1f5d112ab3bbe5e691a2a4fc27a6fa0a6912354d9db50d8025adb35df6f0ffc574c19a1523225e5a77e9cbc4cee", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b89160aa612dcae423685f2be52c60d7f8adf3aae9e2e8e5169acd2742a7f850dd73d7695a4b8ab7d67570ca7540004a6802e226235295469be57f8f80c4ee1d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0199f9dbf310895989dbbbafa009c2902410eacbae15d2903dcd7098455977edc9b2d08f8804360580a7da8e2e81d6276f9fb8c3cc8350c0f66b8fd5765da391"} build_url:https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011177 build_url: 'https://queue.taskcluster.net/v1/task/T1vHtvAzSVWAARDKnY1tgw/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/e4d48b3127e9c862371c0315445840684ca95dcc22848b600ca26d052d6c61b9375e4123ec206810ac3eee59c9916a1751e363d7e4316a2bcf87103b967641f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3e1a405aa79f38982164316b75549a5262b6e1f5d112ab3bbe5e691a2a4fc27a6fa0a6912354d9db50d8025adb35df6f0ffc574c19a1523225e5a77e9cbc4cee", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b89160aa612dcae423685f2be52c60d7f8adf3aae9e2e8e5169acd2742a7f850dd73d7695a4b8ab7d67570ca7540004a6802e226235295469be57f8f80c4ee1d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0199f9dbf310895989dbbbafa009c2902410eacbae15d2903dcd7098455977edc9b2d08f8804360580a7da8e2e81d6276f9fb8c3cc8350c0f66b8fd5765da391"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 08:25:44.781131) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 08:25:44.781465) ========= 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.ulAPCGdced/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.wgKISAwlWF/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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.005243 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 08:25:44.831189) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 08:25:44.831517) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 08:25:44.831860) ========= ========= Total master_lag: 2.28 =========