builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0060 starttime: 1495488783.72 results: success (0) buildid: 20170522133621 builduid: eb23d765494845fda0e59bf890121674 revision: 64096bfc5a89e6c92911a3257fdca0f169418022 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 14:33:03.722335) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 14:33:03.722807) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 14:33:03.723084) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 14:33:03.748134) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 14:33:03.748533) ========= 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.W6ubdOovLY/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.38KVJdw3Gl/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-22 14:33:03-- 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% 164M=0s 2017-05-22 14:33:03 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135887 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 14:33:03.906862) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 14:33:03.907171) ========= 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.W6ubdOovLY/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.38KVJdw3Gl/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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.027775 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 14:33:03.955282) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-22 14:33:03.955641) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 64096bfc5a89e6c92911a3257fdca0f169418022 --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 64096bfc5a89e6c92911a3257fdca0f169418022 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.W6ubdOovLY/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.38KVJdw3Gl/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-22 14:33:04,058 truncating revision to first 12 chars 2017-05-22 14:33:04,058 Setting DEBUG logging. 2017-05-22 14:33:04,058 attempt 1/10 2017-05-22 14:33:04,058 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/64096bfc5a89?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-22 14:33:05,174 unpacking tar archive at: mozilla-beta-64096bfc5a89/testing/mozharness/ program finished with exit code 0 elapsedTime=1.388662 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-22 14:33:05.366152) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 14:33:05.366478) ========= script_repo_revision: 64096bfc5a89e6c92911a3257fdca0f169418022 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 14:33:05.366849) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 14:33:05.367108) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 14:33:05.385366) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-05-22 14:33:05.385650) ========= /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.W6ubdOovLY/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.38KVJdw3Gl/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 14:33:05 INFO - MultiFileLogger online at 20170522 14:33:05 in /builds/slave/test 14:33:05 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 14:33:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:33:05 INFO - {'append_to_log': False, 14:33:05 INFO - 'base_work_dir': '/builds/slave/test', 14:33:05 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:33:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:33:05 INFO - 'branch': 'Mozilla-Beta', 14:33:05 INFO - 'buildbot_json_path': 'buildprops.json', 14:33:05 INFO - 'config_files': ('talos/mac_config.py',), 14:33:05 INFO - 'default_actions': ('clobber', 14:33:05 INFO - 'read-buildbot-config', 14:33:05 INFO - 'download-and-extract', 14:33:05 INFO - 'populate-webroot', 14:33:05 INFO - 'create-virtualenv', 14:33:05 INFO - 'install', 14:33:05 INFO - 'run-tests'), 14:33:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:33:05 INFO - 'download_minidump_stackwalk': True, 14:33:05 INFO - 'download_symbols': 'ondemand', 14:33:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:33:05 INFO - 'tooltool.py': '/tools/tooltool.py', 14:33:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:33:05 INFO - '/tools/misc-python/virtualenv.py')}, 14:33:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:33:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:33:05 INFO - 'gecko_profile': False, 14:33:05 INFO - 'gecko_profile_interval': 0, 14:33:05 INFO - 'installer_path': 'installer.exe', 14:33:05 INFO - 'log_level': 'info', 14:33:05 INFO - 'log_name': 'talos', 14:33:05 INFO - 'log_to_console': True, 14:33:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:33:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:33:05 INFO - 'opt_config_files': (), 14:33:05 INFO - 'pip_index': False, 14:33:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:33:05 INFO - 'cmd': ('bash', 14:33:05 INFO - '-c', 14:33:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:33:05 INFO - 'enabled': True, 14:33:05 INFO - 'halt_on_failure': False, 14:33:05 INFO - 'name': 'check_screen_resolution'},), 14:33:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:33:05 INFO - 'cmd': ('bash', 14:33:05 INFO - '-c', 14:33:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:33:05 INFO - 'enabled': True, 14:33:05 INFO - 'halt_on_failure': False, 14:33:05 INFO - 'name': 'check_screen_resolution'},), 14:33:05 INFO - 'run_cmd_checks_enabled': True, 14:33:05 INFO - 'suite': 'dromaeojs-e10s', 14:33:05 INFO - 'system_bits': '32', 14:33:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:33:05 INFO - 'title': 't-yosemite-r7-0060', 14:33:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:33:05 INFO - 'use_talos_json': True, 14:33:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:33:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:33:05 INFO - 'work_dir': 'build'} 14:33:05 INFO - [mozharness: 2017-05-22 21:33:05.591293Z] Running clobber step. 14:33:05 INFO - Running pre-action listener: _resource_record_pre_action 14:33:05 INFO - Running main action method: clobber 14:33:05 INFO - rmtree: /builds/slave/test/build 14:33:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:33:08 INFO - Running post-action listener: _resource_record_post_action 14:33:08 INFO - [mozharness: 2017-05-22 21:33:08.190875Z] Finished clobber step (success) 14:33:08 INFO - [mozharness: 2017-05-22 21:33:08.191113Z] Running read-buildbot-config step. 14:33:08 INFO - Running pre-action listener: _resource_record_pre_action 14:33:08 INFO - Running main action method: read_buildbot_config 14:33:08 INFO - Using buildbot properties: 14:33:08 INFO - { 14:33:08 INFO - "project": "", 14:33:08 INFO - "product": "firefox", 14:33:08 INFO - "script_repo_revision": "production", 14:33:08 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 14:33:08 INFO - "repository": "", 14:33:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 14:33:08 INFO - "buildid": "20170522133621", 14:33:08 INFO - "pgo_build": "False", 14:33:08 INFO - "basedir": "/builds/slave/test", 14:33:08 INFO - "buildnumber": 0, 14:33:08 INFO - "slavename": "t-yosemite-r7-0060", 14:33:08 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 14:33:08 INFO - "platform": "yosemite_r7", 14:33:08 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 14:33:08 INFO - "branch": "mozilla-beta", 14:33:08 INFO - "repo_path": "releases/mozilla-beta", 14:33:08 INFO - "revision": "64096bfc5a89e6c92911a3257fdca0f169418022", 14:33:08 INFO - "stage_platform": "macosx64", 14:33:08 INFO - "builduid": "eb23d765494845fda0e59bf890121674", 14:33:08 INFO - "slavebuilddir": "test" 14:33:08 INFO - } 14:33:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 14:33:08 INFO - Running post-action listener: _resource_record_post_action 14:33:08 INFO - [mozharness: 2017-05-22 21:33:08.196546Z] Finished read-buildbot-config step (success) 14:33:08 INFO - [mozharness: 2017-05-22 21:33:08.196773Z] Running download-and-extract step. 14:33:08 INFO - Running pre-action listener: _resource_record_pre_action 14:33:08 INFO - Running main action method: download_and_extract 14:33:08 INFO - mkdir: /builds/slave/test/build/tests 14:33:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:08 INFO - trying https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 14:33:08 INFO - Downloading https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/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 14:33:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/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 14:33:09 INFO - Downloaded 1287 bytes. 14:33:09 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 14:33:09 INFO - Using the following test package requirements: 14:33:09 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:33:09 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 14:33:09 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 14:33:09 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:33:09 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 14:33:09 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:33:09 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 14:33:09 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 14:33:09 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:33:09 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 14:33:09 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 14:33:09 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:33:09 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 14:33:09 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:33:09 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 14:33:09 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:33:09 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 14:33:09 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:33:09 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 14:33:09 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 14:33:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 14:33:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 14:33:09 INFO - Fetch https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 14:33:11 INFO - Expected file size: 51393220 14:33:11 INFO - Obtained file size: 51393220 14:33:27 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 14:33:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 14:33:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 14:33:27 INFO - Fetch https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 14:33:29 INFO - Expected file size: 51393220 14:33:29 INFO - Obtained file size: 51393220 14:33:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 14:33:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 14:33:44 INFO - Fetch https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 14:33:45 INFO - Expected file size: 14404449 14:33:45 INFO - Obtained file size: 14404449 14:33:46 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')]} 14:33:46 INFO - trying https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 14:33:46 INFO - Downloading https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 14:33:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/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 14:33:48 INFO - Downloaded 59649299 bytes. 14:33:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 14:33:48 INFO - mkdir: /builds/slave/test/properties 14:33:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:33:48 INFO - Writing to file /builds/slave/test/properties/build_url 14:33:48 INFO - Contents: 14:33:48 INFO - build_url:https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 14:33:48 INFO - Running post-action listener: _resource_record_post_action 14:33:48 INFO - Running post-action listener: set_extra_try_arguments 14:33:48 INFO - [mozharness: 2017-05-22 21:33:48.674872Z] Finished download-and-extract step (success) 14:33:48 INFO - [mozharness: 2017-05-22 21:33:48.674999Z] Running populate-webroot step. 14:33:48 INFO - Running pre-action listener: _resource_record_pre_action 14:33:48 INFO - Running main action method: populate_webroot 14:33:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:33:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:33:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:33:48 INFO - '--tppagecycles', 14:33:48 INFO - '7'], 14:33:48 INFO - 'tests': ['tsvgm']}}, 14:33:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:33:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:33:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:33:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:33:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:33:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:33:48 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:33:48 INFO - 'talos_options': ['--disable-e10s'], 14:33:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:33:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:33:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:33:48 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:33:48 INFO - 'talos_options': ['--disable-e10s'], 14:33:48 INFO - 'tests': ['damp', 'tps']}, 14:33:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:33:48 INFO - 'tests': ['damp', 'tps']}, 14:33:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:33:48 INFO - 'tests': ['dromaeo_dom']}, 14:33:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:33:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:33:48 INFO - 'tests': ['basic_compositor_video']}, 14:33:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:33:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:33:48 INFO - 'tests': ['a11yr', 14:33:48 INFO - 'ts_paint', 14:33:48 INFO - 'tpaint', 14:33:48 INFO - 'sessionrestore', 14:33:48 INFO - 'sessionrestore_no_auto_restore', 14:33:48 INFO - 'tabpaint']}, 14:33:48 INFO - 'other-e10s': {'tests': ['a11yr', 14:33:48 INFO - 'ts_paint', 14:33:48 INFO - 'tpaint', 14:33:48 INFO - 'sessionrestore', 14:33:48 INFO - 'sessionrestore_no_auto_restore', 14:33:48 INFO - 'tabpaint']}, 14:33:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:33:48 INFO - 'ts_paint', 14:33:48 INFO - 'tpaint', 14:33:48 INFO - 'sessionrestore', 14:33:48 INFO - 'sessionrestore_no_auto_restore', 14:33:48 INFO - 'tabpaint']}, 14:33:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:33:48 INFO - 'ts_paint', 14:33:48 INFO - 'tpaint', 14:33:48 INFO - 'sessionrestore', 14:33:48 INFO - 'sessionrestore_no_auto_restore', 14:33:48 INFO - 'tabpaint']}, 14:33:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:33:48 INFO - 'tests': ['a11yr', 14:33:48 INFO - 'ts_paint', 14:33:48 INFO - 'tpaint', 14:33:48 INFO - 'sessionrestore', 14:33:48 INFO - 'sessionrestore_no_auto_restore', 14:33:48 INFO - 'tabpaint']}, 14:33:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:33:48 INFO - 'tests': ['a11yr', 14:33:48 INFO - 'ts_paint', 14:33:48 INFO - 'tpaint', 14:33:48 INFO - 'sessionrestore', 14:33:48 INFO - 'sessionrestore_no_auto_restore', 14:33:48 INFO - 'tabpaint']}, 14:33:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:33:48 INFO - 'tests': ['tsvgx', 14:33:48 INFO - 'tsvgr_opacity', 14:33:48 INFO - 'tart', 14:33:48 INFO - 'tscrollx', 14:33:48 INFO - 'cart', 14:33:48 INFO - 'tsvg_static']}, 14:33:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:33:48 INFO - 'tsvgr_opacity', 14:33:48 INFO - 'tart', 14:33:48 INFO - 'tscrollx', 14:33:48 INFO - 'cart', 14:33:48 INFO - 'tsvg_static']}, 14:33:48 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:33:48 INFO - 'talos_options': ['--disable-e10s'], 14:33:48 INFO - 'tests': ['tp5o']}, 14:33:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:33:48 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:33:48 INFO - 'talos_options': ['--disable-e10s', 14:33:48 INFO - '--xperf_path', 14:33:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:33:48 INFO - 'tests': ['tp5n']}, 14:33:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:33:48 INFO - 'talos_options': ['--xperf_path', 14:33:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:33:48 INFO - 'tests': ['tp5n']}}, 14:33:48 INFO - 'talos.zip': {'path': '', 14:33:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:33:48 INFO - Running post-action listener: _resource_record_post_action 14:33:48 INFO - [mozharness: 2017-05-22 21:33:48.695718Z] Finished populate-webroot step (success) 14:33:48 INFO - [mozharness: 2017-05-22 21:33:48.695855Z] Running create-virtualenv step. 14:33:48 INFO - Running pre-action listener: _resource_record_pre_action 14:33:48 INFO - Running main action method: create_virtualenv 14:33:48 INFO - Creating virtualenv /builds/slave/test/build/venv 14:33:48 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 14:33:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:33:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:33:48 INFO - Using real prefix '/tools/python27' 14:33:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:33:49 INFO - Installing distribute.............................................................................................................................................................................................done. 14:33:52 INFO - Installing pip.................done. 14:33:52 INFO - Return code: 0 14:33:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:33:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:33:52 INFO - Reading from file tmpfile_stdout 14:33:52 INFO - Output received: 14:33:52 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) 14:33:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:33:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:52 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 0x109b8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd99568f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10996cd30>, '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 0x109893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109961be8>, '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.W6ubdOovLY/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.38KVJdw3Gl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 14:33:52 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 14:33:52 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 14:33:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W6ubdOovLY/Render', 14:33:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:33:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:33:52 INFO - 'HOME': '/Users/cltbld', 14:33:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:33:52 INFO - 'LOGNAME': 'cltbld', 14:33:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:52 INFO - 'MOZ_NO_REMOTE': '1', 14:33:52 INFO - 'NO_EM_RESTART': '1', 14:33:52 INFO - 'PAGER': '/bin/cat', 14:33:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:33:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:52 INFO - 'PWD': '/builds/slave/test', 14:33:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:33:52 INFO - 'SHELL': '/bin/bash', 14:33:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.38KVJdw3Gl/Listeners', 14:33:52 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 14:33:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:33:52 INFO - 'USER': 'cltbld', 14:33:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:33:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:33:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:52 INFO - 'XPC_FLAGS': '0x0', 14:33:52 INFO - 'XPC_SERVICE_NAME': '0', 14:33:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:33:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:52 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 14:33:52 INFO - Cleaning up... 14:33:52 INFO - Return code: 0 14:33:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:33:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:52 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 0x109b8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd99568f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10996cd30>, '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 0x109893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109961be8>, '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.W6ubdOovLY/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.38KVJdw3Gl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 14:33:52 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 14:33:52 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 14:33:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W6ubdOovLY/Render', 14:33:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:33:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:33:52 INFO - 'HOME': '/Users/cltbld', 14:33:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:33:52 INFO - 'LOGNAME': 'cltbld', 14:33:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:52 INFO - 'MOZ_NO_REMOTE': '1', 14:33:52 INFO - 'NO_EM_RESTART': '1', 14:33:52 INFO - 'PAGER': '/bin/cat', 14:33:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:33:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:52 INFO - 'PWD': '/builds/slave/test', 14:33:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:33:52 INFO - 'SHELL': '/bin/bash', 14:33:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.38KVJdw3Gl/Listeners', 14:33:52 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 14:33:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:33:52 INFO - 'USER': 'cltbld', 14:33:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:33:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:33:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:52 INFO - 'XPC_FLAGS': '0x0', 14:33:52 INFO - 'XPC_SERVICE_NAME': '0', 14:33:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:33:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:53 INFO - Downloading/unpacking psutil>=3.1.1 14:33:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:33:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:33:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:33:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:33:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:33:55 INFO - Installing collected packages: psutil 14:33:55 INFO - Running setup.py install for psutil 14:33:55 INFO - building 'psutil._psutil_osx' extension 14:33:55 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 14:33:55 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 14:33:55 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 14:33:55 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 14:33:56 INFO - building 'psutil._psutil_posix' extension 14:33:56 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 14:33:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:33:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:33:56 INFO - ^ 14:33:56 INFO - 1 warning generated. 14:33:56 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 14:33:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:33:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:33:56 INFO - Successfully installed psutil 14:33:56 INFO - Cleaning up... 14:33:56 INFO - Return code: 0 14:33:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:33:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109b8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd99568f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10996cd30>, '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 0x109893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109961be8>, '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.W6ubdOovLY/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.38KVJdw3Gl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 14:33:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:33:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:33:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W6ubdOovLY/Render', 14:33:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:33:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:33:56 INFO - 'HOME': '/Users/cltbld', 14:33:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:33:56 INFO - 'LOGNAME': 'cltbld', 14:33:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:56 INFO - 'MOZ_NO_REMOTE': '1', 14:33:56 INFO - 'NO_EM_RESTART': '1', 14:33:56 INFO - 'PAGER': '/bin/cat', 14:33:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:33:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:56 INFO - 'PWD': '/builds/slave/test', 14:33:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:33:56 INFO - 'SHELL': '/bin/bash', 14:33:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.38KVJdw3Gl/Listeners', 14:33:56 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 14:33:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:33:56 INFO - 'USER': 'cltbld', 14:33:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:33:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:33:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:56 INFO - 'XPC_FLAGS': '0x0', 14:33:56 INFO - 'XPC_SERVICE_NAME': '0', 14:33:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:33:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:33:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:33:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:33:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:33:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:33:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:33:58 INFO - Installing collected packages: mozsystemmonitor 14:33:58 INFO - Running setup.py install for mozsystemmonitor 14:33:58 INFO - Successfully installed mozsystemmonitor 14:33:58 INFO - Cleaning up... 14:33:58 INFO - Return code: 0 14:33:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:33:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:33:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:33:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:33:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109b8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd99568f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10996cd30>, '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 0x109893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109961be8>, '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.W6ubdOovLY/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.38KVJdw3Gl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 14:33:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:33:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:33:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W6ubdOovLY/Render', 14:33:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:33:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:33:58 INFO - 'HOME': '/Users/cltbld', 14:33:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:33:58 INFO - 'LOGNAME': 'cltbld', 14:33:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:33:58 INFO - 'MOZ_NO_REMOTE': '1', 14:33:58 INFO - 'NO_EM_RESTART': '1', 14:33:58 INFO - 'PAGER': '/bin/cat', 14:33:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:33:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:33:58 INFO - 'PWD': '/builds/slave/test', 14:33:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:33:58 INFO - 'SHELL': '/bin/bash', 14:33:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.38KVJdw3Gl/Listeners', 14:33:58 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 14:33:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:33:58 INFO - 'USER': 'cltbld', 14:33:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:33:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:33:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:33:58 INFO - 'XPC_FLAGS': '0x0', 14:33:58 INFO - 'XPC_SERVICE_NAME': '0', 14:33:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:33:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:33:58 INFO - Downloading/unpacking jsonschema==2.5.1 14:33:58 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 14:33:58 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 14:33:58 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 14:33:58 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 14:33:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:33:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:33:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:33:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:33:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:00 INFO - Downloading functools32-3.2.3-2.tar.gz 14:34:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:34:00 INFO - warning: no files found matching '*.txt' 14:34:00 INFO - no previously-included directories found matching 'build' 14:34:00 INFO - no previously-included directories found matching 'dist' 14:34:00 INFO - no previously-included directories found matching '.git*' 14:34:00 INFO - Installing collected packages: jsonschema, functools32 14:34:00 INFO - Running setup.py install for functools32 14:34:00 INFO - warning: no files found matching '*.txt' 14:34:00 INFO - no previously-included directories found matching 'build' 14:34:00 INFO - no previously-included directories found matching 'dist' 14:34:00 INFO - no previously-included directories found matching '.git*' 14:34:00 INFO - Successfully installed jsonschema functools32 14:34:00 INFO - Cleaning up... 14:34:00 INFO - Return code: 0 14:34:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:34: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')]} 14:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:01 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')]} 14:34:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:01 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 0x109b8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd99568f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10996cd30>, '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 0x109893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109961be8>, '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.W6ubdOovLY/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.38KVJdw3Gl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 14:34:01 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 14:34:01 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 14:34:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W6ubdOovLY/Render', 14:34:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:01 INFO - 'HOME': '/Users/cltbld', 14:34:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:01 INFO - 'LOGNAME': 'cltbld', 14:34:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:01 INFO - 'MOZ_NO_REMOTE': '1', 14:34:01 INFO - 'NO_EM_RESTART': '1', 14:34:01 INFO - 'PAGER': '/bin/cat', 14:34:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:01 INFO - 'PWD': '/builds/slave/test', 14:34:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:01 INFO - 'SHELL': '/bin/bash', 14:34:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.38KVJdw3Gl/Listeners', 14:34:01 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 14:34:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:01 INFO - 'USER': 'cltbld', 14:34:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:01 INFO - 'XPC_FLAGS': '0x0', 14:34:01 INFO - 'XPC_SERVICE_NAME': '0', 14:34:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:34:01 INFO - Cleaning up... 14:34:01 INFO - Return code: 0 14:34:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:34:01 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')]} 14:34:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:01 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')]} 14:34:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109b8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd99568f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10996cd30>, '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 0x109893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109961be8>, '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.W6ubdOovLY/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.38KVJdw3Gl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 14:34:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:34:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:34:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W6ubdOovLY/Render', 14:34:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:02 INFO - 'HOME': '/Users/cltbld', 14:34:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:02 INFO - 'LOGNAME': 'cltbld', 14:34:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:02 INFO - 'MOZ_NO_REMOTE': '1', 14:34:02 INFO - 'NO_EM_RESTART': '1', 14:34:02 INFO - 'PAGER': '/bin/cat', 14:34:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:02 INFO - 'PWD': '/builds/slave/test', 14:34:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:02 INFO - 'SHELL': '/bin/bash', 14:34:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.38KVJdw3Gl/Listeners', 14:34:02 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 14:34:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:02 INFO - 'USER': 'cltbld', 14:34:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:02 INFO - 'XPC_FLAGS': '0x0', 14:34:02 INFO - 'XPC_SERVICE_NAME': '0', 14:34:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:02 INFO - Downloading/unpacking blobuploader==1.2.4 14:34:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:04 INFO - Downloading blobuploader-1.2.4.tar.gz 14:34:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:34:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:34: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 14:34: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 14:34: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 14:34: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 14:34:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:34:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:34:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:34:05 INFO - Downloading docopt-0.6.1.tar.gz 14:34:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:34:05 INFO - Installing collected packages: blobuploader, requests, docopt 14:34:05 INFO - Running setup.py install for blobuploader 14:34:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:34:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:34:05 INFO - Running setup.py install for requests 14:34:06 INFO - Running setup.py install for docopt 14:34:06 INFO - Successfully installed blobuploader requests docopt 14:34:06 INFO - Cleaning up... 14:34:06 INFO - Return code: 0 14:34:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:34: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')]} 14:34:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34: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')]} 14:34:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:06 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 0x109b8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd99568f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10996cd30>, '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 0x109893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109961be8>, '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.W6ubdOovLY/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.38KVJdw3Gl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 14:34:06 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 14:34:06 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 14:34:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W6ubdOovLY/Render', 14:34:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:06 INFO - 'HOME': '/Users/cltbld', 14:34:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:06 INFO - 'LOGNAME': 'cltbld', 14:34:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:06 INFO - 'MOZ_NO_REMOTE': '1', 14:34:06 INFO - 'NO_EM_RESTART': '1', 14:34:06 INFO - 'PAGER': '/bin/cat', 14:34:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:06 INFO - 'PWD': '/builds/slave/test', 14:34:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:06 INFO - 'SHELL': '/bin/bash', 14:34:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.38KVJdw3Gl/Listeners', 14:34:06 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 14:34:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:06 INFO - 'USER': 'cltbld', 14:34:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:06 INFO - 'XPC_FLAGS': '0x0', 14:34:06 INFO - 'XPC_SERVICE_NAME': '0', 14:34:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:34:06 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-Pl_rFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:34:06 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-khlg0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:34:06 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-OFVzmQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:34:06 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-iFr0s7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:34:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:34:06 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-OHrlwK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:34:07 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-SXz5fU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:34:07 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-VUi07p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:34:07 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-cpOqy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:34:07 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-7Kq__q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:34:07 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-5D6yNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:34:07 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-h1qVRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:34:07 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-cd5Fgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:34:07 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-UDW7ej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:34:07 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-kjgOji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:34:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:34:07 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-dBOtE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:34:08 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-gt_reR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:34:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:34:08 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-euOmGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:34:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:34:08 INFO - Running setup.py install for manifestparser 14:34:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:34:08 INFO - Running setup.py install for mozcrash 14:34:08 INFO - Running setup.py install for mozdebug 14:34:08 INFO - Running setup.py install for mozdevice 14:34:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:34:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:34:08 INFO - Running setup.py install for mozfile 14:34:09 INFO - Running setup.py install for mozhttpd 14:34:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:34:09 INFO - Running setup.py install for mozinfo 14:34:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:34:09 INFO - Running setup.py install for mozInstall 14:34:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:34:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:34:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:34:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:34:09 INFO - Running setup.py install for mozleak 14:34:09 INFO - Running setup.py install for mozlog 14:34:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:34:09 INFO - Running setup.py install for moznetwork 14:34:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:34:09 INFO - Running setup.py install for mozprocess 14:34:10 INFO - Running setup.py install for mozprofile 14:34:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:34:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:34:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:34:10 INFO - Running setup.py install for mozrunner 14:34:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:34:10 INFO - Running setup.py install for mozscreenshot 14:34:10 INFO - Running setup.py install for moztest 14:34:10 INFO - Running setup.py install for mozversion 14:34:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:34:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:34:10 INFO - Cleaning up... 14:34:10 INFO - Return code: 0 14:34:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:34: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')]} 14:34:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34: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')]} 14:34:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:10 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 0x109b8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd99568f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10996cd30>, '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 0x109893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109961be8>, '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.W6ubdOovLY/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.38KVJdw3Gl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 14:34:10 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 14:34:10 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 14:34:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W6ubdOovLY/Render', 14:34:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:10 INFO - 'HOME': '/Users/cltbld', 14:34:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:10 INFO - 'LOGNAME': 'cltbld', 14:34:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:10 INFO - 'MOZ_NO_REMOTE': '1', 14:34:10 INFO - 'NO_EM_RESTART': '1', 14:34:10 INFO - 'PAGER': '/bin/cat', 14:34:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:10 INFO - 'PWD': '/builds/slave/test', 14:34:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:10 INFO - 'SHELL': '/bin/bash', 14:34:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.38KVJdw3Gl/Listeners', 14:34:10 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 14:34:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:10 INFO - 'USER': 'cltbld', 14:34:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:10 INFO - 'XPC_FLAGS': '0x0', 14:34:10 INFO - 'XPC_SERVICE_NAME': '0', 14:34:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:34:11 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-5ZV_Pp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:34:11 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)) 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:34:11 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-ClBBnD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:34:11 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)) 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:34:11 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-rTBCgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:34:11 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)) 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:34:11 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-60nAZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:34:11 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)) 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:34:11 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-CNoOn3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:34:11 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)) 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:34:11 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-14Yop9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:34:11 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)) 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:34:11 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-l0ShT7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:34:11 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)) 14:34:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:34:11 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-RvpF6c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:34:12 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)) 14:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:34:12 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-cv4acJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:34:12 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)) 14:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:34:12 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-ZNPRsa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:34:12 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)) 14:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:34:12 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-h0_U2M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:34:12 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)) 14:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:34:12 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-KE2aSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:34:12 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)) 14:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:34:12 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-oOaRmD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:34:12 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)) 14:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:34:12 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-vKmxio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:34:12 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)) 14:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:34:12 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-IrZeq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:34:12 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)) 14:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:34:12 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-LSprBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:34:12 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)) 14:34:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:34:12 INFO - Running setup.py (path:/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/pip-sSKH_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:34:12 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)) 14:34:12 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)) 14:34:12 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)) 14:34:12 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)) 14:34:12 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)) 14:34:12 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)) 14:34:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:34: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 14:34: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 14:34: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 14:34: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 14:34:14 INFO - Downloading blessings-1.6.tar.gz 14:34:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:34:14 INFO - Installing collected packages: blessings 14:34:14 INFO - Running setup.py install for blessings 14:34:14 INFO - Successfully installed blessings 14:34:14 INFO - Cleaning up... 14:34:14 INFO - Return code: 0 14:34:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:34:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:34:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:34:14 INFO - Reading from file tmpfile_stdout 14:34:14 INFO - Current package versions: 14:34:14 INFO - blessings == 1.6 14:34:14 INFO - blobuploader == 1.2.4 14:34:14 INFO - docopt == 0.6.1 14:34:14 INFO - functools32 == 3.2.3-2 14:34:14 INFO - jsonschema == 2.5.1 14:34:14 INFO - manifestparser == 1.1 14:34:14 INFO - mozInstall == 1.12 14:34:14 INFO - mozcrash == 1.0 14:34:14 INFO - mozdebug == 0.1 14:34:14 INFO - mozdevice == 0.49 14:34:14 INFO - mozfile == 1.2 14:34:14 INFO - mozhttpd == 0.7 14:34:14 INFO - mozinfo == 0.9 14:34:14 INFO - mozleak == 0.1 14:34:14 INFO - mozlog == 3.4 14:34:14 INFO - moznetwork == 0.27 14:34:14 INFO - mozprocess == 0.25 14:34:14 INFO - mozprofile == 0.28 14:34:14 INFO - mozrunner == 6.13 14:34:14 INFO - mozscreenshot == 0.1 14:34:14 INFO - mozsystemmonitor == 0.3 14:34:14 INFO - moztest == 0.8 14:34:14 INFO - mozversion == 1.4 14:34:14 INFO - psutil == 3.1.1 14:34:14 INFO - requests == 1.2.3 14:34:14 INFO - wsgiref == 0.1.2 14:34:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:34:14 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')]} 14:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:14 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')]} 14:34:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:14 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 0x109b8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd99568f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10996cd30>, '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 0x109893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109961be8>, '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.W6ubdOovLY/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.38KVJdw3Gl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 14:34:14 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 14:34:14 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 14:34:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W6ubdOovLY/Render', 14:34:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:14 INFO - 'HOME': '/Users/cltbld', 14:34:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:14 INFO - 'LOGNAME': 'cltbld', 14:34:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:14 INFO - 'MOZ_NO_REMOTE': '1', 14:34:14 INFO - 'NO_EM_RESTART': '1', 14:34:14 INFO - 'PAGER': '/bin/cat', 14:34:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:14 INFO - 'PWD': '/builds/slave/test', 14:34:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:14 INFO - 'SHELL': '/bin/bash', 14:34:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.38KVJdw3Gl/Listeners', 14:34:14 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 14:34:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:14 INFO - 'USER': 'cltbld', 14:34:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:14 INFO - 'XPC_FLAGS': '0x0', 14:34:14 INFO - 'XPC_SERVICE_NAME': '0', 14:34:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:15 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)) 14:34:15 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)) 14:34:15 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)) 14:34:15 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)) 14:34:15 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)) 14:34:15 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)) 14:34:15 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)) 14:34:15 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)) 14:34:15 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)) 14:34:15 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)) 14:34:15 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)) 14:34:15 INFO - Cleaning up... 14:34:15 INFO - Return code: 0 14:34:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:34:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:34:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:34:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:34:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:34:15 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 0x109b8eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109b91768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd99568f60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10996cd30>, '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 0x109893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109961be8>, '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.W6ubdOovLY/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.38KVJdw3Gl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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 14:34:15 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 14:34:15 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 14:34:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W6ubdOovLY/Render', 14:34:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:15 INFO - 'HOME': '/Users/cltbld', 14:34:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:15 INFO - 'LOGNAME': 'cltbld', 14:34:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:15 INFO - 'MOZ_NO_REMOTE': '1', 14:34:15 INFO - 'NO_EM_RESTART': '1', 14:34:15 INFO - 'PAGER': '/bin/cat', 14:34:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:15 INFO - 'PWD': '/builds/slave/test', 14:34:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:15 INFO - 'SHELL': '/bin/bash', 14:34:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.38KVJdw3Gl/Listeners', 14:34:15 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 14:34:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:15 INFO - 'USER': 'cltbld', 14:34:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:15 INFO - 'XPC_FLAGS': '0x0', 14:34:15 INFO - 'XPC_SERVICE_NAME': '0', 14:34:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:34:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:34:15 INFO - Cleaning up... 14:34:15 INFO - Return code: 0 14:34:15 INFO - Running post-action listener: _resource_record_post_action 14:34:15 INFO - Running post-action listener: _start_resource_monitoring 14:34:15 INFO - Starting resource monitoring. 14:34:15 INFO - [mozharness: 2017-05-22 21:34:15.558818Z] Finished create-virtualenv step (success) 14:34:15 INFO - [mozharness: 2017-05-22 21:34:15.559790Z] Running install step. 14:34:15 INFO - Running pre-action listener: _resource_record_pre_action 14:34:15 INFO - Running main action method: install 14:34:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:34:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:34:15 INFO - Reading from file tmpfile_stdout 14:34:15 INFO - Detecting whether we're running mozinstall >=1.0... 14:34:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:34:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:34:15 INFO - Reading from file tmpfile_stdout 14:34:15 INFO - Output received: 14:34:15 INFO - Usage: mozinstall [options] installer 14:34:15 INFO - Options: 14:34:15 INFO - -h, --help show this help message and exit 14:34:15 INFO - -d DEST, --destination=DEST 14:34:15 INFO - Directory to install application into. [default: 14:34:15 INFO - "/builds/slave/test"] 14:34:15 INFO - --app=APP Application being installed. [default: firefox] 14:34:15 INFO - mkdir: /builds/slave/test/build/application 14:34:15 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'] 14:34:15 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 14:34:30 INFO - Reading from file tmpfile_stdout 14:34:30 INFO - Output received: 14:34:30 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 14:34:30 INFO - Running post-action listener: _resource_record_post_action 14:34:30 INFO - [mozharness: 2017-05-22 21:34:30.711348Z] Finished install step (success) 14:34:30 INFO - [mozharness: 2017-05-22 21:34:30.711486Z] Running run-tests step. 14:34:30 INFO - Running pre-action listener: _resource_record_pre_action 14:34:30 INFO - Running main action method: run_tests 14:34:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:34:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:34:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:34:30 INFO - 2017-05-22 14:34:30.726 screenresolution[1623:7295] starting screenresolution argv=screenresolution get 14:34:30 INFO - 2017-05-22 14:34:30.728 screenresolution[1623:7295] Display 0: 1600x1200x32@60 14:34:30 INFO - 2017-05-22 14:34:30.734 screenresolution[1624:7297] starting screenresolution argv=screenresolution list 14:34:30 INFO - Available Modes on Display 0 14:34:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:34:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:34:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:34:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:34:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:34:30 INFO - Intel Iris: 14:34:30 INFO - Chipset Model: Intel Iris 14:34:30 INFO - Type: GPU 14:34:30 INFO - Bus: Built-In 14:34:30 INFO - VRAM (Dynamic, Max): 1536 MB 14:34:30 INFO - Vendor: Intel (0x8086) 14:34:30 INFO - Device ID: 0x0a2e 14:34:30 INFO - Revision ID: 0x0009 14:34:30 INFO - Displays: 14:34:30 INFO - Display: 14:34:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:34:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:34:30 INFO - Display Serial Number: 1600x1200 60 14:34:30 INFO - Main Display: Yes 14:34:30 INFO - Mirror: Off 14:34:30 INFO - Online: Yes 14:34:30 INFO - Rotation: Supported 14:34:30 INFO - Return code: 0 14:34:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:34:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:34:30 INFO - Python 2.7.3 14:34:30 INFO - Return code: 0 14:34:30 INFO - grabbing minidump binary from tooltool 14:34:30 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')]} 14:34:30 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 0x10996cd30>, '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 0x109893db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109961be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:34:30 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 14:34:30 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 14:34:30 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 14:34:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:34:31 INFO - Return code: 0 14:34:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:34:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:34:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:34:31 INFO - ENV: RUST_BACKTRACE is now 1 14:34:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:34:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:34:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0060', '--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 14:34:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0060 --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 14:34:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W6ubdOovLY/Render', 14:34:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:34:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:34:31 INFO - 'HOME': '/Users/cltbld', 14:34:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:34:31 INFO - 'LOGNAME': 'cltbld', 14:34:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:34:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:34:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:34:31 INFO - 'MOZ_NO_REMOTE': '1', 14:34:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:34:31 INFO - 'NO_EM_RESTART': '1', 14:34:31 INFO - 'PAGER': '/bin/cat', 14:34:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:34:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:34:31 INFO - 'PWD': '/builds/slave/test', 14:34:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:34:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:34:31 INFO - 'RUST_BACKTRACE': '1', 14:34:31 INFO - 'SHELL': '/bin/bash', 14:34:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.38KVJdw3Gl/Listeners', 14:34:31 INFO - 'TMPDIR': '/var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/', 14:34:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:34:31 INFO - 'USER': 'cltbld', 14:34:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:34:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:34:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:34:31 INFO - 'XPC_FLAGS': '0x0', 14:34:31 INFO - 'XPC_SERVICE_NAME': '0', 14:34:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:34:31 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0060', '--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 14:34:31 INFO - mozversion application_buildid: 20170522133621 14:34:31 INFO - mozversion application_changeset: 64096bfc5a89e6c92911a3257fdca0f169418022 14:34:31 INFO - mozversion application_display_name: Firefox 14:34:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:34:31 INFO - mozversion application_name: Firefox 14:34:31 INFO - mozversion application_remotingname: firefox 14:34:31 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:34:31 INFO - mozversion application_vendor: Mozilla 14:34:31 INFO - mozversion application_version: 54.0 14:34:31 INFO - mozversion platform_buildid: 20170522133621 14:34:31 INFO - mozversion platform_changeset: 64096bfc5a89e6c92911a3257fdca0f169418022 14:34:31 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:34:31 INFO - mozversion platform_version: 54.0 14:34:31 INFO - using testdate: 1495488871 14:34:31 INFO - actual date: 1495488871 14:34:31 INFO - starting webserver on 'localhost:49219' 14:34:31 INFO - SUITE-START | Running 2 tests 14:34:31 INFO - TEST-START | dromaeo_css 14:34:31 INFO - operating with platform_type : mac_ 14:34:31 INFO - Initialising browser for dromaeo_css test... 14:34:31 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmpXLcJY6/profile http://localhost:49219/getInfo.html) 14:34:34 INFO - PROCESS | 1634 | 2017-05-22 14:34:34.286 plugin-container[1637:7465] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c43, name = 'com.apple.tsm.portname' 14:34:34 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:34:34 INFO - PROCESS | 1634 | 2017-05-22 14:34:34.288 plugin-container[1637:7465] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9303, name = 'com.apple.CFPasteboardClient' 14:34:34 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:34:34 INFO - PROCESS | 1634 | 2017-05-22 14:34:34.288 plugin-container[1637:7465] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:34:34 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 14:34:34 INFO - PROCESS | 1634 | colorDepth:24 14:34:34 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 14:34:34 INFO - PROCESS | 1634 | __metrics 14:34:34 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 14:34:35 INFO - TEST-INFO | 1634: exit 0 14:34:35 INFO - Browser initialized. 14:34:35 INFO - Running cycle 1/1 for dromaeo_css test... 14:34:35 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmpXLcJY6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:34:37 INFO - PROCESS | 1638 | 2017-05-22 14:34:37.262 plugin-container[1639:7605] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8337, name = 'com.apple.tsm.portname' 14:34:37 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:34:37 INFO - PROCESS | 1638 | 2017-05-22 14:34:37.262 plugin-container[1639:7605] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4517, name = 'com.apple.CFPasteboardClient' 14:34:37 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:34:37 INFO - PROCESS | 1638 | 2017-05-22 14:34:37.262 plugin-container[1639:7605] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:34:47 INFO - PROCESS | 1638 | RSS: Main: 174370816 14:34:47 INFO - PROCESS | 1638 | 14:37:50 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49219/tests/dromaeo/cssquery-ext.html) 14:37:51 INFO - PROCESS | 1638 | RSS: Main: 190386176 14:37:51 INFO - PROCESS | 1638 | 14:40:54 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-ext.html (next: http://localhost:49219/tests/dromaeo/cssquery-jquery.html) 14:40:54 INFO - PROCESS | 1638 | RSS: Main: 201277440 14:40:54 INFO - PROCESS | 1638 | 14:43:56 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49219/tests/dromaeo/cssquery-mootools.html) 14:43:57 INFO - PROCESS | 1638 | RSS: Main: 204898304 14:43:57 INFO - PROCESS | 1638 | 14:46:59 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49219/tests/dromaeo/cssquery-prototype.html) 14:46:59 INFO - PROCESS | 1638 | RSS: Main: 208027648 14:46:59 INFO - PROCESS | 1638 | 14:50:02 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49219/tests/dromaeo/cssquery-yui.html) 14:50:03 INFO - PROCESS | 1638 | RSS: Main: 210419712 14:50:03 INFO - PROCESS | 1638 | 14:53:05 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-yui.html (next: http://localhost:49219/tests/dromaeo/cssquery-dojo.html) 14:53:06 INFO - PROCESS | 1638 | RSS: Main: 208719872 14:53:06 INFO - PROCESS | 1638 | 14:53:06 INFO - PROCESS | 1638 | __start_tp_report 14:53:06 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 14:53:06 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 14:53:06 INFO - PROCESS | 1638 | |i|pagename|runs| 14:53:06 INFO - PROCESS | 1638 | |0;dojo.html;3601.405768048272;3604.243108947121;3610.4042832932564;3629.3104310181066;3629.872954446593;5910.793122240722;6166.105914642374;6201.658908760022;6217.409346112117;6242.500599952004;5986.910196347058;6183.227096612924;6200.937990620092;6244.281907580618;6266.185395898527;5557.72211389431;5612.07400778873;5629.380768115525;5647.124695672177;5691.4877661010505;5703.543716502691;5704.77180912763;5721.971390143043;5723.427657234285;5735.053716136832;5457.918131228015;5462.863428414282;5673.943260567383;5715.428457154293;5739.942600574003;5555.50000499957;5558.666480011212;5558.666480011212;5566.359868615121;5569.832905012836;5364.758585863646;5497.422770609109;5509.421510741355;5539.169124631298;5584.748686309126;6198.938010619926;6225.501959843247;6273.247210334744;6294.0873573332065;6298.244210694746;6424.421802037792;6536.215654121488;6583.6708164592055;6595.307492713292;6596.571222870498;6355.3009168991375;6401.967990160066;6688.565243259172;6722.1261236039;6747.662616869137;5211.530962213419;6148.508119350441;6197.721102550396;6251.6561589112125;6286.371362863677;5749.482546570829;6825.146856642919;6849.554778939452;6853.280405557344;6882.449404047664;6434.806955791334;6447.516436267299;6453.709583068773;6457.160569125984;6465.127207826883;4821.734804585782;5498.560115190775;5514.503694667499;6521.804345869631;6542.901856472156;6534.313897040837;6546.214454265423;6549.214094308713;6565.409113179837;6589.143411356493;6518.445932095825;6538.803835884931;6548;6552.770653027121;6571.67141642916;4389.956100439019;5202.3497062867145;5284.392294886137;5303.761330740126;5312.7343632818975;3783.432485127253;4625.283081122431;4700.177468942921;4743.383360163157;4785.186518291899;5592.86017849557;5896.7936122235515;6015.789447369321;6164.6917654118015;6265.342139075423;6620.768273110514;6648.368405001517;6686.264510903797;6697.7655782047395;6704.664766761643;6299.653519056405;6300.338464461257;6306.180196574416;6326.588771729823;6347.047942808524;5651.152327150878;5715.857103572361;5774.566907481955;5804.912926306109;5847.44449277318;6887.483438742114;6942.305769423017;6943.375096241463;6956.826079348057;6957.547759395623;6601.471882249312;6636.83407914806;6668.099806526056;6689;6698.430633396216;6562.8359291018105;6609.339066093352;6858.759943402157;6896.965515172392;6926.134233220848;6406.263279722705;6447.709853056717;6450.161479007699;6484.092227088306;6484.675766211576;6640.402363787282;6644.734210631521;6648.767293144717;6657.53397262187;6681.732730690913;6245.812625621239;6318.968405157945;6417.069524918954;6436.774712885027;6440.774572889926;6190.845228869314;6328.493720502257;6352.68236588178;6450.870982580416;6456.2575303838785;6205.968970155121;6339.080833279057;6351.301356850835;6351.777687780906;6380.744770209325;6360.650164241011;6414.679266036773;6415.262244841903;6437.645929473924;6458.160439142881;6220.502359811114;6232.532560057924;6285.842853928689;6317.115603815378;6329.240491141092 14:53:06 INFO - PROCESS | 1638 | |1;ext.html;761.3148166650014;780.9960950195249;842.9072801991782;979.8873129590099;980.534246233039;39778;40739.98150046247;41332.173356532854;41433.58566414335;41756.956076098075;40362.7981860091;40671.186576268454;40767.98080048;40772.38841417381;40873.18253634925;8271.875921861161;8413.831723365529;11330.206885518017;11571.421428928563;11813.645590632295;11490.712732181679;11561.884381156207;11574.6527604172;11584.53661853529;11639.35983520906;11597.072234221285;11599.942000289986;11605.825912611275;11621.954024137825;11622.709432264177;11540.480678369493;11566.363849988247;11576.652700419001;11582.536698532049;11607.245529040585;10070.89220185779;10260.281780275383;11246.325220486797;11260.774784504347;11310.095192384671;27362;35040.82479587611;35043.82478087619;35131;35142.297154056774;3904.7657140571373;3914.3149948759137;3938.468306778577;3965.3060714375156;4011.3782363733667;25820.870895645403;26070.608940865903;26230.344241393926;27458.5881211782;27482.587761183597;3197.904062878117;3243.4810430331268;3292.061762397729;3357.513160591725;3458.429359155736;356852.21573892224;361205.3879461198;361847;362416;362543;45124.323135153;45372;45414;45607.63177104692;45624;46959.29561056587;47466.76266618714;47492.52507474881;47666.76166619144;47866.52133478621;14379.568612941628;14825.184614846072;14854.183019933791;14868.851311486964;14895.702085958437;12543.937280313723;12614.873851261369;12793.552225672056;12795.976321894226;12819;10592.629257975934;12093.093018023683;12238.694032649077;12275.956543693886;12374.81437778434;5679.403662615448;5700.287464066992;5714;5739.799107031234;5755.280589926259;1106.3748981825242;1161.3380373187201;1168.7195073182374;1191.302394088438;1203.1457665057908;26771.46457070848;27002.5949610756;27150;27173.728262717515;27180.91276348924;1251.674564613189;1259.5843371687322;1290.4064130499887;1324.2120938041849;1327.8539360670318;13516.189028658311;13866.306684665726;14079.788803167961;14105.647358816112;14168.433262669378;490964;492951;493359;493564;493849.53075234394;49497.50502494929;58655.41344586499;58802.41197588055;59002.81994360089;59047;1273.6879464531248;1298.889594384488;1332.5469340424302;1528.0678785940568;1537.2705706080435;27479.488628124844;29819.403611927206;30525.694743053173;30672.693273067875;31218.21954451157;45776.54223457856;45967.77016114898;46091.30863037124;46141.07717845557;46352.6094217174;444135;445620;447217;447634;449706;31625.42581141428;31720.841395792875;31823.204419888763;31993.36013279768;32031.519527207576;37913.24173516459;45617.31574026326;45659.77170114128;45808.770956145;45822.77088614536;29920.551191731705;31270.374592508477;31311.373772523966;31910.361792764477;31913.20216994501;26577;29825;30661.693383066777;30665.386692265583;31523.36953260876;35583.644163558034;35830;36996;37367.62632373641;37405.81297093606;30719.078427647208;30723.539146913263;30909.845450772602;31595;31736;21604.45988850291;23627.763722362557;24667.62998555059;25255.747442526073;25323.620145698198 14:53:06 INFO - PROCESS | 1638 | |2;jquery.html;420.4366149359861;430.1139652316229;434.65662126919733;441.72022731220676;445.1364353154879;19916.402507924748;21032;22205.2228172014;22325.330240092822;22431.214907478243;22280.442988925286;22639.320820375375;23371.883140584316;23615.881920590415;23915.76084239157;6902.102726645554;6932.272111428303;7118.3949364303835;7224.56652600843;7226.096737907762;6935.791926242221;7091.503594748369;7114.146302443688;7123.786286411416;7127.679254433537;6735;7068.823279418004;7068.858622827541;7105.5026148169645;7113.537620054706;6836.589804611739;7022.894656580155;7053.400460960825;7112.25321341262;7120.430365570768;6912.447004239699;6940.30596940307;6947.89578156328;6960.686769095403;6967.790966270969;24081.397965050837;25573.23280301594;26619.334516637045;27418;27887;2552.3236342369287;2633.0652618320605;3445.000949724577;3469.6356882527225;3528.4530897710897;16267.10530920799;16694.4156954506;16708.832911670972;17027.48917532476;17464.47606571805;2138.9465263368384;2170.4899348653053;2188.562287542482;2201.680756290329;2205.503761653615;164641.17679411525;170525;172551;173912.13043934826;174301.9103508553;16736.41422550205;20642.555021148375;21292.82861590641;21560.0297986591;21817.145542628736;32326;32358;32744.34511309808;32856.83571582175;33431.498527522104;10682.78634427307;10727.946360268148;10968.725781855517;10989.175811814146;11093.223474356879;9074.86387704185;9091.636334546676;9097.317701172438;9211.723648290563;9245;9795.510224488748;10013.849792253122;10119.645812396531;10119.862842518696;10147.412409835444;4033.6487276762027;4140.970924247157;4169.728967617095;4171.372035617542;4173.770442625624;885.4864178776294;989.5448093876753;1034.6792494326783;1038.3406536849154;1067.7864427114607;17293.394731184348;20828.791712082988;20957.26649567258;21033.474163145736;21110.894445527672;1017.9592816287266;1020.2705065877784;1045.414567842011;1049.082053203447;1060.8833028366873;10583.312084714624;11036.889631103746;11047.447627618587;11089.112870970303;11102.722431939266;429269;481211.1878881236;730961;732909;747369;149570;153648;155086;155843;160010;1274.2991354754847;1283.57641978147;1302.9609111726475;1305.092960392537;1308.2085338370339;17879.10604469745;18567.53581160482;18630.347937822113;18703.438896833115;18713.438596842116;57854;58616.748925806605;59373.10940335812;59705;60528.39471605227;86221.2755744869;86585.56707216424;87623;89254;89306;10750.784984300426;11576.305421674726;11822.290662560274;11983.580574679845;12112.333821639895;13062.54281100157;13101.279429631462;13337.733245334844;13467.797983030066;13505.257210853497;10181.694549163536;11248.550057997589;11336.829947550958;11567.479463424335;11659.941700291443;9303.116203960613;9897.208223341971;10536;10934.234603577992;10992.945035275092;11234.943825280821;11467.139964502529;11522.654320370402;11915.404229788302;11952.402379880798;11565.595204167814;11628.302301861915;11810.881891181321;11918.046556275303;12051.879481205426;11250.549978001116;11540.653780386601;11677.240979336317;11697.941510292734;11724.472398741906 14:53:06 INFO - PROCESS | 1638 | |3;mootools.html;1402.2077526197697;1607.2204980584406;1627.9255691243782;1641.3516660918938;1645.9259333329994;10050.648227312045;11080.778384432306;11793.587224447147;11838;11961.162718609701;11477.770444591104;11637.650870473879;11647.941760291207;11656.475458604362;11679.948804607584;2491.9003239870385;2524.734902835194;2534.4170840706556;2536.226450932465;2538.809589280802;2482.900683972648;2495;2532.1643857526974;2534.759197876199;2542.250036239314;2220.7224096987875;2460.3849037740565;2491.9377015574573;2525.7095434025136;2534.6071358939394;2426.4055306449945;2469.4567195217114;2482.2801387597574;2492.4516606346756;2506.699196096462;2281.6235321171985;2462.335169504224;2476;2484.7887929526014;2485.7887079598077;11447.828282575769;11648.650540483799;11662.008729258023;11690.590829321018;11734;6032.698365081728;6112.602680825732;6182.84542886427;6188.81433556994;6196.163517925068;3659.3779057560278;3756.34264003801;3768.812823960444;3838.0788610733503;3859.807009649506;1306.2946009155046;1929.2668785861283;1939.5345117171778;1978.3690373327784;2011.9597608047904;14314.212718300489;14649.487267945571;15500;15995.920020399824;16111.516654500383;4802.207635740064;4814.350062741485;4955.72743499111;4981.775820088104;5002.474740152305;12287.56993505223;12337.013038956862;12354.567590134302;12364.505419783289;12374.938125309316;6031.035034394476;6075.696215189311;6084.30030546484;6086.087086936906;6088.847778805477;4889.951100488949;5414.133738601883;5538.363088244903;5622.184783206412;5644.153376993484;3487.8081705505933;4052.3263444963586;4113.403556484293;4126.339785634284;4155.501339839238;917.4585503558565;1079.7894410589859;1094.2777766673958;1094.6606551968914;1104.2270410712372;858.3948316436897;992.2855544008447;992.5682328187262;1014.3558840136419;1030.2376241581176;6822.897656535156;6823.351781580742;6954.165500139914;7010.859782804418;7031.507794454339;927.8654595083675;1001.6043662753171;1029.8661174047327;1037.1443559063803;1075.8224892892586;3437.604675462354;4292.334688123346;4352.434183556117;4362.956370436318;4383.167198232326;14150.433982640785;14555.854441455662;14570.271486425636;14589.635259118395;14594;14425.062370945854;14430;14431.061980971203;14434.711305774035;14485.493007744679;9056.184943355129;10126.240531960133;10470.790584188273;10723.03492685662;10743.731406714738;6042.335343112166;6880.69036893331;6930.306969303029;6960.721571137098;7003.7548685795755;12538.435770390537;12579.87420125787;12769;12776.36118194068;13066;13208.80186797218;13318.733625327633;13319.200847949154;13352.265625390308;13465.663358416117;9193.448393096436;9373.062693730571;9579.616815327588;9583.808323833624;9589.328746987923;10345.482725863527;10448.164146868081;10600.681979540626;10695.251332406657;10892.019718225398;7578.81052973658;8830.49881520156;8963.326882040437;9064;9348.158665720119;8793.868091978753;8794.560271986504;8854.158854908774;9045.321600880092;9074.45553266806;8220.136885626924;8328.125546817739;8367.665293388196;8374.413791034813;8408.915910840813;5948.672822994777;6020.247469066367;6109.2974307955155;6154.692265386623;6223.719932602953;6049.758009679564;6121.296050954197;6191.318954914866;6307.779227727007;6318.747250110128 14:53:06 INFO - PROCESS | 1638 | |4;prototype.html;383.27752187127265;388.36890053662796;391.20975629228957;406.2383031815346;407.32384242158;4519.163954668388;4874.585660218877;5148.047611191927;5197.544687487505;5282.577393808496;4464.272332602281;4668.016093160401;5178.249153872686;5290.894182116365;5426.186072089179;3801.1637439763344;4287.50693670228;4453.997850483648;4469.653431948237;4506.076254367863;3816.82824272907;4431.689781715281;4485.865424037268;4569.223232050543;4617.705533853515;3569.5716514018354;4159.542450330461;4398.318260669601;4495.977520112395;4503.414556107701;3561.216532362876;4002.1795531915886;4172.395002724603;4232.043039757837;4463.709858859196;3491.9825400873087;3638.4542318652148;4067.9186416271805;4074.857379991716;4082.3672330788477;5101.897962040775;5198.662086964373;5282.8529604260475;5290.545100097504;5307.6019298552765;4436.112777444492;4532.660050496226;4739.668223224376;5081.466446023152;5169.379674439091;4375.665422046274;4778.904421911542;5534.714347797883;5632.7746890124345;5680.914786278167;3984.2629113613784;4015.6185162409524;4570.542945705402;4574.4968053514085;4595.609373203249;4924.507549245082;5545.916811247795;5956.260120344647;6008.347704381286;6022.711369679494;4480.238359478896;4618.792154353062;4629.791659375269;4666.533346665306;4680.3915490985955;4159.459270294842;4321.503027151855;4724.88187795301;4808.61531077513;4931.358923340014;4685.156671799041;4790.544898234732;4832.8791780205775;5148.845534633892;5156.175011998137;5551.918030738473;5569.551916501658;5622.522085622768;5666.121751128581;5710.971445142831;1512.2136489025645;1622.8945118567492;1627.4141309128704;1634.5014770495113;1647.4563394079926;1135.761490087091;1177.3877583656451;1215.501644325822;1228.5761412312738;1237.7127787101495;4734.55021772931;4751.548602882721;5436.565074794007;5441.863953401117;5483.643563168382;5199.688018718889;5359.740460991664;5389.0299746046085;5521;6022.969885150546;4894.95105048945;5021.221710634857;5101.336826212643;5172.948270517322;5354;2834.971650283471;3004.248937765559;3048.6189226346705;3066.880588585195;3071.585335979659;5709.628874123168;5890.764369425175;6045.818625441244;6148.4773794227995;6247.3440288769025;5146.845594632167;5346.946530534645;5665.688387138747;5966.224390829165;6059.515238780887;5291.243217315749;5990.940090599125;6115.541334399938;6143.662098584531;6213.565050446426;5897.228302697725;5905.822825315247;5973.671448070398;5992.760289588367;6005.81982540507;5544.7782088716;5762.94237057624;5777.093559125563;5791.82624521265;5898.734556944862;4761.190597598347;5138.820141295037;5845.240118784531;5962.403759624176;6138.600990935575;4282.421873047098;4625.491195968508;4690.906181876275;4694.577488026094;4710.905781884411;4828.493008234085;5310.362756469249;5397.298351214317;5437.700926449083;5904.3210030847;4914.606831453403;5685.459881311269;5721.256236689203;5762.913556296575;5788.884222315614;5116.132611596666;5584.253384623038;5682.659040457585;5719.370869204467;5723.828285151452;5134.6662466941025;5560.386618289397;5570.164475328571;5602.439756024365;5615.073512870331;4846.442659094249;5590.720463976866;5772.567057470623;5853.765849365977;5885.352611212677;4720.622350211906;5549.361823390201;5582.594617283535;5604.971975140098;5690.743916523684 14:53:06 INFO - PROCESS | 1638 | |5;yui.html;730.3463400256774;791.6556298010365;810.2140923304393;836.987245433026;877.9429337093087;16791.83208167918;17334.653306933884;17369.826301736994;17437.38969136077;17620.47138585843;14441.989060765723;15406.69186616267;15996.280167392466;16148.919255403707;16161.272742726576;850.7702920211539;972.0910948263361;977.2475194100539;990.3711143423924;1025.3027941000116;1016.2124353625925;1020.5203554329453;1031.2626472072466;1032.664384075175;1035.3632516002679;1081.2755453845941;1083.1930211992078;1083.8374243863404;1085.4138765066893;1086.8750093739243;1079.4171147580298;1079.6275285026654;1079.8056349857022;1080.2924084724518;1080.8108580998332;806.040811434397;806.2743530822307;806.6450761664848;808.1635507249968;809.6275713171948;17098.82901170997;17541.947483150925;17554.122293885383;17586.560335991573;17832.732509012247;14478.276086195649;14761.18813465259;14892.851071489255;14934.253287335698;14943.402263909431;11652.766944661143;12153.756924861542;12179.964703000256;12270.631881043493;12858.421371038232;3839.232153569297;3848.422736589506;3911.4966421490267;3966;3981.880543583697;101172;118863.40568297189;119502.402487987;120299.39850300693;121148.39425802814;10994.505247263893;11083.889161108447;11259.605913792979;11654.359010254348;11800.055995520337;23685.644715329283;24103.638445423334;24104.75895241025;24168.274951751475;24220.51558968846;6984.476164287698;8180.6318715657935;8194.467359621725;8201.466904651179;8202.138775428613;4534.591886730144;4567.862964111082;4603.976980115078;4638.6752927294765;4751.40607424072;3653.4337177737493;3743.644353786386;3804.2201348723447;3962.603739626072;4023.6177563131455;657.8684263147389;717.7129148340601;721.7401735375261;721.9566825990457;727.1540082509662;564.3932772269826;577.5091172503338;591.5060924128401;597.158007209832;599.5233789137646;10483.633072842415;10775.568977240822;10942.617008404668;10947.50736216872;11166.441677916071;581.9418058194232;596.3321080389991;606.5935822998601;650.8926027205553;754.7773406844993;4938.012397520511;5161.380634323905;5207.02041060333;5257.447967963309;5264.394594621591;102242.48878755559;103141;103916.44125338126;105928;106091.40862887063;63150.368496315365;78113;91504;91920;92358.53820730987;75428.24571754322;77127.61436192783;77998;79237.60381198057;79293;10070.244731645009;10094.949525252327;10146.492675366055;10183.236257280585;10920.399378034284;48984.75507622439;49734.75132624314;51370.22944655758;52316.890881913365;53574.92850142897;63251.36748632455;63987.72024559576;63994;69464.6526767363;70291.29708702848;12899.613011609667;12973.935130324287;13130.212187268795;13289;13443.462261509821;14629.561113166197;15057.472988445352;15219.391224350902;15278.006929549543;15311.310991005654;13718.931405343308;13922.373493192628;14231.50189743354;14320.212388318743;14907.403703851724;11927.880721192678;13317;14389.784153237919;14473.85526144725;14908.403663853325;14379.640508987359;14588.562343129723;14620.195889226196;14762.114273143647;14821.851781482048;9490.240780737662;9706.9029309706;10274;10281.640142594973;10508.369497830156;9033.554631258967;10851.891481085087;11697.415129243675;11815.586454474396;12236.327002014974 14:53:06 INFO - PROCESS | 1638 | __end_tp_report 14:53:06 INFO - PROCESS | 1638 | __start_cc_report 14:53:06 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,387 14:53:06 INFO - PROCESS | 1638 | __end_cc_report 14:53:06 INFO - PROCESS | 1638 | __startTimestamp1495489986199__endTimestamp 14:53:06 INFO - PROCESS | 1638 | 14:53:06 INFO - PROCESS | 1638 | ------- Summary: start ------- 14:53:06 INFO - PROCESS | 1638 | Number of tests: 6 14:53:06 INFO - PROCESS | 1638 | 14:53:06 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:6088.27 Median:6306.18 stddev:704.47 (11.2%) stddev-sans-first:679.08 14:53:06 INFO - PROCESS | 1638 | Values: 3601.4 3604.2 3610.4 3629.3 3629.9 5910.8 6166.1 6201.7 6217.4 6242.5 5986.9 6183.2 6200.9 6244.3 6266.2 5557.7 5612.1 5629.4 5647.1 5691.5 5703.5 5704.8 5722.0 5723.4 5735.1 5457.9 5462.9 5673.9 5715.4 5739.9 5555.5 5558.7 5558.7 5566.4 5569.8 5364.8 5497.4 5509.4 5539.2 5584.7 6198.9 6225.5 6273.2 6294.1 6298.2 6424.4 6536.2 6583.7 6595.3 6596.6 6355.3 6402.0 6688.6 6722.1 6747.7 5211.5 6148.5 6197.7 6251.7 6286.4 5749.5 6825.1 6849.6 6853.3 6882.4 6434.8 6447.5 6453.7 6457.2 6465.1 4821.7 5498.6 5514.5 6521.8 6542.9 6534.3 6546.2 6549.2 6565.4 6589.1 6518.4 6538.8 6548.0 6552.8 6571.7 4390.0 5202.3 5284.4 5303.8 5312.7 3783.4 4625.3 4700.2 4743.4 4785.2 5592.9 5896.8 6015.8 6164.7 6265.3 6620.8 6648.4 6686.3 6697.8 6704.7 6299.7 6300.3 6306.2 6326.6 6347.0 5651.2 5715.9 5774.6 5804.9 5847.4 6887.5 6942.3 6943.4 6956.8 6957.5 6601.5 6636.8 6668.1 6689.0 6698.4 6562.8 6609.3 6858.8 6897.0 6926.1 6406.3 6447.7 6450.2 6484.1 6484.7 6640.4 6644.7 6648.8 6657.5 6681.7 6245.8 6319.0 6417.1 6436.8 6440.8 6190.8 6328.5 6352.7 6450.9 6456.3 6206.0 6339.1 6351.3 6351.8 6380.7 6360.7 6414.7 6415.3 6437.6 6458.2 6220.5 6232.5 6285.8 6317.1 6329.2 14:53:06 INFO - PROCESS | 1638 | 14:53:06 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:56986.08 Median:31610.21 stddev:116002.35 (367.0%) stddev-sans-first:116250.95 14:53:06 INFO - PROCESS | 1638 | Values: 761.3 781.0 842.9 979.9 980.5 39778.0 40740.0 41332.2 41433.6 41757.0 40362.8 40671.2 40768.0 40772.4 40873.2 8271.9 8413.8 11330.2 11571.4 11813.6 11490.7 11561.9 11574.7 11584.5 11639.4 11597.1 11599.9 11605.8 11622.0 11622.7 11540.5 11566.4 11576.7 11582.5 11607.2 10070.9 10260.3 11246.3 11260.8 11310.1 27362.0 35040.8 35043.8 35131.0 35142.3 3904.8 3914.3 3938.5 3965.3 4011.4 25820.9 26070.6 26230.3 27458.6 27482.6 3197.9 3243.5 3292.1 3357.5 3458.4 356852.2 361205.4 361847.0 362416.0 362543.0 45124.3 45372.0 45414.0 45607.6 45624.0 46959.3 47466.8 47492.5 47666.8 47866.5 14379.6 14825.2 14854.2 14868.9 14895.7 12543.9 12614.9 12793.6 12796.0 12819.0 10592.6 12093.1 12238.7 12276.0 12374.8 5679.4 5700.3 5714.0 5739.8 5755.3 1106.4 1161.3 1168.7 1191.3 1203.1 26771.5 27002.6 27150.0 27173.7 27180.9 1251.7 1259.6 1290.4 1324.2 1327.9 13516.2 13866.3 14079.8 14105.6 14168.4 490964.0 492951.0 493359.0 493564.0 493849.5 49497.5 58655.4 58802.4 59002.8 59047.0 1273.7 1298.9 1332.5 1528.1 1537.3 27479.5 29819.4 30525.7 30672.7 31218.2 45776.5 45967.8 46091.3 46141.1 46352.6 444135.0 445620.0 447217.0 447634.0 449706.0 31625.4 31720.8 31823.2 31993.4 32031.5 37913.2 45617.3 45659.8 45808.8 45822.8 29920.6 31270.4 31311.4 31910.4 31913.2 26577.0 29825.0 30661.7 30665.4 31523.4 35583.6 35830.0 36996.0 37367.6 37405.8 30719.1 30723.5 30909.8 31595.0 31736.0 21604.5 23627.8 24667.6 25255.7 25323.6 14:53:06 INFO - PROCESS | 1638 | 14:53:06 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:40223.60 Median:11849.49 stddev:108485.38 (915.5%) stddev-sans-first:108748.54 14:53:06 INFO - PROCESS | 1638 | Values: 420.4 430.1 434.7 441.7 445.1 19916.4 21032.0 22205.2 22325.3 22431.2 22280.4 22639.3 23371.9 23615.9 23915.8 6902.1 6932.3 7118.4 7224.6 7226.1 6935.8 7091.5 7114.1 7123.8 7127.7 6735.0 7068.8 7068.9 7105.5 7113.5 6836.6 7022.9 7053.4 7112.3 7120.4 6912.4 6940.3 6947.9 6960.7 6967.8 24081.4 25573.2 26619.3 27418.0 27887.0 2552.3 2633.1 3445.0 3469.6 3528.5 16267.1 16694.4 16708.8 17027.5 17464.5 2138.9 2170.5 2188.6 2201.7 2205.5 164641.2 170525.0 172551.0 173912.1 174301.9 16736.4 20642.6 21292.8 21560.0 21817.1 32326.0 32358.0 32744.3 32856.8 33431.5 10682.8 10727.9 10968.7 10989.2 11093.2 9074.9 9091.6 9097.3 9211.7 9245.0 9795.5 10013.8 10119.6 10119.9 10147.4 4033.6 4141.0 4169.7 4171.4 4173.8 885.5 989.5 1034.7 1038.3 1067.8 17293.4 20828.8 20957.3 21033.5 21110.9 1018.0 1020.3 1045.4 1049.1 1060.9 10583.3 11036.9 11047.4 11089.1 11102.7 429269.0 481211.2 730961.0 732909.0 747369.0 149570.0 153648.0 155086.0 155843.0 160010.0 1274.3 1283.6 1303.0 1305.1 1308.2 17879.1 18567.5 18630.3 18703.4 18713.4 57854.0 58616.7 59373.1 59705.0 60528.4 86221.3 86585.6 87623.0 89254.0 89306.0 10750.8 11576.3 11822.3 11983.6 12112.3 13062.5 13101.3 13337.7 13467.8 13505.3 10181.7 11248.6 11336.8 11567.5 11659.9 9303.1 9897.2 10536.0 10934.2 10992.9 11234.9 11467.1 11522.7 11915.4 11952.4 11565.6 11628.3 11810.9 11918.0 12051.9 11250.5 11540.7 11677.2 11697.9 11724.5 14:53:06 INFO - PROCESS | 1638 | 14:53:06 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:7024.87 Median:2525.22 stddev:4382.24 (173.5%) stddev-sans-first:4374.16 14:53:06 INFO - PROCESS | 1638 | Values: 1402.2 1607.2 1627.9 1641.4 1645.9 10050.6 11080.8 11793.6 11838.0 11961.2 11477.8 11637.7 11647.9 11656.5 11679.9 2491.9 2524.7 2534.4 2536.2 2538.8 2482.9 2495.0 2532.2 2534.8 2542.3 2220.7 2460.4 2491.9 2525.7 2534.6 2426.4 2469.5 2482.3 2492.5 2506.7 2281.6 2462.3 2476.0 2484.8 2485.8 11447.8 11648.7 11662.0 11690.6 11734.0 6032.7 6112.6 6182.8 6188.8 6196.2 3659.4 3756.3 3768.8 3838.1 3859.8 1306.3 1929.3 1939.5 1978.4 2012.0 14314.2 14649.5 15500.0 15995.9 16111.5 4802.2 4814.4 4955.7 4981.8 5002.5 12287.6 12337.0 12354.6 12364.5 12374.9 6031.0 6075.7 6084.3 6086.1 6088.8 4890.0 5414.1 5538.4 5622.2 5644.2 3487.8 4052.3 4113.4 4126.3 4155.5 917.5 1079.8 1094.3 1094.7 1104.2 858.4 992.3 992.6 1014.4 1030.2 6822.9 6823.4 6954.2 7010.9 7031.5 927.9 1001.6 1029.9 1037.1 1075.8 3437.6 4292.3 4352.4 4363.0 4383.2 14150.4 14555.9 14570.3 14589.6 14594.0 14425.1 14430.0 14431.1 14434.7 14485.5 9056.2 10126.2 10470.8 10723.0 10743.7 6042.3 6880.7 6930.3 6960.7 7003.8 12538.4 12579.9 12769.0 12776.4 13066.0 13208.8 13318.7 13319.2 13352.3 13465.7 9193.4 9373.1 9579.6 9583.8 9589.3 10345.5 10448.2 10600.7 10695.3 10892.0 7578.8 8830.5 8963.3 9064.0 9348.2 8793.9 8794.6 8854.2 9045.3 9074.5 8220.1 8328.1 8367.7 8374.4 8408.9 5948.7 6020.2 6109.3 6154.7 6223.7 6049.8 6121.3 6191.3 6307.8 6318.7 14:53:06 INFO - PROCESS | 1638 | 14:53:06 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4733.16 Median:5125.40 stddev:1325.25 (25.9%) stddev-sans-first:1288.12 14:53:06 INFO - PROCESS | 1638 | Values: 383.3 388.4 391.2 406.2 407.3 4519.2 4874.6 5148.0 5197.5 5282.6 4464.3 4668.0 5178.2 5290.9 5426.2 3801.2 4287.5 4454.0 4469.7 4506.1 3816.8 4431.7 4485.9 4569.2 4617.7 3569.6 4159.5 4398.3 4496.0 4503.4 3561.2 4002.2 4172.4 4232.0 4463.7 3492.0 3638.5 4067.9 4074.9 4082.4 5101.9 5198.7 5282.9 5290.5 5307.6 4436.1 4532.7 4739.7 5081.5 5169.4 4375.7 4778.9 5534.7 5632.8 5680.9 3984.3 4015.6 4570.5 4574.5 4595.6 4924.5 5545.9 5956.3 6008.3 6022.7 4480.2 4618.8 4629.8 4666.5 4680.4 4159.5 4321.5 4724.9 4808.6 4931.4 4685.2 4790.5 4832.9 5148.8 5156.2 5551.9 5569.6 5622.5 5666.1 5711.0 1512.2 1622.9 1627.4 1634.5 1647.5 1135.8 1177.4 1215.5 1228.6 1237.7 4734.6 4751.5 5436.6 5441.9 5483.6 5199.7 5359.7 5389.0 5521.0 6023.0 4895.0 5021.2 5101.3 5172.9 5354.0 2835.0 3004.2 3048.6 3066.9 3071.6 5709.6 5890.8 6045.8 6148.5 6247.3 5146.8 5346.9 5665.7 5966.2 6059.5 5291.2 5990.9 6115.5 6143.7 6213.6 5897.2 5905.8 5973.7 5992.8 6005.8 5544.8 5762.9 5777.1 5791.8 5898.7 4761.2 5138.8 5845.2 5962.4 6138.6 4282.4 4625.5 4690.9 4694.6 4710.9 4828.5 5310.4 5397.3 5437.7 5904.3 4914.6 5685.5 5721.3 5762.9 5788.9 5116.1 5584.3 5682.7 5719.4 5723.8 5134.7 5560.4 5570.2 5602.4 5615.1 4846.4 5590.7 5772.6 5853.8 5885.4 4720.6 5549.4 5582.6 5605.0 5690.7 14:53:06 INFO - PROCESS | 1638 | 14:53:06 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:21066.80 Median:17352.24 stddev:29968.34 (172.7%) stddev-sans-first:30013.50 14:53:06 INFO - PROCESS | 1638 | Values: 730.3 791.7 810.2 837.0 877.9 16791.8 17334.7 17369.8 17437.4 17620.5 14442.0 15406.7 15996.3 16148.9 16161.3 850.8 972.1 977.2 990.4 1025.3 1016.2 1020.5 1031.3 1032.7 1035.4 1081.3 1083.2 1083.8 1085.4 1086.9 1079.4 1079.6 1079.8 1080.3 1080.8 806.0 806.3 806.6 808.2 809.6 17098.8 17541.9 17554.1 17586.6 17832.7 14478.3 14761.2 14892.9 14934.3 14943.4 11652.8 12153.8 12180.0 12270.6 12858.4 3839.2 3848.4 3911.5 3966.0 3981.9 101172.0 118863.4 119502.4 120299.4 121148.4 10994.5 11083.9 11259.6 11654.4 11800.1 23685.6 24103.6 24104.8 24168.3 24220.5 6984.5 8180.6 8194.5 8201.5 8202.1 4534.6 4567.9 4604.0 4638.7 4751.4 3653.4 3743.6 3804.2 3962.6 4023.6 657.9 717.7 721.7 722.0 727.2 564.4 577.5 591.5 597.2 599.5 10483.6 10775.6 10942.6 10947.5 11166.4 581.9 596.3 606.6 650.9 754.8 4938.0 5161.4 5207.0 5257.4 5264.4 102242.5 103141.0 103916.4 105928.0 106091.4 63150.4 78113.0 91504.0 91920.0 92358.5 75428.2 77127.6 77998.0 79237.6 79293.0 10070.2 10094.9 10146.5 10183.2 10920.4 48984.8 49734.8 51370.2 52316.9 53574.9 63251.4 63987.7 63994.0 69464.7 70291.3 12899.6 12973.9 13130.2 13289.0 13443.5 14629.6 15057.5 15219.4 15278.0 15311.3 13718.9 13922.4 14231.5 14320.2 14907.4 11927.9 13317.0 14389.8 14473.9 14908.4 14379.6 14588.6 14620.2 14762.1 14821.9 9490.2 9706.9 10274.0 10281.6 10508.4 9033.6 10851.9 11697.4 11815.6 12236.3 14:53:06 INFO - PROCESS | 1638 | -------- Summary: end -------- 14:53:06 INFO - PROCESS | 1638 | 14:53:06 INFO - TEST-INFO | 1638: exit 0 14:53:06 INFO - TEST-OK | dromaeo_css | took 1115469ms 14:53:06 INFO - TEST-START | kraken 14:53:06 INFO - operating with platform_type : mac_ 14:53:06 INFO - Initialising browser for kraken test... 14:53:06 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmppRXQCe/profile http://localhost:49219/getInfo.html) 14:53:09 INFO - PROCESS | 1649 | 2017-05-22 14:53:09.319 plugin-container[1651:10029] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 14:53:09 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:53:09 INFO - PROCESS | 1649 | 2017-05-22 14:53:09.320 plugin-container[1651:10029] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.CFPasteboardClient' 14:53:09 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:53:09 INFO - PROCESS | 1649 | 2017-05-22 14:53:09.320 plugin-container[1651:10029] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:53:09 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 14:53:09 INFO - PROCESS | 1649 | colorDepth:24 14:53:09 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 14:53:09 INFO - PROCESS | 1649 | __metrics 14:53:09 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 14:53:10 INFO - TEST-INFO | 1649: exit 0 14:53:10 INFO - Browser initialized. 14:53:10 INFO - Running cycle 1/1 for kraken test... 14:53:10 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9c/2dh985pd15l19fywb0pq404000000w/T/tmppRXQCe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:53:12 INFO - PROCESS | 1652 | 2017-05-22 14:53:12.471 plugin-container[1653:10160] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8647, name = 'com.apple.tsm.portname' 14:53:12 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:53:12 INFO - PROCESS | 1652 | 2017-05-22 14:53:12.471 plugin-container[1653:10160] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0f, name = 'com.apple.CFPasteboardClient' 14:53:12 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:53:12 INFO - PROCESS | 1652 | 2017-05-22 14:53:12.471 plugin-container[1653:10160] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:53:22 INFO - PROCESS | 1652 | RSS: Main: 174223360 14:53:22 INFO - PROCESS | 1652 | 14:53:25 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection) 14:53:26 INFO - PROCESS | 1652 | RSS: Main: 187707392 14:53:26 INFO - PROCESS | 1652 | 14:53:28 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-dft) 14:53:29 INFO - PROCESS | 1652 | RSS: Main: 195678208 14:53:29 INFO - PROCESS | 1652 | 14:53:32 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-fft) 14:53:33 INFO - PROCESS | 1652 | RSS: Main: 199442432 14:53:33 INFO - PROCESS | 1652 | 14:53:35 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator) 14:53:35 INFO - PROCESS | 1652 | RSS: Main: 200826880 14:53:35 INFO - PROCESS | 1652 | 14:53:37 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:53:38 INFO - PROCESS | 1652 | RSS: Main: 201175040 14:53:38 INFO - PROCESS | 1652 | 14:53:41 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom) 14:53:42 INFO - PROCESS | 1652 | RSS: Main: 204873728 14:53:42 INFO - PROCESS | 1652 | 14:53:45 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate) 14:53:46 INFO - PROCESS | 1652 | RSS: Main: 204460032 14:53:46 INFO - PROCESS | 1652 | 14:53:48 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial) 14:53:49 INFO - PROCESS | 1652 | RSS: Main: 204619776 14:53:49 INFO - PROCESS | 1652 | 14:53:51 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:53:51 INFO - PROCESS | 1652 | RSS: Main: 204312576 14:53:51 INFO - PROCESS | 1652 | 14:53:53 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:53:54 INFO - PROCESS | 1652 | RSS: Main: 204222464 14:53:54 INFO - PROCESS | 1652 | 14:53:56 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:53:57 INFO - PROCESS | 1652 | RSS: Main: 204267520 14:53:57 INFO - PROCESS | 1652 | 14:54:00 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:54:00 INFO - PROCESS | 1652 | RSS: Main: 204861440 14:54:00 INFO - PROCESS | 1652 | 14:54:03 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:54:04 INFO - PROCESS | 1652 | RSS: Main: 205012992 14:54:04 INFO - PROCESS | 1652 | 14:54:06 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49219/tests/kraken/driver.html?testName=ai-astar) 14:54:06 INFO - PROCESS | 1652 | RSS: Main: 206675968 14:54:06 INFO - PROCESS | 1652 | 14:54:06 INFO - PROCESS | 1652 | __start_tp_report 14:54:06 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 14:54:06 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 14:54:06 INFO - PROCESS | 1652 | |i|pagename|runs| 14:54:06 INFO - PROCESS | 1652 | |0;ai-astar;107;118;120;118;119;120;120;119;119;124 14:54:06 INFO - PROCESS | 1652 | |1;audio-beat-detection;141;127;137;178;110;110;113;107;144;110 14:54:06 INFO - PROCESS | 1652 | |2;audio-dft;193;193;201;199;206;199;222;179;277;284 14:54:06 INFO - PROCESS | 1652 | |3;audio-fft;82;59;58;87;60;62;59;59;72;59 14:54:06 INFO - PROCESS | 1652 | |4;audio-oscillator;84;71;84;72;71;71;101;100;103;97 14:54:06 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;124;136;89;90;91;116;89;84;134;136 14:54:06 INFO - PROCESS | 1652 | |6;imaging-darkroom;97;97;97;99;153;112;97;97;97;97 14:54:06 INFO - PROCESS | 1652 | |7;imaging-desaturate;94;101;86;91;93;92;94;92;90;87 14:54:06 INFO - PROCESS | 1652 | |8;json-parse-financial;67;65;65;67;69;67;89;82;70;69 14:54:06 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;58;57;94;96;92;78;57;58;60;61 14:54:06 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;83;86;81;82;86;81;86;82;87;83 14:54:06 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;143;153;148;136;149;147;151;147;165;159 14:54:06 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;167;218;159;209;153;167;199;192;141;206 14:54:06 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;72;80;85;83;56;72;86;83;85;77 14:54:06 INFO - PROCESS | 1652 | __end_tp_report 14:54:06 INFO - PROCESS | 1652 | __start_cc_report 14:54:06 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,983 14:54:06 INFO - PROCESS | 1652 | __end_cc_report 14:54:06 INFO - PROCESS | 1652 | __startTimestamp1495490046651__endTimestamp 14:54:06 INFO - PROCESS | 1652 | 14:54:06 INFO - PROCESS | 1652 | ------- Summary: start ------- 14:54:06 INFO - PROCESS | 1652 | Number of tests: 14 14:54:06 INFO - PROCESS | 1652 | 14:54:06 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:118.40 Median:119.50 stddev:4.35 (3.6%) stddev-sans-first:1.80 14:54:06 INFO - PROCESS | 1652 | Values: 107.0 118.0 120.0 118.0 119.0 120.0 120.0 119.0 119.0 124.0 14:54:06 INFO - PROCESS | 1652 | 14:54:06 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:127.70 Median:132.00 stddev:22.72 (17.2%) stddev-sans-first:23.58 14:54:06 INFO - PROCESS | 1652 | Values: 141.0 127.0 137.0 178.0 110.0 110.0 113.0 107.0 144.0 110.0 14:54:06 INFO - PROCESS | 1652 | 14:54:06 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:215.30 Median:203.50 stddev:36.06 (17.7%) stddev-sans-first:37.34 14:54:06 INFO - PROCESS | 1652 | Values: 193.0 193.0 201.0 199.0 206.0 199.0 222.0 179.0 277.0 284.0 14:54:06 INFO - PROCESS | 1652 | 14:54:06 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:65.70 Median:61.00 stddev:10.77 (17.7%) stddev-sans-first:9.68 14:54:06 INFO - PROCESS | 1652 | Values: 82.0 59.0 58.0 87.0 60.0 62.0 59.0 59.0 72.0 59.0 14:54:06 INFO - PROCESS | 1652 | 14:54:06 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:85.40 Median:71.50 stddev:13.77 (19.3%) stddev-sans-first:14.60 14:54:06 INFO - PROCESS | 1652 | Values: 84.0 71.0 84.0 72.0 71.0 71.0 101.0 100.0 103.0 97.0 14:54:06 INFO - PROCESS | 1652 | 14:54:06 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:108.90 Median:86.50 stddev:22.28 (25.8%) stddev-sans-first:22.95 14:54:06 INFO - PROCESS | 1652 | Values: 124.0 136.0 89.0 90.0 91.0 116.0 89.0 84.0 134.0 136.0 14:54:06 INFO - PROCESS | 1652 | 14:54:06 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:104.30 Median:97.00 stddev:17.74 (18.3%) stddev-sans-first:18.62 14:54:06 INFO - PROCESS | 1652 | Values: 97.0 97.0 97.0 99.0 153.0 112.0 97.0 97.0 97.0 97.0 14:54:06 INFO - PROCESS | 1652 | 14:54:06 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:92.00 Median:92.00 stddev:4.16 (4.5%) stddev-sans-first:4.35 14:54:06 INFO - PROCESS | 1652 | Values: 94.0 101.0 86.0 91.0 93.0 92.0 94.0 92.0 90.0 87.0 14:54:06 INFO - PROCESS | 1652 | 14:54:06 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:71.00 Median:69.00 stddev:7.99 (11.6%) stddev-sans-first:8.34 14:54:06 INFO - PROCESS | 1652 | Values: 67.0 65.0 65.0 67.0 69.0 67.0 89.0 82.0 70.0 69.0 14:54:06 INFO - PROCESS | 1652 | 14:54:06 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:69.50 stddev:16.98 (24.4%) stddev-sans-first:17.34 14:54:06 INFO - PROCESS | 1652 | Values: 58.0 57.0 94.0 96.0 92.0 78.0 57.0 58.0 60.0 61.0 14:54:06 INFO - PROCESS | 1652 | 14:54:06 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:84.50 stddev:2.31 (2.7%) stddev-sans-first:2.44 14:54:06 INFO - PROCESS | 1652 | Values: 83.0 86.0 81.0 82.0 86.0 81.0 86.0 82.0 87.0 83.0 14:54:06 INFO - PROCESS | 1652 | 14:54:06 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:149.80 Median:150.00 stddev:8.05 (5.4%) stddev-sans-first:8.16 14:54:06 INFO - PROCESS | 1652 | Values: 143.0 153.0 148.0 136.0 149.0 147.0 151.0 147.0 165.0 159.0 14:54:06 INFO - PROCESS | 1652 | 14:54:06 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.10 Median:195.50 stddev:26.84 (13.7%) stddev-sans-first:27.98 14:54:06 INFO - PROCESS | 1652 | Values: 167.0 218.0 159.0 209.0 153.0 167.0 199.0 192.0 141.0 206.0 14:54:06 INFO - PROCESS | 1652 | 14:54:06 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.90 Median:83.00 stddev:9.27 (11.2%) stddev-sans-first:9.58 14:54:06 INFO - PROCESS | 1652 | Values: 72.0 80.0 85.0 83.0 56.0 72.0 86.0 83.0 85.0 77.0 14:54:06 INFO - PROCESS | 1652 | -------- Summary: end -------- 14:54:06 INFO - PROCESS | 1652 | 14:54:07 INFO - TEST-INFO | 1652: exit 0 14:54:07 INFO - TEST-OK | kraken | took 60733ms 14:54:07 INFO - SUITE-END | took 1176s 14:54:07 INFO - Completed test suite (00:19:36) 14:54:07 INFO - javascript benchmark 14:54:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7965.073710134294, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3601.405768048272, 3604.243108947121, 3610.4042832932564, 3629.3104310181066, 3629.872954446593, 5910.793122240722, 6166.105914642374, 6201.658908760022, 6217.409346112117, 6242.500599952004, 5986.910196347058, 6183.227096612924, 6200.937990620092, 6244.281907580618, 6266.185395898527, 5557.72211389431, 5612.07400778873, 5629.380768115525, 5647.124695672177, 5691.4877661010505, 5703.543716502691, 5704.77180912763, 5721.971390143043, 5723.427657234285, 5735.053716136832, 5457.918131228015, 5462.863428414282, 5673.943260567383, 5715.428457154293, 5739.942600574003, 5555.50000499957, 5558.666480011212, 5558.666480011212, 5566.359868615121, 5569.832905012836, 5364.758585863646, 5497.422770609109, 5509.421510741355, 5539.169124631298, 5584.748686309126, 6198.938010619926, 6225.501959843247, 6273.247210334744, 6294.0873573332065, 6298.244210694746, 6424.421802037792, 6536.215654121488, 6583.6708164592055, 6595.307492713292, 6596.571222870498, 6355.3009168991375, 6401.967990160066, 6688.565243259172, 6722.1261236039, 6747.662616869137, 5211.530962213419, 6148.508119350441, 6197.721102550396, 6251.6561589112125, 6286.371362863677, 5749.482546570829, 6825.146856642919, 6849.554778939452, 6853.280405557344, 6882.449404047664, 6434.806955791334, 6447.516436267299, 6453.709583068773, 6457.160569125984, 6465.127207826883, 4821.734804585782, 5498.560115190775, 5514.503694667499, 6521.804345869631, 6542.901856472156, 6534.313897040837, 6546.214454265423, 6549.214094308713, 6565.409113179837, 6589.143411356493, 6518.445932095825, 6538.803835884931, 6548.0, 6552.770653027121, 6571.67141642916, 4389.956100439019, 5202.3497062867145, 5284.392294886137, 5303.761330740126, 5312.7343632818975, 3783.432485127253, 4625.283081122431, 4700.177468942921, 4743.383360163157, 4785.186518291899, 5592.86017849557, 5896.7936122235515, 6015.789447369321, 6164.6917654118015, 6265.342139075423, 6620.768273110514, 6648.368405001517, 6686.264510903797, 6697.7655782047395, 6704.664766761643, 6299.653519056405, 6300.338464461257, 6306.180196574416, 6326.588771729823, 6347.047942808524, 5651.152327150878, 5715.857103572361, 5774.566907481955, 5804.912926306109, 5847.44449277318, 6887.483438742114, 6942.305769423017, 6943.375096241463, 6956.826079348057, 6957.547759395623, 6601.471882249312, 6636.83407914806, 6668.099806526056, 6689.0, 6698.430633396216, 6562.8359291018105, 6609.339066093352, 6858.759943402157, 6896.965515172392, 6926.134233220848, 6406.263279722705, 6447.709853056717, 6450.161479007699, 6484.092227088306, 6484.675766211576, 6640.402363787282, 6644.734210631521, 6648.767293144717, 6657.53397262187, 6681.732730690913, 6245.812625621239, 6318.968405157945, 6417.069524918954, 6436.774712885027, 6440.774572889926, 6190.845228869314, 6328.493720502257, 6352.68236588178, 6450.870982580416, 6456.2575303838785, 6205.968970155121, 6339.080833279057, 6351.301356850835, 6351.777687780906, 6380.744770209325, 6360.650164241011, 6414.679266036773, 6415.262244841903, 6437.645929473924, 6458.160439142881, 6220.502359811114, 6232.532560057924, 6285.842853928689, 6317.115603815378, 6329.240491141092], "value": 6043.860043147838, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [761.3148166650014, 780.9960950195249, 842.9072801991782, 979.8873129590099, 980.534246233039, 39778.0, 40739.98150046247, 41332.173356532854, 41433.58566414335, 41756.956076098075, 40362.7981860091, 40671.186576268454, 40767.98080048, 40772.38841417381, 40873.18253634925, 8271.875921861161, 8413.831723365529, 11330.206885518017, 11571.421428928563, 11813.645590632295, 11490.712732181679, 11561.884381156207, 11574.6527604172, 11584.53661853529, 11639.35983520906, 11597.072234221285, 11599.942000289986, 11605.825912611275, 11621.954024137825, 11622.709432264177, 11540.480678369493, 11566.363849988247, 11576.652700419001, 11582.536698532049, 11607.245529040585, 10070.89220185779, 10260.281780275383, 11246.325220486797, 11260.774784504347, 11310.095192384671, 27362.0, 35040.82479587611, 35043.82478087619, 35131.0, 35142.297154056774, 3904.7657140571373, 3914.3149948759137, 3938.468306778577, 3965.3060714375156, 4011.3782363733667, 25820.870895645403, 26070.608940865903, 26230.344241393926, 27458.5881211782, 27482.587761183597, 3197.904062878117, 3243.4810430331268, 3292.061762397729, 3357.513160591725, 3458.429359155736, 356852.21573892224, 361205.3879461198, 361847.0, 362416.0, 362543.0, 45124.323135153, 45372.0, 45414.0, 45607.63177104692, 45624.0, 46959.29561056587, 47466.76266618714, 47492.52507474881, 47666.76166619144, 47866.52133478621, 14379.568612941628, 14825.184614846072, 14854.183019933791, 14868.851311486964, 14895.702085958437, 12543.937280313723, 12614.873851261369, 12793.552225672056, 12795.976321894226, 12819.0, 10592.629257975934, 12093.093018023683, 12238.694032649077, 12275.956543693886, 12374.81437778434, 5679.403662615448, 5700.287464066992, 5714.0, 5739.799107031234, 5755.280589926259, 1106.3748981825242, 1161.3380373187201, 1168.7195073182374, 1191.302394088438, 1203.1457665057908, 26771.46457070848, 27002.5949610756, 27150.0, 27173.728262717515, 27180.91276348924, 1251.674564613189, 1259.5843371687322, 1290.4064130499887, 1324.2120938041849, 1327.8539360670318, 13516.189028658311, 13866.306684665726, 14079.788803167961, 14105.647358816112, 14168.433262669378, 490964.0, 492951.0, 493359.0, 493564.0, 493849.53075234394, 49497.50502494929, 58655.41344586499, 58802.41197588055, 59002.81994360089, 59047.0, 1273.6879464531248, 1298.889594384488, 1332.5469340424302, 1528.0678785940568, 1537.2705706080435, 27479.488628124844, 29819.403611927206, 30525.694743053173, 30672.693273067875, 31218.21954451157, 45776.54223457856, 45967.77016114898, 46091.30863037124, 46141.07717845557, 46352.6094217174, 444135.0, 445620.0, 447217.0, 447634.0, 449706.0, 31625.42581141428, 31720.841395792875, 31823.204419888763, 31993.36013279768, 32031.519527207576, 37913.24173516459, 45617.31574026326, 45659.77170114128, 45808.770956145, 45822.77088614536, 29920.551191731705, 31270.374592508477, 31311.373772523966, 31910.361792764477, 31913.20216994501, 26577.0, 29825.0, 30661.693383066777, 30665.386692265583, 31523.36953260876, 35583.644163558034, 35830.0, 36996.0, 37367.62632373641, 37405.81297093606, 30719.078427647208, 30723.539146913263, 30909.845450772602, 31595.0, 31736.0, 21604.45988850291, 23627.763722362557, 24667.62998555059, 25255.747442526073, 25323.620145698198], "value": 19153.0932586484, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [420.4366149359861, 430.1139652316229, 434.65662126919733, 441.72022731220676, 445.1364353154879, 19916.402507924748, 21032.0, 22205.2228172014, 22325.330240092822, 22431.214907478243, 22280.442988925286, 22639.320820375375, 23371.883140584316, 23615.881920590415, 23915.76084239157, 6902.102726645554, 6932.272111428303, 7118.3949364303835, 7224.56652600843, 7226.096737907762, 6935.791926242221, 7091.503594748369, 7114.146302443688, 7123.786286411416, 7127.679254433537, 6735.0, 7068.823279418004, 7068.858622827541, 7105.5026148169645, 7113.537620054706, 6836.589804611739, 7022.894656580155, 7053.400460960825, 7112.25321341262, 7120.430365570768, 6912.447004239699, 6940.30596940307, 6947.89578156328, 6960.686769095403, 6967.790966270969, 24081.397965050837, 25573.23280301594, 26619.334516637045, 27418.0, 27887.0, 2552.3236342369287, 2633.0652618320605, 3445.000949724577, 3469.6356882527225, 3528.4530897710897, 16267.10530920799, 16694.4156954506, 16708.832911670972, 17027.48917532476, 17464.47606571805, 2138.9465263368384, 2170.4899348653053, 2188.562287542482, 2201.680756290329, 2205.503761653615, 164641.17679411525, 170525.0, 172551.0, 173912.13043934826, 174301.9103508553, 16736.41422550205, 20642.555021148375, 21292.82861590641, 21560.0297986591, 21817.145542628736, 32326.0, 32358.0, 32744.34511309808, 32856.83571582175, 33431.498527522104, 10682.78634427307, 10727.946360268148, 10968.725781855517, 10989.175811814146, 11093.223474356879, 9074.86387704185, 9091.636334546676, 9097.317701172438, 9211.723648290563, 9245.0, 9795.510224488748, 10013.849792253122, 10119.645812396531, 10119.862842518696, 10147.412409835444, 4033.6487276762027, 4140.970924247157, 4169.728967617095, 4171.372035617542, 4173.770442625624, 885.4864178776294, 989.5448093876753, 1034.6792494326783, 1038.3406536849154, 1067.7864427114607, 17293.394731184348, 20828.791712082988, 20957.26649567258, 21033.474163145736, 21110.894445527672, 1017.9592816287266, 1020.2705065877784, 1045.414567842011, 1049.082053203447, 1060.8833028366873, 10583.312084714624, 11036.889631103746, 11047.447627618587, 11089.112870970303, 11102.722431939266, 429269.0, 481211.1878881236, 730961.0, 732909.0, 747369.0, 149570.0, 153648.0, 155086.0, 155843.0, 160010.0, 1274.2991354754847, 1283.57641978147, 1302.9609111726475, 1305.092960392537, 1308.2085338370339, 17879.10604469745, 18567.53581160482, 18630.347937822113, 18703.438896833115, 18713.438596842116, 57854.0, 58616.748925806605, 59373.10940335812, 59705.0, 60528.39471605227, 86221.2755744869, 86585.56707216424, 87623.0, 89254.0, 89306.0, 10750.784984300426, 11576.305421674726, 11822.290662560274, 11983.580574679845, 12112.333821639895, 13062.54281100157, 13101.279429631462, 13337.733245334844, 13467.797983030066, 13505.257210853497, 10181.694549163536, 11248.550057997589, 11336.829947550958, 11567.479463424335, 11659.941700291443, 9303.116203960613, 9897.208223341971, 10536.0, 10934.234603577992, 10992.945035275092, 11234.943825280821, 11467.139964502529, 11522.654320370402, 11915.404229788302, 11952.402379880798, 11565.595204167814, 11628.302301861915, 11810.881891181321, 11918.046556275303, 12051.879481205426, 11250.549978001116, 11540.653780386601, 11677.240979336317, 11697.941510292734, 11724.472398741906], "value": 11830.38058609706, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1402.2077526197697, 1607.2204980584406, 1627.9255691243782, 1641.3516660918938, 1645.9259333329994, 10050.648227312045, 11080.778384432306, 11793.587224447147, 11838.0, 11961.162718609701, 11477.770444591104, 11637.650870473879, 11647.941760291207, 11656.475458604362, 11679.948804607584, 2491.9003239870385, 2524.734902835194, 2534.4170840706556, 2536.226450932465, 2538.809589280802, 2482.900683972648, 2495.0, 2532.1643857526974, 2534.759197876199, 2542.250036239314, 2220.7224096987875, 2460.3849037740565, 2491.9377015574573, 2525.7095434025136, 2534.6071358939394, 2426.4055306449945, 2469.4567195217114, 2482.2801387597574, 2492.4516606346756, 2506.699196096462, 2281.6235321171985, 2462.335169504224, 2476.0, 2484.7887929526014, 2485.7887079598077, 11447.828282575769, 11648.650540483799, 11662.008729258023, 11690.590829321018, 11734.0, 6032.698365081728, 6112.602680825732, 6182.84542886427, 6188.81433556994, 6196.163517925068, 3659.3779057560278, 3756.34264003801, 3768.812823960444, 3838.0788610733503, 3859.807009649506, 1306.2946009155046, 1929.2668785861283, 1939.5345117171778, 1978.3690373327784, 2011.9597608047904, 14314.212718300489, 14649.487267945571, 15500.0, 15995.920020399824, 16111.516654500383, 4802.207635740064, 4814.350062741485, 4955.72743499111, 4981.775820088104, 5002.474740152305, 12287.56993505223, 12337.013038956862, 12354.567590134302, 12364.505419783289, 12374.938125309316, 6031.035034394476, 6075.696215189311, 6084.30030546484, 6086.087086936906, 6088.847778805477, 4889.951100488949, 5414.133738601883, 5538.363088244903, 5622.184783206412, 5644.153376993484, 3487.8081705505933, 4052.3263444963586, 4113.403556484293, 4126.339785634284, 4155.501339839238, 917.4585503558565, 1079.7894410589859, 1094.2777766673958, 1094.6606551968914, 1104.2270410712372, 858.3948316436897, 992.2855544008447, 992.5682328187262, 1014.3558840136419, 1030.2376241581176, 6822.897656535156, 6823.351781580742, 6954.165500139914, 7010.859782804418, 7031.507794454339, 927.8654595083675, 1001.6043662753171, 1029.8661174047327, 1037.1443559063803, 1075.8224892892586, 3437.604675462354, 4292.334688123346, 4352.434183556117, 4362.956370436318, 4383.167198232326, 14150.433982640785, 14555.854441455662, 14570.271486425636, 14589.635259118395, 14594.0, 14425.062370945854, 14430.0, 14431.061980971203, 14434.711305774035, 14485.493007744679, 9056.184943355129, 10126.240531960133, 10470.790584188273, 10723.03492685662, 10743.731406714738, 6042.335343112166, 6880.69036893331, 6930.306969303029, 6960.721571137098, 7003.7548685795755, 12538.435770390537, 12579.87420125787, 12769.0, 12776.36118194068, 13066.0, 13208.80186797218, 13318.733625327633, 13319.200847949154, 13352.265625390308, 13465.663358416117, 9193.448393096436, 9373.062693730571, 9579.616815327588, 9583.808323833624, 9589.328746987923, 10345.482725863527, 10448.164146868081, 10600.681979540626, 10695.251332406657, 10892.019718225398, 7578.81052973658, 8830.49881520156, 8963.326882040437, 9064.0, 9348.158665720119, 8793.868091978753, 8794.560271986504, 8854.158854908774, 9045.321600880092, 9074.45553266806, 8220.136885626924, 8328.125546817739, 8367.665293388196, 8374.413791034813, 8408.915910840813, 5948.672822994777, 6020.247469066367, 6109.2974307955155, 6154.692265386623, 6223.719932602953, 6049.758009679564, 6121.296050954197, 6191.318954914866, 6307.779227727007, 6318.747250110128], "value": 5390.26025040525, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.27752187127265, 388.36890053662796, 391.20975629228957, 406.2383031815346, 407.32384242158, 4519.163954668388, 4874.585660218877, 5148.047611191927, 5197.544687487505, 5282.577393808496, 4464.272332602281, 4668.016093160401, 5178.249153872686, 5290.894182116365, 5426.186072089179, 3801.1637439763344, 4287.50693670228, 4453.997850483648, 4469.653431948237, 4506.076254367863, 3816.82824272907, 4431.689781715281, 4485.865424037268, 4569.223232050543, 4617.705533853515, 3569.5716514018354, 4159.542450330461, 4398.318260669601, 4495.977520112395, 4503.414556107701, 3561.216532362876, 4002.1795531915886, 4172.395002724603, 4232.043039757837, 4463.709858859196, 3491.9825400873087, 3638.4542318652148, 4067.9186416271805, 4074.857379991716, 4082.3672330788477, 5101.897962040775, 5198.662086964373, 5282.8529604260475, 5290.545100097504, 5307.6019298552765, 4436.112777444492, 4532.660050496226, 4739.668223224376, 5081.466446023152, 5169.379674439091, 4375.665422046274, 4778.904421911542, 5534.714347797883, 5632.7746890124345, 5680.914786278167, 3984.2629113613784, 4015.6185162409524, 4570.542945705402, 4574.4968053514085, 4595.609373203249, 4924.507549245082, 5545.916811247795, 5956.260120344647, 6008.347704381286, 6022.711369679494, 4480.238359478896, 4618.792154353062, 4629.791659375269, 4666.533346665306, 4680.3915490985955, 4159.459270294842, 4321.503027151855, 4724.88187795301, 4808.61531077513, 4931.358923340014, 4685.156671799041, 4790.544898234732, 4832.8791780205775, 5148.845534633892, 5156.175011998137, 5551.918030738473, 5569.551916501658, 5622.522085622768, 5666.121751128581, 5710.971445142831, 1512.2136489025645, 1622.8945118567492, 1627.4141309128704, 1634.5014770495113, 1647.4563394079926, 1135.761490087091, 1177.3877583656451, 1215.501644325822, 1228.5761412312738, 1237.7127787101495, 4734.55021772931, 4751.548602882721, 5436.565074794007, 5441.863953401117, 5483.643563168382, 5199.688018718889, 5359.740460991664, 5389.0299746046085, 5521.0, 6022.969885150546, 4894.95105048945, 5021.221710634857, 5101.336826212643, 5172.948270517322, 5354.0, 2834.971650283471, 3004.248937765559, 3048.6189226346705, 3066.880588585195, 3071.585335979659, 5709.628874123168, 5890.764369425175, 6045.818625441244, 6148.4773794227995, 6247.3440288769025, 5146.845594632167, 5346.946530534645, 5665.688387138747, 5966.224390829165, 6059.515238780887, 5291.243217315749, 5990.940090599125, 6115.541334399938, 6143.662098584531, 6213.565050446426, 5897.228302697725, 5905.822825315247, 5973.671448070398, 5992.760289588367, 6005.81982540507, 5544.7782088716, 5762.94237057624, 5777.093559125563, 5791.82624521265, 5898.734556944862, 4761.190597598347, 5138.820141295037, 5845.240118784531, 5962.403759624176, 6138.600990935575, 4282.421873047098, 4625.491195968508, 4690.906181876275, 4694.577488026094, 4710.905781884411, 4828.493008234085, 5310.362756469249, 5397.298351214317, 5437.700926449083, 5904.3210030847, 4914.606831453403, 5685.459881311269, 5721.256236689203, 5762.913556296575, 5788.884222315614, 5116.132611596666, 5584.253384623038, 5682.659040457585, 5719.370869204467, 5723.828285151452, 5134.6662466941025, 5560.386618289397, 5570.164475328571, 5602.439756024365, 5615.073512870331, 4846.442659094249, 5590.720463976866, 5772.567057470623, 5853.765849365977, 5885.352611212677, 4720.622350211906, 5549.361823390201, 5582.594617283535, 5604.971975140098, 5690.743916523684], "value": 4351.893834114017, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.3463400256774, 791.6556298010365, 810.2140923304393, 836.987245433026, 877.9429337093087, 16791.83208167918, 17334.653306933884, 17369.826301736994, 17437.38969136077, 17620.47138585843, 14441.989060765723, 15406.69186616267, 15996.280167392466, 16148.919255403707, 16161.272742726576, 850.7702920211539, 972.0910948263361, 977.2475194100539, 990.3711143423924, 1025.3027941000116, 1016.2124353625925, 1020.5203554329453, 1031.2626472072466, 1032.664384075175, 1035.3632516002679, 1081.2755453845941, 1083.1930211992078, 1083.8374243863404, 1085.4138765066893, 1086.8750093739243, 1079.4171147580298, 1079.6275285026654, 1079.8056349857022, 1080.2924084724518, 1080.8108580998332, 806.040811434397, 806.2743530822307, 806.6450761664848, 808.1635507249968, 809.6275713171948, 17098.82901170997, 17541.947483150925, 17554.122293885383, 17586.560335991573, 17832.732509012247, 14478.276086195649, 14761.18813465259, 14892.851071489255, 14934.253287335698, 14943.402263909431, 11652.766944661143, 12153.756924861542, 12179.964703000256, 12270.631881043493, 12858.421371038232, 3839.232153569297, 3848.422736589506, 3911.4966421490267, 3966.0, 3981.880543583697, 101172.0, 118863.40568297189, 119502.402487987, 120299.39850300693, 121148.39425802814, 10994.505247263893, 11083.889161108447, 11259.605913792979, 11654.359010254348, 11800.055995520337, 23685.644715329283, 24103.638445423334, 24104.75895241025, 24168.274951751475, 24220.51558968846, 6984.476164287698, 8180.6318715657935, 8194.467359621725, 8201.466904651179, 8202.138775428613, 4534.591886730144, 4567.862964111082, 4603.976980115078, 4638.6752927294765, 4751.40607424072, 3653.4337177737493, 3743.644353786386, 3804.2201348723447, 3962.603739626072, 4023.6177563131455, 657.8684263147389, 717.7129148340601, 721.7401735375261, 721.9566825990457, 727.1540082509662, 564.3932772269826, 577.5091172503338, 591.5060924128401, 597.158007209832, 599.5233789137646, 10483.633072842415, 10775.568977240822, 10942.617008404668, 10947.50736216872, 11166.441677916071, 581.9418058194232, 596.3321080389991, 606.5935822998601, 650.8926027205553, 754.7773406844993, 4938.012397520511, 5161.380634323905, 5207.02041060333, 5257.447967963309, 5264.394594621591, 102242.48878755559, 103141.0, 103916.44125338126, 105928.0, 106091.40862887063, 63150.368496315365, 78113.0, 91504.0, 91920.0, 92358.53820730987, 75428.24571754322, 77127.61436192783, 77998.0, 79237.60381198057, 79293.0, 10070.244731645009, 10094.949525252327, 10146.492675366055, 10183.236257280585, 10920.399378034284, 48984.75507622439, 49734.75132624314, 51370.22944655758, 52316.890881913365, 53574.92850142897, 63251.36748632455, 63987.72024559576, 63994.0, 69464.6526767363, 70291.29708702848, 12899.613011609667, 12973.935130324287, 13130.212187268795, 13289.0, 13443.462261509821, 14629.561113166197, 15057.472988445352, 15219.391224350902, 15278.006929549543, 15311.310991005654, 13718.931405343308, 13922.373493192628, 14231.50189743354, 14320.212388318743, 14907.403703851724, 11927.880721192678, 13317.0, 14389.784153237919, 14473.85526144725, 14908.403663853325, 14379.640508987359, 14588.562343129723, 14620.195889226196, 14762.114273143647, 14821.851781482048, 9490.240780737662, 9706.9029309706, 10274.0, 10281.640142594973, 10508.369497830156, 9033.554631258967, 10851.891481085087, 11697.415129243675, 11815.586454474396, 12236.327002014974], "value": 7948.032624848491, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1552.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 118.0, 120.0, 118.0, 119.0, 120.0, 120.0, 119.0, 119.0, 124.0], "value": 118.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 127.0, 137.0, 178.0, 110.0, 110.0, 113.0, 107.0, 144.0, 110.0], "value": 127.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 193.0, 201.0, 199.0, 206.0, 199.0, 222.0, 179.0, 277.0, 284.0], "value": 215.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 58.0, 87.0, 60.0, 62.0, 59.0, 59.0, 72.0, 59.0], "value": 65.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 71.0, 84.0, 72.0, 71.0, 71.0, 101.0, 100.0, 103.0, 97.0], "value": 85.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 136.0, 89.0, 90.0, 91.0, 116.0, 89.0, 84.0, 134.0, 136.0], "value": 108.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 97.0, 99.0, 153.0, 112.0, 97.0, 97.0, 97.0, 97.0], "value": 104.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 101.0, 86.0, 91.0, 93.0, 92.0, 94.0, 92.0, 90.0, 87.0], "value": 92.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 65.0, 67.0, 69.0, 67.0, 89.0, 82.0, 70.0, 69.0], "value": 71.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 94.0, 96.0, 92.0, 78.0, 57.0, 58.0, 60.0, 61.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 81.0, 82.0, 86.0, 81.0, 86.0, 82.0, 87.0, 83.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 153.0, 148.0, 136.0, 149.0, 147.0, 151.0, 147.0, 165.0, 159.0], "value": 149.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 218.0, 159.0, 209.0, 153.0, 167.0, 199.0, 192.0, 141.0, 206.0], "value": 181.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 80.0, 85.0, 83.0, 56.0, 72.0, 86.0, 83.0, 85.0, 77.0], "value": 77.9, "unit": "score"}]}]} 14:54:07 INFO - Return code: 0 14:54:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:54:07 ERROR - # TBPL SUCCESS # 14:54:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:54:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:54:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:54:07 INFO - 2017-05-22 14:54:07.963 screenresolution[1657:10540] starting screenresolution argv=screenresolution get 14:54:07 INFO - 2017-05-22 14:54:07.965 screenresolution[1657:10540] Display 0: 1600x1200x32@60 14:54:07 INFO - 2017-05-22 14:54:07.972 screenresolution[1658:10543] starting screenresolution argv=screenresolution list 14:54:07 INFO - Available Modes on Display 0 14:54:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:54:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:54:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:54:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:54:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:54:08 INFO - Intel Iris: 14:54:08 INFO - Chipset Model: Intel Iris 14:54:08 INFO - Type: GPU 14:54:08 INFO - Bus: Built-In 14:54:08 INFO - VRAM (Dynamic, Max): 1536 MB 14:54:08 INFO - Vendor: Intel (0x8086) 14:54:08 INFO - Device ID: 0x0a2e 14:54:08 INFO - Revision ID: 0x0009 14:54:08 INFO - Displays: 14:54:08 INFO - Display: 14:54:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:54:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:54:08 INFO - Display Serial Number: 1600x1200 60 14:54:08 INFO - Main Display: Yes 14:54:08 INFO - Mirror: Off 14:54:08 INFO - Online: Yes 14:54:08 INFO - Rotation: Supported 14:54:08 INFO - Return code: 0 14:54:08 INFO - Running post-action listener: _resource_record_post_action 14:54:08 INFO - [mozharness: 2017-05-22 21:54:08.206691Z] Finished run-tests step (success) 14:54:08 INFO - Running post-run listener: _resource_record_post_run 14:54:08 INFO - Total resource usage - Wall time: 1192s; CPU: 55.0%; Read bytes: 39577600; Write bytes: 425674752; Read time: 391; Write time: 1584 14:54:08 INFO - TinderboxPrint: CPU usage
54.9% 14:54:08 INFO - TinderboxPrint: I/O read bytes / time
39,577,600 / 391 14:54:08 INFO - TinderboxPrint: I/O write bytes / time
425,674,752 / 1,584 14:54:08 INFO - TinderboxPrint: CPU idle
2,151.4 (45.1%) 14:54:08 INFO - TinderboxPrint: CPU system
964.6 (20.2%) 14:54:08 INFO - TinderboxPrint: CPU user
1,653.6 (34.7%) 14:54:08 INFO - TinderboxPrint: Swap in / out
161,988,608 / 0 14:54:08 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 123224576; Write bytes: 115286016; Read time: 11080; Write time: 221 14:54:08 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 35899392; Write bytes: 301213696; Read time: 358; Write time: 1324 14:54:08 INFO - Running post-run listener: _upload_blobber_files 14:54:08 INFO - Blob upload gear active. 14:54:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:54:08 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 14:54:08 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'] 14:54:08 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 14:54:09 INFO - (blobuploader) - INFO - Open directory for files ... 14:54:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:54:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:54:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:54:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:54:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:54:10 INFO - (blobuploader) - INFO - Done attempting. 14:54:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:54:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:54:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:54:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:54:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:54:11 INFO - (blobuploader) - INFO - Done attempting. 14:54:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:54:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:54:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:54:12 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:54:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:54:12 INFO - (blobuploader) - INFO - Done attempting. 14:54:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:54:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:54:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:54:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:54:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:54:14 INFO - (blobuploader) - INFO - Done attempting. 14:54:14 INFO - (blobuploader) - INFO - Iteration through files over. 14:54:14 INFO - Return code: 0 14:54:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:54:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:54:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/86ef2d0a1141c055acd77e5af57f4262b7f2c10baa9aa6221fe7e053fdd82e5a88380e2767d1ad7fcf69828337a5886ba64e28ff96ff98420b695a7bf0664330", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/757d365ea32fc254fdb0d7ffb0cbc2a27396597d29c1b8760b1d1d660d79446a795e1cf5e96acec1963abc38227d04f3c580ea3711da3d6473815985a79a2bc5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c7c6b6e58b4aacfa0e049e2e104b293002395059263c0745dc48aaf64fa6b30d7fcf3bcc8b3cf08e37d0c9474a795ee037914d6c3285acfea18d815cf04cd7fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/592b889f3d98a7e17c490cde7d05845ca9fc34609c356908a1552508bc5afdc7e9f1bfa90f02e907de03b3fcdecde9fbc3146c03f936cfb35f9c82557e71fd77"} 14:54:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:54:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:54:14 INFO - Contents: 14:54:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/86ef2d0a1141c055acd77e5af57f4262b7f2c10baa9aa6221fe7e053fdd82e5a88380e2767d1ad7fcf69828337a5886ba64e28ff96ff98420b695a7bf0664330", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/757d365ea32fc254fdb0d7ffb0cbc2a27396597d29c1b8760b1d1d660d79446a795e1cf5e96acec1963abc38227d04f3c580ea3711da3d6473815985a79a2bc5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c7c6b6e58b4aacfa0e049e2e104b293002395059263c0745dc48aaf64fa6b30d7fcf3bcc8b3cf08e37d0c9474a795ee037914d6c3285acfea18d815cf04cd7fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/592b889f3d98a7e17c490cde7d05845ca9fc34609c356908a1552508bc5afdc7e9f1bfa90f02e907de03b3fcdecde9fbc3146c03f936cfb35f9c82557e71fd77"} 14:54:14 INFO - Running post-run listener: copy_logs_to_upload_dir 14:54:14 INFO - Copying logs to upload dir... 14:54:14 INFO - mkdir: /builds/slave/test/build/upload/logs 14:54:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.032664 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-05-22 14:54:14.455918) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 14:54:14.459873) ========= 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.W6ubdOovLY/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.38KVJdw3Gl/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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/86ef2d0a1141c055acd77e5af57f4262b7f2c10baa9aa6221fe7e053fdd82e5a88380e2767d1ad7fcf69828337a5886ba64e28ff96ff98420b695a7bf0664330", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/757d365ea32fc254fdb0d7ffb0cbc2a27396597d29c1b8760b1d1d660d79446a795e1cf5e96acec1963abc38227d04f3c580ea3711da3d6473815985a79a2bc5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c7c6b6e58b4aacfa0e049e2e104b293002395059263c0745dc48aaf64fa6b30d7fcf3bcc8b3cf08e37d0c9474a795ee037914d6c3285acfea18d815cf04cd7fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/592b889f3d98a7e17c490cde7d05845ca9fc34609c356908a1552508bc5afdc7e9f1bfa90f02e907de03b3fcdecde9fbc3146c03f936cfb35f9c82557e71fd77"} build_url:https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010424 build_url: 'https://queue.taskcluster.net/v1/task/c_ioQupCSsuy6tiM1VNvAQ/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/86ef2d0a1141c055acd77e5af57f4262b7f2c10baa9aa6221fe7e053fdd82e5a88380e2767d1ad7fcf69828337a5886ba64e28ff96ff98420b695a7bf0664330", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/757d365ea32fc254fdb0d7ffb0cbc2a27396597d29c1b8760b1d1d660d79446a795e1cf5e96acec1963abc38227d04f3c580ea3711da3d6473815985a79a2bc5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c7c6b6e58b4aacfa0e049e2e104b293002395059263c0745dc48aaf64fa6b30d7fcf3bcc8b3cf08e37d0c9474a795ee037914d6c3285acfea18d815cf04cd7fa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/592b889f3d98a7e17c490cde7d05845ca9fc34609c356908a1552508bc5afdc7e9f1bfa90f02e907de03b3fcdecde9fbc3146c03f936cfb35f9c82557e71fd77"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 14:54:14.492346) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 14:54:14.492683) ========= 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.W6ubdOovLY/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.38KVJdw3Gl/Listeners TMPDIR=/var/folders/9c/2dh985pd15l19fywb0pq404000000w/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.005157 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 14:54:14.541348) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 14:54:14.541680) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 14:54:14.542054) ========= ========= Total master_lag: 0.17 =========