builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0109 starttime: 1496629749.57 results: success (0) buildid: 20170604183818 builduid: ce4c518c2a864e8f87120ac7258c17ad revision: 09f9827c36d63365507fa3999e66c298eed81808 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-04 19:29:09.571600) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-04 19:29:09.572065) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-04 19:29:09.572347) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-04 19:29:09.597706) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 19:29:09.598054) ========= 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.bsHhD7oxgm/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.vWuxy3ohxZ/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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-04 19:29:09-- 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% 50.3M=0s 2017-06-04 19:29:09 (50.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.136954 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 19:29:09.758464) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 19:29:09.758812) ========= 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.bsHhD7oxgm/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.vWuxy3ohxZ/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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.028318 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 19:29:09.808708) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-04 19:29:09.809073) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 09f9827c36d63365507fa3999e66c298eed81808 --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 09f9827c36d63365507fa3999e66c298eed81808 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bsHhD7oxgm/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.vWuxy3ohxZ/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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-04 19:29:09,903 truncating revision to first 12 chars 2017-06-04 19:29:09,904 Setting DEBUG logging. 2017-06-04 19:29:09,904 attempt 1/10 2017-06-04 19:29:09,904 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/09f9827c36d6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-04 19:29:11,446 unpacking tar archive at: mozilla-beta-09f9827c36d6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.833723 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-04 19:29:11.667371) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-04 19:29:11.667713) ========= script_repo_revision: 09f9827c36d63365507fa3999e66c298eed81808 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-04 19:29:11.668125) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-04 19:29:11.668406) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-04 19:29:11.687957) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-06-04 19:29:11.688394) ========= /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.bsHhD7oxgm/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.vWuxy3ohxZ/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 19:29:11 INFO - MultiFileLogger online at 20170604 19:29:11 in /builds/slave/test 19:29:11 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 19:29:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:29:11 INFO - {'append_to_log': False, 19:29:11 INFO - 'base_work_dir': '/builds/slave/test', 19:29:11 INFO - 'blob_upload_branch': 'Mozilla-Beta', 19:29:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:29:11 INFO - 'branch': 'Mozilla-Beta', 19:29:11 INFO - 'buildbot_json_path': 'buildprops.json', 19:29:11 INFO - 'config_files': ('talos/mac_config.py',), 19:29:11 INFO - 'default_actions': ('clobber', 19:29:11 INFO - 'read-buildbot-config', 19:29:11 INFO - 'download-and-extract', 19:29:11 INFO - 'populate-webroot', 19:29:11 INFO - 'create-virtualenv', 19:29:11 INFO - 'install', 19:29:11 INFO - 'run-tests'), 19:29:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:29:11 INFO - 'download_minidump_stackwalk': True, 19:29:11 INFO - 'download_symbols': 'ondemand', 19:29:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:29:11 INFO - 'tooltool.py': '/tools/tooltool.py', 19:29:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:29:11 INFO - '/tools/misc-python/virtualenv.py')}, 19:29:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:29:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:29:11 INFO - 'gecko_profile': False, 19:29:11 INFO - 'gecko_profile_interval': 0, 19:29:11 INFO - 'installer_path': 'installer.exe', 19:29:11 INFO - 'log_level': 'info', 19:29:11 INFO - 'log_name': 'talos', 19:29:11 INFO - 'log_to_console': True, 19:29:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:29:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:29:11 INFO - 'opt_config_files': (), 19:29:11 INFO - 'pip_index': False, 19:29:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:29:11 INFO - 'cmd': ('bash', 19:29:11 INFO - '-c', 19:29:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:29:11 INFO - 'enabled': True, 19:29:11 INFO - 'halt_on_failure': False, 19:29:11 INFO - 'name': 'check_screen_resolution'},), 19:29:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:29:11 INFO - 'cmd': ('bash', 19:29:11 INFO - '-c', 19:29:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:29:11 INFO - 'enabled': True, 19:29:11 INFO - 'halt_on_failure': False, 19:29:11 INFO - 'name': 'check_screen_resolution'},), 19:29:11 INFO - 'run_cmd_checks_enabled': True, 19:29:11 INFO - 'suite': 'dromaeojs-e10s', 19:29:11 INFO - 'system_bits': '32', 19:29:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:29:11 INFO - 'title': 't-yosemite-r7-0109', 19:29:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:29:11 INFO - 'use_talos_json': True, 19:29:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:29:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:29:11 INFO - 'work_dir': 'build'} 19:29:11 INFO - [mozharness: 2017-06-05 02:29:11.896278Z] Running clobber step. 19:29:11 INFO - Running pre-action listener: _resource_record_pre_action 19:29:11 INFO - Running main action method: clobber 19:29:11 INFO - rmtree: /builds/slave/test/build 19:29:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:29:16 INFO - Running post-action listener: _resource_record_post_action 19:29:16 INFO - [mozharness: 2017-06-05 02:29:16.880082Z] Finished clobber step (success) 19:29:16 INFO - [mozharness: 2017-06-05 02:29:16.880205Z] Running read-buildbot-config step. 19:29:16 INFO - Running pre-action listener: _resource_record_pre_action 19:29:16 INFO - Running main action method: read_buildbot_config 19:29:16 INFO - Using buildbot properties: 19:29:16 INFO - { 19:29:16 INFO - "project": "", 19:29:16 INFO - "product": "firefox", 19:29:16 INFO - "script_repo_revision": "production", 19:29:16 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 19:29:16 INFO - "repository": "", 19:29:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 19:29:16 INFO - "buildid": "20170604183818", 19:29:16 INFO - "pgo_build": "False", 19:29:16 INFO - "basedir": "/builds/slave/test", 19:29:16 INFO - "buildnumber": 0, 19:29:16 INFO - "slavename": "t-yosemite-r7-0109", 19:29:16 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 19:29:16 INFO - "platform": "yosemite_r7_devedition", 19:29:16 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 19:29:16 INFO - "branch": "mozilla-beta", 19:29:16 INFO - "repo_path": "releases/mozilla-beta", 19:29:16 INFO - "revision": "09f9827c36d63365507fa3999e66c298eed81808", 19:29:16 INFO - "stage_platform": "macosx64-devedition", 19:29:16 INFO - "builduid": "ce4c518c2a864e8f87120ac7258c17ad", 19:29:16 INFO - "slavebuilddir": "test" 19:29:16 INFO - } 19:29:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 19:29:16 INFO - Running post-action listener: _resource_record_post_action 19:29:16 INFO - [mozharness: 2017-06-05 02:29:16.883741Z] Finished read-buildbot-config step (success) 19:29:16 INFO - [mozharness: 2017-06-05 02:29:16.883881Z] Running download-and-extract step. 19:29:16 INFO - Running pre-action listener: _resource_record_pre_action 19:29:16 INFO - Running main action method: download_and_extract 19:29:16 INFO - mkdir: /builds/slave/test/build/tests 19:29:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:29:16 INFO - trying https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 19:29:16 INFO - Downloading https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/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 19:29:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/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 19:29:18 INFO - Downloaded 1287 bytes. 19:29:18 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 19:29:18 INFO - Using the following test package requirements: 19:29:18 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:29:18 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 19:29:18 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 19:29:18 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:29:18 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 19:29:18 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:29:18 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 19:29:18 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 19:29:18 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:29:18 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 19:29:18 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 19:29:18 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:29:18 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 19:29:18 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:29:18 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 19:29:18 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:29:18 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 19:29:18 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:29:18 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 19:29:18 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 19:29:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 19:29:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 19:29:18 INFO - Fetch https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 19:29:19 INFO - Expected file size: 51966615 19:29:19 INFO - Obtained file size: 51966615 19:29:35 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 19:29:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 19:29:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 19:29:35 INFO - Fetch https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 19:29:37 INFO - Expected file size: 51966615 19:29:37 INFO - Obtained file size: 51966615 19:29:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 19:29:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 19:29:52 INFO - Fetch https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 19:29:53 INFO - Expected file size: 14404461 19:29:53 INFO - Obtained file size: 14404461 19:29:53 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')]} 19:29:53 INFO - trying https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.dmg 19:29:53 INFO - Downloading https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 19:29:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/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 19:29:55 INFO - Downloaded 74895688 bytes. 19:29:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.dmg 19:29:55 INFO - mkdir: /builds/slave/test/properties 19:29:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:29:55 INFO - Writing to file /builds/slave/test/properties/build_url 19:29:55 INFO - Contents: 19:29:55 INFO - build_url:https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.dmg 19:29:56 INFO - Running post-action listener: _resource_record_post_action 19:29:56 INFO - Running post-action listener: set_extra_try_arguments 19:29:56 INFO - [mozharness: 2017-06-05 02:29:56.369662Z] Finished download-and-extract step (success) 19:29:56 INFO - [mozharness: 2017-06-05 02:29:56.369798Z] Running populate-webroot step. 19:29:56 INFO - Running pre-action listener: _resource_record_pre_action 19:29:56 INFO - Running main action method: populate_webroot 19:29:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:29:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:29:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:29:56 INFO - '--tppagecycles', 19:29:56 INFO - '7'], 19:29:56 INFO - 'tests': ['tsvgm']}}, 19:29:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:29:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:29:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:29:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:29:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:29:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:29:56 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:29:56 INFO - 'talos_options': ['--disable-e10s'], 19:29:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:29:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:29:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:29:56 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:29:56 INFO - 'talos_options': ['--disable-e10s'], 19:29:56 INFO - 'tests': ['damp', 'tps']}, 19:29:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:29:56 INFO - 'tests': ['damp', 'tps']}, 19:29:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:29:56 INFO - 'tests': ['dromaeo_dom']}, 19:29:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:29:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:29:56 INFO - 'tests': ['basic_compositor_video']}, 19:29:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:29:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:29:56 INFO - 'tests': ['a11yr', 19:29:56 INFO - 'ts_paint', 19:29:56 INFO - 'tpaint', 19:29:56 INFO - 'sessionrestore', 19:29:56 INFO - 'sessionrestore_no_auto_restore', 19:29:56 INFO - 'tabpaint']}, 19:29:56 INFO - 'other-e10s': {'tests': ['a11yr', 19:29:56 INFO - 'ts_paint', 19:29:56 INFO - 'tpaint', 19:29:56 INFO - 'sessionrestore', 19:29:56 INFO - 'sessionrestore_no_auto_restore', 19:29:56 INFO - 'tabpaint']}, 19:29:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:29:56 INFO - 'ts_paint', 19:29:56 INFO - 'tpaint', 19:29:56 INFO - 'sessionrestore', 19:29:56 INFO - 'sessionrestore_no_auto_restore', 19:29:56 INFO - 'tabpaint']}, 19:29:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:29:56 INFO - 'ts_paint', 19:29:56 INFO - 'tpaint', 19:29:56 INFO - 'sessionrestore', 19:29:56 INFO - 'sessionrestore_no_auto_restore', 19:29:56 INFO - 'tabpaint']}, 19:29:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:29:56 INFO - 'tests': ['a11yr', 19:29:56 INFO - 'ts_paint', 19:29:56 INFO - 'tpaint', 19:29:56 INFO - 'sessionrestore', 19:29:56 INFO - 'sessionrestore_no_auto_restore', 19:29:56 INFO - 'tabpaint']}, 19:29:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:29:56 INFO - 'tests': ['a11yr', 19:29:56 INFO - 'ts_paint', 19:29:56 INFO - 'tpaint', 19:29:56 INFO - 'sessionrestore', 19:29:56 INFO - 'sessionrestore_no_auto_restore', 19:29:56 INFO - 'tabpaint']}, 19:29:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:29:56 INFO - 'tests': ['tsvgx', 19:29:56 INFO - 'tsvgr_opacity', 19:29:56 INFO - 'tart', 19:29:56 INFO - 'tscrollx', 19:29:56 INFO - 'cart', 19:29:56 INFO - 'tsvg_static']}, 19:29:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:29:56 INFO - 'tsvgr_opacity', 19:29:56 INFO - 'tart', 19:29:56 INFO - 'tscrollx', 19:29:56 INFO - 'cart', 19:29:56 INFO - 'tsvg_static']}, 19:29:56 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:29:56 INFO - 'talos_options': ['--disable-e10s'], 19:29:56 INFO - 'tests': ['tp5o']}, 19:29:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:29:56 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:29:56 INFO - 'talos_options': ['--disable-e10s', 19:29:56 INFO - '--xperf_path', 19:29:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:29:56 INFO - 'tests': ['tp5n']}, 19:29:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:29:56 INFO - 'talos_options': ['--xperf_path', 19:29:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:29:56 INFO - 'tests': ['tp5n']}}, 19:29:56 INFO - 'talos.zip': {'path': '', 19:29:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:29:56 INFO - Running post-action listener: _resource_record_post_action 19:29:56 INFO - [mozharness: 2017-06-05 02:29:56.388803Z] Finished populate-webroot step (success) 19:29:56 INFO - [mozharness: 2017-06-05 02:29:56.388936Z] Running create-virtualenv step. 19:29:56 INFO - Running pre-action listener: _resource_record_pre_action 19:29:56 INFO - Running main action method: create_virtualenv 19:29:56 INFO - Creating virtualenv /builds/slave/test/build/venv 19:29:56 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 19:29:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:29:56 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 19:29:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:29:56 INFO - Using real prefix '/tools/python27' 19:29:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:29:57 INFO - Installing distribute.............................................................................................................................................................................................done. 19:30:00 INFO - Installing pip.................done. 19:30:00 INFO - Return code: 0 19:30:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:30:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:30:00 INFO - Reading from file tmpfile_stdout 19:30:00 INFO - Output received: 19:30:00 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) 19:30:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:30:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:30:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d677b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d67a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d01ca8ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d455d30>, '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 0x10d37cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d44abe8>, '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.bsHhD7oxgm/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.vWuxy3ohxZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 19:30:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:30:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:30:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bsHhD7oxgm/Render', 19:30:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:30:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:30:00 INFO - 'HOME': '/Users/cltbld', 19:30:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:30:00 INFO - 'LOGNAME': 'cltbld', 19:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:30:00 INFO - 'MOZ_NO_REMOTE': '1', 19:30:00 INFO - 'NO_EM_RESTART': '1', 19:30:00 INFO - 'PAGER': '/bin/cat', 19:30:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:30:00 INFO - 'PWD': '/builds/slave/test', 19:30:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:30:00 INFO - 'SHELL': '/bin/bash', 19:30:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vWuxy3ohxZ/Listeners', 19:30:00 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 19:30:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:30:00 INFO - 'USER': 'cltbld', 19:30:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:30:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:30:00 INFO - 'XPC_FLAGS': '0x0', 19:30:00 INFO - 'XPC_SERVICE_NAME': '0', 19:30:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:30:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:30:00 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 19:30:00 INFO - Cleaning up... 19:30:00 INFO - Return code: 0 19:30:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:30:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:30:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:30:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d677b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d67a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d01ca8ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d455d30>, '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 0x10d37cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d44abe8>, '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.bsHhD7oxgm/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.vWuxy3ohxZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 19:30:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:30:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:30:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bsHhD7oxgm/Render', 19:30:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:30:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:30:00 INFO - 'HOME': '/Users/cltbld', 19:30:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:30:00 INFO - 'LOGNAME': 'cltbld', 19:30:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:30:00 INFO - 'MOZ_NO_REMOTE': '1', 19:30:00 INFO - 'NO_EM_RESTART': '1', 19:30:00 INFO - 'PAGER': '/bin/cat', 19:30:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:30:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:30:00 INFO - 'PWD': '/builds/slave/test', 19:30:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:30:00 INFO - 'SHELL': '/bin/bash', 19:30:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vWuxy3ohxZ/Listeners', 19:30:00 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 19:30:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:30:00 INFO - 'USER': 'cltbld', 19:30:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:30:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:30:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:30:00 INFO - 'XPC_FLAGS': '0x0', 19:30:00 INFO - 'XPC_SERVICE_NAME': '0', 19:30:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:30:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:30:00 INFO - Downloading/unpacking psutil>=3.1.1 19:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:30:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:30:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:30:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:30:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:30:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:30:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:30:02 INFO - Installing collected packages: psutil 19:30:02 INFO - Running setup.py install for psutil 19:30:02 INFO - building 'psutil._psutil_osx' extension 19:30:02 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 19:30:03 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 19:30:03 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 19:30:03 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 19:30:03 INFO - building 'psutil._psutil_posix' extension 19:30:03 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 19:30:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:30:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:30:03 INFO - ^ 19:30:03 INFO - 1 warning generated. 19:30:03 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 19:30:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:30:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:30:03 INFO - Successfully installed psutil 19:30:03 INFO - Cleaning up... 19:30:04 INFO - Return code: 0 19:30:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:30:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:30:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:30:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:30:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d677b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d67a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d01ca8ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d455d30>, '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 0x10d37cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d44abe8>, '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.bsHhD7oxgm/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.vWuxy3ohxZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 19:30:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:30:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:30:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bsHhD7oxgm/Render', 19:30:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:30:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:30:04 INFO - 'HOME': '/Users/cltbld', 19:30:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:30:04 INFO - 'LOGNAME': 'cltbld', 19:30:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:30:04 INFO - 'MOZ_NO_REMOTE': '1', 19:30:04 INFO - 'NO_EM_RESTART': '1', 19:30:04 INFO - 'PAGER': '/bin/cat', 19:30:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:30:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:30:04 INFO - 'PWD': '/builds/slave/test', 19:30:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:30:04 INFO - 'SHELL': '/bin/bash', 19:30:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vWuxy3ohxZ/Listeners', 19:30:04 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 19:30:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:30:04 INFO - 'USER': 'cltbld', 19:30:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:30:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:30:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:30:04 INFO - 'XPC_FLAGS': '0x0', 19:30:04 INFO - 'XPC_SERVICE_NAME': '0', 19:30:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:30:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:30:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:30:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:30:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:30:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:30:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:30:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:30:05 INFO - Installing collected packages: mozsystemmonitor 19:30:05 INFO - Running setup.py install for mozsystemmonitor 19:30:05 INFO - Successfully installed mozsystemmonitor 19:30:05 INFO - Cleaning up... 19:30:05 INFO - Return code: 0 19:30:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:30:05 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')]} 19:30:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:30:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:30:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:30:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d677b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d67a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d01ca8ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d455d30>, '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 0x10d37cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d44abe8>, '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.bsHhD7oxgm/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.vWuxy3ohxZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 19:30:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:30:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:30:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bsHhD7oxgm/Render', 19:30:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:30:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:30:06 INFO - 'HOME': '/Users/cltbld', 19:30:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:30:06 INFO - 'LOGNAME': 'cltbld', 19:30:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:30:06 INFO - 'MOZ_NO_REMOTE': '1', 19:30:06 INFO - 'NO_EM_RESTART': '1', 19:30:06 INFO - 'PAGER': '/bin/cat', 19:30:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:30:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:30:06 INFO - 'PWD': '/builds/slave/test', 19:30:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:30:06 INFO - 'SHELL': '/bin/bash', 19:30:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vWuxy3ohxZ/Listeners', 19:30:06 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 19:30:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:30:06 INFO - 'USER': 'cltbld', 19:30:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:30:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:30:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:30:06 INFO - 'XPC_FLAGS': '0x0', 19:30:06 INFO - 'XPC_SERVICE_NAME': '0', 19:30:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:30:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:30:07 INFO - Downloading/unpacking jsonschema==2.5.1 19:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:30:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:30:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:30:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:30:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:30:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:30:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:30:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:30:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:30:09 INFO - Downloading functools32-3.2.3-2.tar.gz 19:30:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:30:09 INFO - warning: no files found matching '*.txt' 19:30:09 INFO - no previously-included directories found matching 'build' 19:30:09 INFO - no previously-included directories found matching 'dist' 19:30:09 INFO - no previously-included directories found matching '.git*' 19:30:09 INFO - Installing collected packages: jsonschema, functools32 19:30:09 INFO - Running setup.py install for functools32 19:30:09 INFO - warning: no files found matching '*.txt' 19:30:09 INFO - no previously-included directories found matching 'build' 19:30:09 INFO - no previously-included directories found matching 'dist' 19:30:09 INFO - no previously-included directories found matching '.git*' 19:30:09 INFO - Successfully installed jsonschema functools32 19:30:09 INFO - Cleaning up... 19:30:09 INFO - Return code: 0 19:30:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:30:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:30:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:30:09 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 0x10d677b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d67a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d01ca8ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d455d30>, '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 0x10d37cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d44abe8>, '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.bsHhD7oxgm/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.vWuxy3ohxZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 19:30:09 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 19:30:09 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 19:30:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bsHhD7oxgm/Render', 19:30:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:30:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:30:09 INFO - 'HOME': '/Users/cltbld', 19:30:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:30:09 INFO - 'LOGNAME': 'cltbld', 19:30:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:30:09 INFO - 'MOZ_NO_REMOTE': '1', 19:30:09 INFO - 'NO_EM_RESTART': '1', 19:30:09 INFO - 'PAGER': '/bin/cat', 19:30:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:30:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:30:09 INFO - 'PWD': '/builds/slave/test', 19:30:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:30:09 INFO - 'SHELL': '/bin/bash', 19:30:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vWuxy3ohxZ/Listeners', 19:30:09 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 19:30:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:30:09 INFO - 'USER': 'cltbld', 19:30:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:30:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:30:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:30:09 INFO - 'XPC_FLAGS': '0x0', 19:30:09 INFO - 'XPC_SERVICE_NAME': '0', 19:30:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:30:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:30:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:30:10 INFO - Cleaning up... 19:30:10 INFO - Return code: 0 19:30:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:30:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:30:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:30:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:30:10 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 0x10d677b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d67a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d01ca8ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d455d30>, '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 0x10d37cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d44abe8>, '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.bsHhD7oxgm/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.vWuxy3ohxZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 19:30:10 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 19:30:10 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 19:30:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bsHhD7oxgm/Render', 19:30:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:30:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:30:10 INFO - 'HOME': '/Users/cltbld', 19:30:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:30:10 INFO - 'LOGNAME': 'cltbld', 19:30:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:30:10 INFO - 'MOZ_NO_REMOTE': '1', 19:30:10 INFO - 'NO_EM_RESTART': '1', 19:30:10 INFO - 'PAGER': '/bin/cat', 19:30:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:30:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:30:10 INFO - 'PWD': '/builds/slave/test', 19:30:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:30:10 INFO - 'SHELL': '/bin/bash', 19:30:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vWuxy3ohxZ/Listeners', 19:30:10 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 19:30:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:30:10 INFO - 'USER': 'cltbld', 19:30:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:30:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:30:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:30:10 INFO - 'XPC_FLAGS': '0x0', 19:30:10 INFO - 'XPC_SERVICE_NAME': '0', 19:30:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:30:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:30:10 INFO - Downloading/unpacking blobuploader==1.2.4 19:30:10 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 19:30:10 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 19:30:10 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 19:30:10 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 19:30:11 INFO - Downloading blobuploader-1.2.4.tar.gz 19:30:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:30:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:30:11 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 19:30:11 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 19:30:11 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 19:30:11 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 19:30:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:30:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:30:12 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 19:30:12 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 19:30:12 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 19:30:12 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 19:30:12 INFO - Downloading docopt-0.6.1.tar.gz 19:30:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:30:12 INFO - Installing collected packages: blobuploader, requests, docopt 19:30:12 INFO - Running setup.py install for blobuploader 19:30:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:30:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:30:12 INFO - Running setup.py install for requests 19:30:13 INFO - Running setup.py install for docopt 19:30:13 INFO - Successfully installed blobuploader requests docopt 19:30:13 INFO - Cleaning up... 19:30:13 INFO - Return code: 0 19:30:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:30:13 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')]} 19:30:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:30:13 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')]} 19:30:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:30:13 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 0x10d677b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d67a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d01ca8ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d455d30>, '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 0x10d37cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d44abe8>, '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.bsHhD7oxgm/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.vWuxy3ohxZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 19:30:13 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 19:30:13 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 19:30:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bsHhD7oxgm/Render', 19:30:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:30:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:30:13 INFO - 'HOME': '/Users/cltbld', 19:30:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:30:13 INFO - 'LOGNAME': 'cltbld', 19:30:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:30:13 INFO - 'MOZ_NO_REMOTE': '1', 19:30:13 INFO - 'NO_EM_RESTART': '1', 19:30:13 INFO - 'PAGER': '/bin/cat', 19:30:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:30:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:30:13 INFO - 'PWD': '/builds/slave/test', 19:30:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:30:13 INFO - 'SHELL': '/bin/bash', 19:30:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vWuxy3ohxZ/Listeners', 19:30:13 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 19:30:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:30:13 INFO - 'USER': 'cltbld', 19:30:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:30:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:30:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:30:13 INFO - 'XPC_FLAGS': '0x0', 19:30:13 INFO - 'XPC_SERVICE_NAME': '0', 19:30:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:30:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:30:13 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-nINK5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:30:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:30:13 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-UsMEnU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:30:14 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-EAJ8jq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:30:14 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-wkmfr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:30:14 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-Jy9SHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:30:14 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-LqT0Tk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:30:14 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-bwYABH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:30:14 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-nY8Lgb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:30:14 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-itHY9x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:30:14 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-q8CaQh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:30:14 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-JtBLhZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:30:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:30:14 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-SArjbb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:30:15 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-a1eQIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:30:15 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-yhD1is-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:30:15 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-v_npA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:30:15 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-_c8Nsj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:30:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:30:15 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-DsEstp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:30:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:30:15 INFO - Running setup.py install for manifestparser 19:30:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:30:15 INFO - Running setup.py install for mozcrash 19:30:15 INFO - Running setup.py install for mozdebug 19:30:16 INFO - Running setup.py install for mozdevice 19:30:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:30:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:30:16 INFO - Running setup.py install for mozfile 19:30:16 INFO - Running setup.py install for mozhttpd 19:30:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:30:16 INFO - Running setup.py install for mozinfo 19:30:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:30:16 INFO - Running setup.py install for mozInstall 19:30:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:30:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:30:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:30:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:30:16 INFO - Running setup.py install for mozleak 19:30:16 INFO - Running setup.py install for mozlog 19:30:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:30:17 INFO - Running setup.py install for moznetwork 19:30:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:30:17 INFO - Running setup.py install for mozprocess 19:30:17 INFO - Running setup.py install for mozprofile 19:30:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:30:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:30:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:30:17 INFO - Running setup.py install for mozrunner 19:30:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:30:17 INFO - Running setup.py install for mozscreenshot 19:30:17 INFO - Running setup.py install for moztest 19:30:18 INFO - Running setup.py install for mozversion 19:30:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:30:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:30:18 INFO - Cleaning up... 19:30:18 INFO - Return code: 0 19:30:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:30:18 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')]} 19:30:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:30:18 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')]} 19:30:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:30:18 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 0x10d677b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d67a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d01ca8ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d455d30>, '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 0x10d37cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d44abe8>, '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.bsHhD7oxgm/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.vWuxy3ohxZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 19:30:18 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 19:30:18 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 19:30:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bsHhD7oxgm/Render', 19:30:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:30:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:30:18 INFO - 'HOME': '/Users/cltbld', 19:30:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:30:18 INFO - 'LOGNAME': 'cltbld', 19:30:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:30:18 INFO - 'MOZ_NO_REMOTE': '1', 19:30:18 INFO - 'NO_EM_RESTART': '1', 19:30:18 INFO - 'PAGER': '/bin/cat', 19:30:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:30:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:30:18 INFO - 'PWD': '/builds/slave/test', 19:30:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:30:18 INFO - 'SHELL': '/bin/bash', 19:30:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vWuxy3ohxZ/Listeners', 19:30:18 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 19:30:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:30:18 INFO - 'USER': 'cltbld', 19:30:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:30:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:30:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:30:18 INFO - 'XPC_FLAGS': '0x0', 19:30:18 INFO - 'XPC_SERVICE_NAME': '0', 19:30:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:30:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:30:18 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-oEYh6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:30:18 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)) 19:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:30:18 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-7mCArv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:30:18 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)) 19:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:30:18 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-gAftOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:30:18 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)) 19:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:30:18 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-_l_FqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:30:18 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)) 19:30:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:30:18 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-ssCzuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:30:19 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)) 19:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:30:19 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-0TOfzo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:30:19 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)) 19:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:30:19 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-eOPfne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:30:19 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)) 19:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:30:19 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-4aOPQP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:30:19 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)) 19:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:30:19 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-_Lvl_j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:30:19 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)) 19:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:30:19 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-IE7wXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:30:19 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)) 19:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:30:19 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-q5bzea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:30:19 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)) 19:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:30:19 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-l2sQMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:30:19 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)) 19:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:30:19 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-RgdDIS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:30:19 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)) 19:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:30:19 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-BF3DqM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:30:19 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)) 19:30:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:30:19 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-myGpIr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:30:20 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)) 19:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:30:20 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-6eEoMf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:30:20 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)) 19:30:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:30:20 INFO - Running setup.py (path:/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/pip-8GhZsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:30:20 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)) 19:30:20 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)) 19:30:20 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)) 19:30:20 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)) 19:30:20 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)) 19:30:20 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)) 19:30:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:30:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:30:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:30:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:30:21 INFO - Downloading blessings-1.6.tar.gz 19:30:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:30:21 INFO - Installing collected packages: blessings 19:30:21 INFO - Running setup.py install for blessings 19:30:21 INFO - Successfully installed blessings 19:30:21 INFO - Cleaning up... 19:30:22 INFO - Return code: 0 19:30:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:30:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:30:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:30:22 INFO - Reading from file tmpfile_stdout 19:30:22 INFO - Current package versions: 19:30:22 INFO - blessings == 1.6 19:30:22 INFO - blobuploader == 1.2.4 19:30:22 INFO - docopt == 0.6.1 19:30:22 INFO - functools32 == 3.2.3-2 19:30:22 INFO - jsonschema == 2.5.1 19:30:22 INFO - manifestparser == 1.1 19:30:22 INFO - mozInstall == 1.12 19:30:22 INFO - mozcrash == 1.0 19:30:22 INFO - mozdebug == 0.1 19:30:22 INFO - mozdevice == 0.49 19:30:22 INFO - mozfile == 1.2 19:30:22 INFO - mozhttpd == 0.7 19:30:22 INFO - mozinfo == 0.9 19:30:22 INFO - mozleak == 0.1 19:30:22 INFO - mozlog == 3.4 19:30:22 INFO - moznetwork == 0.27 19:30:22 INFO - mozprocess == 0.25 19:30:22 INFO - mozprofile == 0.28 19:30:22 INFO - mozrunner == 6.13 19:30:22 INFO - mozscreenshot == 0.1 19:30:22 INFO - mozsystemmonitor == 0.3 19:30:22 INFO - moztest == 0.8 19:30:22 INFO - mozversion == 1.4 19:30:22 INFO - psutil == 3.1.1 19:30:22 INFO - requests == 1.2.3 19:30:22 INFO - wsgiref == 0.1.2 19:30:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:30:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:30:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:30:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:30:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:30:22 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 0x10d677b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d67a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d01ca8ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d455d30>, '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 0x10d37cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d44abe8>, '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.bsHhD7oxgm/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.vWuxy3ohxZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 19:30:22 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 19:30:22 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 19:30:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bsHhD7oxgm/Render', 19:30:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:30:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:30:22 INFO - 'HOME': '/Users/cltbld', 19:30:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:30:22 INFO - 'LOGNAME': 'cltbld', 19:30:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:30:22 INFO - 'MOZ_NO_REMOTE': '1', 19:30:22 INFO - 'NO_EM_RESTART': '1', 19:30:22 INFO - 'PAGER': '/bin/cat', 19:30:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:30:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:30:22 INFO - 'PWD': '/builds/slave/test', 19:30:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:30:22 INFO - 'SHELL': '/bin/bash', 19:30:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vWuxy3ohxZ/Listeners', 19:30:22 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 19:30:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:30:22 INFO - 'USER': 'cltbld', 19:30:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:30:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:30:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:30:22 INFO - 'XPC_FLAGS': '0x0', 19:30:22 INFO - 'XPC_SERVICE_NAME': '0', 19:30:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:30:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:30:22 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)) 19:30:22 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)) 19:30:22 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)) 19:30:22 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)) 19:30:22 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)) 19:30:22 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)) 19:30:22 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)) 19:30:22 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)) 19:30:22 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)) 19:30:22 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)) 19:30:22 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)) 19:30:22 INFO - Cleaning up... 19:30:22 INFO - Return code: 0 19:30:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:30:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:30:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:30:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:30:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:30:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d677b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d67a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d01ca8ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d455d30>, '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 0x10d37cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d44abe8>, '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.bsHhD7oxgm/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.vWuxy3ohxZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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 19:30:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:30:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:30:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bsHhD7oxgm/Render', 19:30:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:30:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:30:22 INFO - 'HOME': '/Users/cltbld', 19:30:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:30:22 INFO - 'LOGNAME': 'cltbld', 19:30:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:30:22 INFO - 'MOZ_NO_REMOTE': '1', 19:30:22 INFO - 'NO_EM_RESTART': '1', 19:30:22 INFO - 'PAGER': '/bin/cat', 19:30:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:30:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:30:22 INFO - 'PWD': '/builds/slave/test', 19:30:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:30:22 INFO - 'SHELL': '/bin/bash', 19:30:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vWuxy3ohxZ/Listeners', 19:30:22 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 19:30:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:30:22 INFO - 'USER': 'cltbld', 19:30:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:30:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:30:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:30:22 INFO - 'XPC_FLAGS': '0x0', 19:30:22 INFO - 'XPC_SERVICE_NAME': '0', 19:30:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:30:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:30:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:30:22 INFO - Cleaning up... 19:30:22 INFO - Return code: 0 19:30:22 INFO - Running post-action listener: _resource_record_post_action 19:30:22 INFO - Running post-action listener: _start_resource_monitoring 19:30:22 INFO - Starting resource monitoring. 19:30:22 INFO - [mozharness: 2017-06-05 02:30:22.943504Z] Finished create-virtualenv step (success) 19:30:22 INFO - [mozharness: 2017-06-05 02:30:22.944484Z] Running install step. 19:30:22 INFO - Running pre-action listener: _resource_record_pre_action 19:30:22 INFO - Running main action method: install 19:30:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:30:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:30:23 INFO - Reading from file tmpfile_stdout 19:30:23 INFO - Detecting whether we're running mozinstall >=1.0... 19:30:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:30:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:30:23 INFO - Reading from file tmpfile_stdout 19:30:23 INFO - Output received: 19:30:23 INFO - Usage: mozinstall [options] installer 19:30:23 INFO - Options: 19:30:23 INFO - -h, --help show this help message and exit 19:30:23 INFO - -d DEST, --destination=DEST 19:30:23 INFO - Directory to install application into. [default: 19:30:23 INFO - "/builds/slave/test"] 19:30:23 INFO - --app=APP Application being installed. [default: firefox] 19:30:23 INFO - mkdir: /builds/slave/test/build/application 19:30:23 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'] 19:30:23 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 19:30:43 INFO - Reading from file tmpfile_stdout 19:30:43 INFO - Output received: 19:30:43 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 19:30:43 INFO - Running post-action listener: _resource_record_post_action 19:30:43 INFO - [mozharness: 2017-06-05 02:30:43.724074Z] Finished install step (success) 19:30:43 INFO - [mozharness: 2017-06-05 02:30:43.724243Z] Running run-tests step. 19:30:43 INFO - Running pre-action listener: _resource_record_pre_action 19:30:43 INFO - Running main action method: run_tests 19:30:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:30:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:30:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:30:43 INFO - 2017-06-04 19:30:43.738 screenresolution[1632:7603] starting screenresolution argv=screenresolution get 19:30:43 INFO - 2017-06-04 19:30:43.741 screenresolution[1632:7603] Display 0: 1600x1200x32@60 19:30:43 INFO - 2017-06-04 19:30:43.747 screenresolution[1633:7606] starting screenresolution argv=screenresolution list 19:30:43 INFO - Available Modes on Display 0 19:30:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:30:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:30:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:30:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:30:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:30:43 INFO - Intel Iris: 19:30:43 INFO - Chipset Model: Intel Iris 19:30:43 INFO - Type: GPU 19:30:43 INFO - Bus: Built-In 19:30:43 INFO - VRAM (Dynamic, Max): 1536 MB 19:30:43 INFO - Vendor: Intel (0x8086) 19:30:43 INFO - Device ID: 0x0a2e 19:30:43 INFO - Revision ID: 0x0009 19:30:43 INFO - Displays: 19:30:43 INFO - Display: 19:30:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:30:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:30:43 INFO - Display Serial Number: 1600x1200 60 19:30:43 INFO - Main Display: Yes 19:30:43 INFO - Mirror: Off 19:30:43 INFO - Online: Yes 19:30:43 INFO - Rotation: Supported 19:30:43 INFO - Return code: 0 19:30:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:30:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:30:43 INFO - Python 2.7.3 19:30:43 INFO - Return code: 0 19:30:43 INFO - grabbing minidump binary from tooltool 19:30:43 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')]} 19:30:43 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 0x10d455d30>, '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 0x10d37cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d44abe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:30:43 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 19:30:43 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 19:30:43 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 19:30:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:30:44 INFO - Return code: 0 19:30:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:30:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:30:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:30:44 INFO - ENV: RUST_BACKTRACE is now 1 19:30:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:30:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:30:44 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/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0109', '--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 19:30:44 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/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0109 --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 19:30:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bsHhD7oxgm/Render', 19:30:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:30:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:30:44 INFO - 'HOME': '/Users/cltbld', 19:30:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:30:44 INFO - 'LOGNAME': 'cltbld', 19:30:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:30:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:30:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:30:44 INFO - 'MOZ_NO_REMOTE': '1', 19:30:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:30:44 INFO - 'NO_EM_RESTART': '1', 19:30:44 INFO - 'PAGER': '/bin/cat', 19:30:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:30:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:30:44 INFO - 'PWD': '/builds/slave/test', 19:30:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:30:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:30:44 INFO - 'RUST_BACKTRACE': '1', 19:30:44 INFO - 'SHELL': '/bin/bash', 19:30:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vWuxy3ohxZ/Listeners', 19:30:44 INFO - 'TMPDIR': '/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/', 19:30:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:30:44 INFO - 'USER': 'cltbld', 19:30:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:30:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:30:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:30:44 INFO - 'XPC_FLAGS': '0x0', 19:30:44 INFO - 'XPC_SERVICE_NAME': '0', 19:30:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:30:44 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/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0109', '--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 19:30:44 INFO - mozversion application_buildid: 20170604183818 19:30:44 INFO - mozversion application_changeset: 09f9827c36d63365507fa3999e66c298eed81808 19:30:44 INFO - mozversion application_display_name: FirefoxDeveloperEdition 19:30:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:30:44 INFO - mozversion application_name: Firefox 19:30:44 INFO - mozversion application_remotingname: firefox-dev 19:30:44 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 19:30:44 INFO - mozversion application_vendor: Mozilla 19:30:44 INFO - mozversion application_version: 54.0 19:30:44 INFO - mozversion platform_buildid: 20170604183818 19:30:44 INFO - mozversion platform_changeset: 09f9827c36d63365507fa3999e66c298eed81808 19:30:44 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 19:30:44 INFO - mozversion platform_version: 54.0 19:30:44 INFO - using testdate: 1496629844 19:30:44 INFO - actual date: 1496629844 19:30:44 INFO - starting webserver on 'localhost:49232' 19:30:44 INFO - SUITE-START | Running 2 tests 19:30:44 INFO - TEST-START | dromaeo_css 19:30:44 INFO - operating with platform_type : mac_ 19:30:44 INFO - Initialising browser for dromaeo_css test... 19:30:44 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmpec3jbu/profile http://localhost:49232/getInfo.html) 19:30:47 INFO - PROCESS | 1643 | 2017-06-04 19:30:47.307 plugin-container[1646:7792] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 19:30:47 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:30:47 INFO - PROCESS | 1643 | 2017-06-04 19:30:47.308 plugin-container[1646:7792] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 19:30:47 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:30:47 INFO - PROCESS | 1643 | 2017-06-04 19:30:47.308 plugin-container[1646:7792] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:30:47 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 19:30:47 INFO - PROCESS | 1643 | colorDepth:24 19:30:47 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 19:30:47 INFO - PROCESS | 1643 | __metrics 19:30:47 ERROR - PROCESS | 1643 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3381: TypeError: this.tabs is undefined 19:30:47 INFO - TEST-INFO | 1643: exit 0 19:30:47 INFO - Browser initialized. 19:30:47 INFO - Running cycle 1/1 for dromaeo_css test... 19:30:47 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmpec3jbu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:30:50 INFO - PROCESS | 1647 | 2017-06-04 19:30:50.579 plugin-container[1648:7920] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 19:30:50 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:30:50 INFO - PROCESS | 1647 | 2017-06-04 19:30:50.579 plugin-container[1648:7920] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 19:30:50 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:30:50 INFO - PROCESS | 1647 | 2017-06-04 19:30:50.579 plugin-container[1648:7920] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:31:00 INFO - PROCESS | 1647 | RSS: Main: 202883072 19:31:00 INFO - PROCESS | 1647 | 19:34:04 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49232/tests/dromaeo/cssquery-ext.html) 19:34:06 INFO - PROCESS | 1647 | RSS: Main: 216936448 19:34:06 INFO - PROCESS | 1647 | 19:37:08 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-ext.html (next: http://localhost:49232/tests/dromaeo/cssquery-jquery.html) 19:37:08 INFO - PROCESS | 1647 | RSS: Main: 230146048 19:37:08 INFO - PROCESS | 1647 | 19:40:10 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49232/tests/dromaeo/cssquery-mootools.html) 19:40:11 INFO - PROCESS | 1647 | RSS: Main: 233312256 19:40:11 INFO - PROCESS | 1647 | 19:43:13 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49232/tests/dromaeo/cssquery-prototype.html) 19:43:13 INFO - PROCESS | 1647 | RSS: Main: 236244992 19:43:13 INFO - PROCESS | 1647 | 19:46:16 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49232/tests/dromaeo/cssquery-yui.html) 19:46:17 INFO - PROCESS | 1647 | RSS: Main: 238276608 19:46:17 INFO - PROCESS | 1647 | 19:49:20 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49232/tests/dromaeo/cssquery-yui.html (next: http://localhost:49232/tests/dromaeo/cssquery-dojo.html) 19:49:20 INFO - PROCESS | 1647 | RSS: Main: 236138496 19:49:20 INFO - PROCESS | 1647 | 19:49:20 INFO - PROCESS | 1647 | __start_tp_report 19:49:20 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 19:49:20 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 19:49:20 INFO - PROCESS | 1647 | |i|pagename|runs| 19:49:20 INFO - PROCESS | 1647 | |0;dojo.html;3250.6261779895317;3532.929341413171;3543.8228088595565;3585.551806024247;3591.748577599569;5506.752196151173;5979.431953964372;6048.969755151229;6107.206063211776;6121.785737499188;6025.397460253972;6064.272287325516;6084.965555855504;6095.481884039851;6109.908351374733;5388.7305634718105;5396.541293989996;5461.754221060042;5532.640378375413;5540.529055030327;5500;5508.091164957777;5521.254630624874;5527.834164975057;5571.275734154554;5300.284461597674;5319.574434045287;5377.516023557879;5491.862703432406;5523.392426833045;5405.945940540583;5419.943111093338;5474.91787623182;5485.177223416479;5494.313210848644;5322.840314790563;5452.700101494416;5466.426025267329;5474.698891560963;5517.779288828442;6084.361142080106;6093.451589356979;6116.113163591299;6118.9694051529905;6160.414760597736;6125.540694081744;6236.158118653969;6247.531435142337;6252.06219067139;6303.55875088744;6435.099086127946;6463.967680161616;6479.83800404989;6487.740490380379;6489.383508566679;6153.081016561361;6177.598456100339;6179.16581261533;6190.845228869314;6195.628262304322;6299.905501417483;6345.841353966188;6348.904766428507;6547.083408322838;6650.767223147263;6120.479759220427;6261.68691565429;6266.811995640138;6294.559380843389;6318.620882746958;5461.481159289861;6442.323556026549;6462;6462.967685161544;6464.127342808754;6240.126382306481;6276.089966954766;6362.554621176566;6369.840753981188;6387.744490220339;6272.491003598597;6318.589291696025;6336.714847831859;6364.554481186273;6395.264544577433;4616.215243408561;4809.78355973982;4893.853184404474;4961.677490963076;4969.27945447908;4452.1763473757455;4456;4474.082813023322;4513.616342610849;4519.5480451954545;5861.531077513789;5995.940040599626;5999.580029397987;6004.819855404345;6018.2176317079375;6206.813795586139;6255.12428260044;6290.779822706275;6329.208848893888;6480.902786458207;5993.790217342372;5996.2504686914135;6000.3999600040515;6001.06983417571;6027.547933904974;5601.551875849922;5610.270664813548;5615.943840561624;5616.9157462638095;5630.634008789416;6331.556791024768;6392.776252831129;6462.159919210472;6481.546291759531;6670.633115178614;5307.832276899076;6394.7442102317245;6399;6411.198600174978;6415.294317624965;6485.513586480938;6523.510736694672;6624.735010599522;6625.304343044104;6629.138212032405;6224;6266.342034086355;6277.780277690259;6332;6344.68276586178;6409.134766806422;6454.580452270775;6466.418022378009;6477.870442591027;6479.352064793483;5271.973640131775;5322.866928326823;6129.570930035033;6304.243490781136;6320.241571011508;6196.628202307876;6216.036514340284;6257.5932564383165;6296.811095667137;6324.810255692337;6127.372786728296;6131.988221943329;6169.50643948474;6175.722092505938;6202.41077097675;6314;6324.7153878076515;6332.905006424815;6335.429811317003;6347.428731414195;6117.796956032597;6120.969395152996;6122.632642041492;6130.386961303834;6208.161898143873 19:49:20 INFO - PROCESS | 1647 | |1;ext.html;751.8796992481205;764.5565571968259;804.4971892567146;828.9544075075872;975.6633961283354;37915.241695166114;38732;39357.228924698386;39987;40003.59996400035;31702.68297317026;38699.41950870739;38867.611323886755;38897;38912.61087389125;8088.67645294189;8199.95900020499;10804.945975270151;11096.778064438706;11280.661580152608;8609.827803443928;10051.849222261671;10867.565297388135;11020.944895275512;11117.499712512956;10995.230333876632;10996.450177491133;11016.394098324588;11022.669319920415;11022.779544409106;10940.070094042016;10998.890011099907;11022.724431889266;11031.172662050338;11055.778884422267;10763.730906727316;10782.892171078267;10823.350598964087;10828.620998265023;10831.729206769895;33305.83347083273;33445.33109337824;33491.49762753536;33628.83185584081;33782.662173378194;3815.313243616148;3844.7116466265143;3888.572257051722;3908.1597456546697;3908.218356328746;24961.50076998468;25021.374465638506;25127.87436062808;26613.467730645472;27294;3114.4861097918815;3165.335279591289;3173.079806856009;3180.6342270638943;3287.1289108386295;372296;375376;421349;429056;441783.79108104575;43366.349504757454;43538;43663.563364366586;43691.56308436939;43739.90650233706;45299.77350113228;45374;45422.77288613536;45528.54471455309;45534.772326138154;13590.184588924696;13664.043516953718;13671.794923076162;13680.863191368158;13688.452461901412;12108.455119519644;12112.576059837864;12115.273083614835;12127.575534856238;12148.817767733492;11673.649790506299;11714.531418743154;11734.591848978178;11783.76270491586;11793.528258869721;5873.20711703923;5874.295084589877;5924;5928.110783382441;5944.346121926585;1144.547903578082;1164.574930150489;1170.836082948388;1187.2460987273143;1242.881926217008;26397;27029.459410812065;27523.724762752518;27582.44835103287;27626.861865690542;1076.6178006807627;1176.9352685602203;1261.1298204239047;1288.239938436327;1310.344827586207;13564.796528052088;13696.86303136956;13935.512257070954;14201.928990355189;14240.216788076756;503666;504850;505052.47473763133;505597;506252;48589.514104859205;58552.707236463546;59198;59452.70273648604;59576.70211648914;1489.41912654065;1534.7314220011676;1537.5694805454714;1539.6689711712036;1547.404249364003;25217.49565008726;26707;26789.33026674271;26818.463630727667;26820.329491762863;46005.539944600125;47028.05943881172;49358.506414936826;49419.01161976812;49642;430448.6955130409;431052;431130;432377;432965;26302.736972630028;26613.467730645665;31786.04641860748;31908.680913190572;32180.356392871545;32062.519062213618;38769.80615096907;38947.6105238944;38994.83015509539;39614.603853962246;26669.599956001064;26782.59826102649;27371.86314068417;27420.725792741818;28063.579046313913;28062.43875122527;28181.436371272048;28699;28720.28199294951;28860.7113928858;29423;32438.675613243566;33229.50155747714;33345.83327083349;33624.831875840464;28607.9987200447;28785.280367990967;30033;30385.17688938671;30868.845655771576;20938.267160649306;22197.66703499481;23579.056837726297;23840.165594204118;23998.76001240035 19:49:20 INFO - PROCESS | 1647 | |2;jquery.html;407.69626628162024;412.27439706117224;413.0747126436781;428.1608048225478;434.6848534812261;20667.276645317397;21508.89245553772;22185.445363865892;22373.99999999996;22604;21712.565748685018;21945.780542194574;22420.77579224207;22508.662370064463;22792.886035569798;5353.437889021655;6800.149981252331;6903;6970.7560235391775;6974.128233970754;6871.072405225305;6886.965565172167;6887.552309099918;6974.372306492414;6990.545614535039;6581.078648989122;6842.521023528355;6930.168379794406;6941.305869413069;6961.23426423093;6608.471322294205;6867.828304292408;6925.930740692605;6935.306469353075;6956.339147780953;6634.834129146761;6712.295209003033;6715.395614394728;6716.3955244028275;6735.259121496631;23249.070037198493;26434.6034809478;26751;26958.73041269582;27023.86488067547;2975.3900450407614;2996.1656213774013;3000.6249218847643;3001.639803223627;3023.349979754363;16245.268962896698;16940.814143009993;16994.915025424918;17105.486835394833;17357.045362505058;2093.706881036656;2113.154738104755;2158.3956492182215;2167.512309730314;2232.285668586053;105411;123772;128995;130857.34571327177;131127;21175.36473905785;22223.22218722337;22569.66145507819;22863.542729145658;23210.883945580164;30378.392432151235;34640.13399664978;34950.650493495246;35352.64647353494;35520.467192992124;10740.194485413625;11000.669979900616;11159.944200278947;11197.496112674786;11202.66392008241;8921.330900182513;8968.506732129665;8969.372143949991;9153.862692059625;9166.954165229265;10490.265681402228;10521.263511554118;10592.523336449878;10716;10888.29226100301;3892.618120567158;4427.690061695715;4517.457905051415;4583.427071616048;4599.241125214369;1109.2013750099916;1115.3865374044244;1133.3709791065874;1144.5364627325946;1165.5687395663658;22776.886115569316;23093.769062309497;23236.535269294858;23358.64962025571;23359.182428614917;1134.7446824464594;1137.4142316706736;1148.2077366617007;1168.1297433412162;1179.262960649594;9887.896825396825;12021.278723276631;12223.877761222275;12248.81626775599;12258.81611775824;747197;753224;759344;763076;769660;82886.58556707177;98400.50799745956;126501.30744116537;129606;133367.33316333356;1257.3650306595111;1307.601181639609;1321.8678132186703;1323.6227675112739;1327.3694994877549;19826.603467930272;21276.255331063338;21299.148034079084;21383.35849924505;21401.78598213998;55502;55897.44102558922;55921.44078559162;57085;57411.71294143503;72392.19019524554;75271.24728752642;76570;78088.04779880548;78397.60801195957;7703.614819259126;10534.315269507457;11758.412079395825;11940.298507462714;12102.636920892388;12734.172278802218;12957.157784743962;12988.35058247065;13107.737845242851;13337.466501340226;11090.5563777448;11253.324800511995;11292.096632269562;11327.773444530898;11358.54565817358;8905.599248033983;10364.222683298933;10548.261621686408;10876.891231087904;10890.50992705346;9571.856422153534;11131.886811318804;11680.357580333164;11825.881741182822;11885.405729713653;10810.621628242974;11694.88305116938;11920.582779602672;11950.924416802529;12045.879541204476;10273.589056437659;10587.047165755119;10774.892251077703;11229.887701122883;11496.770064598828 19:49:20 INFO - PROCESS | 1647 | |3;mootools.html;1563.1558958162593;1614.2977804654977;1623.1154021058514;1627.7476991066385;1632.5184070699142;9535.99872013439;9820;10837.349759014454;11378.2035257532;11498.942505287472;10719.732006699836;11089.611863584776;11093.33439993601;11161.888381116187;11212.831807522894;2301.5051763870806;2345.6129738593195;2347.307544274443;2348.6477028445784;2351.564960482316;2255.650374192003;2284.4288927768057;2288.370698058039;2293.185918998748;2297.035245197013;2018.8889611071386;2308.0190918859503;2308.042162502559;2326.6510023496526;2362.9881850590723;2315.374848790817;2325.5697695926306;2333.36999010268;2339.064374250305;2339.4736184358553;2318.3508617587104;2323.349462150584;2325.267540724666;2326.3718795925174;2333.8599684019014;11129.332240065542;11155.832662510069;11235.887641123565;11255.606053788078;11256;5385.380681221632;5484.8354549363185;5533.944660553383;5548.167774833767;5551.555875529948;3219.8390080495883;3269.7057264846276;3484.8431820568135;3485.5120283160377;3496.985874096509;1157.2362240921036;1174.8002839517217;1850.4818650777804;1893.583411649435;1942.6794578894464;15339.616509587238;15340.693186136215;15510;15522.844771552252;15725.921370393075;4332.4367832181615;4479.216137175981;4516.977415112903;4522.140793249272;4529.09418116378;11652.825207621892;11658.70853228676;11665;11679.649610511698;11685;4829.203181475087;5531.170324451365;5546.91679624806;5559.471850174227;5568.610197286151;4342.34864770287;4515.661325400608;5088.592912566985;5116.872078198075;5150.613703972217;3669.6513831185994;3732.8133593320226;3765.2093060457064;3811.7331786774657;3822.961770382316;967.308374512213;968.0754879090451;968.2060710217554;978.3054031637474;978.4569563892109;931.7933276407069;934.5420743835472;935.5182081228173;953.8950715421298;991.2417000994245;6445.484361251089;6547.607143571401;6556.737730490822;6574;6663.833404164935;985.5367977050774;986.2208855004609;1003.9849402258972;1005.3214080495636;1017.1455976979373;4038.818253178614;4154.31453810124;4180.581941805795;4192.203481338536;4269.103488267437;13242.337883105805;13729.656758580915;13759.862401376058;13765;13804.447822087204;13571.253581053137;13646.522371716943;13660.590182294547;13661.521846735317;13678.93160534191;9309.581068851918;10129.544170512345;10339.948300258451;10356.792864142826;10382.169426445866;6761.830954226184;6787.389134977876;6810.42111420515;6910.343517365842;6963.825904352229;11825.113116516124;12191.51233950632;12225.633231003085;12470;12594.055445841781;10060.949695251476;12326.445309961253;12488.937555312164;12574.748505030031;12628.684282893002;7544.886826697494;7970.721024764105;8237.423380363318;9032.503212323123;9221.031791661779;10201.489925503844;10373.636922707668;10404.791904162026;10446.791064178522;10447.05976462122;8719.694810681596;8821.779455513664;8836.558172091498;8872.24585910205;8948.194662480408;7440.255974402733;8705.825883482421;8716.38985271025;8740.650373985223;8793.296536276954;6706.295838936841;7692.230776922263;7732.961335193288;7827;7859.017622797151;5746.16680581305;5748.655080695172;5784.479396854135;5890.263717035371;5893.44012318829;5330.387005494263;5332.5467335276935;5376.274202982548;5426.484483974016;5510.1459273812625 19:49:20 INFO - PROCESS | 1647 | |4;prototype.html;341.75393716524127;359.29219437707724;374.57111607209765;382.9138443850133;389.41976455081925;4724.811007559693;5074.3657042869645;5152.562032227261;5193.052605273023;5234.738263086845;4283.742975421477;4439.822407103712;4512.706674066183;5154.381474223099;5200.37595488541;3960.3663413853787;4181.372794080897;4230.4500414946015;4289.227938970984;4349.847290468028;3769.4157405602177;4277.657787377002;4325.5025672047805;4335.263005289093;4429.623482004017;4317.611414972652;4318.805653745589;4332.220200363933;4334.0031792687705;4363.607275345218;3550.2781151141676;4103.7948102594755;4269.5090064642345;4321.438213032317;4380.6276466500385;3639.6178401267753;3783.7891874599864;3933.9016524587114;3963.861264855745;4010.7794071326066;3989.68855849647;5037.798488060474;5069.61977851659;5080.390353157608;5123.487651234884;4214.515330736974;4481.955180448219;4815.614750819926;5033.974830125826;5043.42000669924;4863.577111520664;4901.362822833009;5253.50091741284;5581.2186293918885;5604.719764011783;3832.4095500367316;4098.303288440972;4434.5343738907595;4464.8214071437105;4489.820407183709;5250.579953603703;5461.399246082928;5919.55603329752;5935.228420305332;5941.792037278675;3731.328360895065;4012.2145645188193;4096.91806163881;4459.331100334911;4541.931871021938;4198.391233271159;4628.837990670378;4640;4662.930056049162;4676.410020593014;4215.59951804578;4576.0121467600475;4673.228917228687;4982.5052484254575;4982.526659967298;4994.325766021613;5005.7997680092385;5062.671171261261;5477.603211181124;5529.949309631238;1498.7751837224503;1556.486359501362;1564.5853848730096;1572.7025204206575;1573.417835400909;1103.2994048778924;1177.9116566257565;1193.9164258501996;1194.946227419768;1210.515793682534;4356.67121527937;4736.218523943511;4862.294967229732;4943.802247910044;4947.134251505972;5519.32312828278;5778.064348443235;5781.739821708034;5809.506191973645;5840.883182336414;4696.839453851198;4715.740634265148;4770.825527663294;4995.850124496271;5025.39695236574;2807.778616301915;2841.5169421198034;2974.3456439583256;3012.1264833198566;3024.7882648214854;5334.626576139711;5728.541716662657;5832.708364581754;5997;6024.945634513942;5334.8132815352055;5387.892242155135;5654.604177707521;5854.619449735754;5900.64596124234;5203.427622961547;5379.973100134474;5433.315672141649;5644.9435505644415;6022.819315420545;4859.611231101433;5790.726040271134;5798.130280457796;5801.796937107181;5830.53355731532;4598.448186217675;5074.797008119634;5209.505097015777;5316.3354580677415;5629.915551266652;5102.897942041212;5289.68261904287;5889.587728858939;5931;5955.791547295824;4025.818838152232;4582.747948862844;4603.6777425579885;4628.768561571976;4642.489326174051;4841.322214889989;4907.386576677915;5288.550473209821;5601.971990140186;5658.264425624642;4973.428055773632;5473.616846820684;5518;5548.417416171405;5588.552915766811;4959.578435832995;5271.868203294948;5487.259220005249;5510.779568817363;5534.371173692464;5112;5253.2645429639115;5315.787368505216;5349.967012535211;5400.9459905400445;5506.035654328877;5530.267069727837;5586.413426590149;5598.272224610774;5630.690312032713;4411.184325558433;5168.819091331786;5398.704310965419;5414.187871819258;5426.457354264542 19:49:20 INFO - PROCESS | 1647 | |5;yui.html;726.4333819620698;743.7657138001525;778.0702061037059;828.8673812190049;850.6342272822686;15185.468508602202;17166.57083572911;17469.126543672828;17480.951142931433;17488.38790642328;15378.384864605403;15752.606184845356;15815.446459373925;15907.44323948662;15981.92009039956;984.34541030215;994.4580203788952;1004.9598016079385;1005.0502275349797;1009.2027298434228;971.6259240192542;981.8772653418323;1003.5082809423367;1004.4274763384838;1005.5927349423495;1049.3127001813825;1052.9315594486372;1053.0417320238585;1053.2364036073861;1054.3252318516118;1042.29124195547;1046.5865982936734;1046.863907691999;1049.0348879031271;1049.5906596427399;776.7242628866727;781.8045488627843;782.2998416417331;783.4985609210053;783.702193166593;16754.497365079067;16924;17015.149242537824;17907.552311192194;18218.90890545552;14249.287535623282;14273.500427485093;14366.56900292993;14370.712585748226;14374.640633984234;11643.301401915827;11761.88238117625;11851.525938962432;11940.940295298555;12416.689582760413;3689.6494832990825;3745.8501659933568;3762.7002322470394;3831.4635950966886;3888.416737489399;101983;105032.94967050308;115546;116156.25765613523;117314.41342793232;10941.23411361197;11153.275037122561;11238.887611123784;11330.490127944262;11386.032187264009;20589.89705051465;23393.883030584737;23406.64890026651;23658.763412366;23820;7657.693692252247;7881.960590197127;7931.206879312138;8029.7189598363775;8120.147384524658;4405.713628614129;4475.2546506862345;4527.524609915978;4535.931961020588;4651.744154071558;3476;3527.788332699995;3536.2750636119536;3538.433850583894;3556.4665300205174;600.717662698531;617.0528239152878;618.8567540317621;622.7296315516296;630.3853742600927;641.913341698874;684.8732984397902;696.9128858892639;712.8966299886488;724.2757242757243;9021.458712477272;10140.898591014144;10343.431111288952;10683.412412317242;10751.759960707786;664.9800505984829;700.2577268095835;705.9470539709542;707.5330282013846;715.6529083394549;4707.599854012447;5085.466026067284;5120.180771076684;5121.795128194831;5133;88109.55945220233;100685.49657251814;105358;107375;107660;71397;76909.6154519235;82220.58889705513;93480;95885.5205723967;69662.65168674124;72331.63834180795;72410;72595.63702181455;73544;10838.891611083789;10938.835917461403;11011.83482247783;11086.168537359568;11181.776364472827;49718.50281497139;49851.25223121583;50486.7475662634;50642.74678626583;51604.96790064253;52685.47314526959;64229.357706422335;65476.34523654702;65721.67139164274;65893;10002.649907253211;10038.949805250926;10342.276040677381;10887.673369798918;12907.290099044267;14827.629309267355;14915.030523015968;14944.701105978036;15155.393784248507;15398.9230053849;13867.514636987657;13874.722505550035;13893.374798133906;14345.856541434452;14532.346044427815;13852.035749751738;14142.92928535392;14328.856711432752;14604.488842890449;14933.402663893323;11639.88360116388;13747.518836840663;13841.377138029015;14534.491292804702;14624.34190461453;10035.448050357301;10143.08712215904;10156.542955567165;10199.949000254952;10275.640352587623;10759.246852720235;11113.22207445471;11379.54481820718;11381.146414018816;11395.886041139815 19:49:20 INFO - PROCESS | 1647 | __end_tp_report 19:49:20 INFO - PROCESS | 1647 | __start_cc_report 19:49:20 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,374 19:49:20 INFO - PROCESS | 1647 | __end_cc_report 19:49:20 INFO - PROCESS | 1647 | __startTimestamp1496630960658__endTimestamp 19:49:20 INFO - PROCESS | 1647 | 19:49:20 INFO - PROCESS | 1647 | ------- Summary: start ------- 19:49:20 INFO - PROCESS | 1647 | Number of tests: 6 19:49:20 INFO - PROCESS | 1647 | 19:49:20 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5921.70 Median:6153.08 stddev:656.24 (10.7%) stddev-sans-first:623.90 19:49:20 INFO - PROCESS | 1647 | Values: 3250.6 3532.9 3543.8 3585.6 3591.7 5506.8 5979.4 6049.0 6107.2 6121.8 6025.4 6064.3 6085.0 6095.5 6109.9 5388.7 5396.5 5461.8 5532.6 5540.5 5500.0 5508.1 5521.3 5527.8 5571.3 5300.3 5319.6 5377.5 5491.9 5523.4 5405.9 5419.9 5474.9 5485.2 5494.3 5322.8 5452.7 5466.4 5474.7 5517.8 6084.4 6093.5 6116.1 6119.0 6160.4 6125.5 6236.2 6247.5 6252.1 6303.6 6435.1 6464.0 6479.8 6487.7 6489.4 6153.1 6177.6 6179.2 6190.8 6195.6 6299.9 6345.8 6348.9 6547.1 6650.8 6120.5 6261.7 6266.8 6294.6 6318.6 5461.5 6442.3 6462.0 6463.0 6464.1 6240.1 6276.1 6362.6 6369.8 6387.7 6272.5 6318.6 6336.7 6364.6 6395.3 4616.2 4809.8 4893.9 4961.7 4969.3 4452.2 4456.0 4474.1 4513.6 4519.5 5861.5 5995.9 5999.6 6004.8 6018.2 6206.8 6255.1 6290.8 6329.2 6480.9 5993.8 5996.3 6000.4 6001.1 6027.5 5601.6 5610.3 5615.9 5616.9 5630.6 6331.6 6392.8 6462.2 6481.5 6670.6 5307.8 6394.7 6399.0 6411.2 6415.3 6485.5 6523.5 6624.7 6625.3 6629.1 6224.0 6266.3 6277.8 6332.0 6344.7 6409.1 6454.6 6466.4 6477.9 6479.4 5272.0 5322.9 6129.6 6304.2 6320.2 6196.6 6216.0 6257.6 6296.8 6324.8 6127.4 6132.0 6169.5 6175.7 6202.4 6314.0 6324.7 6332.9 6335.4 6347.4 6117.8 6121.0 6122.6 6130.4 6208.2 19:49:20 INFO - PROCESS | 1647 | 19:49:20 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:57199.70 Median:29141.86 stddev:119852.63 (411.3%) stddev-sans-first:120113.91 19:49:20 INFO - PROCESS | 1647 | Values: 751.9 764.6 804.5 829.0 975.7 37915.2 38732.0 39357.2 39987.0 40003.6 31702.7 38699.4 38867.6 38897.0 38912.6 8088.7 8200.0 10804.9 11096.8 11280.7 8609.8 10051.8 10867.6 11020.9 11117.5 10995.2 10996.5 11016.4 11022.7 11022.8 10940.1 10998.9 11022.7 11031.2 11055.8 10763.7 10782.9 10823.4 10828.6 10831.7 33305.8 33445.3 33491.5 33628.8 33782.7 3815.3 3844.7 3888.6 3908.2 3908.2 24961.5 25021.4 25127.9 26613.5 27294.0 3114.5 3165.3 3173.1 3180.6 3287.1 372296.0 375376.0 421349.0 429056.0 441783.8 43366.3 43538.0 43663.6 43691.6 43739.9 45299.8 45374.0 45422.8 45528.5 45534.8 13590.2 13664.0 13671.8 13680.9 13688.5 12108.5 12112.6 12115.3 12127.6 12148.8 11673.6 11714.5 11734.6 11783.8 11793.5 5873.2 5874.3 5924.0 5928.1 5944.3 1144.5 1164.6 1170.8 1187.2 1242.9 26397.0 27029.5 27523.7 27582.4 27626.9 1076.6 1176.9 1261.1 1288.2 1310.3 13564.8 13696.9 13935.5 14201.9 14240.2 503666.0 504850.0 505052.5 505597.0 506252.0 48589.5 58552.7 59198.0 59452.7 59576.7 1489.4 1534.7 1537.6 1539.7 1547.4 25217.5 26707.0 26789.3 26818.5 26820.3 46005.5 47028.1 49358.5 49419.0 49642.0 430448.7 431052.0 431130.0 432377.0 432965.0 26302.7 26613.5 31786.0 31908.7 32180.4 32062.5 38769.8 38947.6 38994.8 39614.6 26669.6 26782.6 27371.9 27420.7 28063.6 28062.4 28181.4 28699.0 28720.3 28860.7 29423.0 32438.7 33229.5 33345.8 33624.8 28608.0 28785.3 30033.0 30385.2 30868.8 20938.3 22197.7 23579.1 23840.2 23998.8 19:49:20 INFO - PROCESS | 1647 | 19:49:20 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:41285.84 Median:12303.14 stddev:124861.10 (1014.9%) stddev-sans-first:125173.64 19:49:20 INFO - PROCESS | 1647 | Values: 407.7 412.3 413.1 428.2 434.7 20667.3 21508.9 22185.4 22374.0 22604.0 21712.6 21945.8 22420.8 22508.7 22792.9 5353.4 6800.1 6903.0 6970.8 6974.1 6871.1 6887.0 6887.6 6974.4 6990.5 6581.1 6842.5 6930.2 6941.3 6961.2 6608.5 6867.8 6925.9 6935.3 6956.3 6634.8 6712.3 6715.4 6716.4 6735.3 23249.1 26434.6 26751.0 26958.7 27023.9 2975.4 2996.2 3000.6 3001.6 3023.3 16245.3 16940.8 16994.9 17105.5 17357.0 2093.7 2113.2 2158.4 2167.5 2232.3 105411.0 123772.0 128995.0 130857.3 131127.0 21175.4 22223.2 22569.7 22863.5 23210.9 30378.4 34640.1 34950.7 35352.6 35520.5 10740.2 11000.7 11159.9 11197.5 11202.7 8921.3 8968.5 8969.4 9153.9 9167.0 10490.3 10521.3 10592.5 10716.0 10888.3 3892.6 4427.7 4517.5 4583.4 4599.2 1109.2 1115.4 1133.4 1144.5 1165.6 22776.9 23093.8 23236.5 23358.6 23359.2 1134.7 1137.4 1148.2 1168.1 1179.3 9887.9 12021.3 12223.9 12248.8 12258.8 747197.0 753224.0 759344.0 763076.0 769660.0 82886.6 98400.5 126501.3 129606.0 133367.3 1257.4 1307.6 1321.9 1323.6 1327.4 19826.6 21276.3 21299.1 21383.4 21401.8 55502.0 55897.4 55921.4 57085.0 57411.7 72392.2 75271.2 76570.0 78088.0 78397.6 7703.6 10534.3 11758.4 11940.3 12102.6 12734.2 12957.2 12988.4 13107.7 13337.5 11090.6 11253.3 11292.1 11327.8 11358.5 8905.6 10364.2 10548.3 10876.9 10890.5 9571.9 11131.9 11680.4 11825.9 11885.4 10810.6 11694.9 11920.6 11950.9 12045.9 10273.6 10587.0 10774.9 11229.9 11496.8 19:49:20 INFO - PROCESS | 1647 | 19:49:20 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:6644.83 Median:3244.77 stddev:4223.60 (130.2%) stddev-sans-first:4218.19 19:49:20 INFO - PROCESS | 1647 | Values: 1563.2 1614.3 1623.1 1627.7 1632.5 9536.0 9820.0 10837.3 11378.2 11498.9 10719.7 11089.6 11093.3 11161.9 11212.8 2301.5 2345.6 2347.3 2348.6 2351.6 2255.7 2284.4 2288.4 2293.2 2297.0 2018.9 2308.0 2308.0 2326.7 2363.0 2315.4 2325.6 2333.4 2339.1 2339.5 2318.4 2323.3 2325.3 2326.4 2333.9 11129.3 11155.8 11235.9 11255.6 11256.0 5385.4 5484.8 5533.9 5548.2 5551.6 3219.8 3269.7 3484.8 3485.5 3497.0 1157.2 1174.8 1850.5 1893.6 1942.7 15339.6 15340.7 15510.0 15522.8 15725.9 4332.4 4479.2 4517.0 4522.1 4529.1 11652.8 11658.7 11665.0 11679.6 11685.0 4829.2 5531.2 5546.9 5559.5 5568.6 4342.3 4515.7 5088.6 5116.9 5150.6 3669.7 3732.8 3765.2 3811.7 3823.0 967.3 968.1 968.2 978.3 978.5 931.8 934.5 935.5 953.9 991.2 6445.5 6547.6 6556.7 6574.0 6663.8 985.5 986.2 1004.0 1005.3 1017.1 4038.8 4154.3 4180.6 4192.2 4269.1 13242.3 13729.7 13759.9 13765.0 13804.4 13571.3 13646.5 13660.6 13661.5 13678.9 9309.6 10129.5 10339.9 10356.8 10382.2 6761.8 6787.4 6810.4 6910.3 6963.8 11825.1 12191.5 12225.6 12470.0 12594.1 10060.9 12326.4 12488.9 12574.7 12628.7 7544.9 7970.7 8237.4 9032.5 9221.0 10201.5 10373.6 10404.8 10446.8 10447.1 8719.7 8821.8 8836.6 8872.2 8948.2 7440.3 8705.8 8716.4 8740.7 8793.3 6706.3 7692.2 7733.0 7827.0 7859.0 5746.2 5748.7 5784.5 5890.3 5893.4 5330.4 5332.5 5376.3 5426.5 5510.1 19:49:20 INFO - PROCESS | 1647 | 19:49:20 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4590.63 Median:4953.36 stddev:1276.05 (25.8%) stddev-sans-first:1239.14 19:49:20 INFO - PROCESS | 1647 | Values: 341.8 359.3 374.6 382.9 389.4 4724.8 5074.4 5152.6 5193.1 5234.7 4283.7 4439.8 4512.7 5154.4 5200.4 3960.4 4181.4 4230.5 4289.2 4349.8 3769.4 4277.7 4325.5 4335.3 4429.6 4317.6 4318.8 4332.2 4334.0 4363.6 3550.3 4103.8 4269.5 4321.4 4380.6 3639.6 3783.8 3933.9 3963.9 4010.8 3989.7 5037.8 5069.6 5080.4 5123.5 4214.5 4482.0 4815.6 5034.0 5043.4 4863.6 4901.4 5253.5 5581.2 5604.7 3832.4 4098.3 4434.5 4464.8 4489.8 5250.6 5461.4 5919.6 5935.2 5941.8 3731.3 4012.2 4096.9 4459.3 4541.9 4198.4 4628.8 4640.0 4662.9 4676.4 4215.6 4576.0 4673.2 4982.5 4982.5 4994.3 5005.8 5062.7 5477.6 5529.9 1498.8 1556.5 1564.6 1572.7 1573.4 1103.3 1177.9 1193.9 1194.9 1210.5 4356.7 4736.2 4862.3 4943.8 4947.1 5519.3 5778.1 5781.7 5809.5 5840.9 4696.8 4715.7 4770.8 4995.9 5025.4 2807.8 2841.5 2974.3 3012.1 3024.8 5334.6 5728.5 5832.7 5997.0 6024.9 5334.8 5387.9 5654.6 5854.6 5900.6 5203.4 5380.0 5433.3 5644.9 6022.8 4859.6 5790.7 5798.1 5801.8 5830.5 4598.4 5074.8 5209.5 5316.3 5629.9 5102.9 5289.7 5889.6 5931.0 5955.8 4025.8 4582.7 4603.7 4628.8 4642.5 4841.3 4907.4 5288.6 5602.0 5658.3 4973.4 5473.6 5518.0 5548.4 5588.6 4959.6 5271.9 5487.3 5510.8 5534.4 5112.0 5253.3 5315.8 5350.0 5400.9 5506.0 5530.3 5586.4 5598.3 5630.7 4411.2 5168.8 5398.7 5414.2 5426.5 19:49:20 INFO - PROCESS | 1647 | 19:49:20 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:20443.80 Median:16839.25 stddev:28958.47 (172.0%) stddev-sans-first:29001.86 19:49:20 INFO - PROCESS | 1647 | Values: 726.4 743.8 778.1 828.9 850.6 15185.5 17166.6 17469.1 17481.0 17488.4 15378.4 15752.6 15815.4 15907.4 15981.9 984.3 994.5 1005.0 1005.1 1009.2 971.6 981.9 1003.5 1004.4 1005.6 1049.3 1052.9 1053.0 1053.2 1054.3 1042.3 1046.6 1046.9 1049.0 1049.6 776.7 781.8 782.3 783.5 783.7 16754.5 16924.0 17015.1 17907.6 18218.9 14249.3 14273.5 14366.6 14370.7 14374.6 11643.3 11761.9 11851.5 11940.9 12416.7 3689.6 3745.9 3762.7 3831.5 3888.4 101983.0 105032.9 115546.0 116156.3 117314.4 10941.2 11153.3 11238.9 11330.5 11386.0 20589.9 23393.9 23406.6 23658.8 23820.0 7657.7 7882.0 7931.2 8029.7 8120.1 4405.7 4475.3 4527.5 4535.9 4651.7 3476.0 3527.8 3536.3 3538.4 3556.5 600.7 617.1 618.9 622.7 630.4 641.9 684.9 696.9 712.9 724.3 9021.5 10140.9 10343.4 10683.4 10751.8 665.0 700.3 705.9 707.5 715.7 4707.6 5085.5 5120.2 5121.8 5133.0 88109.6 100685.5 105358.0 107375.0 107660.0 71397.0 76909.6 82220.6 93480.0 95885.5 69662.7 72331.6 72410.0 72595.6 73544.0 10838.9 10938.8 11011.8 11086.2 11181.8 49718.5 49851.3 50486.7 50642.7 51605.0 52685.5 64229.4 65476.3 65721.7 65893.0 10002.6 10038.9 10342.3 10887.7 12907.3 14827.6 14915.0 14944.7 15155.4 15398.9 13867.5 13874.7 13893.4 14345.9 14532.3 13852.0 14142.9 14328.9 14604.5 14933.4 11639.9 13747.5 13841.4 14534.5 14624.3 10035.4 10143.1 10156.5 10199.9 10275.6 10759.2 11113.2 11379.5 11381.1 11395.9 19:49:20 INFO - PROCESS | 1647 | -------- Summary: end -------- 19:49:20 INFO - PROCESS | 1647 | 19:49:21 INFO - TEST-INFO | 1647: exit 0 19:49:21 INFO - TEST-OK | dromaeo_css | took 1116800ms 19:49:21 INFO - TEST-START | kraken 19:49:21 INFO - operating with platform_type : mac_ 19:49:21 INFO - Initialising browser for kraken test... 19:49:21 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmpP2EUTc/profile http://localhost:49232/getInfo.html) 19:49:23 INFO - PROCESS | 1659 | 2017-06-04 19:49:23.888 plugin-container[1661:10382] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8743, name = 'com.apple.tsm.portname' 19:49:23 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:49:23 INFO - PROCESS | 1659 | 2017-06-04 19:49:23.888 plugin-container[1661:10382] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d03, name = 'com.apple.CFPasteboardClient' 19:49:23 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:49:23 INFO - PROCESS | 1659 | 2017-06-04 19:49:23.888 plugin-container[1661:10382] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:49:23 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 19:49:23 INFO - PROCESS | 1659 | colorDepth:24 19:49:23 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 19:49:23 INFO - PROCESS | 1659 | __metrics 19:49:24 ERROR - PROCESS | 1659 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3381: TypeError: this.tabs is undefined 19:49:24 INFO - TEST-INFO | 1659: exit 0 19:49:24 INFO - Browser initialized. 19:49:24 INFO - Running cycle 1/1 for kraken test... 19:49:24 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/T/tmpP2EUTc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:49:26 INFO - PROCESS | 1662 | 2017-06-04 19:49:26.939 plugin-container[1663:10509] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.tsm.portname' 19:49:26 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:49:26 INFO - PROCESS | 1662 | 2017-06-04 19:49:26.940 plugin-container[1663:10509] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 19:49:26 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:49:26 INFO - PROCESS | 1662 | 2017-06-04 19:49:26.940 plugin-container[1663:10509] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:49:36 INFO - PROCESS | 1662 | RSS: Main: 203091968 19:49:36 INFO - PROCESS | 1662 | 19:49:39 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49232/tests/kraken/driver.html?testName=audio-beat-detection) 19:49:40 INFO - PROCESS | 1662 | RSS: Main: 216854528 19:49:40 INFO - PROCESS | 1662 | 19:49:43 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49232/tests/kraken/driver.html?testName=audio-dft) 19:49:43 INFO - PROCESS | 1662 | RSS: Main: 224747520 19:49:43 INFO - PROCESS | 1662 | 19:49:47 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49232/tests/kraken/driver.html?testName=audio-fft) 19:49:47 INFO - PROCESS | 1662 | RSS: Main: 226226176 19:49:47 INFO - PROCESS | 1662 | 19:49:49 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49232/tests/kraken/driver.html?testName=audio-oscillator) 19:49:50 INFO - PROCESS | 1662 | RSS: Main: 228966400 19:49:50 INFO - PROCESS | 1662 | 19:49:52 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49232/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:49:53 INFO - PROCESS | 1662 | RSS: Main: 229376000 19:49:53 INFO - PROCESS | 1662 | 19:49:56 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49232/tests/kraken/driver.html?testName=imaging-darkroom) 19:49:56 INFO - PROCESS | 1662 | RSS: Main: 233361408 19:49:56 INFO - PROCESS | 1662 | 19:49:59 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49232/tests/kraken/driver.html?testName=imaging-desaturate) 19:50:00 INFO - PROCESS | 1662 | RSS: Main: 233123840 19:50:00 INFO - PROCESS | 1662 | 19:50:03 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49232/tests/kraken/driver.html?testName=json-parse-financial) 19:50:04 INFO - PROCESS | 1662 | RSS: Main: 232984576 19:50:04 INFO - PROCESS | 1662 | 19:50:05 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49232/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:50:06 INFO - PROCESS | 1662 | RSS: Main: 232841216 19:50:06 INFO - PROCESS | 1662 | 19:50:08 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:50:09 INFO - PROCESS | 1662 | RSS: Main: 232747008 19:50:09 INFO - PROCESS | 1662 | 19:50:11 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:50:12 INFO - PROCESS | 1662 | RSS: Main: 232796160 19:50:12 INFO - PROCESS | 1662 | 19:50:14 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:50:15 INFO - PROCESS | 1662 | RSS: Main: 233242624 19:50:15 INFO - PROCESS | 1662 | 19:50:18 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:50:19 INFO - PROCESS | 1662 | RSS: Main: 232955904 19:50:19 INFO - PROCESS | 1662 | 19:50:20 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49232/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49232/tests/kraken/driver.html?testName=ai-astar) 19:50:20 INFO - PROCESS | 1662 | RSS: Main: 234643456 19:50:20 INFO - PROCESS | 1662 | 19:50:20 INFO - PROCESS | 1662 | __start_tp_report 19:50:20 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 19:50:20 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 19:50:20 INFO - PROCESS | 1662 | |i|pagename|runs| 19:50:20 INFO - PROCESS | 1662 | |0;ai-astar;107;122;119;120;119;120;120;119;121;122 19:50:20 INFO - PROCESS | 1662 | |1;audio-beat-detection;145;112;111;152;113;136;138;112;131;114 19:50:20 INFO - PROCESS | 1662 | |2;audio-dft;205;202;203;215;201;177;257;285;234;197 19:50:20 INFO - PROCESS | 1662 | |3;audio-fft;80;65;68;102;59;61;60;61;75;58 19:50:20 INFO - PROCESS | 1662 | |4;audio-oscillator;87;71;87;74;70;70;83;70;73;82 19:50:20 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;102;105;91;91;95;102;92;101;112;91 19:50:20 INFO - PROCESS | 1662 | |6;imaging-darkroom;98;99;97;98;98;98;99;102;97;98 19:50:20 INFO - PROCESS | 1662 | |7;imaging-desaturate;139;98;76;141;136;93;97;119;92;93 19:50:20 INFO - PROCESS | 1662 | |8;json-parse-financial;69;68;66;68;71;69;71;71;70;71 19:50:20 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;80;88;59;63;63;58;57;61;75;83 19:50:20 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;82;89;86;88;87;87;88;84;123;126 19:50:20 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;141;142;154;138;138;139;152;137;148;141 19:50:20 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;168;170;167;171;170;167;170;175;170;168 19:50:20 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;66;65;65;64;67;66;67;68;65;67 19:50:20 INFO - PROCESS | 1662 | __end_tp_report 19:50:20 INFO - PROCESS | 1662 | __start_cc_report 19:50:20 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1008 19:50:20 INFO - PROCESS | 1662 | __end_cc_report 19:50:20 INFO - PROCESS | 1662 | __startTimestamp1496631020970__endTimestamp 19:50:20 INFO - PROCESS | 1662 | 19:50:20 INFO - PROCESS | 1662 | ------- Summary: start ------- 19:50:20 INFO - PROCESS | 1662 | Number of tests: 14 19:50:20 INFO - PROCESS | 1662 | 19:50:20 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:118.90 Median:120.00 stddev:4.33 (3.6%) stddev-sans-first:1.20 19:50:20 INFO - PROCESS | 1662 | Values: 107.0 122.0 119.0 120.0 119.0 120.0 120.0 119.0 121.0 122.0 19:50:20 INFO - PROCESS | 1662 | 19:50:20 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:126.40 Median:133.50 stddev:15.76 (11.8%) stddev-sans-first:15.21 19:50:20 INFO - PROCESS | 1662 | Values: 145.0 112.0 111.0 152.0 113.0 136.0 138.0 112.0 131.0 114.0 19:50:20 INFO - PROCESS | 1662 | 19:50:20 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:217.60 Median:210.00 stddev:32.20 (15.3%) stddev-sans-first:33.83 19:50:20 INFO - PROCESS | 1662 | Values: 205.0 202.0 203.0 215.0 201.0 177.0 257.0 285.0 234.0 197.0 19:50:20 INFO - PROCESS | 1662 | 19:50:20 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:68.90 Median:63.00 stddev:13.71 (21.8%) stddev-sans-first:13.95 19:50:20 INFO - PROCESS | 1662 | Values: 80.0 65.0 68.0 102.0 59.0 61.0 60.0 61.0 75.0 58.0 19:50:20 INFO - PROCESS | 1662 | 19:50:20 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:76.70 Median:78.00 stddev:7.21 (9.2%) stddev-sans-first:6.62 19:50:20 INFO - PROCESS | 1662 | Values: 87.0 71.0 87.0 74.0 70.0 70.0 83.0 70.0 73.0 82.0 19:50:20 INFO - PROCESS | 1662 | 19:50:20 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:98.20 Median:91.00 stddev:7.28 (8.0%) stddev-sans-first:7.60 19:50:20 INFO - PROCESS | 1662 | Values: 102.0 105.0 91.0 91.0 95.0 102.0 92.0 101.0 112.0 91.0 19:50:20 INFO - PROCESS | 1662 | 19:50:20 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:98.40 Median:98.00 stddev:1.43 (1.5%) stddev-sans-first:1.51 19:50:20 INFO - PROCESS | 1662 | Values: 98.0 99.0 97.0 98.0 98.0 98.0 99.0 102.0 97.0 98.0 19:50:20 INFO - PROCESS | 1662 | 19:50:20 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:108.40 Median:92.50 stddev:23.34 (25.2%) stddev-sans-first:21.98 19:50:20 INFO - PROCESS | 1662 | Values: 139.0 98.0 76.0 141.0 136.0 93.0 97.0 119.0 92.0 93.0 19:50:20 INFO - PROCESS | 1662 | 19:50:20 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:69.40 Median:70.50 stddev:1.71 (2.4%) stddev-sans-first:1.81 19:50:20 INFO - PROCESS | 1662 | Values: 69.0 68.0 66.0 68.0 71.0 69.0 71.0 71.0 70.0 71.0 19:50:20 INFO - PROCESS | 1662 | 19:50:20 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.00 stddev:11.61 (16.8%) stddev-sans-first:11.58 19:50:20 INFO - PROCESS | 1662 | Values: 80.0 88.0 59.0 63.0 63.0 58.0 57.0 61.0 75.0 83.0 19:50:20 INFO - PROCESS | 1662 | 19:50:20 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:94.00 Median:87.00 stddev:16.22 (18.6%) stddev-sans-first:16.61 19:50:20 INFO - PROCESS | 1662 | Values: 82.0 89.0 86.0 88.0 87.0 87.0 88.0 84.0 123.0 126.0 19:50:20 INFO - PROCESS | 1662 | 19:50:20 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:143.00 Median:141.50 stddev:6.13 (4.3%) stddev-sans-first:6.46 19:50:20 INFO - PROCESS | 1662 | Values: 141.0 142.0 154.0 138.0 138.0 139.0 152.0 137.0 148.0 141.0 19:50:20 INFO - PROCESS | 1662 | 19:50:20 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.60 Median:170.00 stddev:2.37 (1.4%) stddev-sans-first:2.44 19:50:20 INFO - PROCESS | 1662 | Values: 168.0 170.0 167.0 171.0 170.0 167.0 170.0 175.0 170.0 168.0 19:50:20 INFO - PROCESS | 1662 | 19:50:20 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.50 stddev:1.25 (1.9%) stddev-sans-first:1.32 19:50:20 INFO - PROCESS | 1662 | Values: 66.0 65.0 65.0 64.0 67.0 66.0 67.0 68.0 65.0 67.0 19:50:20 INFO - PROCESS | 1662 | -------- Summary: end -------- 19:50:20 INFO - PROCESS | 1662 | 19:50:21 INFO - TEST-INFO | 1662: exit 0 19:50:21 INFO - TEST-OK | kraken | took 60492ms 19:50:21 INFO - SUITE-END | took 1177s 19:50:21 INFO - Completed test suite (00:19:37) 19:50:21 INFO - javascript benchmark 19:50:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7727.418574572252, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3250.6261779895317, 3532.929341413171, 3543.8228088595565, 3585.551806024247, 3591.748577599569, 5506.752196151173, 5979.431953964372, 6048.969755151229, 6107.206063211776, 6121.785737499188, 6025.397460253972, 6064.272287325516, 6084.965555855504, 6095.481884039851, 6109.908351374733, 5388.7305634718105, 5396.541293989996, 5461.754221060042, 5532.640378375413, 5540.529055030327, 5500.0, 5508.091164957777, 5521.254630624874, 5527.834164975057, 5571.275734154554, 5300.284461597674, 5319.574434045287, 5377.516023557879, 5491.862703432406, 5523.392426833045, 5405.945940540583, 5419.943111093338, 5474.91787623182, 5485.177223416479, 5494.313210848644, 5322.840314790563, 5452.700101494416, 5466.426025267329, 5474.698891560963, 5517.779288828442, 6084.361142080106, 6093.451589356979, 6116.113163591299, 6118.9694051529905, 6160.414760597736, 6125.540694081744, 6236.158118653969, 6247.531435142337, 6252.06219067139, 6303.55875088744, 6435.099086127946, 6463.967680161616, 6479.83800404989, 6487.740490380379, 6489.383508566679, 6153.081016561361, 6177.598456100339, 6179.16581261533, 6190.845228869314, 6195.628262304322, 6299.905501417483, 6345.841353966188, 6348.904766428507, 6547.083408322838, 6650.767223147263, 6120.479759220427, 6261.68691565429, 6266.811995640138, 6294.559380843389, 6318.620882746958, 5461.481159289861, 6442.323556026549, 6462.0, 6462.967685161544, 6464.127342808754, 6240.126382306481, 6276.089966954766, 6362.554621176566, 6369.840753981188, 6387.744490220339, 6272.491003598597, 6318.589291696025, 6336.714847831859, 6364.554481186273, 6395.264544577433, 4616.215243408561, 4809.78355973982, 4893.853184404474, 4961.677490963076, 4969.27945447908, 4452.1763473757455, 4456.0, 4474.082813023322, 4513.616342610849, 4519.5480451954545, 5861.531077513789, 5995.940040599626, 5999.580029397987, 6004.819855404345, 6018.2176317079375, 6206.813795586139, 6255.12428260044, 6290.779822706275, 6329.208848893888, 6480.902786458207, 5993.790217342372, 5996.2504686914135, 6000.3999600040515, 6001.06983417571, 6027.547933904974, 5601.551875849922, 5610.270664813548, 5615.943840561624, 5616.9157462638095, 5630.634008789416, 6331.556791024768, 6392.776252831129, 6462.159919210472, 6481.546291759531, 6670.633115178614, 5307.832276899076, 6394.7442102317245, 6399.0, 6411.198600174978, 6415.294317624965, 6485.513586480938, 6523.510736694672, 6624.735010599522, 6625.304343044104, 6629.138212032405, 6224.0, 6266.342034086355, 6277.780277690259, 6332.0, 6344.68276586178, 6409.134766806422, 6454.580452270775, 6466.418022378009, 6477.870442591027, 6479.352064793483, 5271.973640131775, 5322.866928326823, 6129.570930035033, 6304.243490781136, 6320.241571011508, 6196.628202307876, 6216.036514340284, 6257.5932564383165, 6296.811095667137, 6324.810255692337, 6127.372786728296, 6131.988221943329, 6169.50643948474, 6175.722092505938, 6202.41077097675, 6314.0, 6324.7153878076515, 6332.905006424815, 6335.429811317003, 6347.428731414195, 6117.796956032597, 6120.969395152996, 6122.632642041492, 6130.386961303834, 6208.161898143873], "value": 5880.42246353566, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [751.8796992481205, 764.5565571968259, 804.4971892567146, 828.9544075075872, 975.6633961283354, 37915.241695166114, 38732.0, 39357.228924698386, 39987.0, 40003.59996400035, 31702.68297317026, 38699.41950870739, 38867.611323886755, 38897.0, 38912.61087389125, 8088.67645294189, 8199.95900020499, 10804.945975270151, 11096.778064438706, 11280.661580152608, 8609.827803443928, 10051.849222261671, 10867.565297388135, 11020.944895275512, 11117.499712512956, 10995.230333876632, 10996.450177491133, 11016.394098324588, 11022.669319920415, 11022.779544409106, 10940.070094042016, 10998.890011099907, 11022.724431889266, 11031.172662050338, 11055.778884422267, 10763.730906727316, 10782.892171078267, 10823.350598964087, 10828.620998265023, 10831.729206769895, 33305.83347083273, 33445.33109337824, 33491.49762753536, 33628.83185584081, 33782.662173378194, 3815.313243616148, 3844.7116466265143, 3888.572257051722, 3908.1597456546697, 3908.218356328746, 24961.50076998468, 25021.374465638506, 25127.87436062808, 26613.467730645472, 27294.0, 3114.4861097918815, 3165.335279591289, 3173.079806856009, 3180.6342270638943, 3287.1289108386295, 372296.0, 375376.0, 421349.0, 429056.0, 441783.79108104575, 43366.349504757454, 43538.0, 43663.563364366586, 43691.56308436939, 43739.90650233706, 45299.77350113228, 45374.0, 45422.77288613536, 45528.54471455309, 45534.772326138154, 13590.184588924696, 13664.043516953718, 13671.794923076162, 13680.863191368158, 13688.452461901412, 12108.455119519644, 12112.576059837864, 12115.273083614835, 12127.575534856238, 12148.817767733492, 11673.649790506299, 11714.531418743154, 11734.591848978178, 11783.76270491586, 11793.528258869721, 5873.20711703923, 5874.295084589877, 5924.0, 5928.110783382441, 5944.346121926585, 1144.547903578082, 1164.574930150489, 1170.836082948388, 1187.2460987273143, 1242.881926217008, 26397.0, 27029.459410812065, 27523.724762752518, 27582.44835103287, 27626.861865690542, 1076.6178006807627, 1176.9352685602203, 1261.1298204239047, 1288.239938436327, 1310.344827586207, 13564.796528052088, 13696.86303136956, 13935.512257070954, 14201.928990355189, 14240.216788076756, 503666.0, 504850.0, 505052.47473763133, 505597.0, 506252.0, 48589.514104859205, 58552.707236463546, 59198.0, 59452.70273648604, 59576.70211648914, 1489.41912654065, 1534.7314220011676, 1537.5694805454714, 1539.6689711712036, 1547.404249364003, 25217.49565008726, 26707.0, 26789.33026674271, 26818.463630727667, 26820.329491762863, 46005.539944600125, 47028.05943881172, 49358.506414936826, 49419.01161976812, 49642.0, 430448.6955130409, 431052.0, 431130.0, 432377.0, 432965.0, 26302.736972630028, 26613.467730645665, 31786.04641860748, 31908.680913190572, 32180.356392871545, 32062.519062213618, 38769.80615096907, 38947.6105238944, 38994.83015509539, 39614.603853962246, 26669.599956001064, 26782.59826102649, 27371.86314068417, 27420.725792741818, 28063.579046313913, 28062.43875122527, 28181.436371272048, 28699.0, 28720.28199294951, 28860.7113928858, 29423.0, 32438.675613243566, 33229.50155747714, 33345.83327083349, 33624.831875840464, 28607.9987200447, 28785.280367990967, 30033.0, 30385.17688938671, 30868.845655771576, 20938.267160649306, 22197.66703499481, 23579.056837726297, 23840.165594204118, 23998.76001240035], "value": 18497.8269394276, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.69626628162024, 412.27439706117224, 413.0747126436781, 428.1608048225478, 434.6848534812261, 20667.276645317397, 21508.89245553772, 22185.445363865892, 22373.99999999996, 22604.0, 21712.565748685018, 21945.780542194574, 22420.77579224207, 22508.662370064463, 22792.886035569798, 5353.437889021655, 6800.149981252331, 6903.0, 6970.7560235391775, 6974.128233970754, 6871.072405225305, 6886.965565172167, 6887.552309099918, 6974.372306492414, 6990.545614535039, 6581.078648989122, 6842.521023528355, 6930.168379794406, 6941.305869413069, 6961.23426423093, 6608.471322294205, 6867.828304292408, 6925.930740692605, 6935.306469353075, 6956.339147780953, 6634.834129146761, 6712.295209003033, 6715.395614394728, 6716.3955244028275, 6735.259121496631, 23249.070037198493, 26434.6034809478, 26751.0, 26958.73041269582, 27023.86488067547, 2975.3900450407614, 2996.1656213774013, 3000.6249218847643, 3001.639803223627, 3023.349979754363, 16245.268962896698, 16940.814143009993, 16994.915025424918, 17105.486835394833, 17357.045362505058, 2093.706881036656, 2113.154738104755, 2158.3956492182215, 2167.512309730314, 2232.285668586053, 105411.0, 123772.0, 128995.0, 130857.34571327177, 131127.0, 21175.36473905785, 22223.22218722337, 22569.66145507819, 22863.542729145658, 23210.883945580164, 30378.392432151235, 34640.13399664978, 34950.650493495246, 35352.64647353494, 35520.467192992124, 10740.194485413625, 11000.669979900616, 11159.944200278947, 11197.496112674786, 11202.66392008241, 8921.330900182513, 8968.506732129665, 8969.372143949991, 9153.862692059625, 9166.954165229265, 10490.265681402228, 10521.263511554118, 10592.523336449878, 10716.0, 10888.29226100301, 3892.618120567158, 4427.690061695715, 4517.457905051415, 4583.427071616048, 4599.241125214369, 1109.2013750099916, 1115.3865374044244, 1133.3709791065874, 1144.5364627325946, 1165.5687395663658, 22776.886115569316, 23093.769062309497, 23236.535269294858, 23358.64962025571, 23359.182428614917, 1134.7446824464594, 1137.4142316706736, 1148.2077366617007, 1168.1297433412162, 1179.262960649594, 9887.896825396825, 12021.278723276631, 12223.877761222275, 12248.81626775599, 12258.81611775824, 747197.0, 753224.0, 759344.0, 763076.0, 769660.0, 82886.58556707177, 98400.50799745956, 126501.30744116537, 129606.0, 133367.33316333356, 1257.3650306595111, 1307.601181639609, 1321.8678132186703, 1323.6227675112739, 1327.3694994877549, 19826.603467930272, 21276.255331063338, 21299.148034079084, 21383.35849924505, 21401.78598213998, 55502.0, 55897.44102558922, 55921.44078559162, 57085.0, 57411.71294143503, 72392.19019524554, 75271.24728752642, 76570.0, 78088.04779880548, 78397.60801195957, 7703.614819259126, 10534.315269507457, 11758.412079395825, 11940.298507462714, 12102.636920892388, 12734.172278802218, 12957.157784743962, 12988.35058247065, 13107.737845242851, 13337.466501340226, 11090.5563777448, 11253.324800511995, 11292.096632269562, 11327.773444530898, 11358.54565817358, 8905.599248033983, 10364.222683298933, 10548.261621686408, 10876.891231087904, 10890.50992705346, 9571.856422153534, 11131.886811318804, 11680.357580333164, 11825.881741182822, 11885.405729713653, 10810.621628242974, 11694.88305116938, 11920.582779602672, 11950.924416802529, 12045.879541204476, 10273.589056437659, 10587.047165755119, 10774.892251077703, 11229.887701122883, 11496.770064598828], "value": 11734.945787400056, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1563.1558958162593, 1614.2977804654977, 1623.1154021058514, 1627.7476991066385, 1632.5184070699142, 9535.99872013439, 9820.0, 10837.349759014454, 11378.2035257532, 11498.942505287472, 10719.732006699836, 11089.611863584776, 11093.33439993601, 11161.888381116187, 11212.831807522894, 2301.5051763870806, 2345.6129738593195, 2347.307544274443, 2348.6477028445784, 2351.564960482316, 2255.650374192003, 2284.4288927768057, 2288.370698058039, 2293.185918998748, 2297.035245197013, 2018.8889611071386, 2308.0190918859503, 2308.042162502559, 2326.6510023496526, 2362.9881850590723, 2315.374848790817, 2325.5697695926306, 2333.36999010268, 2339.064374250305, 2339.4736184358553, 2318.3508617587104, 2323.349462150584, 2325.267540724666, 2326.3718795925174, 2333.8599684019014, 11129.332240065542, 11155.832662510069, 11235.887641123565, 11255.606053788078, 11256.0, 5385.380681221632, 5484.8354549363185, 5533.944660553383, 5548.167774833767, 5551.555875529948, 3219.8390080495883, 3269.7057264846276, 3484.8431820568135, 3485.5120283160377, 3496.985874096509, 1157.2362240921036, 1174.8002839517217, 1850.4818650777804, 1893.583411649435, 1942.6794578894464, 15339.616509587238, 15340.693186136215, 15510.0, 15522.844771552252, 15725.921370393075, 4332.4367832181615, 4479.216137175981, 4516.977415112903, 4522.140793249272, 4529.09418116378, 11652.825207621892, 11658.70853228676, 11665.0, 11679.649610511698, 11685.0, 4829.203181475087, 5531.170324451365, 5546.91679624806, 5559.471850174227, 5568.610197286151, 4342.34864770287, 4515.661325400608, 5088.592912566985, 5116.872078198075, 5150.613703972217, 3669.6513831185994, 3732.8133593320226, 3765.2093060457064, 3811.7331786774657, 3822.961770382316, 967.308374512213, 968.0754879090451, 968.2060710217554, 978.3054031637474, 978.4569563892109, 931.7933276407069, 934.5420743835472, 935.5182081228173, 953.8950715421298, 991.2417000994245, 6445.484361251089, 6547.607143571401, 6556.737730490822, 6574.0, 6663.833404164935, 985.5367977050774, 986.2208855004609, 1003.9849402258972, 1005.3214080495636, 1017.1455976979373, 4038.818253178614, 4154.31453810124, 4180.581941805795, 4192.203481338536, 4269.103488267437, 13242.337883105805, 13729.656758580915, 13759.862401376058, 13765.0, 13804.447822087204, 13571.253581053137, 13646.522371716943, 13660.590182294547, 13661.521846735317, 13678.93160534191, 9309.581068851918, 10129.544170512345, 10339.948300258451, 10356.792864142826, 10382.169426445866, 6761.830954226184, 6787.389134977876, 6810.42111420515, 6910.343517365842, 6963.825904352229, 11825.113116516124, 12191.51233950632, 12225.633231003085, 12470.0, 12594.055445841781, 10060.949695251476, 12326.445309961253, 12488.937555312164, 12574.748505030031, 12628.684282893002, 7544.886826697494, 7970.721024764105, 8237.423380363318, 9032.503212323123, 9221.031791661779, 10201.489925503844, 10373.636922707668, 10404.791904162026, 10446.791064178522, 10447.05976462122, 8719.694810681596, 8821.779455513664, 8836.558172091498, 8872.24585910205, 8948.194662480408, 7440.255974402733, 8705.825883482421, 8716.38985271025, 8740.650373985223, 8793.296536276954, 6706.295838936841, 7692.230776922263, 7732.961335193288, 7827.0, 7859.017622797151, 5746.16680581305, 5748.655080695172, 5784.479396854135, 5890.263717035371, 5893.44012318829, 5330.387005494263, 5332.5467335276935, 5376.274202982548, 5426.484483974016, 5510.1459273812625], "value": 5073.767777244808, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.75393716524127, 359.29219437707724, 374.57111607209765, 382.9138443850133, 389.41976455081925, 4724.811007559693, 5074.3657042869645, 5152.562032227261, 5193.052605273023, 5234.738263086845, 4283.742975421477, 4439.822407103712, 4512.706674066183, 5154.381474223099, 5200.37595488541, 3960.3663413853787, 4181.372794080897, 4230.4500414946015, 4289.227938970984, 4349.847290468028, 3769.4157405602177, 4277.657787377002, 4325.5025672047805, 4335.263005289093, 4429.623482004017, 4317.611414972652, 4318.805653745589, 4332.220200363933, 4334.0031792687705, 4363.607275345218, 3550.2781151141676, 4103.7948102594755, 4269.5090064642345, 4321.438213032317, 4380.6276466500385, 3639.6178401267753, 3783.7891874599864, 3933.9016524587114, 3963.861264855745, 4010.7794071326066, 3989.68855849647, 5037.798488060474, 5069.61977851659, 5080.390353157608, 5123.487651234884, 4214.515330736974, 4481.955180448219, 4815.614750819926, 5033.974830125826, 5043.42000669924, 4863.577111520664, 4901.362822833009, 5253.50091741284, 5581.2186293918885, 5604.719764011783, 3832.4095500367316, 4098.303288440972, 4434.5343738907595, 4464.8214071437105, 4489.820407183709, 5250.579953603703, 5461.399246082928, 5919.55603329752, 5935.228420305332, 5941.792037278675, 3731.328360895065, 4012.2145645188193, 4096.91806163881, 4459.331100334911, 4541.931871021938, 4198.391233271159, 4628.837990670378, 4640.0, 4662.930056049162, 4676.410020593014, 4215.59951804578, 4576.0121467600475, 4673.228917228687, 4982.5052484254575, 4982.526659967298, 4994.325766021613, 5005.7997680092385, 5062.671171261261, 5477.603211181124, 5529.949309631238, 1498.7751837224503, 1556.486359501362, 1564.5853848730096, 1572.7025204206575, 1573.417835400909, 1103.2994048778924, 1177.9116566257565, 1193.9164258501996, 1194.946227419768, 1210.515793682534, 4356.67121527937, 4736.218523943511, 4862.294967229732, 4943.802247910044, 4947.134251505972, 5519.32312828278, 5778.064348443235, 5781.739821708034, 5809.506191973645, 5840.883182336414, 4696.839453851198, 4715.740634265148, 4770.825527663294, 4995.850124496271, 5025.39695236574, 2807.778616301915, 2841.5169421198034, 2974.3456439583256, 3012.1264833198566, 3024.7882648214854, 5334.626576139711, 5728.541716662657, 5832.708364581754, 5997.0, 6024.945634513942, 5334.8132815352055, 5387.892242155135, 5654.604177707521, 5854.619449735754, 5900.64596124234, 5203.427622961547, 5379.973100134474, 5433.315672141649, 5644.9435505644415, 6022.819315420545, 4859.611231101433, 5790.726040271134, 5798.130280457796, 5801.796937107181, 5830.53355731532, 4598.448186217675, 5074.797008119634, 5209.505097015777, 5316.3354580677415, 5629.915551266652, 5102.897942041212, 5289.68261904287, 5889.587728858939, 5931.0, 5955.791547295824, 4025.818838152232, 4582.747948862844, 4603.6777425579885, 4628.768561571976, 4642.489326174051, 4841.322214889989, 4907.386576677915, 5288.550473209821, 5601.971990140186, 5658.264425624642, 4973.428055773632, 5473.616846820684, 5518.0, 5548.417416171405, 5588.552915766811, 4959.578435832995, 5271.868203294948, 5487.259220005249, 5510.779568817363, 5534.371173692464, 5112.0, 5253.2645429639115, 5315.787368505216, 5349.967012535211, 5400.9459905400445, 5506.035654328877, 5530.267069727837, 5586.413426590149, 5598.272224610774, 5630.690312032713, 4411.184325558433, 5168.819091331786, 5398.704310965419, 5414.187871819258, 5426.457354264542], "value": 4219.398498806665, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [726.4333819620698, 743.7657138001525, 778.0702061037059, 828.8673812190049, 850.6342272822686, 15185.468508602202, 17166.57083572911, 17469.126543672828, 17480.951142931433, 17488.38790642328, 15378.384864605403, 15752.606184845356, 15815.446459373925, 15907.44323948662, 15981.92009039956, 984.34541030215, 994.4580203788952, 1004.9598016079385, 1005.0502275349797, 1009.2027298434228, 971.6259240192542, 981.8772653418323, 1003.5082809423367, 1004.4274763384838, 1005.5927349423495, 1049.3127001813825, 1052.9315594486372, 1053.0417320238585, 1053.2364036073861, 1054.3252318516118, 1042.29124195547, 1046.5865982936734, 1046.863907691999, 1049.0348879031271, 1049.5906596427399, 776.7242628866727, 781.8045488627843, 782.2998416417331, 783.4985609210053, 783.702193166593, 16754.497365079067, 16924.0, 17015.149242537824, 17907.552311192194, 18218.90890545552, 14249.287535623282, 14273.500427485093, 14366.56900292993, 14370.712585748226, 14374.640633984234, 11643.301401915827, 11761.88238117625, 11851.525938962432, 11940.940295298555, 12416.689582760413, 3689.6494832990825, 3745.8501659933568, 3762.7002322470394, 3831.4635950966886, 3888.416737489399, 101983.0, 105032.94967050308, 115546.0, 116156.25765613523, 117314.41342793232, 10941.23411361197, 11153.275037122561, 11238.887611123784, 11330.490127944262, 11386.032187264009, 20589.89705051465, 23393.883030584737, 23406.64890026651, 23658.763412366, 23820.0, 7657.693692252247, 7881.960590197127, 7931.206879312138, 8029.7189598363775, 8120.147384524658, 4405.713628614129, 4475.2546506862345, 4527.524609915978, 4535.931961020588, 4651.744154071558, 3476.0, 3527.788332699995, 3536.2750636119536, 3538.433850583894, 3556.4665300205174, 600.717662698531, 617.0528239152878, 618.8567540317621, 622.7296315516296, 630.3853742600927, 641.913341698874, 684.8732984397902, 696.9128858892639, 712.8966299886488, 724.2757242757243, 9021.458712477272, 10140.898591014144, 10343.431111288952, 10683.412412317242, 10751.759960707786, 664.9800505984829, 700.2577268095835, 705.9470539709542, 707.5330282013846, 715.6529083394549, 4707.599854012447, 5085.466026067284, 5120.180771076684, 5121.795128194831, 5133.0, 88109.55945220233, 100685.49657251814, 105358.0, 107375.0, 107660.0, 71397.0, 76909.6154519235, 82220.58889705513, 93480.0, 95885.5205723967, 69662.65168674124, 72331.63834180795, 72410.0, 72595.63702181455, 73544.0, 10838.891611083789, 10938.835917461403, 11011.83482247783, 11086.168537359568, 11181.776364472827, 49718.50281497139, 49851.25223121583, 50486.7475662634, 50642.74678626583, 51604.96790064253, 52685.47314526959, 64229.357706422335, 65476.34523654702, 65721.67139164274, 65893.0, 10002.649907253211, 10038.949805250926, 10342.276040677381, 10887.673369798918, 12907.290099044267, 14827.629309267355, 14915.030523015968, 14944.701105978036, 15155.393784248507, 15398.9230053849, 13867.514636987657, 13874.722505550035, 13893.374798133906, 14345.856541434452, 14532.346044427815, 13852.035749751738, 14142.92928535392, 14328.856711432752, 14604.488842890449, 14933.402663893323, 11639.88360116388, 13747.518836840663, 13841.377138029015, 14534.491292804702, 14624.34190461453, 10035.448050357301, 10143.08712215904, 10156.542955567165, 10199.949000254952, 10275.640352587623, 10759.246852720235, 11113.22207445471, 11379.54481820718, 11381.146414018816, 11395.886041139815], "value": 7790.651512044603, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1524.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 122.0, 119.0, 120.0, 119.0, 120.0, 120.0, 119.0, 121.0, 122.0], "value": 118.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 112.0, 111.0, 152.0, 113.0, 136.0, 138.0, 112.0, 131.0, 114.0], "value": 126.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 202.0, 203.0, 215.0, 201.0, 177.0, 257.0, 285.0, 234.0, 197.0], "value": 217.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 65.0, 68.0, 102.0, 59.0, 61.0, 60.0, 61.0, 75.0, 58.0], "value": 68.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 87.0, 74.0, 70.0, 70.0, 83.0, 70.0, 73.0, 82.0], "value": 76.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 105.0, 91.0, 91.0, 95.0, 102.0, 92.0, 101.0, 112.0, 91.0], "value": 98.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 99.0, 97.0, 98.0, 98.0, 98.0, 99.0, 102.0, 97.0, 98.0], "value": 98.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 98.0, 76.0, 141.0, 136.0, 93.0, 97.0, 119.0, 92.0, 93.0], "value": 108.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 66.0, 68.0, 71.0, 69.0, 71.0, 71.0, 70.0, 71.0], "value": 69.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 88.0, 59.0, 63.0, 63.0, 58.0, 57.0, 61.0, 75.0, 83.0], "value": 68.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 89.0, 86.0, 88.0, 87.0, 87.0, 88.0, 84.0, 123.0, 126.0], "value": 94.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 142.0, 154.0, 138.0, 138.0, 139.0, 152.0, 137.0, 148.0, 141.0], "value": 143.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 167.0, 171.0, 170.0, 167.0, 170.0, 175.0, 170.0, 168.0], "value": 169.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 64.0, 67.0, 66.0, 67.0, 68.0, 65.0, 67.0], "value": 66.0, "unit": "score"}]}]} 19:50:21 INFO - Return code: 0 19:50:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:50:21 ERROR - # TBPL SUCCESS # 19:50:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:50:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:50:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:50:21 INFO - 2017-06-04 19:50:21.819 screenresolution[1667:10891] starting screenresolution argv=screenresolution get 19:50:21 INFO - 2017-06-04 19:50:21.821 screenresolution[1667:10891] Display 0: 1600x1200x32@60 19:50:21 INFO - 2017-06-04 19:50:21.827 screenresolution[1668:10894] starting screenresolution argv=screenresolution list 19:50:21 INFO - Available Modes on Display 0 19:50:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:50:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:50:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:50:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:50:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:50:22 INFO - Intel Iris: 19:50:22 INFO - Chipset Model: Intel Iris 19:50:22 INFO - Type: GPU 19:50:22 INFO - Bus: Built-In 19:50:22 INFO - VRAM (Dynamic, Max): 1536 MB 19:50:22 INFO - Vendor: Intel (0x8086) 19:50:22 INFO - Device ID: 0x0a2e 19:50:22 INFO - Revision ID: 0x0009 19:50:22 INFO - Displays: 19:50:22 INFO - Display: 19:50:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:50:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:50:22 INFO - Display Serial Number: 1600x1200 60 19:50:22 INFO - Main Display: Yes 19:50:22 INFO - Mirror: Off 19:50:22 INFO - Online: Yes 19:50:22 INFO - Rotation: Supported 19:50:22 INFO - Return code: 0 19:50:22 INFO - Running post-action listener: _resource_record_post_action 19:50:22 INFO - [mozharness: 2017-06-05 02:50:22.076141Z] Finished run-tests step (success) 19:50:22 INFO - Running post-run listener: _resource_record_post_run 19:50:22 INFO - Total resource usage - Wall time: 1198s; CPU: 55.0%; Read bytes: 48171008; Write bytes: 511998976; Read time: 523; Write time: 2125 19:50:22 INFO - TinderboxPrint: CPU usage
54.6% 19:50:22 INFO - TinderboxPrint: I/O read bytes / time
48,171,008 / 523 19:50:22 INFO - TinderboxPrint: I/O write bytes / time
511,998,976 / 2,125 19:50:22 INFO - TinderboxPrint: CPU idle
2,178.3 (45.4%) 19:50:22 INFO - TinderboxPrint: CPU system
960.9 (20.0%) 19:50:22 INFO - TinderboxPrint: CPU user
1,654.4 (34.5%) 19:50:22 INFO - TinderboxPrint: Swap in / out
250,085,376 / 0 19:50:22 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201153024; Write bytes: 202536960; Read time: 15569; Write time: 413 19:50:22 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 47138816; Write bytes: 299533312; Read time: 503; Write time: 1657 19:50:22 INFO - Running post-run listener: _upload_blobber_files 19:50:22 INFO - Blob upload gear active. 19:50:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:50:22 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 19:50:22 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'] 19:50:22 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 19:50:22 INFO - (blobuploader) - INFO - Open directory for files ... 19:50:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:50:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:50:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:50:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:50:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:50:24 INFO - (blobuploader) - INFO - Done attempting. 19:50:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:50:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:50:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:50:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:50:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:50:25 INFO - (blobuploader) - INFO - Done attempting. 19:50:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:50:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:50:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:50:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:50:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:50:26 INFO - (blobuploader) - INFO - Done attempting. 19:50:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:50:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:50:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:50:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:50:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:50:28 INFO - (blobuploader) - INFO - Done attempting. 19:50:28 INFO - (blobuploader) - INFO - Iteration through files over. 19:50:28 INFO - Return code: 0 19:50:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:50:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:50:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f70dd163365b4eba29eaba68d94a5939c5ef351ac085377cb2340a7905b112b1a8d1ba1a9340f35109bb30577b20a756eb7bf6f7513a234e7216ad5b3561e094", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8f66a13f2289163ffa9804d2f60ecbfa64a0a3e85e143d045eccee9ec7fb2f52388ea29d011357aa2d7b1e60d09ff9a1daabdf63c5a98e310546f9a4bf743ab2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/96e64c48762d61841f74c77d93794c487883952741c04e743d99f54dfdc6a1b1b02e8238ed5bb3e86846a58235235b868e278c7c2e5e7299e23b647262a43700", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/313499f348e0dbace7353e39e364e577ac387bb7c3c17e00359c74a8e31aa9ca2d1721709ae87fa86acc466c83e23fbcad769b84afa8189bef0362c52f74e92a"} 19:50:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:50:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:50:28 INFO - Contents: 19:50:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f70dd163365b4eba29eaba68d94a5939c5ef351ac085377cb2340a7905b112b1a8d1ba1a9340f35109bb30577b20a756eb7bf6f7513a234e7216ad5b3561e094", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8f66a13f2289163ffa9804d2f60ecbfa64a0a3e85e143d045eccee9ec7fb2f52388ea29d011357aa2d7b1e60d09ff9a1daabdf63c5a98e310546f9a4bf743ab2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/96e64c48762d61841f74c77d93794c487883952741c04e743d99f54dfdc6a1b1b02e8238ed5bb3e86846a58235235b868e278c7c2e5e7299e23b647262a43700", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/313499f348e0dbace7353e39e364e577ac387bb7c3c17e00359c74a8e31aa9ca2d1721709ae87fa86acc466c83e23fbcad769b84afa8189bef0362c52f74e92a"} 19:50:28 INFO - Running post-run listener: copy_logs_to_upload_dir 19:50:28 INFO - Copying logs to upload dir... 19:50:28 INFO - mkdir: /builds/slave/test/build/upload/logs 19:50:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.383582 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-06-04 19:50:28.110762) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-04 19:50:28.114266) ========= 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.bsHhD7oxgm/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.vWuxy3ohxZ/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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/f70dd163365b4eba29eaba68d94a5939c5ef351ac085377cb2340a7905b112b1a8d1ba1a9340f35109bb30577b20a756eb7bf6f7513a234e7216ad5b3561e094", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8f66a13f2289163ffa9804d2f60ecbfa64a0a3e85e143d045eccee9ec7fb2f52388ea29d011357aa2d7b1e60d09ff9a1daabdf63c5a98e310546f9a4bf743ab2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/96e64c48762d61841f74c77d93794c487883952741c04e743d99f54dfdc6a1b1b02e8238ed5bb3e86846a58235235b868e278c7c2e5e7299e23b647262a43700", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/313499f348e0dbace7353e39e364e577ac387bb7c3c17e00359c74a8e31aa9ca2d1721709ae87fa86acc466c83e23fbcad769b84afa8189bef0362c52f74e92a"} build_url:https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011131 build_url: 'https://queue.taskcluster.net/v1/task/Sm99qXQnSCqYQ8KB7YFP-A/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/f70dd163365b4eba29eaba68d94a5939c5ef351ac085377cb2340a7905b112b1a8d1ba1a9340f35109bb30577b20a756eb7bf6f7513a234e7216ad5b3561e094", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8f66a13f2289163ffa9804d2f60ecbfa64a0a3e85e143d045eccee9ec7fb2f52388ea29d011357aa2d7b1e60d09ff9a1daabdf63c5a98e310546f9a4bf743ab2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/96e64c48762d61841f74c77d93794c487883952741c04e743d99f54dfdc6a1b1b02e8238ed5bb3e86846a58235235b868e278c7c2e5e7299e23b647262a43700", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/313499f348e0dbace7353e39e364e577ac387bb7c3c17e00359c74a8e31aa9ca2d1721709ae87fa86acc466c83e23fbcad769b84afa8189bef0362c52f74e92a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-04 19:50:28.147730) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 19:50:28.148078) ========= 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.bsHhD7oxgm/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.vWuxy3ohxZ/Listeners TMPDIR=/var/folders/29/m_1c7lhj4kb13s_f9szps0wr00000w/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.005081 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 19:50:28.198146) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-04 19:50:28.198701) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-04 19:50:28.199263) ========= ========= Total master_lag: 0.18 =========