builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0384 starttime: 1496948927.06 results: success (0) buildid: 20170608104024 builduid: 6c076a982fa74dafa72304d8c38ff2af revision: d8424bcbf72f7a451e89f8f625a9ebafecded872 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-08 12:08:47.059278) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-08 12:08:47.059693) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-08 12:08:47.059980) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-08 12:08:47.083387) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 12:08:47.083723) ========= 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.3q907TDvU3/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.ZEdwKJyzMJ/Listeners TMPDIR=/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-08 12:08:47-- 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% 137M=0s 2017-06-08 12:08:47 (137 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.118487 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 12:08:47.224653) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 12:08:47.224977) ========= 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.3q907TDvU3/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.ZEdwKJyzMJ/Listeners TMPDIR=/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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.019579 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 12:08:47.273885) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-08 12:08:47.274238) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d8424bcbf72f7a451e89f8f625a9ebafecded872 --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 d8424bcbf72f7a451e89f8f625a9ebafecded872 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3q907TDvU3/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.ZEdwKJyzMJ/Listeners TMPDIR=/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-08 12:08:47,354 truncating revision to first 12 chars 2017-06-08 12:08:47,354 Setting DEBUG logging. 2017-06-08 12:08:47,354 attempt 1/10 2017-06-08 12:08:47,354 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d8424bcbf72f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-08 12:08:48,512 unpacking tar archive at: mozilla-beta-d8424bcbf72f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.431155 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-08 12:08:48.727899) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-08 12:08:48.728221) ========= script_repo_revision: d8424bcbf72f7a451e89f8f625a9ebafecded872 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-08 12:08:48.728671) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-08 12:08:48.728955) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-08 12:08:48.749120) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2017-06-08 12:08:48.749750) ========= /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.3q907TDvU3/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.ZEdwKJyzMJ/Listeners TMPDIR=/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 12:08:48 INFO - MultiFileLogger online at 20170608 12:08:48 in /builds/slave/test 12:08:48 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 12:08:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:08:48 INFO - {'append_to_log': False, 12:08:48 INFO - 'base_work_dir': '/builds/slave/test', 12:08:48 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:08:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:08:48 INFO - 'branch': 'Mozilla-Beta', 12:08:48 INFO - 'buildbot_json_path': 'buildprops.json', 12:08:48 INFO - 'config_files': ('talos/mac_config.py',), 12:08:48 INFO - 'default_actions': ('clobber', 12:08:48 INFO - 'read-buildbot-config', 12:08:48 INFO - 'download-and-extract', 12:08:48 INFO - 'populate-webroot', 12:08:48 INFO - 'create-virtualenv', 12:08:48 INFO - 'install', 12:08:48 INFO - 'run-tests'), 12:08:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:08:48 INFO - 'download_minidump_stackwalk': True, 12:08:48 INFO - 'download_symbols': 'ondemand', 12:08:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:08:48 INFO - 'tooltool.py': '/tools/tooltool.py', 12:08:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:08:48 INFO - '/tools/misc-python/virtualenv.py')}, 12:08:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:08:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:08:48 INFO - 'gecko_profile': False, 12:08:48 INFO - 'gecko_profile_interval': 0, 12:08:48 INFO - 'installer_path': 'installer.exe', 12:08:48 INFO - 'log_level': 'info', 12:08:48 INFO - 'log_name': 'talos', 12:08:48 INFO - 'log_to_console': True, 12:08:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:08:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:08:48 INFO - 'opt_config_files': (), 12:08:48 INFO - 'pip_index': False, 12:08:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:08:48 INFO - 'cmd': ('bash', 12:08:48 INFO - '-c', 12:08:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:08:48 INFO - 'enabled': True, 12:08:48 INFO - 'halt_on_failure': False, 12:08:48 INFO - 'name': 'check_screen_resolution'},), 12:08:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:08:48 INFO - 'cmd': ('bash', 12:08:48 INFO - '-c', 12:08:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:08:48 INFO - 'enabled': True, 12:08:48 INFO - 'halt_on_failure': False, 12:08:48 INFO - 'name': 'check_screen_resolution'},), 12:08:48 INFO - 'run_cmd_checks_enabled': True, 12:08:48 INFO - 'suite': 'dromaeojs-e10s', 12:08:48 INFO - 'system_bits': '32', 12:08:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:08:48 INFO - 'title': 't-yosemite-r7-0384', 12:08:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:08:48 INFO - 'use_talos_json': True, 12:08:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:08:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:08:48 INFO - 'work_dir': 'build'} 12:08:48 INFO - [mozharness: 2017-06-08 19:08:48.953527Z] Running clobber step. 12:08:48 INFO - Running pre-action listener: _resource_record_pre_action 12:08:48 INFO - Running main action method: clobber 12:08:48 INFO - rmtree: /builds/slave/test/build 12:08:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:08:50 INFO - Running post-action listener: _resource_record_post_action 12:08:50 INFO - [mozharness: 2017-06-08 19:08:50.469484Z] Finished clobber step (success) 12:08:50 INFO - [mozharness: 2017-06-08 19:08:50.469608Z] Running read-buildbot-config step. 12:08:50 INFO - Running pre-action listener: _resource_record_pre_action 12:08:50 INFO - Running main action method: read_buildbot_config 12:08:50 INFO - Using buildbot properties: 12:08:50 INFO - { 12:08:50 INFO - "project": "", 12:08:50 INFO - "product": "firefox", 12:08:50 INFO - "script_repo_revision": "production", 12:08:50 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 12:08:50 INFO - "repository": "", 12:08:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 12:08:50 INFO - "buildid": "20170608104024", 12:08:50 INFO - "pgo_build": "False", 12:08:50 INFO - "basedir": "/builds/slave/test", 12:08:50 INFO - "buildnumber": 2, 12:08:50 INFO - "slavename": "t-yosemite-r7-0384", 12:08:50 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 12:08:50 INFO - "platform": "yosemite_r7_devedition", 12:08:50 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 12:08:50 INFO - "branch": "mozilla-beta", 12:08:50 INFO - "repo_path": "releases/mozilla-beta", 12:08:50 INFO - "revision": "d8424bcbf72f7a451e89f8f625a9ebafecded872", 12:08:50 INFO - "stage_platform": "macosx64-devedition", 12:08:50 INFO - "builduid": "6c076a982fa74dafa72304d8c38ff2af", 12:08:50 INFO - "slavebuilddir": "test" 12:08:50 INFO - } 12:08:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 12:08:50 INFO - Running post-action listener: _resource_record_post_action 12:08:50 INFO - [mozharness: 2017-06-08 19:08:50.472935Z] Finished read-buildbot-config step (success) 12:08:50 INFO - [mozharness: 2017-06-08 19:08:50.473063Z] Running download-and-extract step. 12:08:50 INFO - Running pre-action listener: _resource_record_pre_action 12:08:50 INFO - Running main action method: download_and_extract 12:08:50 INFO - mkdir: /builds/slave/test/build/tests 12:08:50 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')]} 12:08:50 INFO - trying https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 12:08:50 INFO - Downloading https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/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 12:08:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/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 12:08:51 INFO - Downloaded 1287 bytes. 12:08:51 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 12:08:51 INFO - Using the following test package requirements: 12:08:51 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:08:51 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 12:08:51 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:08:51 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:08:51 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 12:08:51 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:08:51 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 12:08:51 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 12:08:51 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:08:51 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 12:08:51 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:08:51 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:08:51 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 12:08:51 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:08:51 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 12:08:51 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:08:51 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 12:08:51 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:08:51 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 12:08:51 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 12:08:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:08:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:08:51 INFO - Fetch https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:08:53 INFO - Expected file size: 51964029 12:08:53 INFO - Obtained file size: 51964029 12:09:08 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 12:09:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:09:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:09:08 INFO - Fetch https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:09:10 INFO - Expected file size: 51964029 12:09:10 INFO - Obtained file size: 51964029 12:09:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 12:09:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 12:09:25 INFO - Fetch https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 12:09:26 INFO - Expected file size: 14404461 12:09:26 INFO - Obtained file size: 14404461 12:09:26 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')]} 12:09:26 INFO - trying https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:09:26 INFO - Downloading https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 12:09:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/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 12:09:29 INFO - Downloaded 74890013 bytes. 12:09:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:09:29 INFO - mkdir: /builds/slave/test/properties 12:09:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:09:29 INFO - Writing to file /builds/slave/test/properties/build_url 12:09:29 INFO - Contents: 12:09:29 INFO - build_url:https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:09:30 INFO - Running post-action listener: _resource_record_post_action 12:09:30 INFO - Running post-action listener: set_extra_try_arguments 12:09:30 INFO - [mozharness: 2017-06-08 19:09:30.029723Z] Finished download-and-extract step (success) 12:09:30 INFO - [mozharness: 2017-06-08 19:09:30.029844Z] Running populate-webroot step. 12:09:30 INFO - Running pre-action listener: _resource_record_pre_action 12:09:30 INFO - Running main action method: populate_webroot 12:09:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:09:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:09:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:09:30 INFO - '--tppagecycles', 12:09:30 INFO - '7'], 12:09:30 INFO - 'tests': ['tsvgm']}}, 12:09:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:09:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:09:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:09:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:09:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:09:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:09:30 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:09:30 INFO - 'talos_options': ['--disable-e10s'], 12:09:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:09:30 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:09:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:09:30 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:09:30 INFO - 'talos_options': ['--disable-e10s'], 12:09:30 INFO - 'tests': ['damp', 'tps']}, 12:09:30 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:09:30 INFO - 'tests': ['damp', 'tps']}, 12:09:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:09:30 INFO - 'tests': ['dromaeo_dom']}, 12:09:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:09:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:09:30 INFO - 'tests': ['basic_compositor_video']}, 12:09:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:09:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:09:30 INFO - 'tests': ['a11yr', 12:09:30 INFO - 'ts_paint', 12:09:30 INFO - 'tpaint', 12:09:30 INFO - 'sessionrestore', 12:09:30 INFO - 'sessionrestore_no_auto_restore', 12:09:30 INFO - 'tabpaint']}, 12:09:30 INFO - 'other-e10s': {'tests': ['a11yr', 12:09:30 INFO - 'ts_paint', 12:09:30 INFO - 'tpaint', 12:09:30 INFO - 'sessionrestore', 12:09:30 INFO - 'sessionrestore_no_auto_restore', 12:09:30 INFO - 'tabpaint']}, 12:09:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:09:30 INFO - 'ts_paint', 12:09:30 INFO - 'tpaint', 12:09:30 INFO - 'sessionrestore', 12:09:30 INFO - 'sessionrestore_no_auto_restore', 12:09:30 INFO - 'tabpaint']}, 12:09:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:09:30 INFO - 'ts_paint', 12:09:30 INFO - 'tpaint', 12:09:30 INFO - 'sessionrestore', 12:09:30 INFO - 'sessionrestore_no_auto_restore', 12:09:30 INFO - 'tabpaint']}, 12:09:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:09:30 INFO - 'tests': ['a11yr', 12:09:30 INFO - 'ts_paint', 12:09:30 INFO - 'tpaint', 12:09:30 INFO - 'sessionrestore', 12:09:30 INFO - 'sessionrestore_no_auto_restore', 12:09:30 INFO - 'tabpaint']}, 12:09:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:09:30 INFO - 'tests': ['a11yr', 12:09:30 INFO - 'ts_paint', 12:09:30 INFO - 'tpaint', 12:09:30 INFO - 'sessionrestore', 12:09:30 INFO - 'sessionrestore_no_auto_restore', 12:09:30 INFO - 'tabpaint']}, 12:09:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:09:30 INFO - 'tests': ['tsvgx', 12:09:30 INFO - 'tsvgr_opacity', 12:09:30 INFO - 'tart', 12:09:30 INFO - 'tscrollx', 12:09:30 INFO - 'cart', 12:09:30 INFO - 'tsvg_static']}, 12:09:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:09:30 INFO - 'tsvgr_opacity', 12:09:30 INFO - 'tart', 12:09:30 INFO - 'tscrollx', 12:09:30 INFO - 'cart', 12:09:30 INFO - 'tsvg_static']}, 12:09:30 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:09:30 INFO - 'talos_options': ['--disable-e10s'], 12:09:30 INFO - 'tests': ['tp5o']}, 12:09:30 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:09:30 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:09:30 INFO - 'talos_options': ['--disable-e10s', 12:09:30 INFO - '--xperf_path', 12:09:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:09:30 INFO - 'tests': ['tp5n']}, 12:09:30 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:09:30 INFO - 'talos_options': ['--xperf_path', 12:09:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:09:30 INFO - 'tests': ['tp5n']}}, 12:09:30 INFO - 'talos.zip': {'path': '', 12:09:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:09:30 INFO - Running post-action listener: _resource_record_post_action 12:09:30 INFO - [mozharness: 2017-06-08 19:09:30.048775Z] Finished populate-webroot step (success) 12:09:30 INFO - [mozharness: 2017-06-08 19:09:30.048906Z] Running create-virtualenv step. 12:09:30 INFO - Running pre-action listener: _resource_record_pre_action 12:09:30 INFO - Running main action method: create_virtualenv 12:09:30 INFO - Creating virtualenv /builds/slave/test/build/venv 12:09:30 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 12:09:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:09:30 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:09:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:09:30 INFO - Using real prefix '/tools/python27' 12:09:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:09:30 INFO - Installing distribute.............................................................................................................................................................................................done. 12:09:33 INFO - Installing pip.................done. 12:09:33 INFO - Return code: 0 12:09:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:09:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:09:33 INFO - Reading from file tmpfile_stdout 12:09:33 INFO - Output received: 12:09:33 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) 12:09:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:09:33 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')]} 12:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:33 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')]} 12:09:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:34 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 0x1042e2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042e5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0aadb4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040c0d30>, '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 0x103fe7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040b5be8>, '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.3q907TDvU3/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.ZEdwKJyzMJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 12:09:34 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 12:09:34 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 12:09:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3q907TDvU3/Render', 12:09:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:34 INFO - 'HOME': '/Users/cltbld', 12:09:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:34 INFO - 'LOGNAME': 'cltbld', 12:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:34 INFO - 'MOZ_NO_REMOTE': '1', 12:09:34 INFO - 'NO_EM_RESTART': '1', 12:09:34 INFO - 'PAGER': '/bin/cat', 12:09:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:34 INFO - 'PWD': '/builds/slave/test', 12:09:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:34 INFO - 'SHELL': '/bin/bash', 12:09:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZEdwKJyzMJ/Listeners', 12:09:34 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 12:09:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:34 INFO - 'USER': 'cltbld', 12:09:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:34 INFO - 'XPC_FLAGS': '0x0', 12:09:34 INFO - 'XPC_SERVICE_NAME': '0', 12:09:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:34 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 12:09:34 INFO - Cleaning up... 12:09:34 INFO - Return code: 0 12:09:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:09:34 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')]} 12:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:34 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')]} 12:09:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:34 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 0x1042e2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042e5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0aadb4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040c0d30>, '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 0x103fe7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040b5be8>, '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.3q907TDvU3/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.ZEdwKJyzMJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 12:09:34 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 12:09:34 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 12:09:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3q907TDvU3/Render', 12:09:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:34 INFO - 'HOME': '/Users/cltbld', 12:09:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:34 INFO - 'LOGNAME': 'cltbld', 12:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:34 INFO - 'MOZ_NO_REMOTE': '1', 12:09:34 INFO - 'NO_EM_RESTART': '1', 12:09:34 INFO - 'PAGER': '/bin/cat', 12:09:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:34 INFO - 'PWD': '/builds/slave/test', 12:09:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:34 INFO - 'SHELL': '/bin/bash', 12:09:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZEdwKJyzMJ/Listeners', 12:09:34 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 12:09:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:34 INFO - 'USER': 'cltbld', 12:09:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:34 INFO - 'XPC_FLAGS': '0x0', 12:09:34 INFO - 'XPC_SERVICE_NAME': '0', 12:09:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:34 INFO - Downloading/unpacking psutil>=3.1.1 12:09:34 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 12:09:34 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 12:09:34 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 12:09:34 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 12:09:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:09:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:09:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:09:36 INFO - Installing collected packages: psutil 12:09:36 INFO - Running setup.py install for psutil 12:09:36 INFO - building 'psutil._psutil_osx' extension 12:09:36 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 12:09:36 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 12:09:36 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 12:09:36 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 12:09:36 INFO - building 'psutil._psutil_posix' extension 12:09:36 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 12:09:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:09:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:09:37 INFO - ^ 12:09:37 INFO - 1 warning generated. 12:09:37 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 12:09:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:09:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:09:37 INFO - Successfully installed psutil 12:09:37 INFO - Cleaning up... 12:09:37 INFO - Return code: 0 12:09:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:09: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')]} 12:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09: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')]} 12:09:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042e2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042e5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0aadb4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040c0d30>, '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 0x103fe7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040b5be8>, '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.3q907TDvU3/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.ZEdwKJyzMJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 12:09: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:09: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 mozsystemmonitor==0.3 12:09:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3q907TDvU3/Render', 12:09:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:37 INFO - 'HOME': '/Users/cltbld', 12:09:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:37 INFO - 'LOGNAME': 'cltbld', 12:09:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:37 INFO - 'MOZ_NO_REMOTE': '1', 12:09:37 INFO - 'NO_EM_RESTART': '1', 12:09:37 INFO - 'PAGER': '/bin/cat', 12:09:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:37 INFO - 'PWD': '/builds/slave/test', 12:09:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:37 INFO - 'SHELL': '/bin/bash', 12:09:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZEdwKJyzMJ/Listeners', 12:09:37 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 12:09:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:37 INFO - 'USER': 'cltbld', 12:09:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:37 INFO - 'XPC_FLAGS': '0x0', 12:09:37 INFO - 'XPC_SERVICE_NAME': '0', 12:09:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:09:37 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 12:09:37 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 12:09:37 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 12:09:37 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 12:09:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:09:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:09:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:09:39 INFO - Installing collected packages: mozsystemmonitor 12:09:39 INFO - Running setup.py install for mozsystemmonitor 12:09:39 INFO - Successfully installed mozsystemmonitor 12:09:39 INFO - Cleaning up... 12:09:39 INFO - Return code: 0 12:09:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:09:39 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')]} 12:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:39 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')]} 12:09:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:39 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 0x1042e2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042e5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0aadb4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040c0d30>, '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 0x103fe7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040b5be8>, '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.3q907TDvU3/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.ZEdwKJyzMJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 12:09:39 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 12:09:39 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 12:09:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3q907TDvU3/Render', 12:09:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:39 INFO - 'HOME': '/Users/cltbld', 12:09:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:39 INFO - 'LOGNAME': 'cltbld', 12:09:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:39 INFO - 'MOZ_NO_REMOTE': '1', 12:09:39 INFO - 'NO_EM_RESTART': '1', 12:09:39 INFO - 'PAGER': '/bin/cat', 12:09:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:39 INFO - 'PWD': '/builds/slave/test', 12:09:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:39 INFO - 'SHELL': '/bin/bash', 12:09:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZEdwKJyzMJ/Listeners', 12:09:39 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 12:09:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:39 INFO - 'USER': 'cltbld', 12:09:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:39 INFO - 'XPC_FLAGS': '0x0', 12:09:39 INFO - 'XPC_SERVICE_NAME': '0', 12:09:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:39 INFO - Downloading/unpacking jsonschema==2.5.1 12:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:09:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:09:40 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 12:09:40 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 12:09:40 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 12:09:40 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 12:09:41 INFO - Downloading functools32-3.2.3-2.tar.gz 12:09:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:09:41 INFO - warning: no files found matching '*.txt' 12:09:41 INFO - no previously-included directories found matching 'build' 12:09:41 INFO - no previously-included directories found matching 'dist' 12:09:41 INFO - no previously-included directories found matching '.git*' 12:09:41 INFO - Installing collected packages: jsonschema, functools32 12:09:41 INFO - Running setup.py install for functools32 12:09:41 INFO - warning: no files found matching '*.txt' 12:09:41 INFO - no previously-included directories found matching 'build' 12:09:41 INFO - no previously-included directories found matching 'dist' 12:09:41 INFO - no previously-included directories found matching '.git*' 12:09:41 INFO - Successfully installed jsonschema functools32 12:09:41 INFO - Cleaning up... 12:09:41 INFO - Return code: 0 12:09:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:09:41 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')]} 12:09:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:41 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')]} 12:09:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:41 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 0x1042e2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042e5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0aadb4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040c0d30>, '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 0x103fe7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040b5be8>, '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.3q907TDvU3/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.ZEdwKJyzMJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 12:09:41 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 12:09:41 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 12:09:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3q907TDvU3/Render', 12:09:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:41 INFO - 'HOME': '/Users/cltbld', 12:09:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:41 INFO - 'LOGNAME': 'cltbld', 12:09:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:41 INFO - 'MOZ_NO_REMOTE': '1', 12:09:41 INFO - 'NO_EM_RESTART': '1', 12:09:41 INFO - 'PAGER': '/bin/cat', 12:09:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:41 INFO - 'PWD': '/builds/slave/test', 12:09:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:41 INFO - 'SHELL': '/bin/bash', 12:09:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZEdwKJyzMJ/Listeners', 12:09:41 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 12:09:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:41 INFO - 'USER': 'cltbld', 12:09:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:41 INFO - 'XPC_FLAGS': '0x0', 12:09:41 INFO - 'XPC_SERVICE_NAME': '0', 12:09:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:09:41 INFO - Cleaning up... 12:09:41 INFO - Return code: 0 12:09:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:09:41 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')]} 12:09:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:41 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')]} 12:09:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:41 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 0x1042e2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042e5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0aadb4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040c0d30>, '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 0x103fe7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040b5be8>, '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.3q907TDvU3/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.ZEdwKJyzMJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 12:09:41 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 12:09:41 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 12:09:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3q907TDvU3/Render', 12:09:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:41 INFO - 'HOME': '/Users/cltbld', 12:09:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:41 INFO - 'LOGNAME': 'cltbld', 12:09:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:41 INFO - 'MOZ_NO_REMOTE': '1', 12:09:41 INFO - 'NO_EM_RESTART': '1', 12:09:41 INFO - 'PAGER': '/bin/cat', 12:09:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:41 INFO - 'PWD': '/builds/slave/test', 12:09:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:41 INFO - 'SHELL': '/bin/bash', 12:09:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZEdwKJyzMJ/Listeners', 12:09:41 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 12:09:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:41 INFO - 'USER': 'cltbld', 12:09:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:41 INFO - 'XPC_FLAGS': '0x0', 12:09:41 INFO - 'XPC_SERVICE_NAME': '0', 12:09:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:41 INFO - Downloading/unpacking blobuploader==1.2.4 12:09:41 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 12:09:41 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 12:09:41 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 12:09:41 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 12:09:43 INFO - Downloading blobuploader-1.2.4.tar.gz 12:09:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:09:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:09:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:44 INFO - Downloading docopt-0.6.1.tar.gz 12:09:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:09:44 INFO - Installing collected packages: blobuploader, requests, docopt 12:09:44 INFO - Running setup.py install for blobuploader 12:09:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:09:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:09:44 INFO - Running setup.py install for requests 12:09:44 INFO - Running setup.py install for docopt 12:09:45 INFO - Successfully installed blobuploader requests docopt 12:09:45 INFO - Cleaning up... 12:09:45 INFO - Return code: 0 12:09:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09:45 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')]} 12:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:45 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')]} 12:09:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:45 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 0x1042e2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042e5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0aadb4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040c0d30>, '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 0x103fe7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040b5be8>, '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.3q907TDvU3/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.ZEdwKJyzMJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 12:09:45 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 12:09:45 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 12:09:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3q907TDvU3/Render', 12:09:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:45 INFO - 'HOME': '/Users/cltbld', 12:09:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:45 INFO - 'LOGNAME': 'cltbld', 12:09:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:45 INFO - 'MOZ_NO_REMOTE': '1', 12:09:45 INFO - 'NO_EM_RESTART': '1', 12:09:45 INFO - 'PAGER': '/bin/cat', 12:09:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:45 INFO - 'PWD': '/builds/slave/test', 12:09:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:45 INFO - 'SHELL': '/bin/bash', 12:09:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZEdwKJyzMJ/Listeners', 12:09:45 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 12:09:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:45 INFO - 'USER': 'cltbld', 12:09:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:45 INFO - 'XPC_FLAGS': '0x0', 12:09:45 INFO - 'XPC_SERVICE_NAME': '0', 12:09:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:09:46 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-h8yAk5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:09:46 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-OH55hb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:09:46 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-QI_5PM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:09:46 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-gYMVem-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:09:46 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-J5kQ86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:09:46 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-iQggp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:09:46 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-ZjYuEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:09:46 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-Pu2hBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:09:47 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-IRRzdb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:09:47 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-7A9S1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:09:47 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-o6J6m6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:09:47 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-S76y3W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:09:47 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-tY8xIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:09:47 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-ptchu5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:47 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-oj5Jmn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:09:47 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-zjablN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:09:47 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-bCBwqp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:09:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:09:48 INFO - Running setup.py install for manifestparser 12:09:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:09:48 INFO - Running setup.py install for mozcrash 12:09:48 INFO - Running setup.py install for mozdebug 12:09:48 INFO - Running setup.py install for mozdevice 12:09:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:09:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:09:48 INFO - Running setup.py install for mozfile 12:09:48 INFO - Running setup.py install for mozhttpd 12:09:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:09:48 INFO - Running setup.py install for mozinfo 12:09:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:09:49 INFO - Running setup.py install for mozInstall 12:09:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:09:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:09:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:09:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:09:49 INFO - Running setup.py install for mozleak 12:09:49 INFO - Running setup.py install for mozlog 12:09:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:09:49 INFO - Running setup.py install for moznetwork 12:09:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:09:49 INFO - Running setup.py install for mozprocess 12:09:49 INFO - Running setup.py install for mozprofile 12:09:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:09:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:09:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:09:49 INFO - Running setup.py install for mozrunner 12:09:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:09:50 INFO - Running setup.py install for mozscreenshot 12:09:50 INFO - Running setup.py install for moztest 12:09:50 INFO - Running setup.py install for mozversion 12:09:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:09:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:09:50 INFO - Cleaning up... 12:09:50 INFO - Return code: 0 12:09:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09:50 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')]} 12:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:50 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')]} 12:09:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:50 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 0x1042e2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042e5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0aadb4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040c0d30>, '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 0x103fe7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040b5be8>, '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.3q907TDvU3/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.ZEdwKJyzMJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 12:09:50 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 12:09:50 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 12:09:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3q907TDvU3/Render', 12:09:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:50 INFO - 'HOME': '/Users/cltbld', 12:09:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:50 INFO - 'LOGNAME': 'cltbld', 12:09:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:50 INFO - 'MOZ_NO_REMOTE': '1', 12:09:50 INFO - 'NO_EM_RESTART': '1', 12:09:50 INFO - 'PAGER': '/bin/cat', 12:09:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:50 INFO - 'PWD': '/builds/slave/test', 12:09:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:50 INFO - 'SHELL': '/bin/bash', 12:09:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZEdwKJyzMJ/Listeners', 12:09:50 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 12:09:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:50 INFO - 'USER': 'cltbld', 12:09:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:50 INFO - 'XPC_FLAGS': '0x0', 12:09:50 INFO - 'XPC_SERVICE_NAME': '0', 12:09:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:09:51 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-OJeYM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:09:51 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)) 12:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:09:51 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-DUmcpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:09:51 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)) 12:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:09:51 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-6YJzir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:09:51 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)) 12:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:09:51 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-OQDXvt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:09:51 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)) 12:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:09:51 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-HdVvYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:09:51 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)) 12:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:09:51 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-Of71Tr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:09:51 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)) 12:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:09:51 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-lCk1Cq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:09:52 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)) 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:09:52 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-Pvxslb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:09:52 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)) 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:09:52 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-wCnaQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:09:52 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)) 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:09:52 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-pHBMRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:09:52 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)) 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:09:52 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-ox_wcP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:09:52 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)) 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:09:52 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-nv6TuV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:09:52 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)) 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:09:52 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-XpLcxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:09:52 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)) 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:09:52 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-m_z7Bb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:09:52 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)) 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:52 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-jKo_hR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:52 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)) 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:09:52 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-57gNLO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:09:52 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)) 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:09:52 INFO - Running setup.py (path:/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/pip-elAY6r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:09:53 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)) 12:09:53 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)) 12:09:53 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)) 12:09:53 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)) 12:09:53 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)) 12:09:53 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)) 12:09:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:09:53 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 12:09:53 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 12:09:53 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 12:09:53 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 12:09:54 INFO - Downloading blessings-1.6.tar.gz 12:09:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:09:54 INFO - Installing collected packages: blessings 12:09:54 INFO - Running setup.py install for blessings 12:09:55 INFO - Successfully installed blessings 12:09:55 INFO - Cleaning up... 12:09:55 INFO - Return code: 0 12:09:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:09:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:55 INFO - Reading from file tmpfile_stdout 12:09:55 INFO - Current package versions: 12:09:55 INFO - blessings == 1.6 12:09:55 INFO - blobuploader == 1.2.4 12:09:55 INFO - docopt == 0.6.1 12:09:55 INFO - functools32 == 3.2.3-2 12:09:55 INFO - jsonschema == 2.5.1 12:09:55 INFO - manifestparser == 1.1 12:09:55 INFO - mozInstall == 1.12 12:09:55 INFO - mozcrash == 1.0 12:09:55 INFO - mozdebug == 0.1 12:09:55 INFO - mozdevice == 0.49 12:09:55 INFO - mozfile == 1.2 12:09:55 INFO - mozhttpd == 0.7 12:09:55 INFO - mozinfo == 0.9 12:09:55 INFO - mozleak == 0.1 12:09:55 INFO - mozlog == 3.4 12:09:55 INFO - moznetwork == 0.27 12:09:55 INFO - mozprocess == 0.25 12:09:55 INFO - mozprofile == 0.28 12:09:55 INFO - mozrunner == 6.13 12:09:55 INFO - mozscreenshot == 0.1 12:09:55 INFO - mozsystemmonitor == 0.3 12:09:55 INFO - moztest == 0.8 12:09:55 INFO - mozversion == 1.4 12:09:55 INFO - psutil == 3.1.1 12:09:55 INFO - requests == 1.2.3 12:09:55 INFO - wsgiref == 0.1.2 12:09:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:55 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 0x1042e2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042e5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0aadb4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040c0d30>, '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 0x103fe7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040b5be8>, '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.3q907TDvU3/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.ZEdwKJyzMJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 12:09:55 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 12:09:55 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 12:09:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3q907TDvU3/Render', 12:09:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:55 INFO - 'HOME': '/Users/cltbld', 12:09:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:55 INFO - 'LOGNAME': 'cltbld', 12:09:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:55 INFO - 'MOZ_NO_REMOTE': '1', 12:09:55 INFO - 'NO_EM_RESTART': '1', 12:09:55 INFO - 'PAGER': '/bin/cat', 12:09:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:55 INFO - 'PWD': '/builds/slave/test', 12:09:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:55 INFO - 'SHELL': '/bin/bash', 12:09:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZEdwKJyzMJ/Listeners', 12:09:55 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 12:09:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:55 INFO - 'USER': 'cltbld', 12:09:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:55 INFO - 'XPC_FLAGS': '0x0', 12:09:55 INFO - 'XPC_SERVICE_NAME': '0', 12:09:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:55 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)) 12:09:55 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)) 12:09:55 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)) 12:09:55 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)) 12:09:55 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)) 12:09:55 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)) 12:09:55 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)) 12:09:55 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)) 12:09:55 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)) 12:09:55 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)) 12:09:55 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)) 12:09:55 INFO - Cleaning up... 12:09:55 INFO - Return code: 0 12:09:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:09:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042e2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042e5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb0aadb4770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1040c0d30>, '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 0x103fe7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040b5be8>, '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.3q907TDvU3/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.ZEdwKJyzMJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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 12:09:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:09:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:09:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3q907TDvU3/Render', 12:09:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:55 INFO - 'HOME': '/Users/cltbld', 12:09:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:55 INFO - 'LOGNAME': 'cltbld', 12:09:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:55 INFO - 'MOZ_NO_REMOTE': '1', 12:09:55 INFO - 'NO_EM_RESTART': '1', 12:09:55 INFO - 'PAGER': '/bin/cat', 12:09:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:55 INFO - 'PWD': '/builds/slave/test', 12:09:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:55 INFO - 'SHELL': '/bin/bash', 12:09:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZEdwKJyzMJ/Listeners', 12:09:55 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 12:09:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:55 INFO - 'USER': 'cltbld', 12:09:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:55 INFO - 'XPC_FLAGS': '0x0', 12:09:55 INFO - 'XPC_SERVICE_NAME': '0', 12:09:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:09:56 INFO - Cleaning up... 12:09:56 INFO - Return code: 0 12:09:56 INFO - Running post-action listener: _resource_record_post_action 12:09:56 INFO - Running post-action listener: _start_resource_monitoring 12:09:56 INFO - Starting resource monitoring. 12:09:56 INFO - [mozharness: 2017-06-08 19:09:56.099192Z] Finished create-virtualenv step (success) 12:09:56 INFO - [mozharness: 2017-06-08 19:09:56.100119Z] Running install step. 12:09:56 INFO - Running pre-action listener: _resource_record_pre_action 12:09:56 INFO - Running main action method: install 12:09:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:56 INFO - Reading from file tmpfile_stdout 12:09:56 INFO - Detecting whether we're running mozinstall >=1.0... 12:09:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:09:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:09:56 INFO - Reading from file tmpfile_stdout 12:09:56 INFO - Output received: 12:09:56 INFO - Usage: mozinstall [options] installer 12:09:56 INFO - Options: 12:09:56 INFO - -h, --help show this help message and exit 12:09:56 INFO - -d DEST, --destination=DEST 12:09:56 INFO - Directory to install application into. [default: 12:09:56 INFO - "/builds/slave/test"] 12:09:56 INFO - --app=APP Application being installed. [default: firefox] 12:09:56 INFO - mkdir: /builds/slave/test/build/application 12:09:56 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'] 12:09:56 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 12:10:16 INFO - Reading from file tmpfile_stdout 12:10:16 INFO - Output received: 12:10:16 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:10:16 INFO - Running post-action listener: _resource_record_post_action 12:10:16 INFO - [mozharness: 2017-06-08 19:10:16.823313Z] Finished install step (success) 12:10:16 INFO - [mozharness: 2017-06-08 19:10:16.823457Z] Running run-tests step. 12:10:16 INFO - Running pre-action listener: _resource_record_pre_action 12:10:16 INFO - Running main action method: run_tests 12:10:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:10:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:10:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:10:16 INFO - 2017-06-08 12:10:16.837 screenresolution[1890:11863] starting screenresolution argv=screenresolution get 12:10:16 INFO - 2017-06-08 12:10:16.840 screenresolution[1890:11863] Display 0: 1600x1200x32@60 12:10:16 INFO - 2017-06-08 12:10:16.846 screenresolution[1891:11866] starting screenresolution argv=screenresolution list 12:10:16 INFO - Available Modes on Display 0 12:10:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:10:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:10:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:10:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:10:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:10:17 INFO - Intel Iris: 12:10:17 INFO - Chipset Model: Intel Iris 12:10:17 INFO - Type: GPU 12:10:17 INFO - Bus: Built-In 12:10:17 INFO - VRAM (Dynamic, Max): 1536 MB 12:10:17 INFO - Vendor: Intel (0x8086) 12:10:17 INFO - Device ID: 0x0a2e 12:10:17 INFO - Revision ID: 0x0009 12:10:17 INFO - Displays: 12:10:17 INFO - Display: 12:10:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:10:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:10:17 INFO - Display Serial Number: 1600x1200 60 12:10:17 INFO - Main Display: Yes 12:10:17 INFO - Mirror: Off 12:10:17 INFO - Online: Yes 12:10:17 INFO - Rotation: Supported 12:10:17 INFO - Return code: 0 12:10:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:10:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:10:17 INFO - Python 2.7.3 12:10:17 INFO - Return code: 0 12:10:17 INFO - grabbing minidump binary from tooltool 12:10:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:17 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 0x1040c0d30>, '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 0x103fe7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1040b5be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:10:17 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 12:10:17 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 12:10:17 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 12:10:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:10:17 INFO - Return code: 0 12:10:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:10:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:10:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:10:17 INFO - ENV: RUST_BACKTRACE is now 1 12:10:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:10:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:10:17 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/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0384', '--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 12:10:17 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/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0384 --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 12:10:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3q907TDvU3/Render', 12:10:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:10:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:10:17 INFO - 'HOME': '/Users/cltbld', 12:10:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:10:17 INFO - 'LOGNAME': 'cltbld', 12:10:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:10:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:10:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:17 INFO - 'MOZ_NO_REMOTE': '1', 12:10:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:10:17 INFO - 'NO_EM_RESTART': '1', 12:10:17 INFO - 'PAGER': '/bin/cat', 12:10:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:10:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:17 INFO - 'PWD': '/builds/slave/test', 12:10:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:10:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:10:17 INFO - 'RUST_BACKTRACE': '1', 12:10:17 INFO - 'SHELL': '/bin/bash', 12:10:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZEdwKJyzMJ/Listeners', 12:10:17 INFO - 'TMPDIR': '/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/', 12:10:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:10:17 INFO - 'USER': 'cltbld', 12:10:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:10:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:10:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:17 INFO - 'XPC_FLAGS': '0x0', 12:10:17 INFO - 'XPC_SERVICE_NAME': '0', 12:10:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:10:17 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/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0384', '--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 12:10:17 INFO - mozversion application_buildid: 20170608104024 12:10:17 INFO - mozversion application_changeset: d8424bcbf72f7a451e89f8f625a9ebafecded872 12:10:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:10:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:10:17 INFO - mozversion application_name: Firefox 12:10:17 INFO - mozversion application_remotingname: firefox-dev 12:10:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:10:17 INFO - mozversion application_vendor: Mozilla 12:10:17 INFO - mozversion application_version: 54.0 12:10:17 INFO - mozversion platform_buildid: 20170608104024 12:10:17 INFO - mozversion platform_changeset: d8424bcbf72f7a451e89f8f625a9ebafecded872 12:10:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:10:17 INFO - mozversion platform_version: 54.0 12:10:17 INFO - using testdate: 1496949017 12:10:17 INFO - actual date: 1496949017 12:10:17 INFO - starting webserver on 'localhost:51214' 12:10:17 INFO - SUITE-START | Running 2 tests 12:10:17 INFO - TEST-START | dromaeo_css 12:10:17 INFO - operating with platform_type : mac_ 12:10:17 INFO - Initialising browser for dromaeo_css test... 12:10:17 INFO - TEST-INFO | started process 1901 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/tmpx_zzrs/profile http://localhost:51214/getInfo.html) 12:10:20 INFO - PROCESS | 1901 | 2017-06-08 12:10:20.348 plugin-container[1904:12028] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d47, name = 'com.apple.tsm.portname' 12:10:20 INFO - PROCESS | 1901 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:10:20 INFO - PROCESS | 1901 | 2017-06-08 12:10:20.349 plugin-container[1904:12028] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 12:10:20 INFO - PROCESS | 1901 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:10:20 INFO - PROCESS | 1901 | 2017-06-08 12:10:20.349 plugin-container[1904:12028] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:10:20 INFO - PROCESS | 1901 | __metrics Screen width/height:1600/1200 12:10:20 INFO - PROCESS | 1901 | colorDepth:24 12:10:20 INFO - PROCESS | 1901 | Browser inner width/height: 1024/689 12:10:20 INFO - PROCESS | 1901 | __metrics 12:10:20 ERROR - PROCESS | 1901 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3381: TypeError: this.tabs is undefined 12:10:20 INFO - TEST-INFO | 1901: exit 0 12:10:20 INFO - Browser initialized. 12:10:20 INFO - Running cycle 1/1 for dromaeo_css test... 12:10:20 INFO - TEST-INFO | started process 1905 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/tmpx_zzrs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:10:23 INFO - PROCESS | 1905 | 2017-06-08 12:10:23.414 plugin-container[1906:12165] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 12:10:23 INFO - PROCESS | 1905 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:10:23 INFO - PROCESS | 1905 | 2017-06-08 12:10:23.415 plugin-container[1906:12165] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c03, name = 'com.apple.CFPasteboardClient' 12:10:23 INFO - PROCESS | 1905 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:10:23 INFO - PROCESS | 1905 | 2017-06-08 12:10:23.415 plugin-container[1906:12165] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:10:33 INFO - PROCESS | 1905 | RSS: Main: 203067392 12:10:33 INFO - PROCESS | 1905 | 12:13:36 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:51214/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51214/tests/dromaeo/cssquery-ext.html) 12:13:38 INFO - PROCESS | 1905 | RSS: Main: 215896064 12:13:38 INFO - PROCESS | 1905 | 12:16:40 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:51214/tests/dromaeo/cssquery-ext.html (next: http://localhost:51214/tests/dromaeo/cssquery-jquery.html) 12:16:40 INFO - PROCESS | 1905 | RSS: Main: 232693760 12:16:40 INFO - PROCESS | 1905 | 12:19:42 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:51214/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51214/tests/dromaeo/cssquery-mootools.html) 12:19:43 INFO - PROCESS | 1905 | RSS: Main: 234086400 12:19:43 INFO - PROCESS | 1905 | 12:22:45 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:51214/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51214/tests/dromaeo/cssquery-prototype.html) 12:22:46 INFO - PROCESS | 1905 | RSS: Main: 237273088 12:22:46 INFO - PROCESS | 1905 | 12:25:48 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:51214/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51214/tests/dromaeo/cssquery-yui.html) 12:25:49 INFO - PROCESS | 1905 | RSS: Main: 239337472 12:25:49 INFO - PROCESS | 1905 | 12:28:52 INFO - PROCESS | 1905 | Cycle 1(1): loaded http://localhost:51214/tests/dromaeo/cssquery-yui.html (next: http://localhost:51214/tests/dromaeo/cssquery-dojo.html) 12:28:52 INFO - PROCESS | 1905 | RSS: Main: 237301760 12:28:52 INFO - PROCESS | 1905 | 12:28:52 INFO - PROCESS | 1905 | __start_tp_report 12:28:52 INFO - PROCESS | 1905 | _x_x_mozilla_page_load 12:28:52 INFO - PROCESS | 1905 | _x_x_mozilla_page_load_details 12:28:52 INFO - PROCESS | 1905 | |i|pagename|runs| 12:28:52 INFO - PROCESS | 1905 | |0;dojo.html;3392.898213053609;3438.5873695156583;3455.9136021599465;3461.255829996551;3469.4969229461713;5853.063509838421;5868.325142608602;5900;5906.320773111093;5980.940190598093;5930.466258036776;5970.582059255854;5989.760409583622;5990.970045149779;6006.129111278873;5277.3139491866;5280.1551751719735;5314.468553144674;5420.972895135519;5465;4542.909141817178;5357.196420536911;5379.408265090837;5439;5454.836354909359;5158.200478925773;5239.685618862881;5361.490658387447;5381;5381.300430943952;5247.763850626731;5292.973535132338;5295.073362161606;5299.6025298102795;5317.9202311965355;5258.447862974371;5274.235235890813;5314.123169676998;5349.037173308803;5397.352317721859;5461.399246082928;5858.2677165354335;5881.2354393928945;5997.5501837361935;6016.308124565688;6236.906446403307;6249.343818899041;6270.749170033193;6325.87348253037;6352.872942541123;6403.199600049994;6406;6427.646479443629;6428.100065990765;6435.452986496153;6122.724477398483;6127.938720612781;6159.199304090529;6160.476359509492;6160.6611636359985;6238.095476155932;6366.4588509977175;6512.316206798313;6524.282328943812;6540.738370465223;4592.402987611589;5503.174523821459;6204.410580994799;6263.937360626426;6296.0555916612875;6365.395287447777;6372.776952806722;6374.649394283359;6389.169407976933;6398.744050238031;4754.833580824724;4941.962187940573;6260.937390626126;6266.968665156645;6290.4653104485715;6213.130161777355;6223.0354295084335;6230.813075607648;6251.3123556408755;6268.968655156695;4147.212029714345;4883.51164883516;4888.951110488921;4917.28699338601;4923.507649235048;4268.210381079511;4305.892352691145;4407.845725399596;4419.226635338803;4438.667099967515;4782.713037217778;4895.951040489621;5711.228984087178;5716.171155182558;5726.455986681259;6314.116023756677;6353.777617783448;6360.204974378203;6367.331430199855;6391.648459334781;5824.126381042877;5826.650400975955;5852.122181672783;5858.53131749459;5877.147813567094;5336;5466.644668096561;5530.557555395559;5547.805826796042;5557.305336832896;6330.588511746721;6346.619202847844;6435.195600549931;6462.483001359786;6488.448481878904;6335.968320158369;6339.049142628643;6361.395667411589;6374.043893416025;6425.935740642533;6429.38920802523;6507.381798729113;6553.180852393451;6562.475001999733;6582.76960306387;6189.783357582463;6215.689215539114;6252.968735156295;6254.843628909313;6273.37266273369;6355.173827402408;6361.61830290184;6368.968155159195;6378.52161087911;6385.042243663488;6170.938290617216;6301.495880329654;6308.621482711052;6346.873062538631;6351.745930162741;6079.787207447718;6134.877302453837;6161.630302181883;6192.2569291683485;6275.874482510416;6055.1220073090035;6078.7264573093435;6099.725512352044;6182.536309776695;6206.193194884636;5657.2079908811975;6256.31180570146;6273.435390814849;6281.654509002049;6289.339619339903;5907.822765317047;6096.02463605821;6215.502759779297;6234.345393733593;6281.4660753834605 12:28:52 INFO - PROCESS | 1905 | |1;ext.html;749.7338444852078;794.4835856693148;816.4040250617049;971.8007808399275;974.6880998080617;36669;38854.02864928371;38941.80529097358;38984.805075974655;39103.41344879825;37316.06709832256;37464.063398415055;37574.0606484838;37619.43570846439;37707.24585508288;7943.5631040292765;8085.110637829834;10657;10972.725681857937;11147.275427097253;10744.086752626035;10876.67369978898;10938;10982.06652434544;10988.890111098866;8381.28759055481;9541.713748587554;10214.540345684425;10976.890231097666;11000.064994475479;9643.421394716304;10524.789649190365;10912.50893709785;10956.835647465294;10969.396683182422;10671.35971841692;10678.985496377833;10730.248882578144;10736;10741.62404315853;32632.83683581567;32699.50950735741;32816.3436731264;32880.83559582187;33051.66948330534;3100.5430442421502;3730.301216836231;3753.512043434336;3800.3539398302355;3908.667763240128;19771.604567908704;24631;24766.628500572508;26255.474890502082;26272.474550508883;2547.5287071891576;3171.0962375722806;3185.3470038642254;3203.7116659500525;3209.518572214163;326522;342379;356467;356511;378708.1064594687;43127.568724312354;43199.78400108042;43250.56749432528;43267.567324326985;43429.78285108554;44161.8959526008;44194.11611767811;44294.77852610716;44298.55701442944;44350.778246108566;13319.067665263532;13429.39567719455;13436.731265374638;13454.52909148175;13479.326033698277;11923.940380298043;11938.1046421517;11952.282863028073;11996.220245684002;11998.040156787436;11333.546658133582;11444.54221831134;11511;11548.133889958286;11600.187986841009;5354.303940487712;5459.372172200168;5477.780888764484;5484;5497.147942068986;1057.7831544533353;1108.2076315434342;1145.667756350649;1197.9880201197877;1220.9633710988685;26294.868525657632;26717;26773.732262677513;26853.462930741665;26864.731352686224;1277.6358737759695;1293.46321276671;1298.7792075347213;1338.0499845109887;1338.5181334719493;11710.531578736754;13770.518031869036;13996.650083747987;14045.297735113203;14046.367913443919;411614;412867;413682;415539;416074.919625406;55102.173467398025;55523.889522210135;55631.443685563434;55637.33088007366;55744.72127639417;1432.4028707990515;1448.5147475595584;1484.4210757804465;1489.113977183574;1494.9701005979819;24822.255332340057;26131.73868261369;26214.34464138412;26249.73750262473;26265.73734262633;46225.30662040005;46353;46661.766691166325;46844.531554684014;46859.06281874412;392862;392997;394643;395688;396528;28720;29305;30115.698843012167;30558.694413056473;30656.08031759051;38249.808750956065;38822.80588597039;39047.21905561816;39050.804745976086;39160.608393915696;21682.67475987893;26590.335241619116;26903.05839295615;28407.431851362442;28654.71345286604;25697.486050278516;26329.73670263273;27085.916563337247;27781.44437111287;27948.16155515338;24454.633180502664;31832.840835795672;32007.519887201244;32037.839810800797;33764.66235337615;22815.087396503954;26845.865770671022;28066.438671226053;28503;29338.706612934453;18478.722819157454;22405.66391504096;23230.070797167926;23294.039597392344;23449.531009380058 12:28:52 INFO - PROCESS | 1905 | |2;jquery.html;406.32549967054695;406.7356218458004;420.90108824426267;434.11657277440406;443.9356293337464;19255.61488770224;20131.29540466082;21812.345629631116;21818.781812181875;21824.781752182473;17821.91089044556;20633;21510.354689359305;21614;21729.67405488919;6660.533762636605;6958.199807022182;6969.094017777682;6993.545419547739;6998.510104292702;6596.406323430891;6675.532712710112;6761.35767102127;6826.556273842184;6832.521723479358;6102.931986902297;6544.476441884638;6555.1150594669825;6759.459243260528;6808.2510923798345;6768.864622707543;6872.896906546406;6900.654967251618;6905.585664860125;6936.410405115571;6430;6619.271880093186;6654.234763002269;6669.866602667919;6684.766033188864;22881.656775148218;25865;25927;26223.557704326253;26665.46669066627;2909.808823675304;2934.8092373995623;3038.589790378293;3055.4041961817456;3084.984575077133;15734.763978540332;16403.64702831037;16527.91736041324;16929.238184281738;17069.7439538407;2066.749616482037;2092.487340601547;2143.20701340505;2173.521825198454;2381.4284571702665;165361.51957720643;169893;173406.5263799937;175741;175986;20046;20671.379858604265;20771.896140519504;20959.68560471594;21017;31213.843930780655;31775.205619859687;31859.681403186136;31976.360472790413;32076.198095047344;10348.689539313833;10420;10475.376316670929;10541.682289713786;10775.6228532001;8803.471791692391;8845.203931646054;8865.202131808168;9025.368224224241;9041.593128309241;9521.523923803783;9529.857052144223;9660.516974151264;9711.951440242894;9734.75663108428;4106.398504583215;4163.417121602978;4194.5881118357065;4198.4961804583645;4202.474690663667;923.2383283790899;1037.2479952034864;1051.8054159980275;1069.6201899549649;1101.9504122314515;15661;21028.789712102684;21798.45503862416;21824.90875456221;21863.78136218649;1078.741102135482;1086.3970496374588;1119.6137332620233;1122.2200570603516;1122.640754958422;7781.9610901945125;11254.887451125383;11314.547418103348;11376.033037191932;11436.942815286036;694853;746483;771233;783043.0847845725;790437;103468;155791.22104389407;156106.21946890192;156388.65417018754;163302;1290.9354532273348;1309.4369421148806;1313.6912825486004;1337.3313343328336;1339.34372157644;19415.611687765882;19835.60328793387;20085;20091.799082008994;20134.19463221455;54441;57660;62303.6884815573;62362;63353.68323158355;87449;87900;87972;89313.55343223242;90037.84867529605;11286.040686541735;11543.595974140864;11667.299962001967;11848.111391645833;11963.760724785281;12887.871121289043;12919.22484650924;13061.934690326487;13200.669983250496;13238.470461181445;10918.290311129751;11011.00900918921;11108.50011749457;11321.71695707614;11342.319460832376;10220.233482488991;10555.050045495942;10726.356418614909;10793.784124317626;10843.132549395872;11027.117830573374;11312.773744525228;11529.082032126618;11779.764404712028;11788.4695188715;11585.82621260665;11637.359945203096;11757.706057348292;11931.880681193077;11966.880331196577;11266.704329001941;11282.210245282751;11350.375729334966;11372.374519401512;11410.714732131431 12:28:52 INFO - PROCESS | 1905 | |3;mootools.html;1431.8424973252947;1440.6686462113714;1607.284758282564;1611.2588209423657;1624.740041593345;10137.087662110409;10782.46087695616;11141;11221.775564488706;11251.268667536604;11060.889391106086;11277.774444511126;11292.604758833442;11295.209335346508;11326.546938122465;2297.6553516972463;2356.4815740537056;2364.6334818103137;2365.9408514787183;2374.6675465434855;2285.268714011517;2353.011735071266;2364.586197415454;2373;2373.6439534069855;1940.2917934953782;2308.572914010905;2357.9410514737096;2362.8582285062953;2366.219147681261;2293.3693234360517;2302.3438320078862;2340.4850932794757;2340.637201233803;2342.0865862313713;2333.0317918063984;2334.743178250391;2344.9648255276184;2346.0498498108295;2346.0615753698657;11164.944175279154;11194.440277986068;11254.26847254934;11256.268342557707;11256.268342557789;5465.781368745245;5658.462446067617;5671.234383358235;5681.460261275172;5691.459311365414;3214.4696125139326;3332.766706330558;3437.2781715839456;3454;3472.3576138414473;1103.8450436461662;1123.359684979562;1160.2690305107812;1167.1654766841748;1180.887815657511;12403.689907752378;12611.306378149106;13461.798073028913;15249.923750381176;15331.923340383339;3241.9675803241666;4239.97880010598;4502.234620114588;4509.503954564995;4515.119551687455;11685.357305348118;11698.239614425034;11748.177627565989;11813.940930295294;11862.169648124722;5365.141577347684;5385;5388.811391601275;5417.783288668488;5431.429699881534;4190.727602705814;4929.408470983505;4969.254611808257;4985.351904252424;5068.619853511002;3350.2294472271515;3629.2197177606413;3675.209829886587;3731.6268373162466;3796.6962642988497;839.6473481137937;894.0746327550953;940.6707652321633;961.5961296255589;967.0522887570221;802.7671975127148;803.6745118227127;818.8239528501282;821.4414198345182;825.8554752918216;6586.80239592813;6633.535652504375;6755.054292399068;6790.830229244309;6835.624040677711;929.7396728915915;938.174406522256;1011.4891979550428;1030.7835354575623;1085.9565617375374;4254.319308910622;4297.183535128315;4300.913981720348;4342.283523218697;4457.509673935864;13449.798253026212;14008.43966241339;14018.579442616738;14018.859811401959;14020;12891.226526408445;13371.665708357174;13792.586222413343;13821.101628394123;13899.86100139006;8675.696350627572;10026.69919902404;10215.387076775418;10300.845487317696;10302.639407620698;6000.69996500182;6029.909551356645;6353.714082866374;7013.544119632207;7049.753258635923;11868.465919033837;12081.57714479989;12121.75756484883;12144.939275303566;12146.574869879512;11886.643400697993;11912.52349905994;12182.147249692436;12184.878151218374;12542.811857821958;9146.268298536233;9351.251899847859;9491.715248542554;9494.762630934281;9534.52327383642;10213.387196768217;10329.380237185793;10381.584736610452;10471.528781204712;10471.63349282802;8896.377253592187;8936.41913275635;8944.955275223581;9083.45499270046;9136.862947055666;8634.481931084156;8704.695335663222;8775.42959707617;8803.779905502157;8821.382503224713;7864.21357864209;7943.721969731263;8021.2379823916635;8023.037235531773;8064.758057258292;5746.482816546531;5794.507466865195;5858.9121163181735;6004.8799024020145;6055.061465472859;4570.588647021784;5953.345132035386;5958.910616340845;6092.086187071975;6141.385861413823 12:28:52 INFO - PROCESS | 1905 | |4;prototype.html;372.22948496612025;380.38567713143266;387.47303667012864;391.95688474267826;395.8100111946265;4549.4768101668315;4577.123379414436;4709.9058018839605;5181.611379146565;5222.746540830202;4526.932096018562;4985.304996301266;5118.897622047557;5174.663646862951;5178.2232665100255;3504.3341765064683;4148.854790082348;4210.557891421403;4306.621880998082;4367.519572846984;4138.958610413887;4265.31754919213;4332;4347.78261086946;4362.760048197364;3827.0049787055427;4021.6620205229588;4353.368761529577;4419.535948725385;4443.755593442359;3706.7405281630295;4155.792210389499;4278.053485664015;4354.390385346054;4412.338149277602;3457.343104810078;3791.6777073948747;3960.861369852041;3968.0476685595536;3998.2203470323298;4128.8348466061325;4484.551544845522;5057.544820966131;5088.056756014082;5106.489351064901;4489.506154323022;4671.32265821454;4734.26618874075;4800.351952486404;5125.564327032207;4983;5087.974560127176;5541.612087153901;5617.494425501724;5641.486810551583;3958.4853968984135;4414.366684326787;4546;4552.590266875997;4556.567126123013;5006.949930500685;5057.974710126426;5525.640833345819;5817.905554000557;6067.666278354689;3761.777422337751;4560.429946256718;4602.424696912886;4602.930956035662;4608.354830323758;4180.352045432963;4217.039076829289;4461.687681862239;4696.201645720235;4701.2242979908615;4831.178699621073;5031.597472202215;5067.695938243717;5101.158308879068;5158.484151584887;4398.724369932747;5459;5577.9163312550345;5586.692731899784;5593.244911936918;1409.6405416618697;1425.6008317670862;1574.8188958269716;1585.4450942170379;1606.6706325203306;1035.8756949166147;1137.2721458266717;1206.197878410847;1215.0826126274605;1217.7077501399601;5051.595872330205;5081.891015228639;5086.567641750493;5086.745662716849;5167.302414174113;5245.452591485521;5321.3880403754065;5426.457354264621;5579.911917176111;5665.49010589049;4270.636995855325;4390.7585082820115;4718.598919091847;5142.845714628567;5153.252778347119;2586.663733714605;2607.504574130947;2966.4512065267995;3022.1235841606126;3072.5083986562045;5712.571557133231;5960.850978725566;5998.700064996733;6059.818205453844;6123.989541725566;4582.88542786433;5329.333833270841;5858.443447872531;5936.910946335808;5947.31605865331;5494.532964697965;5883.646981181143;5974.9370428109305;6015.120247440464;6058.485028772604;5013.749312534285;5730.140478928194;5844.620099693507;5853.0635098384055;5899.764009439746;4863.416390033219;4962.057209130254;5501.339839219159;5577.386487486292;5808.535317174531;5150.356205474316;6077.878442431215;6096.634201947897;6112.358202388685;6144.988955411905;4592.724436533818;4634.073185362873;4649.953500465087;4673.439187297546;4688.812447502061;5132.948670513397;5207.6875387476875;5606.635568688022;5725.5992080555625;5833.308340581184;5430.70131142791;5619.381867994599;5647.813959068477;5649.830505084854;5657.603967722384;5278.498542638443;5567.805126820542;5635.267415235993;5640.379558248672;5680.2047713321;4747.053707979711;5075.746212689424;5515.283013208257;5520.613557050968;5529.723513824212;5120.078385890575;5305.442928492453;5316.341829085457;5422.6475279106735;5624.268845050117;5145;5202.323697919398;5218.765155568084;5272.337469577838;5533.944660553504 12:28:52 INFO - PROCESS | 1905 | |5;yui.html;737.2774680812803;742.261449857392;790.7904405332588;831.8876951611533;847.8601030829911;15754.448594299201;16319.265633046512;16481.340746370144;16748.66502669946;16928.068956207404;15339.693206135871;15402.229888505557;15690.529284121467;15750.684986300295;15761.921190394032;926.8563372677229;962.1821451766012;965.3435663748648;966.9613215471372;967.8209531236708;932.3892850183141;957.195955397466;964.0889359555223;971.519098046468;973.3770386952356;983.8475036369374;994.5077186792512;997.7954519323521;998.2562990571997;999.4503023337136;978.681928373278;986.225812736998;993.8409854423257;995.1740055753708;995.3579940938091;610.3865615056884;737.8266107464741;737.9667914943841;738.9926100738977;740.4779630360583;14447.277636118151;16344;16428.342866285333;16712.08083555404;17224.138793060298;12506.74986500274;12651.9878409727;12752.6811829705;12768.297743624096;12969.675758106028;11594.47824847884;11696.239744416585;11831.881681183164;11924.10569207313;12066.457009434509;3714.0121352841065;3720.274546463441;3723.8510459581585;3728.272986767581;3750.062484378905;91585;110927;111141.44429277883;113660.4316978418;115562;9522.714318570454;10927.617533386294;10932.945335273273;11348.489317980711;11442.656720298404;18697.90651046736;18764.71852922207;22685.2060177893;22805.973731182137;22826.771732282796;7867.409944254204;7870.173631768696;7904.920950790533;7931.960340198378;7964.8805267921025;4409.382686423904;4409.823607055746;4465.866024019285;4499.10017996402;4508.932366014513;3282.622498412665;3313.287643156733;3313.6686331366673;3344.6822551857535;3450.3789317922515;608.4523928464329;616.4975544930957;624.1854380034008;627.9717412716439;629.5844742469948;587.8853623543454;605.5639939243738;609.2840911928466;621.8109873108899;636.4462917261919;9078.534239497745;9170.266378689688;9488.525573721425;9731.513424328756;9781.559829807677;636.9745210191633;646.4718354758808;684.3167493962588;687.9475736504206;706.8162277807807;4350.1734670412525;4368.8689339319235;4625.722456652612;4701.200795864711;4753.33453316536;88176;91278;93141;94085;95452;85268;88412;88509.55745221232;90113;90400.5479972596;56861.43138568562;69661;71821.6408917952;72337.6383118081;73309.6334518324;8855.778605534913;8873.378863479495;8876.20114189726;8880.644774208959;8971.416857904216;48149;49280;49307.26039109488;49786;50289;60991.39008610035;62489;63614.09157725276;64057.67971160301;64736.67631661812;10730.985971783739;10782.67651970442;10927;10955.068819150143;11201.831972520582;10854.67435976922;12858.228506289652;12927.418266178232;12977.610671679864;13736;9786.363886347364;11821.172517924007;12102.636920892388;12177.878221218029;12501;11962.222455540363;12128.878711213129;12151.635450936486;12475.688107797378;12501.56244531437;10033.899661003297;10595.205359598214;11800.173987821114;11967.521299148284;12303.876961230273;7140.39306658919;8773.824523509367;8832.425892316978;8884.156005179497;8966.193042626195;7849.5290282580945;8639.179277968582;9345.906540934504;9394.295427842802;10061 12:28:52 INFO - PROCESS | 1905 | __end_tp_report 12:28:52 INFO - PROCESS | 1905 | __start_cc_report 12:28:52 INFO - PROCESS | 1905 | _x_x_mozilla_cycle_collect,381 12:28:52 INFO - PROCESS | 1905 | __end_cc_report 12:28:52 INFO - PROCESS | 1905 | __startTimestamp1496950132610__endTimestamp 12:28:52 INFO - PROCESS | 1905 | 12:28:52 INFO - PROCESS | 1905 | ------- Summary: start ------- 12:28:52 INFO - PROCESS | 1905 | Number of tests: 6 12:28:52 INFO - PROCESS | 1905 | 12:28:52 INFO - PROCESS | 1905 | [#0] dojo.html Cycles:165 Average:5819.08 Median:6127.94 stddev:704.09 (11.5%) stddev-sans-first:680.04 12:28:52 INFO - PROCESS | 1905 | Values: 3392.9 3438.6 3455.9 3461.3 3469.5 5853.1 5868.3 5900.0 5906.3 5980.9 5930.5 5970.6 5989.8 5991.0 6006.1 5277.3 5280.2 5314.5 5421.0 5465.0 4542.9 5357.2 5379.4 5439.0 5454.8 5158.2 5239.7 5361.5 5381.0 5381.3 5247.8 5293.0 5295.1 5299.6 5317.9 5258.4 5274.2 5314.1 5349.0 5397.4 5461.4 5858.3 5881.2 5997.6 6016.3 6236.9 6249.3 6270.7 6325.9 6352.9 6403.2 6406.0 6427.6 6428.1 6435.5 6122.7 6127.9 6159.2 6160.5 6160.7 6238.1 6366.5 6512.3 6524.3 6540.7 4592.4 5503.2 6204.4 6263.9 6296.1 6365.4 6372.8 6374.6 6389.2 6398.7 4754.8 4942.0 6260.9 6267.0 6290.5 6213.1 6223.0 6230.8 6251.3 6269.0 4147.2 4883.5 4889.0 4917.3 4923.5 4268.2 4305.9 4407.8 4419.2 4438.7 4782.7 4896.0 5711.2 5716.2 5726.5 6314.1 6353.8 6360.2 6367.3 6391.6 5824.1 5826.7 5852.1 5858.5 5877.1 5336.0 5466.6 5530.6 5547.8 5557.3 6330.6 6346.6 6435.2 6462.5 6488.4 6336.0 6339.0 6361.4 6374.0 6425.9 6429.4 6507.4 6553.2 6562.5 6582.8 6189.8 6215.7 6253.0 6254.8 6273.4 6355.2 6361.6 6369.0 6378.5 6385.0 6170.9 6301.5 6308.6 6346.9 6351.7 6079.8 6134.9 6161.6 6192.3 6275.9 6055.1 6078.7 6099.7 6182.5 6206.2 5657.2 6256.3 6273.4 6281.7 6289.3 5907.8 6096.0 6215.5 6234.3 6281.5 12:28:52 INFO - PROCESS | 1905 | 12:28:52 INFO - PROCESS | 1905 | [#1] ext.html Cycles:180 Average:51602.77 Median:28687.36 stddev:102723.46 (358.1%) stddev-sans-first:102940.67 12:28:52 INFO - PROCESS | 1905 | Values: 749.7 794.5 816.4 971.8 974.7 36669.0 38854.0 38941.8 38984.8 39103.4 37316.1 37464.1 37574.1 37619.4 37707.2 7943.6 8085.1 10657.0 10972.7 11147.3 10744.1 10876.7 10938.0 10982.1 10988.9 8381.3 9541.7 10214.5 10976.9 11000.1 9643.4 10524.8 10912.5 10956.8 10969.4 10671.4 10679.0 10730.2 10736.0 10741.6 32632.8 32699.5 32816.3 32880.8 33051.7 3100.5 3730.3 3753.5 3800.4 3908.7 19771.6 24631.0 24766.6 26255.5 26272.5 2547.5 3171.1 3185.3 3203.7 3209.5 326522.0 342379.0 356467.0 356511.0 378708.1 43127.6 43199.8 43250.6 43267.6 43429.8 44161.9 44194.1 44294.8 44298.6 44350.8 13319.1 13429.4 13436.7 13454.5 13479.3 11923.9 11938.1 11952.3 11996.2 11998.0 11333.5 11444.5 11511.0 11548.1 11600.2 5354.3 5459.4 5477.8 5484.0 5497.1 1057.8 1108.2 1145.7 1198.0 1221.0 26294.9 26717.0 26773.7 26853.5 26864.7 1277.6 1293.5 1298.8 1338.0 1338.5 11710.5 13770.5 13996.7 14045.3 14046.4 411614.0 412867.0 413682.0 415539.0 416074.9 55102.2 55523.9 55631.4 55637.3 55744.7 1432.4 1448.5 1484.4 1489.1 1495.0 24822.3 26131.7 26214.3 26249.7 26265.7 46225.3 46353.0 46661.8 46844.5 46859.1 392862.0 392997.0 394643.0 395688.0 396528.0 28720.0 29305.0 30115.7 30558.7 30656.1 38249.8 38822.8 39047.2 39050.8 39160.6 21682.7 26590.3 26903.1 28407.4 28654.7 25697.5 26329.7 27085.9 27781.4 27948.2 24454.6 31832.8 32007.5 32037.8 33764.7 22815.1 26845.9 28066.4 28503.0 29338.7 18478.7 22405.7 23230.1 23294.0 23449.5 12:28:52 INFO - PROCESS | 1905 | 12:28:52 INFO - PROCESS | 1905 | [#2] jquery.html Cycles:180 Average:43585.23 Median:11586.00 stddev:126734.43 (1093.9%) stddev-sans-first:127048.48 12:28:52 INFO - PROCESS | 1905 | Values: 406.3 406.7 420.9 434.1 443.9 19255.6 20131.3 21812.3 21818.8 21824.8 17821.9 20633.0 21510.4 21614.0 21729.7 6660.5 6958.2 6969.1 6993.5 6998.5 6596.4 6675.5 6761.4 6826.6 6832.5 6102.9 6544.5 6555.1 6759.5 6808.3 6768.9 6872.9 6900.7 6905.6 6936.4 6430.0 6619.3 6654.2 6669.9 6684.8 22881.7 25865.0 25927.0 26223.6 26665.5 2909.8 2934.8 3038.6 3055.4 3085.0 15734.8 16403.6 16527.9 16929.2 17069.7 2066.7 2092.5 2143.2 2173.5 2381.4 165361.5 169893.0 173406.5 175741.0 175986.0 20046.0 20671.4 20771.9 20959.7 21017.0 31213.8 31775.2 31859.7 31976.4 32076.2 10348.7 10420.0 10475.4 10541.7 10775.6 8803.5 8845.2 8865.2 9025.4 9041.6 9521.5 9529.9 9660.5 9712.0 9734.8 4106.4 4163.4 4194.6 4198.5 4202.5 923.2 1037.2 1051.8 1069.6 1102.0 15661.0 21028.8 21798.5 21824.9 21863.8 1078.7 1086.4 1119.6 1122.2 1122.6 7782.0 11254.9 11314.5 11376.0 11436.9 694853.0 746483.0 771233.0 783043.1 790437.0 103468.0 155791.2 156106.2 156388.7 163302.0 1290.9 1309.4 1313.7 1337.3 1339.3 19415.6 19835.6 20085.0 20091.8 20134.2 54441.0 57660.0 62303.7 62362.0 63353.7 87449.0 87900.0 87972.0 89313.6 90037.8 11286.0 11543.6 11667.3 11848.1 11963.8 12887.9 12919.2 13061.9 13200.7 13238.5 10918.3 11011.0 11108.5 11321.7 11342.3 10220.2 10555.1 10726.4 10793.8 10843.1 11027.1 11312.8 11529.1 11779.8 11788.5 11585.8 11637.4 11757.7 11931.9 11966.9 11266.7 11282.2 11350.4 11372.4 11410.7 12:28:52 INFO - PROCESS | 1905 | 12:28:52 INFO - PROCESS | 1905 | [#3] mootools.html Cycles:180 Average:6648.77 Median:2374.16 stddev:4201.07 (176.9%) stddev-sans-first:4194.56 12:28:52 INFO - PROCESS | 1905 | Values: 1431.8 1440.7 1607.3 1611.3 1624.7 10137.1 10782.5 11141.0 11221.8 11251.3 11060.9 11277.8 11292.6 11295.2 11326.5 2297.7 2356.5 2364.6 2365.9 2374.7 2285.3 2353.0 2364.6 2373.0 2373.6 1940.3 2308.6 2357.9 2362.9 2366.2 2293.4 2302.3 2340.5 2340.6 2342.1 2333.0 2334.7 2345.0 2346.0 2346.1 11164.9 11194.4 11254.3 11256.3 11256.3 5465.8 5658.5 5671.2 5681.5 5691.5 3214.5 3332.8 3437.3 3454.0 3472.4 1103.8 1123.4 1160.3 1167.2 1180.9 12403.7 12611.3 13461.8 15249.9 15331.9 3242.0 4240.0 4502.2 4509.5 4515.1 11685.4 11698.2 11748.2 11813.9 11862.2 5365.1 5385.0 5388.8 5417.8 5431.4 4190.7 4929.4 4969.3 4985.4 5068.6 3350.2 3629.2 3675.2 3731.6 3796.7 839.6 894.1 940.7 961.6 967.1 802.8 803.7 818.8 821.4 825.9 6586.8 6633.5 6755.1 6790.8 6835.6 929.7 938.2 1011.5 1030.8 1086.0 4254.3 4297.2 4300.9 4342.3 4457.5 13449.8 14008.4 14018.6 14018.9 14020.0 12891.2 13371.7 13792.6 13821.1 13899.9 8675.7 10026.7 10215.4 10300.8 10302.6 6000.7 6029.9 6353.7 7013.5 7049.8 11868.5 12081.6 12121.8 12144.9 12146.6 11886.6 11912.5 12182.1 12184.9 12542.8 9146.3 9351.3 9491.7 9494.8 9534.5 10213.4 10329.4 10381.6 10471.5 10471.6 8896.4 8936.4 8945.0 9083.5 9136.9 8634.5 8704.7 8775.4 8803.8 8821.4 7864.2 7943.7 8021.2 8023.0 8064.8 5746.5 5794.5 5858.9 6004.9 6055.1 4570.6 5953.3 5958.9 6092.1 6141.4 12:28:52 INFO - PROCESS | 1905 | 12:28:52 INFO - PROCESS | 1905 | [#4] prototype.html Cycles:180 Average:4646.01 Median:5057.76 stddev:1308.76 (25.9%) stddev-sans-first:1272.52 12:28:52 INFO - PROCESS | 1905 | Values: 372.2 380.4 387.5 392.0 395.8 4549.5 4577.1 4709.9 5181.6 5222.7 4526.9 4985.3 5118.9 5174.7 5178.2 3504.3 4148.9 4210.6 4306.6 4367.5 4139.0 4265.3 4332.0 4347.8 4362.8 3827.0 4021.7 4353.4 4419.5 4443.8 3706.7 4155.8 4278.1 4354.4 4412.3 3457.3 3791.7 3960.9 3968.0 3998.2 4128.8 4484.6 5057.5 5088.1 5106.5 4489.5 4671.3 4734.3 4800.4 5125.6 4983.0 5088.0 5541.6 5617.5 5641.5 3958.5 4414.4 4546.0 4552.6 4556.6 5006.9 5058.0 5525.6 5817.9 6067.7 3761.8 4560.4 4602.4 4602.9 4608.4 4180.4 4217.0 4461.7 4696.2 4701.2 4831.2 5031.6 5067.7 5101.2 5158.5 4398.7 5459.0 5577.9 5586.7 5593.2 1409.6 1425.6 1574.8 1585.4 1606.7 1035.9 1137.3 1206.2 1215.1 1217.7 5051.6 5081.9 5086.6 5086.7 5167.3 5245.5 5321.4 5426.5 5579.9 5665.5 4270.6 4390.8 4718.6 5142.8 5153.3 2586.7 2607.5 2966.5 3022.1 3072.5 5712.6 5960.9 5998.7 6059.8 6124.0 4582.9 5329.3 5858.4 5936.9 5947.3 5494.5 5883.6 5974.9 6015.1 6058.5 5013.7 5730.1 5844.6 5853.1 5899.8 4863.4 4962.1 5501.3 5577.4 5808.5 5150.4 6077.9 6096.6 6112.4 6145.0 4592.7 4634.1 4650.0 4673.4 4688.8 5132.9 5207.7 5606.6 5725.6 5833.3 5430.7 5619.4 5647.8 5649.8 5657.6 5278.5 5567.8 5635.3 5640.4 5680.2 4747.1 5075.7 5515.3 5520.6 5529.7 5120.1 5305.4 5316.3 5422.6 5624.3 5145.0 5202.3 5218.8 5272.3 5533.9 12:28:52 INFO - PROCESS | 1905 | 12:28:52 INFO - PROCESS | 1905 | [#5] yui.html Cycles:180 Average:19493.55 Median:607.01 stddev:28255.26 (4654.8%) stddev-sans-first:28299.42 12:28:52 INFO - PROCESS | 1905 | Values: 737.3 742.3 790.8 831.9 847.9 15754.4 16319.3 16481.3 16748.7 16928.1 15339.7 15402.2 15690.5 15750.7 15761.9 926.9 962.2 965.3 967.0 967.8 932.4 957.2 964.1 971.5 973.4 983.8 994.5 997.8 998.3 999.5 978.7 986.2 993.8 995.2 995.4 610.4 737.8 738.0 739.0 740.5 14447.3 16344.0 16428.3 16712.1 17224.1 12506.7 12652.0 12752.7 12768.3 12969.7 11594.5 11696.2 11831.9 11924.1 12066.5 3714.0 3720.3 3723.9 3728.3 3750.1 91585.0 110927.0 111141.4 113660.4 115562.0 9522.7 10927.6 10932.9 11348.5 11442.7 18697.9 18764.7 22685.2 22806.0 22826.8 7867.4 7870.2 7904.9 7932.0 7964.9 4409.4 4409.8 4465.9 4499.1 4508.9 3282.6 3313.3 3313.7 3344.7 3450.4 608.5 616.5 624.2 628.0 629.6 587.9 605.6 609.3 621.8 636.4 9078.5 9170.3 9488.5 9731.5 9781.6 637.0 646.5 684.3 687.9 706.8 4350.2 4368.9 4625.7 4701.2 4753.3 88176.0 91278.0 93141.0 94085.0 95452.0 85268.0 88412.0 88509.6 90113.0 90400.5 56861.4 69661.0 71821.6 72337.6 73309.6 8855.8 8873.4 8876.2 8880.6 8971.4 48149.0 49280.0 49307.3 49786.0 50289.0 60991.4 62489.0 63614.1 64057.7 64736.7 10731.0 10782.7 10927.0 10955.1 11201.8 10854.7 12858.2 12927.4 12977.6 13736.0 9786.4 11821.2 12102.6 12177.9 12501.0 11962.2 12128.9 12151.6 12475.7 12501.6 10033.9 10595.2 11800.2 11967.5 12303.9 7140.4 8773.8 8832.4 8884.2 8966.2 7849.5 8639.2 9345.9 9394.3 10061.0 12:28:52 INFO - PROCESS | 1905 | -------- Summary: end -------- 12:28:52 INFO - PROCESS | 1905 | 12:28:53 INFO - TEST-INFO | 1905: exit 0 12:28:53 INFO - TEST-OK | dromaeo_css | took 1115646ms 12:28:53 INFO - TEST-START | kraken 12:28:53 INFO - operating with platform_type : mac_ 12:28:53 INFO - Initialising browser for kraken test... 12:28:53 INFO - TEST-INFO | started process 1910 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/tmpYTzJ2T/profile http://localhost:51214/getInfo.html) 12:28:55 INFO - PROCESS | 1910 | 2017-06-08 12:28:55.735 plugin-container[1912:14260] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 12:28:55 INFO - PROCESS | 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:28:55 INFO - PROCESS | 1910 | 2017-06-08 12:28:55.736 plugin-container[1912:14260] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 12:28:55 INFO - PROCESS | 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:28:55 INFO - PROCESS | 1910 | 2017-06-08 12:28:55.736 plugin-container[1912:14260] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:28:55 INFO - PROCESS | 1910 | __metrics Screen width/height:1600/1200 12:28:55 INFO - PROCESS | 1910 | colorDepth:24 12:28:55 INFO - PROCESS | 1910 | Browser inner width/height: 1024/689 12:28:55 INFO - PROCESS | 1910 | __metrics 12:28:55 ERROR - PROCESS | 1910 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3381: TypeError: this.tabs is undefined 12:28:56 INFO - TEST-INFO | 1910: exit 0 12:28:56 INFO - Browser initialized. 12:28:56 INFO - Running cycle 1/1 for kraken test... 12:28:56 INFO - TEST-INFO | started process 1913 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/T/tmpYTzJ2T/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:28:58 INFO - PROCESS | 1913 | 2017-06-08 12:28:58.729 plugin-container[1914:14386] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 12:28:58 INFO - PROCESS | 1913 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:28:58 INFO - PROCESS | 1913 | 2017-06-08 12:28:58.730 plugin-container[1914:14386] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x870b, name = 'com.apple.CFPasteboardClient' 12:28:58 INFO - PROCESS | 1913 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:28:58 INFO - PROCESS | 1913 | 2017-06-08 12:28:58.730 plugin-container[1914:14386] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:29:08 INFO - PROCESS | 1913 | RSS: Main: 203137024 12:29:08 INFO - PROCESS | 1913 | 12:29:11 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:51214/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51214/tests/kraken/driver.html?testName=audio-beat-detection) 12:29:12 INFO - PROCESS | 1913 | RSS: Main: 216547328 12:29:12 INFO - PROCESS | 1913 | 12:29:15 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:51214/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51214/tests/kraken/driver.html?testName=audio-dft) 12:29:15 INFO - PROCESS | 1913 | RSS: Main: 224481280 12:29:15 INFO - PROCESS | 1913 | 12:29:19 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:51214/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51214/tests/kraken/driver.html?testName=audio-fft) 12:29:19 INFO - PROCESS | 1913 | RSS: Main: 228151296 12:29:19 INFO - PROCESS | 1913 | 12:29:21 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:51214/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51214/tests/kraken/driver.html?testName=audio-oscillator) 12:29:22 INFO - PROCESS | 1913 | RSS: Main: 228450304 12:29:22 INFO - PROCESS | 1913 | 12:29:24 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:51214/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51214/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:29:24 INFO - PROCESS | 1913 | RSS: Main: 229883904 12:29:24 INFO - PROCESS | 1913 | 12:29:28 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:51214/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51214/tests/kraken/driver.html?testName=imaging-darkroom) 12:29:28 INFO - PROCESS | 1913 | RSS: Main: 233611264 12:29:28 INFO - PROCESS | 1913 | 12:29:31 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:51214/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51214/tests/kraken/driver.html?testName=imaging-desaturate) 12:29:32 INFO - PROCESS | 1913 | RSS: Main: 232964096 12:29:32 INFO - PROCESS | 1913 | 12:29:35 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:51214/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51214/tests/kraken/driver.html?testName=json-parse-financial) 12:29:36 INFO - PROCESS | 1913 | RSS: Main: 233336832 12:29:36 INFO - PROCESS | 1913 | 12:29:37 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:51214/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51214/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:29:38 INFO - PROCESS | 1913 | RSS: Main: 232550400 12:29:38 INFO - PROCESS | 1913 | 12:29:40 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:51214/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51214/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:29:41 INFO - PROCESS | 1913 | RSS: Main: 232509440 12:29:41 INFO - PROCESS | 1913 | 12:29:43 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:51214/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51214/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:29:43 INFO - PROCESS | 1913 | RSS: Main: 232644608 12:29:43 INFO - PROCESS | 1913 | 12:29:46 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:51214/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51214/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:29:47 INFO - PROCESS | 1913 | RSS: Main: 232972288 12:29:47 INFO - PROCESS | 1913 | 12:29:50 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:51214/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51214/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:29:51 INFO - PROCESS | 1913 | RSS: Main: 233230336 12:29:51 INFO - PROCESS | 1913 | 12:29:52 INFO - PROCESS | 1913 | Cycle 1(1): loaded http://localhost:51214/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51214/tests/kraken/driver.html?testName=ai-astar) 12:29:53 INFO - PROCESS | 1913 | RSS: Main: 234618880 12:29:53 INFO - PROCESS | 1913 | 12:29:53 INFO - PROCESS | 1913 | __start_tp_report 12:29:53 INFO - PROCESS | 1913 | _x_x_mozilla_page_load 12:29:53 INFO - PROCESS | 1913 | _x_x_mozilla_page_load_details 12:29:53 INFO - PROCESS | 1913 | |i|pagename|runs| 12:29:53 INFO - PROCESS | 1913 | |0;ai-astar;110;123;120;121;120;147;178;157;120;120 12:29:53 INFO - PROCESS | 1913 | |1;audio-beat-detection;143;107;112;149;117;151;111;113;132;112 12:29:53 INFO - PROCESS | 1913 | |2;audio-dft;198;203;196;200;198;198;197;195;200;194 12:29:53 INFO - PROCESS | 1913 | |3;audio-fft;82;62;61;90;65;62;62;63;78;81 12:29:53 INFO - PROCESS | 1913 | |4;audio-oscillator;90;71;84;73;72;72;81;82;102;83 12:29:53 INFO - PROCESS | 1913 | |5;imaging-gaussian-blur;88;89;90;108;135;108;125;136;91;90 12:29:53 INFO - PROCESS | 1913 | |6;imaging-darkroom;156;122;154;132;97;98;96;99;98;97 12:29:53 INFO - PROCESS | 1913 | |7;imaging-desaturate;92;118;128;83;139;147;96;90;93;93 12:29:53 INFO - PROCESS | 1913 | |8;json-parse-financial;68;66;66;66;71;71;70;69;71;70 12:29:53 INFO - PROCESS | 1913 | |9;json-stringify-tinderbox;62;58;55;61;78;68;57;56;59;77 12:29:53 INFO - PROCESS | 1913 | |10;stanford-crypto-aes;87;87;103;69;91;100;107;70;98;75 12:29:53 INFO - PROCESS | 1913 | |11;stanford-crypto-ccm;139;151;129;138;130;155;145;169;178;173 12:29:53 INFO - PROCESS | 1913 | |12;stanford-crypto-pbkdf2;167;171;165;167;162;162;167;165;167;166 12:29:53 INFO - PROCESS | 1913 | |13;stanford-crypto-sha256-iterative;68;66;67;73;77;75;68;65;67;63 12:29:53 INFO - PROCESS | 1913 | __end_tp_report 12:29:53 INFO - PROCESS | 1913 | __start_cc_report 12:29:53 INFO - PROCESS | 1913 | _x_x_mozilla_cycle_collect,1022 12:29:53 INFO - PROCESS | 1913 | __end_cc_report 12:29:53 INFO - PROCESS | 1913 | __startTimestamp1496950193135__endTimestamp 12:29:53 INFO - PROCESS | 1913 | 12:29:53 INFO - PROCESS | 1913 | ------- Summary: start ------- 12:29:53 INFO - PROCESS | 1913 | Number of tests: 14 12:29:53 INFO - PROCESS | 1913 | 12:29:53 INFO - PROCESS | 1913 | [#0] ai-astar Cycles:10 Average:131.60 Median:122.00 stddev:21.67 (17.8%) stddev-sans-first:21.53 12:29:53 INFO - PROCESS | 1913 | Values: 110.0 123.0 120.0 121.0 120.0 147.0 178.0 157.0 120.0 120.0 12:29:53 INFO - PROCESS | 1913 | 12:29:53 INFO - PROCESS | 1913 | [#1] audio-beat-detection Cycles:10 Average:124.70 Median:124.50 stddev:17.29 (13.9%) stddev-sans-first:17.02 12:29:53 INFO - PROCESS | 1913 | Values: 143.0 107.0 112.0 149.0 117.0 151.0 111.0 113.0 132.0 112.0 12:29:53 INFO - PROCESS | 1913 | 12:29:53 INFO - PROCESS | 1913 | [#2] audio-dft Cycles:10 Average:197.90 Median:198.00 stddev:2.64 (1.3%) stddev-sans-first:2.80 12:29:53 INFO - PROCESS | 1913 | Values: 198.0 203.0 196.0 200.0 198.0 198.0 197.0 195.0 200.0 194.0 12:29:53 INFO - PROCESS | 1913 | 12:29:53 INFO - PROCESS | 1913 | [#3] audio-fft Cycles:10 Average:70.60 Median:71.50 stddev:10.92 (15.3%) stddev-sans-first:10.77 12:29:53 INFO - PROCESS | 1913 | Values: 82.0 62.0 61.0 90.0 65.0 62.0 62.0 63.0 78.0 81.0 12:29:53 INFO - PROCESS | 1913 | 12:29:53 INFO - PROCESS | 1913 | [#4] audio-oscillator Cycles:10 Average:81.00 Median:81.50 stddev:9.79 (12.0%) stddev-sans-first:9.82 12:29:53 INFO - PROCESS | 1913 | Values: 90.0 71.0 84.0 73.0 72.0 72.0 81.0 82.0 102.0 83.0 12:29:53 INFO - PROCESS | 1913 | 12:29:53 INFO - PROCESS | 1913 | [#5] imaging-gaussian-blur Cycles:10 Average:106.00 Median:88.50 stddev:19.61 (22.2%) stddev-sans-first:19.69 12:29:53 INFO - PROCESS | 1913 | Values: 88.0 89.0 90.0 108.0 135.0 108.0 125.0 136.0 91.0 90.0 12:29:53 INFO - PROCESS | 1913 | 12:29:53 INFO - PROCESS | 1913 | [#6] imaging-darkroom Cycles:10 Average:114.90 Median:97.00 stddev:24.46 (25.2%) stddev-sans-first:20.93 12:29:53 INFO - PROCESS | 1913 | Values: 156.0 122.0 154.0 132.0 97.0 98.0 96.0 99.0 98.0 97.0 12:29:53 INFO - PROCESS | 1913 | 12:29:53 INFO - PROCESS | 1913 | [#7] imaging-desaturate Cycles:10 Average:107.90 Median:91.00 stddev:23.05 (25.3%) stddev-sans-first:23.72 12:29:53 INFO - PROCESS | 1913 | Values: 92.0 118.0 128.0 83.0 139.0 147.0 96.0 90.0 93.0 93.0 12:29:53 INFO - PROCESS | 1913 | 12:29:53 INFO - PROCESS | 1913 | [#8] json-parse-financial Cycles:10 Average:68.80 Median:70.00 stddev:2.15 (3.1%) stddev-sans-first:2.26 12:29:53 INFO - PROCESS | 1913 | Values: 68.0 66.0 66.0 66.0 71.0 71.0 70.0 69.0 71.0 70.0 12:29:53 INFO - PROCESS | 1913 | 12:29:53 INFO - PROCESS | 1913 | [#9] json-stringify-tinderbox Cycles:10 Average:63.10 Median:61.50 stddev:8.44 (13.7%) stddev-sans-first:8.94 12:29:53 INFO - PROCESS | 1913 | Values: 62.0 58.0 55.0 61.0 78.0 68.0 57.0 56.0 59.0 77.0 12:29:53 INFO - PROCESS | 1913 | 12:29:53 INFO - PROCESS | 1913 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:81.00 stddev:13.70 (16.9%) stddev-sans-first:14.52 12:29:53 INFO - PROCESS | 1913 | Values: 87.0 87.0 103.0 69.0 91.0 100.0 107.0 70.0 98.0 75.0 12:29:53 INFO - PROCESS | 1913 | 12:29:53 INFO - PROCESS | 1913 | [#11] stanford-crypto-ccm Cycles:10 Average:150.70 Median:153.00 stddev:17.72 (11.6%) stddev-sans-first:18.28 12:29:53 INFO - PROCESS | 1913 | Values: 139.0 151.0 129.0 138.0 130.0 155.0 145.0 169.0 178.0 173.0 12:29:53 INFO - PROCESS | 1913 | 12:29:53 INFO - PROCESS | 1913 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.90 Median:167.00 stddev:2.64 (1.6%) stddev-sans-first:2.77 12:29:53 INFO - PROCESS | 1913 | Values: 167.0 171.0 165.0 167.0 162.0 162.0 167.0 165.0 167.0 166.0 12:29:53 INFO - PROCESS | 1913 | 12:29:53 INFO - PROCESS | 1913 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:68.00 stddev:4.56 (6.7%) stddev-sans-first:4.82 12:29:53 INFO - PROCESS | 1913 | Values: 68.0 66.0 67.0 73.0 77.0 75.0 68.0 65.0 67.0 63.0 12:29:53 INFO - PROCESS | 1913 | -------- Summary: end -------- 12:29:53 INFO - PROCESS | 1913 | 12:29:53 INFO - TEST-INFO | 1913: exit 0 12:29:53 INFO - TEST-OK | kraken | took 60700ms 12:29:53 INFO - SUITE-END | took 1176s 12:29:53 INFO - Completed test suite (00:19:36) 12:29:53 INFO - javascript benchmark 12:29:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7573.483198458269, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3392.898213053609, 3438.5873695156583, 3455.9136021599465, 3461.255829996551, 3469.4969229461713, 5853.063509838421, 5868.325142608602, 5900.0, 5906.320773111093, 5980.940190598093, 5930.466258036776, 5970.582059255854, 5989.760409583622, 5990.970045149779, 6006.129111278873, 5277.3139491866, 5280.1551751719735, 5314.468553144674, 5420.972895135519, 5465.0, 4542.909141817178, 5357.196420536911, 5379.408265090837, 5439.0, 5454.836354909359, 5158.200478925773, 5239.685618862881, 5361.490658387447, 5381.0, 5381.300430943952, 5247.763850626731, 5292.973535132338, 5295.073362161606, 5299.6025298102795, 5317.9202311965355, 5258.447862974371, 5274.235235890813, 5314.123169676998, 5349.037173308803, 5397.352317721859, 5461.399246082928, 5858.2677165354335, 5881.2354393928945, 5997.5501837361935, 6016.308124565688, 6236.906446403307, 6249.343818899041, 6270.749170033193, 6325.87348253037, 6352.872942541123, 6403.199600049994, 6406.0, 6427.646479443629, 6428.100065990765, 6435.452986496153, 6122.724477398483, 6127.938720612781, 6159.199304090529, 6160.476359509492, 6160.6611636359985, 6238.095476155932, 6366.4588509977175, 6512.316206798313, 6524.282328943812, 6540.738370465223, 4592.402987611589, 5503.174523821459, 6204.410580994799, 6263.937360626426, 6296.0555916612875, 6365.395287447777, 6372.776952806722, 6374.649394283359, 6389.169407976933, 6398.744050238031, 4754.833580824724, 4941.962187940573, 6260.937390626126, 6266.968665156645, 6290.4653104485715, 6213.130161777355, 6223.0354295084335, 6230.813075607648, 6251.3123556408755, 6268.968655156695, 4147.212029714345, 4883.51164883516, 4888.951110488921, 4917.28699338601, 4923.507649235048, 4268.210381079511, 4305.892352691145, 4407.845725399596, 4419.226635338803, 4438.667099967515, 4782.713037217778, 4895.951040489621, 5711.228984087178, 5716.171155182558, 5726.455986681259, 6314.116023756677, 6353.777617783448, 6360.204974378203, 6367.331430199855, 6391.648459334781, 5824.126381042877, 5826.650400975955, 5852.122181672783, 5858.53131749459, 5877.147813567094, 5336.0, 5466.644668096561, 5530.557555395559, 5547.805826796042, 5557.305336832896, 6330.588511746721, 6346.619202847844, 6435.195600549931, 6462.483001359786, 6488.448481878904, 6335.968320158369, 6339.049142628643, 6361.395667411589, 6374.043893416025, 6425.935740642533, 6429.38920802523, 6507.381798729113, 6553.180852393451, 6562.475001999733, 6582.76960306387, 6189.783357582463, 6215.689215539114, 6252.968735156295, 6254.843628909313, 6273.37266273369, 6355.173827402408, 6361.61830290184, 6368.968155159195, 6378.52161087911, 6385.042243663488, 6170.938290617216, 6301.495880329654, 6308.621482711052, 6346.873062538631, 6351.745930162741, 6079.787207447718, 6134.877302453837, 6161.630302181883, 6192.2569291683485, 6275.874482510416, 6055.1220073090035, 6078.7264573093435, 6099.725512352044, 6182.536309776695, 6206.193194884636, 5657.2079908811975, 6256.31180570146, 6273.435390814849, 6281.654509002049, 6289.339619339903, 5907.822765317047, 6096.02463605821, 6215.502759779297, 6234.345393733593, 6281.4660753834605], "value": 5773.840793032139, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [749.7338444852078, 794.4835856693148, 816.4040250617049, 971.8007808399275, 974.6880998080617, 36669.0, 38854.02864928371, 38941.80529097358, 38984.805075974655, 39103.41344879825, 37316.06709832256, 37464.063398415055, 37574.0606484838, 37619.43570846439, 37707.24585508288, 7943.5631040292765, 8085.110637829834, 10657.0, 10972.725681857937, 11147.275427097253, 10744.086752626035, 10876.67369978898, 10938.0, 10982.06652434544, 10988.890111098866, 8381.28759055481, 9541.713748587554, 10214.540345684425, 10976.890231097666, 11000.064994475479, 9643.421394716304, 10524.789649190365, 10912.50893709785, 10956.835647465294, 10969.396683182422, 10671.35971841692, 10678.985496377833, 10730.248882578144, 10736.0, 10741.62404315853, 32632.83683581567, 32699.50950735741, 32816.3436731264, 32880.83559582187, 33051.66948330534, 3100.5430442421502, 3730.301216836231, 3753.512043434336, 3800.3539398302355, 3908.667763240128, 19771.604567908704, 24631.0, 24766.628500572508, 26255.474890502082, 26272.474550508883, 2547.5287071891576, 3171.0962375722806, 3185.3470038642254, 3203.7116659500525, 3209.518572214163, 326522.0, 342379.0, 356467.0, 356511.0, 378708.1064594687, 43127.568724312354, 43199.78400108042, 43250.56749432528, 43267.567324326985, 43429.78285108554, 44161.8959526008, 44194.11611767811, 44294.77852610716, 44298.55701442944, 44350.778246108566, 13319.067665263532, 13429.39567719455, 13436.731265374638, 13454.52909148175, 13479.326033698277, 11923.940380298043, 11938.1046421517, 11952.282863028073, 11996.220245684002, 11998.040156787436, 11333.546658133582, 11444.54221831134, 11511.0, 11548.133889958286, 11600.187986841009, 5354.303940487712, 5459.372172200168, 5477.780888764484, 5484.0, 5497.147942068986, 1057.7831544533353, 1108.2076315434342, 1145.667756350649, 1197.9880201197877, 1220.9633710988685, 26294.868525657632, 26717.0, 26773.732262677513, 26853.462930741665, 26864.731352686224, 1277.6358737759695, 1293.46321276671, 1298.7792075347213, 1338.0499845109887, 1338.5181334719493, 11710.531578736754, 13770.518031869036, 13996.650083747987, 14045.297735113203, 14046.367913443919, 411614.0, 412867.0, 413682.0, 415539.0, 416074.919625406, 55102.173467398025, 55523.889522210135, 55631.443685563434, 55637.33088007366, 55744.72127639417, 1432.4028707990515, 1448.5147475595584, 1484.4210757804465, 1489.113977183574, 1494.9701005979819, 24822.255332340057, 26131.73868261369, 26214.34464138412, 26249.73750262473, 26265.73734262633, 46225.30662040005, 46353.0, 46661.766691166325, 46844.531554684014, 46859.06281874412, 392862.0, 392997.0, 394643.0, 395688.0, 396528.0, 28720.0, 29305.0, 30115.698843012167, 30558.694413056473, 30656.08031759051, 38249.808750956065, 38822.80588597039, 39047.21905561816, 39050.804745976086, 39160.608393915696, 21682.67475987893, 26590.335241619116, 26903.05839295615, 28407.431851362442, 28654.71345286604, 25697.486050278516, 26329.73670263273, 27085.916563337247, 27781.44437111287, 27948.16155515338, 24454.633180502664, 31832.840835795672, 32007.519887201244, 32037.839810800797, 33764.66235337615, 22815.087396503954, 26845.865770671022, 28066.438671226053, 28503.0, 29338.706612934453, 18478.722819157454, 22405.66391504096, 23230.070797167926, 23294.039597392344, 23449.531009380058], "value": 17885.4457757, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [406.32549967054695, 406.7356218458004, 420.90108824426267, 434.11657277440406, 443.9356293337464, 19255.61488770224, 20131.29540466082, 21812.345629631116, 21818.781812181875, 21824.781752182473, 17821.91089044556, 20633.0, 21510.354689359305, 21614.0, 21729.67405488919, 6660.533762636605, 6958.199807022182, 6969.094017777682, 6993.545419547739, 6998.510104292702, 6596.406323430891, 6675.532712710112, 6761.35767102127, 6826.556273842184, 6832.521723479358, 6102.931986902297, 6544.476441884638, 6555.1150594669825, 6759.459243260528, 6808.2510923798345, 6768.864622707543, 6872.896906546406, 6900.654967251618, 6905.585664860125, 6936.410405115571, 6430.0, 6619.271880093186, 6654.234763002269, 6669.866602667919, 6684.766033188864, 22881.656775148218, 25865.0, 25927.0, 26223.557704326253, 26665.46669066627, 2909.808823675304, 2934.8092373995623, 3038.589790378293, 3055.4041961817456, 3084.984575077133, 15734.763978540332, 16403.64702831037, 16527.91736041324, 16929.238184281738, 17069.7439538407, 2066.749616482037, 2092.487340601547, 2143.20701340505, 2173.521825198454, 2381.4284571702665, 165361.51957720643, 169893.0, 173406.5263799937, 175741.0, 175986.0, 20046.0, 20671.379858604265, 20771.896140519504, 20959.68560471594, 21017.0, 31213.843930780655, 31775.205619859687, 31859.681403186136, 31976.360472790413, 32076.198095047344, 10348.689539313833, 10420.0, 10475.376316670929, 10541.682289713786, 10775.6228532001, 8803.471791692391, 8845.203931646054, 8865.202131808168, 9025.368224224241, 9041.593128309241, 9521.523923803783, 9529.857052144223, 9660.516974151264, 9711.951440242894, 9734.75663108428, 4106.398504583215, 4163.417121602978, 4194.5881118357065, 4198.4961804583645, 4202.474690663667, 923.2383283790899, 1037.2479952034864, 1051.8054159980275, 1069.6201899549649, 1101.9504122314515, 15661.0, 21028.789712102684, 21798.45503862416, 21824.90875456221, 21863.78136218649, 1078.741102135482, 1086.3970496374588, 1119.6137332620233, 1122.2200570603516, 1122.640754958422, 7781.9610901945125, 11254.887451125383, 11314.547418103348, 11376.033037191932, 11436.942815286036, 694853.0, 746483.0, 771233.0, 783043.0847845725, 790437.0, 103468.0, 155791.22104389407, 156106.21946890192, 156388.65417018754, 163302.0, 1290.9354532273348, 1309.4369421148806, 1313.6912825486004, 1337.3313343328336, 1339.34372157644, 19415.611687765882, 19835.60328793387, 20085.0, 20091.799082008994, 20134.19463221455, 54441.0, 57660.0, 62303.6884815573, 62362.0, 63353.68323158355, 87449.0, 87900.0, 87972.0, 89313.55343223242, 90037.84867529605, 11286.040686541735, 11543.595974140864, 11667.299962001967, 11848.111391645833, 11963.760724785281, 12887.871121289043, 12919.22484650924, 13061.934690326487, 13200.669983250496, 13238.470461181445, 10918.290311129751, 11011.00900918921, 11108.50011749457, 11321.71695707614, 11342.319460832376, 10220.233482488991, 10555.050045495942, 10726.356418614909, 10793.784124317626, 10843.132549395872, 11027.117830573374, 11312.773744525228, 11529.082032126618, 11779.764404712028, 11788.4695188715, 11585.82621260665, 11637.359945203096, 11757.706057348292, 11931.880681193077, 11966.880331196577, 11266.704329001941, 11282.210245282751, 11350.375729334966, 11372.374519401512, 11410.714732131431], "value": 11738.503229919246, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1431.8424973252947, 1440.6686462113714, 1607.284758282564, 1611.2588209423657, 1624.740041593345, 10137.087662110409, 10782.46087695616, 11141.0, 11221.775564488706, 11251.268667536604, 11060.889391106086, 11277.774444511126, 11292.604758833442, 11295.209335346508, 11326.546938122465, 2297.6553516972463, 2356.4815740537056, 2364.6334818103137, 2365.9408514787183, 2374.6675465434855, 2285.268714011517, 2353.011735071266, 2364.586197415454, 2373.0, 2373.6439534069855, 1940.2917934953782, 2308.572914010905, 2357.9410514737096, 2362.8582285062953, 2366.219147681261, 2293.3693234360517, 2302.3438320078862, 2340.4850932794757, 2340.637201233803, 2342.0865862313713, 2333.0317918063984, 2334.743178250391, 2344.9648255276184, 2346.0498498108295, 2346.0615753698657, 11164.944175279154, 11194.440277986068, 11254.26847254934, 11256.268342557707, 11256.268342557789, 5465.781368745245, 5658.462446067617, 5671.234383358235, 5681.460261275172, 5691.459311365414, 3214.4696125139326, 3332.766706330558, 3437.2781715839456, 3454.0, 3472.3576138414473, 1103.8450436461662, 1123.359684979562, 1160.2690305107812, 1167.1654766841748, 1180.887815657511, 12403.689907752378, 12611.306378149106, 13461.798073028913, 15249.923750381176, 15331.923340383339, 3241.9675803241666, 4239.97880010598, 4502.234620114588, 4509.503954564995, 4515.119551687455, 11685.357305348118, 11698.239614425034, 11748.177627565989, 11813.940930295294, 11862.169648124722, 5365.141577347684, 5385.0, 5388.811391601275, 5417.783288668488, 5431.429699881534, 4190.727602705814, 4929.408470983505, 4969.254611808257, 4985.351904252424, 5068.619853511002, 3350.2294472271515, 3629.2197177606413, 3675.209829886587, 3731.6268373162466, 3796.6962642988497, 839.6473481137937, 894.0746327550953, 940.6707652321633, 961.5961296255589, 967.0522887570221, 802.7671975127148, 803.6745118227127, 818.8239528501282, 821.4414198345182, 825.8554752918216, 6586.80239592813, 6633.535652504375, 6755.054292399068, 6790.830229244309, 6835.624040677711, 929.7396728915915, 938.174406522256, 1011.4891979550428, 1030.7835354575623, 1085.9565617375374, 4254.319308910622, 4297.183535128315, 4300.913981720348, 4342.283523218697, 4457.509673935864, 13449.798253026212, 14008.43966241339, 14018.579442616738, 14018.859811401959, 14020.0, 12891.226526408445, 13371.665708357174, 13792.586222413343, 13821.101628394123, 13899.86100139006, 8675.696350627572, 10026.69919902404, 10215.387076775418, 10300.845487317696, 10302.639407620698, 6000.69996500182, 6029.909551356645, 6353.714082866374, 7013.544119632207, 7049.753258635923, 11868.465919033837, 12081.57714479989, 12121.75756484883, 12144.939275303566, 12146.574869879512, 11886.643400697993, 11912.52349905994, 12182.147249692436, 12184.878151218374, 12542.811857821958, 9146.268298536233, 9351.251899847859, 9491.715248542554, 9494.762630934281, 9534.52327383642, 10213.387196768217, 10329.380237185793, 10381.584736610452, 10471.528781204712, 10471.63349282802, 8896.377253592187, 8936.41913275635, 8944.955275223581, 9083.45499270046, 9136.862947055666, 8634.481931084156, 8704.695335663222, 8775.42959707617, 8803.779905502157, 8821.382503224713, 7864.21357864209, 7943.721969731263, 8021.2379823916635, 8023.037235531773, 8064.758057258292, 5746.482816546531, 5794.507466865195, 5858.9121163181735, 6004.8799024020145, 6055.061465472859, 4570.588647021784, 5953.345132035386, 5958.910616340845, 6092.086187071975, 6141.385861413823], "value": 5017.596760713196, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.22948496612025, 380.38567713143266, 387.47303667012864, 391.95688474267826, 395.8100111946265, 4549.4768101668315, 4577.123379414436, 4709.9058018839605, 5181.611379146565, 5222.746540830202, 4526.932096018562, 4985.304996301266, 5118.897622047557, 5174.663646862951, 5178.2232665100255, 3504.3341765064683, 4148.854790082348, 4210.557891421403, 4306.621880998082, 4367.519572846984, 4138.958610413887, 4265.31754919213, 4332.0, 4347.78261086946, 4362.760048197364, 3827.0049787055427, 4021.6620205229588, 4353.368761529577, 4419.535948725385, 4443.755593442359, 3706.7405281630295, 4155.792210389499, 4278.053485664015, 4354.390385346054, 4412.338149277602, 3457.343104810078, 3791.6777073948747, 3960.861369852041, 3968.0476685595536, 3998.2203470323298, 4128.8348466061325, 4484.551544845522, 5057.544820966131, 5088.056756014082, 5106.489351064901, 4489.506154323022, 4671.32265821454, 4734.26618874075, 4800.351952486404, 5125.564327032207, 4983.0, 5087.974560127176, 5541.612087153901, 5617.494425501724, 5641.486810551583, 3958.4853968984135, 4414.366684326787, 4546.0, 4552.590266875997, 4556.567126123013, 5006.949930500685, 5057.974710126426, 5525.640833345819, 5817.905554000557, 6067.666278354689, 3761.777422337751, 4560.429946256718, 4602.424696912886, 4602.930956035662, 4608.354830323758, 4180.352045432963, 4217.039076829289, 4461.687681862239, 4696.201645720235, 4701.2242979908615, 4831.178699621073, 5031.597472202215, 5067.695938243717, 5101.158308879068, 5158.484151584887, 4398.724369932747, 5459.0, 5577.9163312550345, 5586.692731899784, 5593.244911936918, 1409.6405416618697, 1425.6008317670862, 1574.8188958269716, 1585.4450942170379, 1606.6706325203306, 1035.8756949166147, 1137.2721458266717, 1206.197878410847, 1215.0826126274605, 1217.7077501399601, 5051.595872330205, 5081.891015228639, 5086.567641750493, 5086.745662716849, 5167.302414174113, 5245.452591485521, 5321.3880403754065, 5426.457354264621, 5579.911917176111, 5665.49010589049, 4270.636995855325, 4390.7585082820115, 4718.598919091847, 5142.845714628567, 5153.252778347119, 2586.663733714605, 2607.504574130947, 2966.4512065267995, 3022.1235841606126, 3072.5083986562045, 5712.571557133231, 5960.850978725566, 5998.700064996733, 6059.818205453844, 6123.989541725566, 4582.88542786433, 5329.333833270841, 5858.443447872531, 5936.910946335808, 5947.31605865331, 5494.532964697965, 5883.646981181143, 5974.9370428109305, 6015.120247440464, 6058.485028772604, 5013.749312534285, 5730.140478928194, 5844.620099693507, 5853.0635098384055, 5899.764009439746, 4863.416390033219, 4962.057209130254, 5501.339839219159, 5577.386487486292, 5808.535317174531, 5150.356205474316, 6077.878442431215, 6096.634201947897, 6112.358202388685, 6144.988955411905, 4592.724436533818, 4634.073185362873, 4649.953500465087, 4673.439187297546, 4688.812447502061, 5132.948670513397, 5207.6875387476875, 5606.635568688022, 5725.5992080555625, 5833.308340581184, 5430.70131142791, 5619.381867994599, 5647.813959068477, 5649.830505084854, 5657.603967722384, 5278.498542638443, 5567.805126820542, 5635.267415235993, 5640.379558248672, 5680.2047713321, 4747.053707979711, 5075.746212689424, 5515.283013208257, 5520.613557050968, 5529.723513824212, 5120.078385890575, 5305.442928492453, 5316.341829085457, 5422.6475279106735, 5624.268845050117, 5145.0, 5202.323697919398, 5218.765155568084, 5272.337469577838, 5533.944660553504], "value": 4265.492778629075, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.2774680812803, 742.261449857392, 790.7904405332588, 831.8876951611533, 847.8601030829911, 15754.448594299201, 16319.265633046512, 16481.340746370144, 16748.66502669946, 16928.068956207404, 15339.693206135871, 15402.229888505557, 15690.529284121467, 15750.684986300295, 15761.921190394032, 926.8563372677229, 962.1821451766012, 965.3435663748648, 966.9613215471372, 967.8209531236708, 932.3892850183141, 957.195955397466, 964.0889359555223, 971.519098046468, 973.3770386952356, 983.8475036369374, 994.5077186792512, 997.7954519323521, 998.2562990571997, 999.4503023337136, 978.681928373278, 986.225812736998, 993.8409854423257, 995.1740055753708, 995.3579940938091, 610.3865615056884, 737.8266107464741, 737.9667914943841, 738.9926100738977, 740.4779630360583, 14447.277636118151, 16344.0, 16428.342866285333, 16712.08083555404, 17224.138793060298, 12506.74986500274, 12651.9878409727, 12752.6811829705, 12768.297743624096, 12969.675758106028, 11594.47824847884, 11696.239744416585, 11831.881681183164, 11924.10569207313, 12066.457009434509, 3714.0121352841065, 3720.274546463441, 3723.8510459581585, 3728.272986767581, 3750.062484378905, 91585.0, 110927.0, 111141.44429277883, 113660.4316978418, 115562.0, 9522.714318570454, 10927.617533386294, 10932.945335273273, 11348.489317980711, 11442.656720298404, 18697.90651046736, 18764.71852922207, 22685.2060177893, 22805.973731182137, 22826.771732282796, 7867.409944254204, 7870.173631768696, 7904.920950790533, 7931.960340198378, 7964.8805267921025, 4409.382686423904, 4409.823607055746, 4465.866024019285, 4499.10017996402, 4508.932366014513, 3282.622498412665, 3313.287643156733, 3313.6686331366673, 3344.6822551857535, 3450.3789317922515, 608.4523928464329, 616.4975544930957, 624.1854380034008, 627.9717412716439, 629.5844742469948, 587.8853623543454, 605.5639939243738, 609.2840911928466, 621.8109873108899, 636.4462917261919, 9078.534239497745, 9170.266378689688, 9488.525573721425, 9731.513424328756, 9781.559829807677, 636.9745210191633, 646.4718354758808, 684.3167493962588, 687.9475736504206, 706.8162277807807, 4350.1734670412525, 4368.8689339319235, 4625.722456652612, 4701.200795864711, 4753.33453316536, 88176.0, 91278.0, 93141.0, 94085.0, 95452.0, 85268.0, 88412.0, 88509.55745221232, 90113.0, 90400.5479972596, 56861.43138568562, 69661.0, 71821.6408917952, 72337.6383118081, 73309.6334518324, 8855.778605534913, 8873.378863479495, 8876.20114189726, 8880.644774208959, 8971.416857904216, 48149.0, 49280.0, 49307.26039109488, 49786.0, 50289.0, 60991.39008610035, 62489.0, 63614.09157725276, 64057.67971160301, 64736.67631661812, 10730.985971783739, 10782.67651970442, 10927.0, 10955.068819150143, 11201.831972520582, 10854.67435976922, 12858.228506289652, 12927.418266178232, 12977.610671679864, 13736.0, 9786.363886347364, 11821.172517924007, 12102.636920892388, 12177.878221218029, 12501.0, 11962.222455540363, 12128.878711213129, 12151.635450936486, 12475.688107797378, 12501.56244531437, 10033.899661003297, 10595.205359598214, 11800.173987821114, 11967.521299148284, 12303.876961230273, 7140.39306658919, 8773.824523509367, 8832.425892316978, 8884.156005179497, 8966.193042626195, 7849.5290282580945, 8639.179277968582, 9345.906540934504, 9394.295427842802, 10061.0], "value": 7272.625508431522, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1540.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 123.0, 120.0, 121.0, 120.0, 147.0, 178.0, 157.0, 120.0, 120.0], "value": 131.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 107.0, 112.0, 149.0, 117.0, 151.0, 111.0, 113.0, 132.0, 112.0], "value": 124.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 203.0, 196.0, 200.0, 198.0, 198.0, 197.0, 195.0, 200.0, 194.0], "value": 197.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 62.0, 61.0, 90.0, 65.0, 62.0, 62.0, 63.0, 78.0, 81.0], "value": 70.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 71.0, 84.0, 73.0, 72.0, 72.0, 81.0, 82.0, 102.0, 83.0], "value": 81.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 90.0, 108.0, 135.0, 108.0, 125.0, 136.0, 91.0, 90.0], "value": 106.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 122.0, 154.0, 132.0, 97.0, 98.0, 96.0, 99.0, 98.0, 97.0], "value": 114.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 118.0, 128.0, 83.0, 139.0, 147.0, 96.0, 90.0, 93.0, 93.0], "value": 107.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 66.0, 66.0, 71.0, 71.0, 70.0, 69.0, 71.0, 70.0], "value": 68.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 58.0, 55.0, 61.0, 78.0, 68.0, 57.0, 56.0, 59.0, 77.0], "value": 63.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 103.0, 69.0, 91.0, 100.0, 107.0, 70.0, 98.0, 75.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 151.0, 129.0, 138.0, 130.0, 155.0, 145.0, 169.0, 178.0, 173.0], "value": 150.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 171.0, 165.0, 167.0, 162.0, 162.0, 167.0, 165.0, 167.0, 166.0], "value": 165.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 73.0, 77.0, 75.0, 68.0, 65.0, 67.0, 63.0], "value": 68.9, "unit": "score"}]}]} 12:29:53 INFO - Return code: 0 12:29:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:29:53 ERROR - # TBPL SUCCESS # 12:29:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:29:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:29:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:29:53 INFO - 2017-06-08 12:29:53.985 screenresolution[1918:14769] starting screenresolution argv=screenresolution get 12:29:53 INFO - 2017-06-08 12:29:53.988 screenresolution[1918:14769] Display 0: 1600x1200x32@60 12:29:53 INFO - 2017-06-08 12:29:53.995 screenresolution[1919:14772] starting screenresolution argv=screenresolution list 12:29:53 INFO - Available Modes on Display 0 12:29:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:29:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:29:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:29:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:29:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:29:54 INFO - Intel Iris: 12:29:54 INFO - Chipset Model: Intel Iris 12:29:54 INFO - Type: GPU 12:29:54 INFO - Bus: Built-In 12:29:54 INFO - VRAM (Dynamic, Max): 1536 MB 12:29:54 INFO - Vendor: Intel (0x8086) 12:29:54 INFO - Device ID: 0x0a2e 12:29:54 INFO - Revision ID: 0x0009 12:29:54 INFO - Displays: 12:29:54 INFO - Display: 12:29:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:29:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:29:54 INFO - Display Serial Number: 1600x1200 60 12:29:54 INFO - Main Display: Yes 12:29:54 INFO - Mirror: Off 12:29:54 INFO - Online: Yes 12:29:54 INFO - Rotation: Supported 12:29:54 INFO - Return code: 0 12:29:54 INFO - Running post-action listener: _resource_record_post_action 12:29:54 INFO - [mozharness: 2017-06-08 19:29:54.241686Z] Finished run-tests step (success) 12:29:54 INFO - Running post-run listener: _resource_record_post_run 12:29:54 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 2098176; Write bytes: 509202432; Read time: 19; Write time: 1611 12:29:54 INFO - TinderboxPrint: CPU usage
54.5% 12:29:54 INFO - TinderboxPrint: I/O read bytes / time
2,098,176 / 19 12:29:54 INFO - TinderboxPrint: I/O write bytes / time
509,202,432 / 1,611 12:29:54 INFO - TinderboxPrint: CPU idle
2,180.6 (45.5%) 12:29:54 INFO - TinderboxPrint: CPU system
964.2 (20.1%) 12:29:54 INFO - TinderboxPrint: CPU user
1,645.1 (34.3%) 12:29:54 INFO - TinderboxPrint: Swap in / out
211,521,536 / 0 12:29:54 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201918976; Write bytes: 202286592; Read time: 15865; Write time: 439 12:29:54 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 2098176; Write bytes: 296987136; Read time: 19; Write time: 1141 12:29:54 INFO - Running post-run listener: _upload_blobber_files 12:29:54 INFO - Blob upload gear active. 12:29:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:29:54 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 12:29:54 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'] 12:29:54 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 12:29:55 INFO - (blobuploader) - INFO - Open directory for files ... 12:29:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:29:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:29:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:55 INFO - (blobuploader) - INFO - Done attempting. 12:29:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:29:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:29:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:56 INFO - (blobuploader) - INFO - Done attempting. 12:29:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:29:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:29:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:58 INFO - (blobuploader) - INFO - Done attempting. 12:29:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:29:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:29:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:29:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:29:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:29:59 INFO - (blobuploader) - INFO - Done attempting. 12:29:59 INFO - (blobuploader) - INFO - Iteration through files over. 12:29:59 INFO - Return code: 0 12:29:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:29:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:29:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c0c05a045b4c658880a884e25318d993db586177ee05567cbe600d198eebd2e6c8fa7f2fb670fbfbc543f147765894efbd36c616115e9e904ee5e287678aae5d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/81c4d2533f1f02228f3ffd02c9495b124028f9689d3fe3854dbe5e85b2db463b55708934d616954a40695be53f8244b1be1969ea71e1f6bc84b4150252e98485", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4c04191d8837491e317a42bab1b98361a014c899f2a8786d15b94f6c62c8a5c3f6e229f665476d179fae1d19612d6f216dfa8ea94c4c377d6f15a522e105694f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a3844ed02b7f631bd30f4727bdf099b5b40b90d4ce3726d657ac852e048ddda206aee0398582fc22f635de87897ee3c050d92dd30824da021d75097d01f7da67"} 12:29:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:29:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:29:59 INFO - Contents: 12:29:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c0c05a045b4c658880a884e25318d993db586177ee05567cbe600d198eebd2e6c8fa7f2fb670fbfbc543f147765894efbd36c616115e9e904ee5e287678aae5d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/81c4d2533f1f02228f3ffd02c9495b124028f9689d3fe3854dbe5e85b2db463b55708934d616954a40695be53f8244b1be1969ea71e1f6bc84b4150252e98485", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4c04191d8837491e317a42bab1b98361a014c899f2a8786d15b94f6c62c8a5c3f6e229f665476d179fae1d19612d6f216dfa8ea94c4c377d6f15a522e105694f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a3844ed02b7f631bd30f4727bdf099b5b40b90d4ce3726d657ac852e048ddda206aee0398582fc22f635de87897ee3c050d92dd30824da021d75097d01f7da67"} 12:29:59 INFO - Running post-run listener: copy_logs_to_upload_dir 12:29:59 INFO - Copying logs to upload dir... 12:29:59 INFO - mkdir: /builds/slave/test/build/upload/logs 12:30:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.281063 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2017-06-08 12:30:00.072042) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-08 12:30:00.075263) ========= 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.3q907TDvU3/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.ZEdwKJyzMJ/Listeners TMPDIR=/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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/c0c05a045b4c658880a884e25318d993db586177ee05567cbe600d198eebd2e6c8fa7f2fb670fbfbc543f147765894efbd36c616115e9e904ee5e287678aae5d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/81c4d2533f1f02228f3ffd02c9495b124028f9689d3fe3854dbe5e85b2db463b55708934d616954a40695be53f8244b1be1969ea71e1f6bc84b4150252e98485", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4c04191d8837491e317a42bab1b98361a014c899f2a8786d15b94f6c62c8a5c3f6e229f665476d179fae1d19612d6f216dfa8ea94c4c377d6f15a522e105694f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a3844ed02b7f631bd30f4727bdf099b5b40b90d4ce3726d657ac852e048ddda206aee0398582fc22f635de87897ee3c050d92dd30824da021d75097d01f7da67"} build_url:https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011338 build_url: 'https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/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/c0c05a045b4c658880a884e25318d993db586177ee05567cbe600d198eebd2e6c8fa7f2fb670fbfbc543f147765894efbd36c616115e9e904ee5e287678aae5d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/81c4d2533f1f02228f3ffd02c9495b124028f9689d3fe3854dbe5e85b2db463b55708934d616954a40695be53f8244b1be1969ea71e1f6bc84b4150252e98485", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4c04191d8837491e317a42bab1b98361a014c899f2a8786d15b94f6c62c8a5c3f6e229f665476d179fae1d19612d6f216dfa8ea94c4c377d6f15a522e105694f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a3844ed02b7f631bd30f4727bdf099b5b40b90d4ce3726d657ac852e048ddda206aee0398582fc22f635de87897ee3c050d92dd30824da021d75097d01f7da67"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-08 12:30:00.109581) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 12:30:00.109967) ========= 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.3q907TDvU3/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.ZEdwKJyzMJ/Listeners TMPDIR=/var/folders/g3/9s44hl6d1bl5lknrx5vblzqc00000w/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.004566 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 12:30:00.158703) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-08 12:30:00.159081) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-08 12:30:00.159409) ========= ========= Total master_lag: 0.18 =========