builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0235 starttime: 1496322313.66 results: success (0) buildid: 20170601042031 builduid: dd6d90b4711245e88f9ad64be49f2a89 revision: 86339b41e6e98b5e0580ae19ffbe6c85a54bfc9f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:13.664380) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:13.664787) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:13.665056) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:13.695348) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:13.695673) ========= 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.vI65GjoyDY/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.mibAUaBPHM/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-01 06:05:13-- 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% 207M=0s 2017-06-01 06:05:13 (207 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125530 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:13.844151) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:13.844458) ========= 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.vI65GjoyDY/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.mibAUaBPHM/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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.032226 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:13.901554) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:13.904947) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 86339b41e6e98b5e0580ae19ffbe6c85a54bfc9f --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 86339b41e6e98b5e0580ae19ffbe6c85a54bfc9f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vI65GjoyDY/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.mibAUaBPHM/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-01 06:05:14,004 truncating revision to first 12 chars 2017-06-01 06:05:14,004 Setting DEBUG logging. 2017-06-01 06:05:14,004 attempt 1/10 2017-06-01 06:05:14,004 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/86339b41e6e9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-01 06:05:14,311 unpacking tar archive at: mozilla-beta-86339b41e6e9/testing/mozharness/ program finished with exit code 0 elapsedTime=0.597954 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:14.525606) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:14.529013) ========= script_repo_revision: 86339b41e6e98b5e0580ae19ffbe6c85a54bfc9f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:14.529453) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:14.529735) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:14.549049) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-06-01 06:05:14.549425) ========= /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.vI65GjoyDY/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.mibAUaBPHM/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 06:05:14 INFO - MultiFileLogger online at 20170601 06:05:14 in /builds/slave/test 06:05:14 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 06:05:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:05:14 INFO - {'append_to_log': False, 06:05:14 INFO - 'base_work_dir': '/builds/slave/test', 06:05:14 INFO - 'blob_upload_branch': 'Mozilla-Beta', 06:05:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:05:14 INFO - 'branch': 'Mozilla-Beta', 06:05:14 INFO - 'buildbot_json_path': 'buildprops.json', 06:05:14 INFO - 'config_files': ('talos/mac_config.py',), 06:05:14 INFO - 'default_actions': ('clobber', 06:05:14 INFO - 'read-buildbot-config', 06:05:14 INFO - 'download-and-extract', 06:05:14 INFO - 'populate-webroot', 06:05:14 INFO - 'create-virtualenv', 06:05:14 INFO - 'install', 06:05:14 INFO - 'run-tests'), 06:05:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:05:14 INFO - 'download_minidump_stackwalk': True, 06:05:14 INFO - 'download_symbols': 'ondemand', 06:05:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:05:14 INFO - 'tooltool.py': '/tools/tooltool.py', 06:05:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:05:14 INFO - '/tools/misc-python/virtualenv.py')}, 06:05:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:05:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:05:14 INFO - 'gecko_profile': False, 06:05:14 INFO - 'gecko_profile_interval': 0, 06:05:14 INFO - 'installer_path': 'installer.exe', 06:05:14 INFO - 'log_level': 'info', 06:05:14 INFO - 'log_name': 'talos', 06:05:14 INFO - 'log_to_console': True, 06:05:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:05:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:05:14 INFO - 'opt_config_files': (), 06:05:14 INFO - 'pip_index': False, 06:05:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:05:14 INFO - 'cmd': ('bash', 06:05:14 INFO - '-c', 06:05:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:05:14 INFO - 'enabled': True, 06:05:14 INFO - 'halt_on_failure': False, 06:05:14 INFO - 'name': 'check_screen_resolution'},), 06:05:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:05:14 INFO - 'cmd': ('bash', 06:05:14 INFO - '-c', 06:05:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:05:14 INFO - 'enabled': True, 06:05:14 INFO - 'halt_on_failure': False, 06:05:14 INFO - 'name': 'check_screen_resolution'},), 06:05:14 INFO - 'run_cmd_checks_enabled': True, 06:05:14 INFO - 'suite': 'dromaeojs-e10s', 06:05:14 INFO - 'system_bits': '32', 06:05:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:05:14 INFO - 'title': 't-yosemite-r7-0235', 06:05:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:05:14 INFO - 'use_talos_json': True, 06:05:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:05:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:05:14 INFO - 'work_dir': 'build'} 06:05:14 INFO - [mozharness: 2017-06-01 13:05:14.756482Z] Running clobber step. 06:05:14 INFO - Running pre-action listener: _resource_record_pre_action 06:05:14 INFO - Running main action method: clobber 06:05:14 INFO - rmtree: /builds/slave/test/build 06:05:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:05:17 INFO - Running post-action listener: _resource_record_post_action 06:05:17 INFO - [mozharness: 2017-06-01 13:05:17.087914Z] Finished clobber step (success) 06:05:17 INFO - [mozharness: 2017-06-01 13:05:17.088047Z] Running read-buildbot-config step. 06:05:17 INFO - Running pre-action listener: _resource_record_pre_action 06:05:17 INFO - Running main action method: read_buildbot_config 06:05:17 INFO - Using buildbot properties: 06:05:17 INFO - { 06:05:17 INFO - "project": "", 06:05:17 INFO - "product": "firefox", 06:05:17 INFO - "script_repo_revision": "production", 06:05:17 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 06:05:17 INFO - "repository": "", 06:05:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 06:05:17 INFO - "buildid": "20170601042031", 06:05:17 INFO - "pgo_build": "False", 06:05:17 INFO - "basedir": "/builds/slave/test", 06:05:17 INFO - "buildnumber": 9, 06:05:17 INFO - "slavename": "t-yosemite-r7-0235", 06:05:17 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 06:05:17 INFO - "platform": "yosemite_r7", 06:05:17 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 06:05:17 INFO - "branch": "mozilla-beta", 06:05:17 INFO - "repo_path": "releases/mozilla-beta", 06:05:17 INFO - "revision": "86339b41e6e98b5e0580ae19ffbe6c85a54bfc9f", 06:05:17 INFO - "stage_platform": "macosx64", 06:05:17 INFO - "builduid": "dd6d90b4711245e88f9ad64be49f2a89", 06:05:17 INFO - "slavebuilddir": "test" 06:05:17 INFO - } 06:05:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/aFidrUb3QfWGY_qZyH4Arw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 06:05:17 INFO - Running post-action listener: _resource_record_post_action 06:05:17 INFO - [mozharness: 2017-06-01 13:05:17.091627Z] Finished read-buildbot-config step (success) 06:05:17 INFO - [mozharness: 2017-06-01 13:05:17.091767Z] Running download-and-extract step. 06:05:17 INFO - Running pre-action listener: _resource_record_pre_action 06:05:17 INFO - Running main action method: download_and_extract 06:05:17 INFO - mkdir: /builds/slave/test/build/tests 06:05:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:05:17 INFO - trying https://queue.taskcluster.net/v1/task/aFidrUb3QfWGY_qZyH4Arw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 06:05:17 INFO - Downloading https://queue.taskcluster.net/v1/task/aFidrUb3QfWGY_qZyH4Arw/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 06:05:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aFidrUb3QfWGY_qZyH4Arw/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 06:05:18 INFO - Downloaded 1287 bytes. 06:05:18 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 06:05:18 INFO - Using the following test package requirements: 06:05:18 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:05:18 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 06:05:18 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 06:05:18 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:05:18 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 06:05:18 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:05:18 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 06:05:18 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 06:05:18 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:05:18 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 06:05:18 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 06:05:18 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:05:18 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 06:05:18 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:05:18 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 06:05:18 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:05:18 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 06:05:18 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:05:18 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 06:05:18 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 06:05:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aFidrUb3QfWGY_qZyH4Arw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 06:05:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aFidrUb3QfWGY_qZyH4Arw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 06:05:18 INFO - Fetch https://queue.taskcluster.net/v1/task/aFidrUb3QfWGY_qZyH4Arw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 06:05:20 INFO - Expected file size: 51393280 06:05:20 INFO - Obtained file size: 51393280 06:05:35 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 06:05:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aFidrUb3QfWGY_qZyH4Arw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 06:05:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aFidrUb3QfWGY_qZyH4Arw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 06:05:35 INFO - Fetch https://queue.taskcluster.net/v1/task/aFidrUb3QfWGY_qZyH4Arw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 06:05:38 INFO - Expected file size: 51393280 06:05:38 INFO - Obtained file size: 51393280 06:05:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aFidrUb3QfWGY_qZyH4Arw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 06:05:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aFidrUb3QfWGY_qZyH4Arw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 06:05:53 INFO - Fetch https://queue.taskcluster.net/v1/task/aFidrUb3QfWGY_qZyH4Arw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 06:05:54 INFO - Expected file size: 14404461 06:05:54 INFO - Obtained file size: 14404461 06:05:54 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')]} 06:05:54 INFO - trying https://queue.taskcluster.net/v1/task/aFidrUb3QfWGY_qZyH4Arw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 06:05:54 INFO - Downloading https://queue.taskcluster.net/v1/task/aFidrUb3QfWGY_qZyH4Arw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 06:05:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aFidrUb3QfWGY_qZyH4Arw/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 06:05:56 INFO - Downloaded 59647424 bytes. 06:05:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aFidrUb3QfWGY_qZyH4Arw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 06:05:56 INFO - mkdir: /builds/slave/test/properties 06:05:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:05:56 INFO - Writing to file /builds/slave/test/properties/build_url 06:05:56 INFO - Contents: 06:05:56 INFO - build_url:https://queue.taskcluster.net/v1/task/aFidrUb3QfWGY_qZyH4Arw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 06:05:57 INFO - Running post-action listener: _resource_record_post_action 06:05:57 INFO - Running post-action listener: set_extra_try_arguments 06:05:57 INFO - [mozharness: 2017-06-01 13:05:57.245993Z] Finished download-and-extract step (success) 06:05:57 INFO - [mozharness: 2017-06-01 13:05:57.246148Z] Running populate-webroot step. 06:05:57 INFO - Running pre-action listener: _resource_record_pre_action 06:05:57 INFO - Running main action method: populate_webroot 06:05:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:05:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:05:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:05:57 INFO - '--tppagecycles', 06:05:57 INFO - '7'], 06:05:57 INFO - 'tests': ['tsvgm']}}, 06:05:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:05:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:05:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:05:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:05:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:05:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:05:57 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:05:57 INFO - 'talos_options': ['--disable-e10s'], 06:05:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:05:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:05:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:05:57 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:05:57 INFO - 'talos_options': ['--disable-e10s'], 06:05:57 INFO - 'tests': ['damp', 'tps']}, 06:05:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:05:57 INFO - 'tests': ['damp', 'tps']}, 06:05:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:05:57 INFO - 'tests': ['dromaeo_dom']}, 06:05:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:05:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:05:57 INFO - 'tests': ['basic_compositor_video']}, 06:05:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:05:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:05:57 INFO - 'tests': ['a11yr', 06:05:57 INFO - 'ts_paint', 06:05:57 INFO - 'tpaint', 06:05:57 INFO - 'sessionrestore', 06:05:57 INFO - 'sessionrestore_no_auto_restore', 06:05:57 INFO - 'tabpaint']}, 06:05:57 INFO - 'other-e10s': {'tests': ['a11yr', 06:05:57 INFO - 'ts_paint', 06:05:57 INFO - 'tpaint', 06:05:57 INFO - 'sessionrestore', 06:05:57 INFO - 'sessionrestore_no_auto_restore', 06:05:57 INFO - 'tabpaint']}, 06:05:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:05:57 INFO - 'ts_paint', 06:05:57 INFO - 'tpaint', 06:05:57 INFO - 'sessionrestore', 06:05:57 INFO - 'sessionrestore_no_auto_restore', 06:05:57 INFO - 'tabpaint']}, 06:05:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:05:57 INFO - 'ts_paint', 06:05:57 INFO - 'tpaint', 06:05:57 INFO - 'sessionrestore', 06:05:57 INFO - 'sessionrestore_no_auto_restore', 06:05:57 INFO - 'tabpaint']}, 06:05:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:05:57 INFO - 'tests': ['a11yr', 06:05:57 INFO - 'ts_paint', 06:05:57 INFO - 'tpaint', 06:05:57 INFO - 'sessionrestore', 06:05:57 INFO - 'sessionrestore_no_auto_restore', 06:05:57 INFO - 'tabpaint']}, 06:05:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:05:57 INFO - 'tests': ['a11yr', 06:05:57 INFO - 'ts_paint', 06:05:57 INFO - 'tpaint', 06:05:57 INFO - 'sessionrestore', 06:05:57 INFO - 'sessionrestore_no_auto_restore', 06:05:57 INFO - 'tabpaint']}, 06:05:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:05:57 INFO - 'tests': ['tsvgx', 06:05:57 INFO - 'tsvgr_opacity', 06:05:57 INFO - 'tart', 06:05:57 INFO - 'tscrollx', 06:05:57 INFO - 'cart', 06:05:57 INFO - 'tsvg_static']}, 06:05:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:05:57 INFO - 'tsvgr_opacity', 06:05:57 INFO - 'tart', 06:05:57 INFO - 'tscrollx', 06:05:57 INFO - 'cart', 06:05:57 INFO - 'tsvg_static']}, 06:05:57 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:05:57 INFO - 'talos_options': ['--disable-e10s'], 06:05:57 INFO - 'tests': ['tp5o']}, 06:05:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:05:57 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:05:57 INFO - 'talos_options': ['--disable-e10s', 06:05:57 INFO - '--xperf_path', 06:05:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:05:57 INFO - 'tests': ['tp5n']}, 06:05:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:05:57 INFO - 'talos_options': ['--xperf_path', 06:05:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:05:57 INFO - 'tests': ['tp5n']}}, 06:05:57 INFO - 'talos.zip': {'path': '', 06:05:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:05:57 INFO - Running post-action listener: _resource_record_post_action 06:05:57 INFO - [mozharness: 2017-06-01 13:05:57.264800Z] Finished populate-webroot step (success) 06:05:57 INFO - [mozharness: 2017-06-01 13:05:57.264933Z] Running create-virtualenv step. 06:05:57 INFO - Running pre-action listener: _resource_record_pre_action 06:05:57 INFO - Running main action method: create_virtualenv 06:05:57 INFO - Creating virtualenv /builds/slave/test/build/venv 06:05:57 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 06:05:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:05:57 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 06:05:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:05:57 INFO - Using real prefix '/tools/python27' 06:05:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:05:58 INFO - Installing distribute.............................................................................................................................................................................................done. 06:06:00 INFO - Installing pip.................done. 06:06:00 INFO - Return code: 0 06:06:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:06:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:06:01 INFO - Reading from file tmpfile_stdout 06:06:01 INFO - Output received: 06:06:01 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) 06:06:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:06: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')]} 06:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06: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')]} 06:06:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104d08b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d0b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82885a71c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ae6d30>, '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 0x104a0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104adbbe8>, '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.vI65GjoyDY/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.mibAUaBPHM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 06:06: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', 'pip>=1.5'] in /builds/slave/test/build 06:06: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 pip>=1.5 06:06:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vI65GjoyDY/Render', 06:06:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:01 INFO - 'HOME': '/Users/cltbld', 06:06:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:01 INFO - 'LOGNAME': 'cltbld', 06:06:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:01 INFO - 'MOZ_NO_REMOTE': '1', 06:06:01 INFO - 'NO_EM_RESTART': '1', 06:06:01 INFO - 'PAGER': '/bin/cat', 06:06:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:01 INFO - 'PWD': '/builds/slave/test', 06:06:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:01 INFO - 'SHELL': '/bin/bash', 06:06:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mibAUaBPHM/Listeners', 06:06:01 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 06:06:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:01 INFO - 'USER': 'cltbld', 06:06:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:01 INFO - 'XPC_FLAGS': '0x0', 06:06:01 INFO - 'XPC_SERVICE_NAME': '0', 06:06:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:01 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 06:06:01 INFO - Cleaning up... 06:06:01 INFO - Return code: 0 06:06:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:06: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')]} 06:06:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06: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')]} 06:06:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06: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', '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 0x104d08b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d0b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82885a71c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ae6d30>, '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 0x104a0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104adbbe8>, '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.vI65GjoyDY/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.mibAUaBPHM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 06:06: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', 'psutil>=3.1.1'] in /builds/slave/test/build 06:06: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 psutil>=3.1.1 06:06:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vI65GjoyDY/Render', 06:06:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:01 INFO - 'HOME': '/Users/cltbld', 06:06:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:01 INFO - 'LOGNAME': 'cltbld', 06:06:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:01 INFO - 'MOZ_NO_REMOTE': '1', 06:06:01 INFO - 'NO_EM_RESTART': '1', 06:06:01 INFO - 'PAGER': '/bin/cat', 06:06:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:01 INFO - 'PWD': '/builds/slave/test', 06:06:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:01 INFO - 'SHELL': '/bin/bash', 06:06:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mibAUaBPHM/Listeners', 06:06:01 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 06:06:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:01 INFO - 'USER': 'cltbld', 06:06:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:01 INFO - 'XPC_FLAGS': '0x0', 06:06:01 INFO - 'XPC_SERVICE_NAME': '0', 06:06:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:01 INFO - Downloading/unpacking psutil>=3.1.1 06:06:01 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 06:06:01 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 06:06:01 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 06:06:01 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 06:06:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:06:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:06:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:06:05 INFO - Installing collected packages: psutil 06:06:05 INFO - Running setup.py install for psutil 06:06:05 INFO - building 'psutil._psutil_osx' extension 06:06:05 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 06:06:05 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 06:06:05 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 06:06:05 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 06:06:05 INFO - building 'psutil._psutil_posix' extension 06:06:05 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 06:06:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:06:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:06:06 INFO - ^ 06:06:06 INFO - 1 warning generated. 06:06:06 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 06:06:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:06:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:06:06 INFO - Successfully installed psutil 06:06:06 INFO - Cleaning up... 06:06:06 INFO - Return code: 0 06:06:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:06: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')]} 06:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06: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')]} 06:06:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104d08b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d0b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82885a71c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ae6d30>, '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 0x104a0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104adbbe8>, '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.vI65GjoyDY/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.mibAUaBPHM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 06:06:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:06:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:06:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vI65GjoyDY/Render', 06:06:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:06 INFO - 'HOME': '/Users/cltbld', 06:06:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:06 INFO - 'LOGNAME': 'cltbld', 06:06:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:06 INFO - 'MOZ_NO_REMOTE': '1', 06:06:06 INFO - 'NO_EM_RESTART': '1', 06:06:06 INFO - 'PAGER': '/bin/cat', 06:06:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:06 INFO - 'PWD': '/builds/slave/test', 06:06:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:06 INFO - 'SHELL': '/bin/bash', 06:06:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mibAUaBPHM/Listeners', 06:06:06 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 06:06:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:06 INFO - 'USER': 'cltbld', 06:06:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:06 INFO - 'XPC_FLAGS': '0x0', 06:06:06 INFO - 'XPC_SERVICE_NAME': '0', 06:06:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:06:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:06:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:06:08 INFO - Installing collected packages: mozsystemmonitor 06:06:08 INFO - Running setup.py install for mozsystemmonitor 06:06:08 INFO - Successfully installed mozsystemmonitor 06:06:08 INFO - Cleaning up... 06:06:08 INFO - Return code: 0 06:06:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:06: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')]} 06:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06: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')]} 06:06:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:08 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 0x104d08b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d0b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82885a71c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ae6d30>, '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 0x104a0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104adbbe8>, '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.vI65GjoyDY/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.mibAUaBPHM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 06:06:08 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 06:06:08 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 06:06:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vI65GjoyDY/Render', 06:06:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:08 INFO - 'HOME': '/Users/cltbld', 06:06:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:08 INFO - 'LOGNAME': 'cltbld', 06:06:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:08 INFO - 'MOZ_NO_REMOTE': '1', 06:06:08 INFO - 'NO_EM_RESTART': '1', 06:06:08 INFO - 'PAGER': '/bin/cat', 06:06:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:08 INFO - 'PWD': '/builds/slave/test', 06:06:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:08 INFO - 'SHELL': '/bin/bash', 06:06:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mibAUaBPHM/Listeners', 06:06:08 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 06:06:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:08 INFO - 'USER': 'cltbld', 06:06:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:08 INFO - 'XPC_FLAGS': '0x0', 06:06:08 INFO - 'XPC_SERVICE_NAME': '0', 06:06:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:08 INFO - Downloading/unpacking jsonschema==2.5.1 06:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:06:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:06:09 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 06:06:09 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 06:06:09 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 06:06:09 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 06:06:10 INFO - Downloading functools32-3.2.3-2.tar.gz 06:06:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:06:10 INFO - warning: no files found matching '*.txt' 06:06:10 INFO - no previously-included directories found matching 'build' 06:06:10 INFO - no previously-included directories found matching 'dist' 06:06:10 INFO - no previously-included directories found matching '.git*' 06:06:10 INFO - Installing collected packages: jsonschema, functools32 06:06:10 INFO - Running setup.py install for functools32 06:06:10 INFO - warning: no files found matching '*.txt' 06:06:10 INFO - no previously-included directories found matching 'build' 06:06:10 INFO - no previously-included directories found matching 'dist' 06:06:10 INFO - no previously-included directories found matching '.git*' 06:06:10 INFO - Successfully installed jsonschema functools32 06:06:10 INFO - Cleaning up... 06:06:10 INFO - Return code: 0 06:06:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:06: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')]} 06:06:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06: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')]} 06:06:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104d08b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d0b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82885a71c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ae6d30>, '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 0x104a0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104adbbe8>, '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.vI65GjoyDY/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.mibAUaBPHM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 06:06:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:06:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:06:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vI65GjoyDY/Render', 06:06:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:10 INFO - 'HOME': '/Users/cltbld', 06:06:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:10 INFO - 'LOGNAME': 'cltbld', 06:06:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:10 INFO - 'MOZ_NO_REMOTE': '1', 06:06:10 INFO - 'NO_EM_RESTART': '1', 06:06:10 INFO - 'PAGER': '/bin/cat', 06:06:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:10 INFO - 'PWD': '/builds/slave/test', 06:06:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:10 INFO - 'SHELL': '/bin/bash', 06:06:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mibAUaBPHM/Listeners', 06:06:10 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 06:06:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:10 INFO - 'USER': 'cltbld', 06:06:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:10 INFO - 'XPC_FLAGS': '0x0', 06:06:10 INFO - 'XPC_SERVICE_NAME': '0', 06:06:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:06:10 INFO - Cleaning up... 06:06:10 INFO - Return code: 0 06:06:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:06: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')]} 06:06:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06: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')]} 06:06:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104d08b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d0b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82885a71c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ae6d30>, '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 0x104a0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104adbbe8>, '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.vI65GjoyDY/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.mibAUaBPHM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 06:06:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:06:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:06:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vI65GjoyDY/Render', 06:06:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:10 INFO - 'HOME': '/Users/cltbld', 06:06:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:10 INFO - 'LOGNAME': 'cltbld', 06:06:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:10 INFO - 'MOZ_NO_REMOTE': '1', 06:06:10 INFO - 'NO_EM_RESTART': '1', 06:06:10 INFO - 'PAGER': '/bin/cat', 06:06:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:10 INFO - 'PWD': '/builds/slave/test', 06:06:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:10 INFO - 'SHELL': '/bin/bash', 06:06:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mibAUaBPHM/Listeners', 06:06:10 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 06:06:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:10 INFO - 'USER': 'cltbld', 06:06:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:10 INFO - 'XPC_FLAGS': '0x0', 06:06:10 INFO - 'XPC_SERVICE_NAME': '0', 06:06:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:10 INFO - Downloading/unpacking blobuploader==1.2.4 06:06:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:12 INFO - Downloading blobuploader-1.2.4.tar.gz 06:06:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:06:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:06: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 06:06: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 06:06: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 06:06: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 06:06:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:06:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:06:13 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 06:06:13 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 06:06:13 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 06:06:13 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 06:06:13 INFO - Downloading docopt-0.6.1.tar.gz 06:06:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:06:13 INFO - Installing collected packages: blobuploader, requests, docopt 06:06:13 INFO - Running setup.py install for blobuploader 06:06:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:06:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:06:13 INFO - Running setup.py install for requests 06:06:14 INFO - Running setup.py install for docopt 06:06:14 INFO - Successfully installed blobuploader requests docopt 06:06:14 INFO - Cleaning up... 06:06:14 INFO - Return code: 0 06:06:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:06: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')]} 06:06:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06: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')]} 06:06:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:14 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 0x104d08b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d0b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82885a71c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ae6d30>, '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 0x104a0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104adbbe8>, '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.vI65GjoyDY/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.mibAUaBPHM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 06:06:14 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 06:06:14 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 06:06:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vI65GjoyDY/Render', 06:06:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:14 INFO - 'HOME': '/Users/cltbld', 06:06:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:14 INFO - 'LOGNAME': 'cltbld', 06:06:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:14 INFO - 'MOZ_NO_REMOTE': '1', 06:06:14 INFO - 'NO_EM_RESTART': '1', 06:06:14 INFO - 'PAGER': '/bin/cat', 06:06:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:14 INFO - 'PWD': '/builds/slave/test', 06:06:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:14 INFO - 'SHELL': '/bin/bash', 06:06:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mibAUaBPHM/Listeners', 06:06:14 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 06:06:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:14 INFO - 'USER': 'cltbld', 06:06:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:14 INFO - 'XPC_FLAGS': '0x0', 06:06:14 INFO - 'XPC_SERVICE_NAME': '0', 06:06:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:06:14 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-mkgoEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:06:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:06:14 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-LIIT_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:06:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:06:14 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-c60tre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:06:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:06:14 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-EuX_fl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:06:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:06:14 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-HcrpXR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:06:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:06:15 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-OBlpT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:06:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:06:15 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-BUMG8k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:06:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:06:15 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-9gtHeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:06:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:06:15 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-YCl0Al-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:06:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:06:15 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-VTgST5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:06:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:06:15 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-fLGKX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:06:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:06:15 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-vo2Snq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:06:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:06:15 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-P8ielM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:06:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:06:15 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-eJ9NcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:06:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:06:16 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-94Dynn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:06:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:06:16 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-CU6Wfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:06:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:06:16 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-Thbgol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:06:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:06:16 INFO - Running setup.py install for manifestparser 06:06:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:06:16 INFO - Running setup.py install for mozcrash 06:06:16 INFO - Running setup.py install for mozdebug 06:06:16 INFO - Running setup.py install for mozdevice 06:06:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:06:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:06:16 INFO - Running setup.py install for mozfile 06:06:17 INFO - Running setup.py install for mozhttpd 06:06:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:06:17 INFO - Running setup.py install for mozinfo 06:06:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:06:17 INFO - Running setup.py install for mozInstall 06:06:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:06:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:06:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:06:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:06:17 INFO - Running setup.py install for mozleak 06:06:17 INFO - Running setup.py install for mozlog 06:06:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:06:17 INFO - Running setup.py install for moznetwork 06:06:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:06:17 INFO - Running setup.py install for mozprocess 06:06:18 INFO - Running setup.py install for mozprofile 06:06:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:06:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:06:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:06:18 INFO - Running setup.py install for mozrunner 06:06:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:06:18 INFO - Running setup.py install for mozscreenshot 06:06:18 INFO - Running setup.py install for moztest 06:06:18 INFO - Running setup.py install for mozversion 06:06:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:06:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:06:18 INFO - Cleaning up... 06:06:18 INFO - Return code: 0 06:06:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:06:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104d08b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d0b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82885a71c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ae6d30>, '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 0x104a0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104adbbe8>, '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.vI65GjoyDY/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.mibAUaBPHM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 06:06:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:06:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:06:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vI65GjoyDY/Render', 06:06:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:18 INFO - 'HOME': '/Users/cltbld', 06:06:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:18 INFO - 'LOGNAME': 'cltbld', 06:06:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:18 INFO - 'MOZ_NO_REMOTE': '1', 06:06:18 INFO - 'NO_EM_RESTART': '1', 06:06:18 INFO - 'PAGER': '/bin/cat', 06:06:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:18 INFO - 'PWD': '/builds/slave/test', 06:06:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:18 INFO - 'SHELL': '/bin/bash', 06:06:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mibAUaBPHM/Listeners', 06:06:18 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 06:06:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:18 INFO - 'USER': 'cltbld', 06:06:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:18 INFO - 'XPC_FLAGS': '0x0', 06:06:18 INFO - 'XPC_SERVICE_NAME': '0', 06:06:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:06:19 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-4f1dve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:06:19 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)) 06:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:06:19 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-kI4Nbw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:06:19 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)) 06:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:06:19 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-CzOVX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:06:19 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)) 06:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:06:19 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-jGrsn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:06:19 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)) 06:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:06:19 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-1m6Gli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:06:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 06:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:06:19 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-OersLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:06:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 06:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:06:19 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-htgS4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:06:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 06:06:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:06:19 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-frAy9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:06:20 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)) 06:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:06:20 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-fFLaUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:06:20 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)) 06:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:06:20 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-ALXGSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:06:20 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)) 06:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:06:20 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-egTuYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:06:20 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)) 06:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:06:20 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-WCtMf6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:06:20 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)) 06:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:06:20 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-Ss4LJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:06:20 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)) 06:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:06:20 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-L7I7jK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:06:20 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)) 06:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:06:20 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-oJ7QcM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:06:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 06:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:06:20 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-8CzU9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:06:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 06:06:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:06:20 INFO - Running setup.py (path:/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/pip-47sEVN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:06:21 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)) 06:06:21 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)) 06:06:21 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)) 06:06:21 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)) 06:06:21 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)) 06:06:21 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)) 06:06:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:06:21 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 06:06:21 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 06:06:21 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 06:06:21 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 06:06:22 INFO - Downloading blessings-1.6.tar.gz 06:06:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:06:22 INFO - Installing collected packages: blessings 06:06:22 INFO - Running setup.py install for blessings 06:06:22 INFO - Successfully installed blessings 06:06:22 INFO - Cleaning up... 06:06:22 INFO - Return code: 0 06:06:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:06:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:06:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:06:23 INFO - Reading from file tmpfile_stdout 06:06:23 INFO - Current package versions: 06:06:23 INFO - blessings == 1.6 06:06:23 INFO - blobuploader == 1.2.4 06:06:23 INFO - docopt == 0.6.1 06:06:23 INFO - functools32 == 3.2.3-2 06:06:23 INFO - jsonschema == 2.5.1 06:06:23 INFO - manifestparser == 1.1 06:06:23 INFO - mozInstall == 1.12 06:06:23 INFO - mozcrash == 1.0 06:06:23 INFO - mozdebug == 0.1 06:06:23 INFO - mozdevice == 0.49 06:06:23 INFO - mozfile == 1.2 06:06:23 INFO - mozhttpd == 0.7 06:06:23 INFO - mozinfo == 0.9 06:06:23 INFO - mozleak == 0.1 06:06:23 INFO - mozlog == 3.4 06:06:23 INFO - moznetwork == 0.27 06:06:23 INFO - mozprocess == 0.25 06:06:23 INFO - mozprofile == 0.28 06:06:23 INFO - mozrunner == 6.13 06:06:23 INFO - mozscreenshot == 0.1 06:06:23 INFO - mozsystemmonitor == 0.3 06:06:23 INFO - moztest == 0.8 06:06:23 INFO - mozversion == 1.4 06:06:23 INFO - psutil == 3.1.1 06:06:23 INFO - requests == 1.2.3 06:06:23 INFO - wsgiref == 0.1.2 06:06:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:06:23 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')]} 06:06:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06:23 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')]} 06:06:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:23 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 0x104d08b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d0b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82885a71c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ae6d30>, '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 0x104a0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104adbbe8>, '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.vI65GjoyDY/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.mibAUaBPHM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 06:06:23 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 06:06:23 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 06:06:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vI65GjoyDY/Render', 06:06:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:23 INFO - 'HOME': '/Users/cltbld', 06:06:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:23 INFO - 'LOGNAME': 'cltbld', 06:06:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:23 INFO - 'MOZ_NO_REMOTE': '1', 06:06:23 INFO - 'NO_EM_RESTART': '1', 06:06:23 INFO - 'PAGER': '/bin/cat', 06:06:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:23 INFO - 'PWD': '/builds/slave/test', 06:06:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:23 INFO - 'SHELL': '/bin/bash', 06:06:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mibAUaBPHM/Listeners', 06:06:23 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 06:06:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:23 INFO - 'USER': 'cltbld', 06:06:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:23 INFO - 'XPC_FLAGS': '0x0', 06:06:23 INFO - 'XPC_SERVICE_NAME': '0', 06:06:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:23 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)) 06:06:23 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)) 06:06:23 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)) 06:06:23 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)) 06:06:23 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)) 06:06:23 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)) 06:06:23 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)) 06:06:23 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)) 06:06:23 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)) 06:06:23 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)) 06:06:23 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)) 06:06:23 INFO - Cleaning up... 06:06:23 INFO - Return code: 0 06:06:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:06:23 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')]} 06:06:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06:23 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')]} 06:06:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:23 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 0x104d08b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d0b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f82885a71c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ae6d30>, '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 0x104a0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104adbbe8>, '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.vI65GjoyDY/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.mibAUaBPHM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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 06:06:23 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 06:06:23 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 06:06:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vI65GjoyDY/Render', 06:06:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:23 INFO - 'HOME': '/Users/cltbld', 06:06:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:23 INFO - 'LOGNAME': 'cltbld', 06:06:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:23 INFO - 'MOZ_NO_REMOTE': '1', 06:06:23 INFO - 'NO_EM_RESTART': '1', 06:06:23 INFO - 'PAGER': '/bin/cat', 06:06:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:23 INFO - 'PWD': '/builds/slave/test', 06:06:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:23 INFO - 'SHELL': '/bin/bash', 06:06:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mibAUaBPHM/Listeners', 06:06:23 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 06:06:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:23 INFO - 'USER': 'cltbld', 06:06:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:23 INFO - 'XPC_FLAGS': '0x0', 06:06:23 INFO - 'XPC_SERVICE_NAME': '0', 06:06:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:06:23 INFO - Cleaning up... 06:06:23 INFO - Return code: 0 06:06:23 INFO - Running post-action listener: _resource_record_post_action 06:06:23 INFO - Running post-action listener: _start_resource_monitoring 06:06:23 INFO - Starting resource monitoring. 06:06:23 INFO - [mozharness: 2017-06-01 13:06:23.647609Z] Finished create-virtualenv step (success) 06:06:23 INFO - [mozharness: 2017-06-01 13:06:23.648576Z] Running install step. 06:06:23 INFO - Running pre-action listener: _resource_record_pre_action 06:06:23 INFO - Running main action method: install 06:06:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:06:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:06:23 INFO - Reading from file tmpfile_stdout 06:06:23 INFO - Detecting whether we're running mozinstall >=1.0... 06:06:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:06:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:06:24 INFO - Reading from file tmpfile_stdout 06:06:24 INFO - Output received: 06:06:24 INFO - Usage: mozinstall [options] installer 06:06:24 INFO - Options: 06:06:24 INFO - -h, --help show this help message and exit 06:06:24 INFO - -d DEST, --destination=DEST 06:06:24 INFO - Directory to install application into. [default: 06:06:24 INFO - "/builds/slave/test"] 06:06:24 INFO - --app=APP Application being installed. [default: firefox] 06:06:24 INFO - mkdir: /builds/slave/test/build/application 06:06:24 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'] 06:06:24 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 06:06:38 INFO - Reading from file tmpfile_stdout 06:06:38 INFO - Output received: 06:06:38 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 06:06:38 INFO - Running post-action listener: _resource_record_post_action 06:06:38 INFO - [mozharness: 2017-06-01 13:06:38.614015Z] Finished install step (success) 06:06:38 INFO - [mozharness: 2017-06-01 13:06:38.614141Z] Running run-tests step. 06:06:38 INFO - Running pre-action listener: _resource_record_pre_action 06:06:38 INFO - Running main action method: run_tests 06:06:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:06:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:06:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:06:38 INFO - 2017-06-01 06:06:38.628 screenresolution[1666:7877] starting screenresolution argv=screenresolution get 06:06:38 INFO - 2017-06-01 06:06:38.631 screenresolution[1666:7877] Display 0: 1600x1200x32@60 06:06:38 INFO - 2017-06-01 06:06:38.637 screenresolution[1667:7879] starting screenresolution argv=screenresolution list 06:06:38 INFO - Available Modes on Display 0 06:06:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:06:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:06:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:06:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:06:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:06:38 INFO - Intel Iris: 06:06:38 INFO - Chipset Model: Intel Iris 06:06:38 INFO - Type: GPU 06:06:38 INFO - Bus: Built-In 06:06:38 INFO - VRAM (Dynamic, Max): 1536 MB 06:06:38 INFO - Vendor: Intel (0x8086) 06:06:38 INFO - Device ID: 0x0a2e 06:06:38 INFO - Revision ID: 0x0009 06:06:38 INFO - Displays: 06:06:38 INFO - Display: 06:06:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:06:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:06:38 INFO - Display Serial Number: 1600x1200 60 06:06:38 INFO - Main Display: Yes 06:06:38 INFO - Mirror: Off 06:06:38 INFO - Online: Yes 06:06:38 INFO - Rotation: Supported 06:06:38 INFO - Return code: 0 06:06:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:06:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:06:38 INFO - Python 2.7.3 06:06:38 INFO - Return code: 0 06:06:38 INFO - grabbing minidump binary from tooltool 06:06:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:38 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ae6d30>, '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 0x104a0ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104adbbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:06:38 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 06:06:38 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 06:06:38 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 06:06:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:06:38 INFO - Return code: 0 06:06:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:06:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:06:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:06:38 INFO - ENV: RUST_BACKTRACE is now 1 06:06:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:06:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:06:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aFidrUb3QfWGY_qZyH4Arw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0235', '--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 06:06:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aFidrUb3QfWGY_qZyH4Arw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0235 --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 06:06:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vI65GjoyDY/Render', 06:06:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:39 INFO - 'HOME': '/Users/cltbld', 06:06:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:39 INFO - 'LOGNAME': 'cltbld', 06:06:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:06:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:06:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:39 INFO - 'MOZ_NO_REMOTE': '1', 06:06:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:06:39 INFO - 'NO_EM_RESTART': '1', 06:06:39 INFO - 'PAGER': '/bin/cat', 06:06:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:39 INFO - 'PWD': '/builds/slave/test', 06:06:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:06:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:39 INFO - 'RUST_BACKTRACE': '1', 06:06:39 INFO - 'SHELL': '/bin/bash', 06:06:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mibAUaBPHM/Listeners', 06:06:39 INFO - 'TMPDIR': '/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/', 06:06:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:39 INFO - 'USER': 'cltbld', 06:06:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:39 INFO - 'XPC_FLAGS': '0x0', 06:06:39 INFO - 'XPC_SERVICE_NAME': '0', 06:06:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:39 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/aFidrUb3QfWGY_qZyH4Arw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0235', '--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 06:06:39 INFO - mozversion application_buildid: 20170601042031 06:06:39 INFO - mozversion application_changeset: 86339b41e6e98b5e0580ae19ffbe6c85a54bfc9f 06:06:39 INFO - mozversion application_display_name: Firefox 06:06:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:06:39 INFO - mozversion application_name: Firefox 06:06:39 INFO - mozversion application_remotingname: firefox 06:06:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 06:06:39 INFO - mozversion application_vendor: Mozilla 06:06:39 INFO - mozversion application_version: 54.0 06:06:39 INFO - mozversion platform_buildid: 20170601042031 06:06:39 INFO - mozversion platform_changeset: 86339b41e6e98b5e0580ae19ffbe6c85a54bfc9f 06:06:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 06:06:39 INFO - mozversion platform_version: 54.0 06:06:39 INFO - using testdate: 1496322399 06:06:39 INFO - actual date: 1496322399 06:06:39 INFO - starting webserver on 'localhost:49224' 06:06:39 INFO - SUITE-START | Running 2 tests 06:06:39 INFO - TEST-START | dromaeo_css 06:06:39 INFO - operating with platform_type : mac_ 06:06:39 INFO - Initialising browser for dromaeo_css test... 06:06:39 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/tmpFv0Yw5/profile http://localhost:49224/getInfo.html) 06:06:41 INFO - PROCESS | 1677 | 2017-06-01 06:06:41.990 plugin-container[1680:8054] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a43, name = 'com.apple.tsm.portname' 06:06:41 INFO - PROCESS | 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:06:41 INFO - PROCESS | 1677 | 2017-06-01 06:06:41.991 plugin-container[1680:8054] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c4b, name = 'com.apple.CFPasteboardClient' 06:06:41 INFO - PROCESS | 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:06:41 INFO - PROCESS | 1677 | 2017-06-01 06:06:41.991 plugin-container[1680:8054] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:06:42 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 06:06:42 INFO - PROCESS | 1677 | colorDepth:24 06:06:42 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 06:06:42 INFO - PROCESS | 1677 | __metrics 06:06:42 ERROR - PROCESS | 1677 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 06:06:42 INFO - TEST-INFO | 1677: exit 0 06:06:42 INFO - Browser initialized. 06:06:42 INFO - Running cycle 1/1 for dromaeo_css test... 06:06:42 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/tmpFv0Yw5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:06:45 INFO - PROCESS | 1682 | 2017-06-01 06:06:45.235 plugin-container[1683:8184] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 06:06:45 INFO - PROCESS | 1682 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:06:45 INFO - PROCESS | 1682 | 2017-06-01 06:06:45.236 plugin-container[1683:8184] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8813, name = 'com.apple.CFPasteboardClient' 06:06:45 INFO - PROCESS | 1682 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:06:45 INFO - PROCESS | 1682 | 2017-06-01 06:06:45.236 plugin-container[1683:8184] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:06:54 INFO - PROCESS | 1682 | RSS: Main: 174419968 06:06:54 INFO - PROCESS | 1682 | 06:09:58 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49224/tests/dromaeo/cssquery-ext.html) 06:09:59 INFO - PROCESS | 1682 | RSS: Main: 192774144 06:09:59 INFO - PROCESS | 1682 | 06:13:02 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-ext.html (next: http://localhost:49224/tests/dromaeo/cssquery-jquery.html) 06:13:02 INFO - PROCESS | 1682 | RSS: Main: 207159296 06:13:02 INFO - PROCESS | 1682 | 06:16:04 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49224/tests/dromaeo/cssquery-mootools.html) 06:16:05 INFO - PROCESS | 1682 | RSS: Main: 207613952 06:16:05 INFO - PROCESS | 1682 | 06:19:07 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49224/tests/dromaeo/cssquery-prototype.html) 06:19:08 INFO - PROCESS | 1682 | RSS: Main: 210706432 06:19:08 INFO - PROCESS | 1682 | 06:22:10 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49224/tests/dromaeo/cssquery-yui.html) 06:22:11 INFO - PROCESS | 1682 | RSS: Main: 213086208 06:22:11 INFO - PROCESS | 1682 | 06:25:14 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-yui.html (next: http://localhost:49224/tests/dromaeo/cssquery-dojo.html) 06:25:14 INFO - PROCESS | 1682 | RSS: Main: 211324928 06:25:14 INFO - PROCESS | 1682 | 06:25:14 INFO - PROCESS | 1682 | __start_tp_report 06:25:14 INFO - PROCESS | 1682 | _x_x_mozilla_page_load 06:25:14 INFO - PROCESS | 1682 | _x_x_mozilla_page_load_details 06:25:14 INFO - PROCESS | 1682 | |i|pagename|runs| 06:25:14 INFO - PROCESS | 1682 | |0;dojo.html;2853.543433050712;3690.760100593463;3692.5753538343097;3710.4619830124634;3747.7938713370763;6213;6235.657038862861;6236.314005459396;6242.625442473449;6254.84362890928;6232.407921247485;6272.278687950876;6279.937200627993;6280.937190628092;6307.810765677025;5653.321601407826;5746.827595172152;5753.741081651315;5770.913436298459;5815.767369305223;5769.076947688371;5799.18811366409;5808.883822323551;5811.622244554091;5818.4181581841685;5529.612927095105;5554.777808887639;5794.971025144869;5796.884062318751;5802.651840889539;5625.071863142556;5641.238432811559;5721.427857214287;5736.311642602915;5751.942480575182;5683.261176047129;5720.570957178228;5722.427757224286;5741.483266505993;5787.681677507735;6284.9315616344875;6315.210598675166;6341.04884267359;6352.205974253218;6356.809295721136;6384.106225128486;6456.451201647866;6529.804105876831;6597.538172327939;6716.059751634775;6746.561473504206;6802.625855577941;6803.217629972518;6834.111565496503;6839.487038472134;6282.994720844643;6307.747690092345;6327.050942358637;6347.238331400215;6354;6556.377144171296;6654.068430419745;6864.759733409307;6939.965300173568;6958.338957799001;6382.170317858741;6467.708953097122;6497;6533.379328963836;6572.244191917895;6789.966050169717;6801.523893327519;6807.829804254933;6808.523403361717;6814.080099186645;6610.669466526654;6635.734570617121;6650.135482387259;6684.96657516719;6702.061711360413;6370.362963703686;6395.52033597482;6575.868482630416;6623;6664.200295964515;5250.65870718402;5310.893782124336;5311.389190243094;5387.299651045418;5425.864353391197;4765.1660959331975;4791.496892826203;4794.904101917942;4808.471068182497;4814.614830813596;6266.467350275278;6354.078658594571;6354.142190804275;6358.77744278948;6366.204224471941;6459.418652321219;6525.021246812921;6692.866142677217;6698.866022679616;6821.658917054227;6322.494200464043;6330.39861213184;6340.4293613575;6343.619382837044;6346.174997250328;5959.165716799652;5964.671943043088;5979.252593425822;6001.819945401645;6008.969955150196;5862.443067908542;6631.204255489372;6662.700178492077;6864.21061577925;6902.792916212421;5695.601307908407;6749.865002700017;6753.527253092237;6774.254831968381;6840.521163518705;6029.668368239789;7005.404540614105;7012.929870701431;7022.157341119098;7026.437884969292;6394.520410969103;6445;6588.934110658832;6685.064091027163;6701.128853249046;6676.632785196861;6691.130153080263;6729.158855143107;6758.42553382968;6782.084418603426;6605.339466053549;6606.645637644176;6609.900851487328;6633.9668301658185;6655.567388119756;5470.288862447856;6338.492920566436;6522.706478208397;6604.042413849868;6625.536212465081;6262.718177681924;6264.498840092872;6379.298277189413;6455.48356131499;6483.4164925156965;5907.025340818888;6137.60105593135;6599.406053455212;6604.240512341143;6605.669716514251;6220.440160385587;6240.157578726814;6328.873422531616;6417.2299324081405;6422.3577642235405 06:25:14 INFO - PROCESS | 1682 | |1;ext.html;793.1156760212365;832.2177153475733;854.3464249848864;880.55972013993;980.7401038724744;33785.9864204074;39688.80155599221;41408.58591414085;42276;42317.7884110579;40811.59188408115;40980.385294220614;41077.383839242364;41109;41193.176136477254;8318.958405207995;8478.703245386412;11229.943850280779;11479.827802582968;11711.882881171206;11394.373309467976;11471;11515.596954106608;11518;11523.962843344098;11467.19729618927;11483.827742583868;11498.482568284448;11520.020798232119;11523.19337646365;9541.71374858752;11424.971752542227;11449.828252576219;11457;11473.770524589545;11197.66407007791;11224.326540407601;11255.943720281428;11259.380734059623;11273.60542381021;35719.928402147976;35801.28397432063;36065;36141;36163.638363616294;3933.940990885139;3952.170044290702;3962.841486340543;3999.24014437256;4037.8990525237105;26226.86886565574;26388.81250343739;26560.60159097615;27849.02528411496;27999;3201.8719251230154;3252.284497410566;3273.770836041478;3295.307985323085;3400.1669590950128;357914;360781;374970;379776;432758.67241327814;35829.8208508961;36935.63064369322;45498.09003819905;45592.54407455883;45640.771796140805;38774.6122538771;47444.7627761859;47633.76183119062;47705;47845.04309913852;12080.637580872588;14607.342669579895;14651.487197948234;14653.853461465249;14712.411503539952;12657.114002019955;12751.298678572583;12757.48970041188;12765.42555585001;12775.425105870072;12217.633470995885;12302.876971230353;12354.0734444917;12371.814422783666;12425.689357765947;5706.205373426707;5736.13957906309;5737.3975732548315;5766;5786.623869448473;1041.8123339393098;1095.8794532601407;1105.309181761399;1129.3562669278433;1155.607093588184;24372.634410483857;27719.584206236923;28099;28377.006804761735;28431.71568284332;1096.9067629251444;1178.1693905796392;1196.7427003194355;1197.3235122155954;1212.4119801896074;13025.088243822842;14722.484713034992;14825.555233343017;15155.408682088442;15861.762073568905;318984;346624;353422.4657753441;397607;398337;51192.23211651828;51425;51637.22544161841;51767.741161293954;51910;1531.4410240262223;1556.8209655889718;1560.3134620766825;1565.107888503542;1571.0102635339663;30094.84952575223;31730.778845480596;31899.202519937186;32138;32413.86551470687;50094.24858627196;50200.748996254784;50243.74878125586;50335.74832125816;50545.49454505555;465593;466662;467177;468154.3184568247;468306;30819.845900770353;31389.372212555165;38036.42945355877;38193.04517387087;38361.4245786319;37450.43824342582;47275.76362118167;47658.28512572245;48069.75965120152;48839.5116048835;32480.35039299248;32794;32863;33027;34485.82757086299;27031.459370812867;32659.020229393158;32906.341873161924;33088.1727956803;33674.49488257727;32683.50974735333;38025;38391.808040959615;38850.6114938847;38859.611403885596;32423.837880810446;32500;32587.837060814545;33123.33753324872;33634.66365336315;24290.87854560716;25877.353066173495;26107.347316317246;26221.082262120734;26310.21069367922 06:25:14 INFO - PROCESS | 1682 | |2;jquery.html;439.5823967231131;448.45960617455967;455.33066392403157;463.1824829176503;464.1969392950196;21769;22580.870956452174;22850.657240141412;23671.526569468642;23674.88162559187;23449.882750586265;23489.647655285185;24059.278221653334;24226.39434014146;24381.024759009662;6654.500912431563;6725.8991115133185;6846.315368463163;6921.446284297269;6945.826354341131;6802.115724955774;6935.202451718042;6937.514373993822;7463.067116610424;7787.766367008999;7880.133185349636;7924.088729796061;7933.484323518982;7936.642851071687;7944.721934732285;7335.77992660221;7660.923390766105;7756.1468238494;7802.492837965514;7811.4922530035365;6537.888558944943;7361.5583065015735;7487.064116985377;7498.800191969259;7516.774496765106;27324.86337568319;27596.10240924694;27994.16017519478;28184.43631127366;28287.292817679518;3021.078571035833;3050.5729197912087;3079.1532328609587;3142.4972004479173;3157.526371044362;16102.919485402615;17058.2088880667;17080.231389587498;17113.058781767;17440.91279543607;2101.9264325748527;2128.1381040678393;2129.5208578069964;2133.925312614051;2139.1443422631073;117109.41445292805;139665;166189;167032;176049.23950760457;16985.575360616083;20612.793872061087;21595.352139435843;21699.915004249724;21807.345779626638;26631.600525992126;33511.83244083764;33519.83240083784;33594.98430094202;34132.65867341345;10483.319288157498;10858.402787846744;11173.217874748852;11225.831612525819;11341.489632966537;9368.156865882103;9448.716538503855;9511.143997040299;9612.51937403127;9616.423014619146;8108.270255677017;9818.45998470091;10090.394576325443;10121.520227544152;10223.846642300372;4427.092446048552;4429.446319210098;4467.642588592905;4471.754053527022;4473.619742321939;873.2620935309653;963.0562049191691;1014.3356101753363;1025.3540269630084;1076.923076923077;19506.414807555797;21348.466288342606;21518.784812151993;21863.12547498082;22675.88662056679;888.7911340834899;889.9154580314989;905.3255324782947;915.3729695158837;990.9355891867006;11338.263012904135;11533.615966083957;11670;11772;11788.646340609796;761895;762263;776083;792522;805969;169600;176774;177025;177877;184493;1245.2341809786915;1254.7239607286383;1275.7002104505434;1278.6483716977718;1281.301690578637;17517.912410437868;19066.332678356746;19375.224991000767;19378.740381875134;19612.509687257934;53935.46064539305;66855.66572167107;66883.66558167178;67360.6631966837;67419.97740067806;87219;87589.68615470901;100865;101015;101403;11340.94329528347;11827.408629568316;11860.05119590448;11964.401779911144;12308.815367769312;13286.136401133896;13430.597082087554;13686.384112715152;13790.448382065006;13802.792958105436;10761.38003540825;11565.306081635128;11709.063274938155;11824.349660768741;12057;11033.007029367396;11043.668689939315;11102.833457497982;11112.22214444981;11141.331520108819;11554.59558915434;11693.941530292293;11812.468438920097;12084.939575302067;12092.972097371821;11840.1711880171;11954.701132471759;12024.819627705416;12308.630741077783;12330.876691232972;8582.442141260799;11736.471858766547;11765.882341176479;11866;12135.757284854077 06:25:14 INFO - PROCESS | 1682 | |3;mootools.html;1443.9133651980883;1535.33980388433;1626.3332033866118;1641.7209074457342;1648.2088597473216;10946;11179.776404471906;11544.65366039018;11687.311918258794;11699.707507312321;10971.396573188471;11131.554737810478;11139.832902506469;11140.832887506675;11151.498182581783;2474.480359124586;2511.510255500178;2512.773850353468;2514.0823599386276;2516.5973444248925;2446.0338166424253;2457.4225057111566;2478.6405971134177;2485.440775825443;2490.950180996379;2453.619688948207;2463.2117722328862;2478.3060742991993;2481.2432208176497;2490.5641512735288;2432.6107822748277;2442.242904699549;2459.32368598635;2467.629855521668;2474.1464194852747;2419.1049311754587;2460.0159936025734;2472.1223965492172;2478.8884500197355;2487.8756062197;11467.885321146765;11552.422378881023;11567.88432115685;11574.768504629861;11574.826377604342;5971.373005834412;6129.601575897597;6133.723982420802;6137.324894261627;6149.815505534841;3299.2081900343983;3649.7627654202392;3743.625637436262;3763.2661630981697;3815.313243616148;1231.5197073141483;1244.7883859743865;1272.5100836178046;1287.7295767888754;1291.8062290656383;13817.480077191602;13955.092918960236;14185.361658725382;15030.849691503163;15159;3661.8352174151696;4219.535851056381;4905.509449055137;4917.901641967141;4937.259411088366;10765;11507.597234096766;11879;12102.939485302693;12129.575464858688;6038.094285857156;6041.003234466351;6042.848928776816;6044.879102418015;6052.182955301066;5353.892922141536;5489;5555.416681248411;5587.1339942309005;5587.7485513152005;4013.6588389986596;4113.382992551141;4131.8967025824595;4132.070284186034;4155.044339801862;1082.6697857153645;1083.9891601084046;1093.4860615510697;1094.8467214589964;1102.895224953628;931.6180366049887;936.864154697565;942.6323733743845;948.7106432538142;960.4861399151421;6314.400131987454;6354.745810167634;6425.228972523327;6494.123293355431;6585.9012114818315;973.5716284834791;1003.207466101786;1009.1926458833051;1013.1793247469426;1047.4291511126455;3510.8771193008124;3530.6645868642436;3984.203159368138;4115.5678653741525;4185.581441855851;13518;14058.22679752603;14090.929545352412;14096.929515352358;14116.717665646629;14047.78928316076;14057.78913316301;14083.718325633636;14086.929565352108;14130.293485325692;9937.155341795884;10359.119474844572;10527.21045921559;10550;10565.471726413802;6697.564658297195;6726.966365168143;6774;6880.8967865483055;6972.337627925339;11830.231034982698;12261.87738122643;12538.811917821058;12592.496300147892;12826.422810973341;10889.401082940514;11048.116150708085;12602.87397126017;12746.872531274568;12801.871981280068;9023.593938272663;9275.489848058422;9352;9358.906410935804;9366.906330936603;10208.591656333663;10227.130693891102;10250.641227557;10320.845187322046;10343;8721.269916718766;8731.083236260101;8801.603927823391;8823.558822059;8830.116988301119;7820.983272174809;7982.84034319322;8458.74623761288;8603.95698021531;8661.653533858575;7756.495827771177;8027.5584842834205;8062.55655938929;8187.549684767395;8231.46495477792;5998.550108741949;5998.610090344114;6003.189569408074;6079.270487541523;6116.8776624468155;5905.320888097924;6096.817095487142;6161.075838624242;6164.075388691732;6166.290876549254 06:25:14 INFO - PROCESS | 1682 | |4;prototype.html;381.9751716138451;396.09492310071494;398.3327925724411;403.7153806566373;407.6249850137872;4279.737477444153;5091.898162036757;5120.769565369554;5550.501364631547;5693.43065693431;4928.778204980776;5017.673851199669;5365.570754339653;5436.592255580827;5446.2375267462485;4165.208610364036;4688.640294314645;4709.811607535695;4738.952610473885;4757.976210118944;3842.943190622573;3855;3958.5447673517624;4110.0752330725645;4367.890802729941;3623.927521449569;3893.240818040483;4418.100216906729;4433.027302850233;4485.416895803541;3597.712183025352;3657.4513822926506;3907.960920390788;4403.779811009436;4529.214533062143;3744.8876533704033;3831.965369350288;3922.3528117860806;4090.6909788867574;4144.41978123063;4366.628836548897;4373;4413.845515406945;4966.6771659842;5236.764345604457;4199.895002624929;4611.21609326412;4802.951970480285;4856.198727210006;5155.639105262633;5103.591712663015;5601.859953501154;5675.275867583583;5863.325717542494;5871.91369596626;3950.466686997276;3958.990457433365;4289.605878089602;4549.203889319356;4627.213373726475;5628.971855140739;5785.710714464302;5809.564282678816;5967.12137110877;5972.522198224132;3804.6113168693228;4295.463067116611;4361.258586040381;4513.751743654065;4725.480197178307;4471.217536931024;4626.930596041062;4824.34871292371;4826.396700412449;4876;4069.877903662895;4435.157320109169;4438.335624140947;4863.392075990501;5145.3568303962;5412.079946409041;5563.721813909288;5767.942320576824;5790.654784812158;5796.739396543537;1541.768734689783;1563.6090977255685;1582.6389305197522;1617.7088124137533;1637.5333030086367;1021.9948900255451;1102.404701461218;1136.2614300704608;1160.4313886195703;1255.5731051442554;4924.3352147460355;5126.384833819965;5342.706151161646;5429.904954176081;5441.211024401439;4994.900101998012;5066.417362003417;5280.577553795687;5959.28488581373;6003.549733769897;5255.634964266095;5260.29149449637;5331.81338653153;5359.946400536022;5431.827269092395;2709.105995021638;2757.462294852525;2802.9159125226274;2967.9487499562943;2972.7621790256726;4889.875328674354;5697.9430205698245;6033.78881739137;6083.8174854754425;6184.5052395809125;4876.878078048077;5394.865128371822;5735.512481439041;5980.43185897339;6026.885026270154;5665.999999999917;5818.31268932005;6077.723678027575;6154.846128846814;6267.655278959701;4952.257161425815;5067.986402719397;5100.515451032146;5164.767585458739;5864.74506648797;5126.641135120506;5285.577153827608;5791.362950075572;5889.852753681021;5907.291125064848;4950.960298337245;5420.403755586961;5718.285214348207;6054.820264704791;6183.578741200064;3871.245107204068;4639.211334073283;4641.234196357564;4677.789499472464;4679.719216847;5632.5212356950115;5851.970740146442;6021.42796434338;6023;6027.517798576016;5280.630355875205;5762.740676669644;5776.249087618583;5821.679807610452;5825.70871456434;5703.231998080609;5824.94175058244;5835;5846.970765146147;5874.236349274738;4543.568361005699;4787.0559605842855;4821.614270858253;5195.6622819516615;5361.51746342831;4847.2729090636685;5436.592255580927;5563.248961390323;5817.272840894888;5898.852528686645;4969.204927211629;5157.916837464175;5520.355689945021;5771.307443106854;5793 06:25:14 INFO - PROCESS | 1682 | |5;yui.html;767.8502691975063;770.5800338815345;805.931495822855;815.5962798414782;930.5161316115618;15734.606634834136;17868;18898.24407023719;19053.428397148073;19256.13347399367;18031.639367212647;18772.061396930134;18891.433257002278;18914.432567022977;18927.33754318599;698.3435570563679;812.8943237379133;998.366037566147;1022.616518805448;1027.5889644142364;986.6546708651986;1015.8984101589818;1016.1819735113231;1018.8981101889826;1020.9387436753782;1060.331990845766;1069.48664640972;1071.8177909755361;1072.0726571515622;1074.6023971130653;1046.1735229168946;1064.0636240108731;1064.1327318235612;1064.7018834726289;1066.568039943824;779.3959681247022;788.6648174525861;789.9921000789976;791.5250849490317;792.2671528835862;14003.789943150861;16484;17604;17905.925644461244;18047.27810887563;14670.633234169125;14678.706425871424;14795.778063329059;14814.333354998944;14868.62828429287;12073.758524829542;12102.697432564168;12143;12253.571125010581;12330.75338493234;3409.8406541775666;3794.2221844522082;3874.244522318149;3937.5865534118793;3988.0428697112775;107327;111350;111357.44321278342;118729;122222;11054.062216852959;11071.16966227536;11434.142439317084;11636.650900473;11740.58907938218;24225.39436514066;24432;24467.510649786906;24492.755072449403;24577.631335529983;8133.75598732039;8196.754097376968;8290.668373265002;8298.294644955125;8466.365022623328;4487.483939346952;4546.202932680079;4617.2612382018715;4654.906901862011;4664.696794708333;3671.57776855665;3744.6068162843053;3773.754705944105;3790.7915064671706;3806.1055651921774;662.460095022555;670.1790306874137;671.633959492078;685.1333063674456;685.6605980039913;607.4897086447405;688.6074937285799;693.3655705029842;722.7687140115113;753.8416932444138;9635.662751803653;10479.266451348485;10607.522661480098;10930.508127134299;11054.447277636085;711.839836036898;717.1322699533519;721.2823240875258;756.6254703921484;759.8442319324525;4388.912221755547;5100.540951314325;5177.58579313654;5232.424433312332;5251.422343542284;83055.92360190922;85528;91624.87688054054;95930.52034739782;97389;65937.34062659313;75743.62128189324;83406.58296708477;86985;90179.54910225407;74730;75930;76904.23095769083;78841.60579197068;79088;10923.399213043358;11188.384638844942;11215.60745373908;11518.712032199262;11539.538418463168;45051.648450546534;45976;48852.755736221094;50138.145526514294;50219.74890125526;66900;67682.66158669176;69340.26649333807;69680;71700;13222.933885330513;13327.866721332663;13490.055696101179;13567.59297221085;13680.931595342357;15461.381544738084;15746.685066298382;15902.363905443653;15952.680946381239;16343.019418834909;14013.299335033003;14444.277786110442;14586;14596.562103136499;14642.633934151732;13260.270685112411;14205.928970355082;14314;14517.128972261698;14611.92694036523;13428.244490347628;14261.714765704835;14291.356888939814;14299.428022878968;14403.855961440671;10090;10191.7452063699;10439.791204176026;10543.31468454548;10758.569657213624;11373.31760094397;11406.087512999105;11619.47712352963;11647.59233426826;11674.649760507198 06:25:14 INFO - PROCESS | 1682 | __end_tp_report 06:25:14 INFO - PROCESS | 1682 | __start_cc_report 06:25:14 INFO - PROCESS | 1682 | _x_x_mozilla_cycle_collect,398 06:25:14 INFO - PROCESS | 1682 | __end_cc_report 06:25:14 INFO - PROCESS | 1682 | __startTimestamp1496323514796__endTimestamp 06:25:14 INFO - PROCESS | 1682 | 06:25:14 INFO - PROCESS | 1682 | ------- Summary: start ------- 06:25:14 INFO - PROCESS | 1682 | Number of tests: 6 06:25:14 INFO - PROCESS | 1682 | 06:25:14 INFO - PROCESS | 1682 | [#0] dojo.html Cycles:165 Average:6202.37 Median:6354.08 stddev:679.93 (10.7%) stddev-sans-first:629.22 06:25:14 INFO - PROCESS | 1682 | Values: 2853.5 3690.8 3692.6 3710.5 3747.8 6213.0 6235.7 6236.3 6242.6 6254.8 6232.4 6272.3 6279.9 6280.9 6307.8 5653.3 5746.8 5753.7 5770.9 5815.8 5769.1 5799.2 5808.9 5811.6 5818.4 5529.6 5554.8 5795.0 5796.9 5802.7 5625.1 5641.2 5721.4 5736.3 5751.9 5683.3 5720.6 5722.4 5741.5 5787.7 6284.9 6315.2 6341.0 6352.2 6356.8 6384.1 6456.5 6529.8 6597.5 6716.1 6746.6 6802.6 6803.2 6834.1 6839.5 6283.0 6307.7 6327.1 6347.2 6354.0 6556.4 6654.1 6864.8 6940.0 6958.3 6382.2 6467.7 6497.0 6533.4 6572.2 6790.0 6801.5 6807.8 6808.5 6814.1 6610.7 6635.7 6650.1 6685.0 6702.1 6370.4 6395.5 6575.9 6623.0 6664.2 5250.7 5310.9 5311.4 5387.3 5425.9 4765.2 4791.5 4794.9 4808.5 4814.6 6266.5 6354.1 6354.1 6358.8 6366.2 6459.4 6525.0 6692.9 6698.9 6821.7 6322.5 6330.4 6340.4 6343.6 6346.2 5959.2 5964.7 5979.3 6001.8 6009.0 5862.4 6631.2 6662.7 6864.2 6902.8 5695.6 6749.9 6753.5 6774.3 6840.5 6029.7 7005.4 7012.9 7022.2 7026.4 6394.5 6445.0 6588.9 6685.1 6701.1 6676.6 6691.1 6729.2 6758.4 6782.1 6605.3 6606.6 6609.9 6634.0 6655.6 5470.3 6338.5 6522.7 6604.0 6625.5 6262.7 6264.5 6379.3 6455.5 6483.4 5907.0 6137.6 6599.4 6604.2 6605.7 6220.4 6240.2 6328.9 6417.2 6422.4 06:25:14 INFO - PROCESS | 1682 | 06:25:14 INFO - PROCESS | 1682 | [#1] ext.html Cycles:180 Average:54817.19 Median:32828.50 stddev:107678.15 (328.0%) stddev-sans-first:107903.82 06:25:14 INFO - PROCESS | 1682 | Values: 793.1 832.2 854.3 880.6 980.7 33786.0 39688.8 41408.6 42276.0 42317.8 40811.6 40980.4 41077.4 41109.0 41193.2 8319.0 8478.7 11229.9 11479.8 11711.9 11394.4 11471.0 11515.6 11518.0 11524.0 11467.2 11483.8 11498.5 11520.0 11523.2 9541.7 11425.0 11449.8 11457.0 11473.8 11197.7 11224.3 11255.9 11259.4 11273.6 35719.9 35801.3 36065.0 36141.0 36163.6 3933.9 3952.2 3962.8 3999.2 4037.9 26226.9 26388.8 26560.6 27849.0 27999.0 3201.9 3252.3 3273.8 3295.3 3400.2 357914.0 360781.0 374970.0 379776.0 432758.7 35829.8 36935.6 45498.1 45592.5 45640.8 38774.6 47444.8 47633.8 47705.0 47845.0 12080.6 14607.3 14651.5 14653.9 14712.4 12657.1 12751.3 12757.5 12765.4 12775.4 12217.6 12302.9 12354.1 12371.8 12425.7 5706.2 5736.1 5737.4 5766.0 5786.6 1041.8 1095.9 1105.3 1129.4 1155.6 24372.6 27719.6 28099.0 28377.0 28431.7 1096.9 1178.2 1196.7 1197.3 1212.4 13025.1 14722.5 14825.6 15155.4 15861.8 318984.0 346624.0 353422.5 397607.0 398337.0 51192.2 51425.0 51637.2 51767.7 51910.0 1531.4 1556.8 1560.3 1565.1 1571.0 30094.8 31730.8 31899.2 32138.0 32413.9 50094.2 50200.7 50243.7 50335.7 50545.5 465593.0 466662.0 467177.0 468154.3 468306.0 30819.8 31389.4 38036.4 38193.0 38361.4 37450.4 47275.8 47658.3 48069.8 48839.5 32480.4 32794.0 32863.0 33027.0 34485.8 27031.5 32659.0 32906.3 33088.2 33674.5 32683.5 38025.0 38391.8 38850.6 38859.6 32423.8 32500.0 32587.8 33123.3 33634.7 24290.9 25877.4 26107.3 26221.1 26310.2 06:25:14 INFO - PROCESS | 1682 | 06:25:14 INFO - PROCESS | 1682 | [#2] jquery.html Cycles:180 Average:45287.95 Median:21788.17 stddev:130469.72 (598.8%) stddev-sans-first:130792.27 06:25:14 INFO - PROCESS | 1682 | Values: 439.6 448.5 455.3 463.2 464.2 21769.0 22580.9 22850.7 23671.5 23674.9 23449.9 23489.6 24059.3 24226.4 24381.0 6654.5 6725.9 6846.3 6921.4 6945.8 6802.1 6935.2 6937.5 7463.1 7787.8 7880.1 7924.1 7933.5 7936.6 7944.7 7335.8 7660.9 7756.1 7802.5 7811.5 6537.9 7361.6 7487.1 7498.8 7516.8 27324.9 27596.1 27994.2 28184.4 28287.3 3021.1 3050.6 3079.2 3142.5 3157.5 16102.9 17058.2 17080.2 17113.1 17440.9 2101.9 2128.1 2129.5 2133.9 2139.1 117109.4 139665.0 166189.0 167032.0 176049.2 16985.6 20612.8 21595.4 21699.9 21807.3 26631.6 33511.8 33519.8 33595.0 34132.7 10483.3 10858.4 11173.2 11225.8 11341.5 9368.2 9448.7 9511.1 9612.5 9616.4 8108.3 9818.5 10090.4 10121.5 10223.8 4427.1 4429.4 4467.6 4471.8 4473.6 873.3 963.1 1014.3 1025.4 1076.9 19506.4 21348.5 21518.8 21863.1 22675.9 888.8 889.9 905.3 915.4 990.9 11338.3 11533.6 11670.0 11772.0 11788.6 761895.0 762263.0 776083.0 792522.0 805969.0 169600.0 176774.0 177025.0 177877.0 184493.0 1245.2 1254.7 1275.7 1278.6 1281.3 17517.9 19066.3 19375.2 19378.7 19612.5 53935.5 66855.7 66883.7 67360.7 67420.0 87219.0 87589.7 100865.0 101015.0 101403.0 11340.9 11827.4 11860.1 11964.4 12308.8 13286.1 13430.6 13686.4 13790.4 13802.8 10761.4 11565.3 11709.1 11824.3 12057.0 11033.0 11043.7 11102.8 11112.2 11141.3 11554.6 11693.9 11812.5 12084.9 12093.0 11840.2 11954.7 12024.8 12308.6 12330.9 8582.4 11736.5 11765.9 11866.0 12135.8 06:25:14 INFO - PROCESS | 1682 | 06:25:14 INFO - PROCESS | 1682 | [#3] mootools.html Cycles:180 Average:6810.94 Median:2486.66 stddev:4212.53 (169.4%) stddev-sans-first:4205.04 06:25:14 INFO - PROCESS | 1682 | Values: 1443.9 1535.3 1626.3 1641.7 1648.2 10946.0 11179.8 11544.7 11687.3 11699.7 10971.4 11131.6 11139.8 11140.8 11151.5 2474.5 2511.5 2512.8 2514.1 2516.6 2446.0 2457.4 2478.6 2485.4 2491.0 2453.6 2463.2 2478.3 2481.2 2490.6 2432.6 2442.2 2459.3 2467.6 2474.1 2419.1 2460.0 2472.1 2478.9 2487.9 11467.9 11552.4 11567.9 11574.8 11574.8 5971.4 6129.6 6133.7 6137.3 6149.8 3299.2 3649.8 3743.6 3763.3 3815.3 1231.5 1244.8 1272.5 1287.7 1291.8 13817.5 13955.1 14185.4 15030.8 15159.0 3661.8 4219.5 4905.5 4917.9 4937.3 10765.0 11507.6 11879.0 12102.9 12129.6 6038.1 6041.0 6042.8 6044.9 6052.2 5353.9 5489.0 5555.4 5587.1 5587.7 4013.7 4113.4 4131.9 4132.1 4155.0 1082.7 1084.0 1093.5 1094.8 1102.9 931.6 936.9 942.6 948.7 960.5 6314.4 6354.7 6425.2 6494.1 6585.9 973.6 1003.2 1009.2 1013.2 1047.4 3510.9 3530.7 3984.2 4115.6 4185.6 13518.0 14058.2 14090.9 14096.9 14116.7 14047.8 14057.8 14083.7 14086.9 14130.3 9937.2 10359.1 10527.2 10550.0 10565.5 6697.6 6727.0 6774.0 6880.9 6972.3 11830.2 12261.9 12538.8 12592.5 12826.4 10889.4 11048.1 12602.9 12746.9 12801.9 9023.6 9275.5 9352.0 9358.9 9366.9 10208.6 10227.1 10250.6 10320.8 10343.0 8721.3 8731.1 8801.6 8823.6 8830.1 7821.0 7982.8 8458.7 8604.0 8661.7 7756.5 8027.6 8062.6 8187.5 8231.5 5998.6 5998.6 6003.2 6079.3 6116.9 5905.3 6096.8 6161.1 6164.1 6166.3 06:25:14 INFO - PROCESS | 1682 | 06:25:14 INFO - PROCESS | 1682 | [#4] prototype.html Cycles:180 Average:4703.07 Median:5042.05 stddev:1347.04 (26.7%) stddev-sans-first:1311.20 06:25:14 INFO - PROCESS | 1682 | Values: 382.0 396.1 398.3 403.7 407.6 4279.7 5091.9 5120.8 5550.5 5693.4 4928.8 5017.7 5365.6 5436.6 5446.2 4165.2 4688.6 4709.8 4739.0 4758.0 3842.9 3855.0 3958.5 4110.1 4367.9 3623.9 3893.2 4418.1 4433.0 4485.4 3597.7 3657.5 3908.0 4403.8 4529.2 3744.9 3832.0 3922.4 4090.7 4144.4 4366.6 4373.0 4413.8 4966.7 5236.8 4199.9 4611.2 4803.0 4856.2 5155.6 5103.6 5601.9 5675.3 5863.3 5871.9 3950.5 3959.0 4289.6 4549.2 4627.2 5629.0 5785.7 5809.6 5967.1 5972.5 3804.6 4295.5 4361.3 4513.8 4725.5 4471.2 4626.9 4824.3 4826.4 4876.0 4069.9 4435.2 4438.3 4863.4 5145.4 5412.1 5563.7 5767.9 5790.7 5796.7 1541.8 1563.6 1582.6 1617.7 1637.5 1022.0 1102.4 1136.3 1160.4 1255.6 4924.3 5126.4 5342.7 5429.9 5441.2 4994.9 5066.4 5280.6 5959.3 6003.5 5255.6 5260.3 5331.8 5359.9 5431.8 2709.1 2757.5 2802.9 2967.9 2972.8 4889.9 5697.9 6033.8 6083.8 6184.5 4876.9 5394.9 5735.5 5980.4 6026.9 5666.0 5818.3 6077.7 6154.8 6267.7 4952.3 5068.0 5100.5 5164.8 5864.7 5126.6 5285.6 5791.4 5889.9 5907.3 4951.0 5420.4 5718.3 6054.8 6183.6 3871.2 4639.2 4641.2 4677.8 4679.7 5632.5 5852.0 6021.4 6023.0 6027.5 5280.6 5762.7 5776.2 5821.7 5825.7 5703.2 5824.9 5835.0 5847.0 5874.2 4543.6 4787.1 4821.6 5195.7 5361.5 4847.3 5436.6 5563.2 5817.3 5898.9 4969.2 5157.9 5520.4 5771.3 5793.0 06:25:14 INFO - PROCESS | 1682 | 06:25:14 INFO - PROCESS | 1682 | [#5] yui.html Cycles:180 Average:20697.65 Median:18906.34 stddev:28570.47 (151.1%) stddev-sans-first:28611.43 06:25:14 INFO - PROCESS | 1682 | Values: 767.9 770.6 805.9 815.6 930.5 15734.6 17868.0 18898.2 19053.4 19256.1 18031.6 18772.1 18891.4 18914.4 18927.3 698.3 812.9 998.4 1022.6 1027.6 986.7 1015.9 1016.2 1018.9 1020.9 1060.3 1069.5 1071.8 1072.1 1074.6 1046.2 1064.1 1064.1 1064.7 1066.6 779.4 788.7 790.0 791.5 792.3 14003.8 16484.0 17604.0 17905.9 18047.3 14670.6 14678.7 14795.8 14814.3 14868.6 12073.8 12102.7 12143.0 12253.6 12330.8 3409.8 3794.2 3874.2 3937.6 3988.0 107327.0 111350.0 111357.4 118729.0 122222.0 11054.1 11071.2 11434.1 11636.7 11740.6 24225.4 24432.0 24467.5 24492.8 24577.6 8133.8 8196.8 8290.7 8298.3 8466.4 4487.5 4546.2 4617.3 4654.9 4664.7 3671.6 3744.6 3773.8 3790.8 3806.1 662.5 670.2 671.6 685.1 685.7 607.5 688.6 693.4 722.8 753.8 9635.7 10479.3 10607.5 10930.5 11054.4 711.8 717.1 721.3 756.6 759.8 4388.9 5100.5 5177.6 5232.4 5251.4 83055.9 85528.0 91624.9 95930.5 97389.0 65937.3 75743.6 83406.6 86985.0 90179.5 74730.0 75930.0 76904.2 78841.6 79088.0 10923.4 11188.4 11215.6 11518.7 11539.5 45051.6 45976.0 48852.8 50138.1 50219.7 66900.0 67682.7 69340.3 69680.0 71700.0 13222.9 13327.9 13490.1 13567.6 13680.9 15461.4 15746.7 15902.4 15952.7 16343.0 14013.3 14444.3 14586.0 14596.6 14642.6 13260.3 14205.9 14314.0 14517.1 14611.9 13428.2 14261.7 14291.4 14299.4 14403.9 10090.0 10191.7 10439.8 10543.3 10758.6 11373.3 11406.1 11619.5 11647.6 11674.6 06:25:14 INFO - PROCESS | 1682 | -------- Summary: end -------- 06:25:14 INFO - PROCESS | 1682 | 06:25:15 INFO - TEST-INFO | 1682: exit 0 06:25:15 INFO - TEST-OK | dromaeo_css | took 1116165ms 06:25:15 INFO - TEST-START | kraken 06:25:15 INFO - operating with platform_type : mac_ 06:25:15 INFO - Initialising browser for kraken test... 06:25:15 INFO - TEST-INFO | started process 1801 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/tmp5TifCU/profile http://localhost:49224/getInfo.html) 06:25:17 INFO - PROCESS | 1801 | 2017-06-01 06:25:17.966 plugin-container[1803:10620] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8543, name = 'com.apple.tsm.portname' 06:25:17 INFO - PROCESS | 1801 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:25:17 INFO - PROCESS | 1801 | 2017-06-01 06:25:17.967 plugin-container[1803:10620] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8547, name = 'com.apple.CFPasteboardClient' 06:25:17 INFO - PROCESS | 1801 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:25:17 INFO - PROCESS | 1801 | 2017-06-01 06:25:17.967 plugin-container[1803:10620] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:25:18 INFO - PROCESS | 1801 | __metrics Screen width/height:1600/1200 06:25:18 INFO - PROCESS | 1801 | colorDepth:24 06:25:18 INFO - PROCESS | 1801 | Browser inner width/height: 1024/689 06:25:18 INFO - PROCESS | 1801 | __metrics 06:25:18 ERROR - PROCESS | 1801 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 06:25:18 INFO - TEST-INFO | 1801: exit 0 06:25:18 INFO - Browser initialized. 06:25:18 INFO - Running cycle 1/1 for kraken test... 06:25:18 INFO - TEST-INFO | started process 1804 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/T/tmp5TifCU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:25:21 INFO - PROCESS | 1804 | 2017-06-01 06:25:21.113 plugin-container[1806:10743] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433f, name = 'com.apple.tsm.portname' 06:25:21 INFO - PROCESS | 1804 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:25:21 INFO - PROCESS | 1804 | 2017-06-01 06:25:21.113 plugin-container[1806:10743] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 06:25:21 INFO - PROCESS | 1804 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:25:21 INFO - PROCESS | 1804 | 2017-06-01 06:25:21.114 plugin-container[1806:10743] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:25:30 INFO - PROCESS | 1804 | RSS: Main: 174460928 06:25:30 INFO - PROCESS | 1804 | 06:25:33 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection) 06:25:34 INFO - PROCESS | 1804 | RSS: Main: 188043264 06:25:34 INFO - PROCESS | 1804 | 06:25:37 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-dft) 06:25:38 INFO - PROCESS | 1804 | RSS: Main: 196009984 06:25:38 INFO - PROCESS | 1804 | 06:25:41 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-fft) 06:25:42 INFO - PROCESS | 1804 | RSS: Main: 197562368 06:25:42 INFO - PROCESS | 1804 | 06:25:44 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator) 06:25:44 INFO - PROCESS | 1804 | RSS: Main: 199499776 06:25:44 INFO - PROCESS | 1804 | 06:25:46 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:25:47 INFO - PROCESS | 1804 | RSS: Main: 199856128 06:25:47 INFO - PROCESS | 1804 | 06:25:50 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom) 06:25:50 INFO - PROCESS | 1804 | RSS: Main: 203853824 06:25:50 INFO - PROCESS | 1804 | 06:25:54 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate) 06:25:54 INFO - PROCESS | 1804 | RSS: Main: 203546624 06:25:54 INFO - PROCESS | 1804 | 06:25:57 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial) 06:25:58 INFO - PROCESS | 1804 | RSS: Main: 203448320 06:25:58 INFO - PROCESS | 1804 | 06:25:59 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:26:00 INFO - PROCESS | 1804 | RSS: Main: 203059200 06:26:00 INFO - PROCESS | 1804 | 06:26:02 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:26:02 INFO - PROCESS | 1804 | RSS: Main: 203120640 06:26:02 INFO - PROCESS | 1804 | 06:26:05 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:26:05 INFO - PROCESS | 1804 | RSS: Main: 203120640 06:26:05 INFO - PROCESS | 1804 | 06:26:08 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:26:09 INFO - PROCESS | 1804 | RSS: Main: 203505664 06:26:09 INFO - PROCESS | 1804 | 06:26:12 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:26:12 INFO - PROCESS | 1804 | RSS: Main: 203599872 06:26:12 INFO - PROCESS | 1804 | 06:26:14 INFO - PROCESS | 1804 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49224/tests/kraken/driver.html?testName=ai-astar) 06:26:14 INFO - PROCESS | 1804 | RSS: Main: 207015936 06:26:14 INFO - PROCESS | 1804 | 06:26:14 INFO - PROCESS | 1804 | __start_tp_report 06:26:14 INFO - PROCESS | 1804 | _x_x_mozilla_page_load 06:26:14 INFO - PROCESS | 1804 | _x_x_mozilla_page_load_details 06:26:14 INFO - PROCESS | 1804 | |i|pagename|runs| 06:26:14 INFO - PROCESS | 1804 | |0;ai-astar;105;121;118;121;124;99;169;177;132;117 06:26:14 INFO - PROCESS | 1804 | |1;audio-beat-detection;140;109;124;179;116;114;113;126;159;138 06:26:14 INFO - PROCESS | 1804 | |2;audio-dft;255;253;193;196;196;241;277;199;195;197 06:26:14 INFO - PROCESS | 1804 | |3;audio-fft;83;62;81;77;76;54;58;65;99;86 06:26:14 INFO - PROCESS | 1804 | |4;audio-oscillator;82;70;85;70;72;69;82;71;71;82 06:26:14 INFO - PROCESS | 1804 | |5;imaging-gaussian-blur;90;88;88;88;93;88;96;137;85;122 06:26:14 INFO - PROCESS | 1804 | |6;imaging-darkroom;153;133;151;95;95;95;95;99;96;94 06:26:14 INFO - PROCESS | 1804 | |7;imaging-desaturate;95;90;91;91;91;90;89;90;91;90 06:26:14 INFO - PROCESS | 1804 | |8;json-parse-financial;65;64;66;66;67;69;67;67;68;67 06:26:14 INFO - PROCESS | 1804 | |9;json-stringify-tinderbox;62;57;60;63;58;57;52;61;60;60 06:26:14 INFO - PROCESS | 1804 | |10;stanford-crypto-aes;79;82;84;85;86;86;98;104;101;82 06:26:14 INFO - PROCESS | 1804 | |11;stanford-crypto-ccm;135;140;143;145;133;136;160;159;199;147 06:26:14 INFO - PROCESS | 1804 | |12;stanford-crypto-pbkdf2;172;162;163;168;166;165;166;162;192;219 06:26:14 INFO - PROCESS | 1804 | |13;stanford-crypto-sha256-iterative;65;62;65;64;65;64;65;62;64;64 06:26:14 INFO - PROCESS | 1804 | __end_tp_report 06:26:14 INFO - PROCESS | 1804 | __start_cc_report 06:26:14 INFO - PROCESS | 1804 | _x_x_mozilla_cycle_collect,994 06:26:14 INFO - PROCESS | 1804 | __end_cc_report 06:26:14 INFO - PROCESS | 1804 | __startTimestamp1496323574747__endTimestamp 06:26:14 INFO - PROCESS | 1804 | 06:26:14 INFO - PROCESS | 1804 | ------- Summary: start ------- 06:26:14 INFO - PROCESS | 1804 | Number of tests: 14 06:26:14 INFO - PROCESS | 1804 | 06:26:14 INFO - PROCESS | 1804 | [#0] ai-astar Cycles:10 Average:128.30 Median:128.00 stddev:25.39 (19.8%) stddev-sans-first:25.49 06:26:14 INFO - PROCESS | 1804 | Values: 105.0 121.0 118.0 121.0 124.0 99.0 169.0 177.0 132.0 117.0 06:26:14 INFO - PROCESS | 1804 | 06:26:14 INFO - PROCESS | 1804 | [#1] audio-beat-detection Cycles:10 Average:131.80 Median:132.00 stddev:22.63 (17.1%) stddev-sans-first:23.80 06:26:14 INFO - PROCESS | 1804 | Values: 140.0 109.0 124.0 179.0 116.0 114.0 113.0 126.0 159.0 138.0 06:26:14 INFO - PROCESS | 1804 | 06:26:14 INFO - PROCESS | 1804 | [#2] audio-dft Cycles:10 Average:220.20 Median:220.00 stddev:32.45 (14.8%) stddev-sans-first:31.89 06:26:14 INFO - PROCESS | 1804 | Values: 255.0 253.0 193.0 196.0 196.0 241.0 277.0 199.0 195.0 197.0 06:26:14 INFO - PROCESS | 1804 | 06:26:14 INFO - PROCESS | 1804 | [#3] audio-fft Cycles:10 Average:74.10 Median:79.00 stddev:14.11 (17.9%) stddev-sans-first:14.60 06:26:14 INFO - PROCESS | 1804 | Values: 83.0 62.0 81.0 77.0 76.0 54.0 58.0 65.0 99.0 86.0 06:26:14 INFO - PROCESS | 1804 | 06:26:14 INFO - PROCESS | 1804 | [#4] audio-oscillator Cycles:10 Average:75.40 Median:77.00 stddev:6.43 (8.4%) stddev-sans-first:6.36 06:26:14 INFO - PROCESS | 1804 | Values: 82.0 70.0 85.0 70.0 72.0 69.0 82.0 71.0 71.0 82.0 06:26:14 INFO - PROCESS | 1804 | 06:26:14 INFO - PROCESS | 1804 | [#5] imaging-gaussian-blur Cycles:10 Average:97.50 Median:88.00 stddev:17.50 (19.9%) stddev-sans-first:18.35 06:26:14 INFO - PROCESS | 1804 | Values: 90.0 88.0 88.0 88.0 93.0 88.0 96.0 137.0 85.0 122.0 06:26:14 INFO - PROCESS | 1804 | 06:26:14 INFO - PROCESS | 1804 | [#6] imaging-darkroom Cycles:10 Average:110.60 Median:95.00 stddev:24.78 (26.1%) stddev-sans-first:21.01 06:26:14 INFO - PROCESS | 1804 | Values: 153.0 133.0 151.0 95.0 95.0 95.0 95.0 99.0 96.0 94.0 06:26:14 INFO - PROCESS | 1804 | 06:26:14 INFO - PROCESS | 1804 | [#7] imaging-desaturate Cycles:10 Average:90.80 Median:91.00 stddev:1.62 (1.8%) stddev-sans-first:0.71 06:26:14 INFO - PROCESS | 1804 | Values: 95.0 90.0 91.0 91.0 91.0 90.0 89.0 90.0 91.0 90.0 06:26:14 INFO - PROCESS | 1804 | 06:26:14 INFO - PROCESS | 1804 | [#8] json-parse-financial Cycles:10 Average:66.60 Median:67.00 stddev:1.43 (2.1%) stddev-sans-first:1.39 06:26:14 INFO - PROCESS | 1804 | Values: 65.0 64.0 66.0 66.0 67.0 69.0 67.0 67.0 68.0 67.0 06:26:14 INFO - PROCESS | 1804 | 06:26:14 INFO - PROCESS | 1804 | [#9] json-stringify-tinderbox Cycles:10 Average:59.00 Median:60.00 stddev:3.16 (5.3%) stddev-sans-first:3.16 06:26:14 INFO - PROCESS | 1804 | Values: 62.0 57.0 60.0 63.0 58.0 57.0 52.0 61.0 60.0 60.0 06:26:14 INFO - PROCESS | 1804 | 06:26:14 INFO - PROCESS | 1804 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:84.50 stddev:8.86 (10.5%) stddev-sans-first:8.67 06:26:14 INFO - PROCESS | 1804 | Values: 79.0 82.0 84.0 85.0 86.0 86.0 98.0 104.0 101.0 82.0 06:26:14 INFO - PROCESS | 1804 | 06:26:14 INFO - PROCESS | 1804 | [#11] stanford-crypto-ccm Cycles:10 Average:149.70 Median:146.00 stddev:19.65 (13.5%) stddev-sans-first:20.11 06:26:14 INFO - PROCESS | 1804 | Values: 135.0 140.0 143.0 145.0 133.0 136.0 160.0 159.0 199.0 147.0 06:26:14 INFO - PROCESS | 1804 | 06:26:14 INFO - PROCESS | 1804 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.50 Median:167.00 stddev:18.27 (10.9%) stddev-sans-first:19.37 06:26:14 INFO - PROCESS | 1804 | Values: 172.0 162.0 163.0 168.0 166.0 165.0 166.0 162.0 192.0 219.0 06:26:14 INFO - PROCESS | 1804 | 06:26:14 INFO - PROCESS | 1804 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.00 Median:64.50 stddev:1.15 (1.8%) stddev-sans-first:1.17 06:26:14 INFO - PROCESS | 1804 | Values: 65.0 62.0 65.0 64.0 65.0 64.0 65.0 62.0 64.0 64.0 06:26:14 INFO - PROCESS | 1804 | -------- Summary: end -------- 06:26:14 INFO - PROCESS | 1804 | 06:26:15 INFO - TEST-INFO | 1804: exit 0 06:26:15 INFO - TEST-OK | kraken | took 60156ms 06:26:15 INFO - SUITE-END | took 1176s 06:26:15 INFO - Completed test suite (00:19:36) 06:26:15 INFO - javascript benchmark 06:26:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7993.061399049683, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2853.543433050712, 3690.760100593463, 3692.5753538343097, 3710.4619830124634, 3747.7938713370763, 6213.0, 6235.657038862861, 6236.314005459396, 6242.625442473449, 6254.84362890928, 6232.407921247485, 6272.278687950876, 6279.937200627993, 6280.937190628092, 6307.810765677025, 5653.321601407826, 5746.827595172152, 5753.741081651315, 5770.913436298459, 5815.767369305223, 5769.076947688371, 5799.18811366409, 5808.883822323551, 5811.622244554091, 5818.4181581841685, 5529.612927095105, 5554.777808887639, 5794.971025144869, 5796.884062318751, 5802.651840889539, 5625.071863142556, 5641.238432811559, 5721.427857214287, 5736.311642602915, 5751.942480575182, 5683.261176047129, 5720.570957178228, 5722.427757224286, 5741.483266505993, 5787.681677507735, 6284.9315616344875, 6315.210598675166, 6341.04884267359, 6352.205974253218, 6356.809295721136, 6384.106225128486, 6456.451201647866, 6529.804105876831, 6597.538172327939, 6716.059751634775, 6746.561473504206, 6802.625855577941, 6803.217629972518, 6834.111565496503, 6839.487038472134, 6282.994720844643, 6307.747690092345, 6327.050942358637, 6347.238331400215, 6354.0, 6556.377144171296, 6654.068430419745, 6864.759733409307, 6939.965300173568, 6958.338957799001, 6382.170317858741, 6467.708953097122, 6497.0, 6533.379328963836, 6572.244191917895, 6789.966050169717, 6801.523893327519, 6807.829804254933, 6808.523403361717, 6814.080099186645, 6610.669466526654, 6635.734570617121, 6650.135482387259, 6684.96657516719, 6702.061711360413, 6370.362963703686, 6395.52033597482, 6575.868482630416, 6623.0, 6664.200295964515, 5250.65870718402, 5310.893782124336, 5311.389190243094, 5387.299651045418, 5425.864353391197, 4765.1660959331975, 4791.496892826203, 4794.904101917942, 4808.471068182497, 4814.614830813596, 6266.467350275278, 6354.078658594571, 6354.142190804275, 6358.77744278948, 6366.204224471941, 6459.418652321219, 6525.021246812921, 6692.866142677217, 6698.866022679616, 6821.658917054227, 6322.494200464043, 6330.39861213184, 6340.4293613575, 6343.619382837044, 6346.174997250328, 5959.165716799652, 5964.671943043088, 5979.252593425822, 6001.819945401645, 6008.969955150196, 5862.443067908542, 6631.204255489372, 6662.700178492077, 6864.21061577925, 6902.792916212421, 5695.601307908407, 6749.865002700017, 6753.527253092237, 6774.254831968381, 6840.521163518705, 6029.668368239789, 7005.404540614105, 7012.929870701431, 7022.157341119098, 7026.437884969292, 6394.520410969103, 6445.0, 6588.934110658832, 6685.064091027163, 6701.128853249046, 6676.632785196861, 6691.130153080263, 6729.158855143107, 6758.42553382968, 6782.084418603426, 6605.339466053549, 6606.645637644176, 6609.900851487328, 6633.9668301658185, 6655.567388119756, 5470.288862447856, 6338.492920566436, 6522.706478208397, 6604.042413849868, 6625.536212465081, 6262.718177681924, 6264.498840092872, 6379.298277189413, 6455.48356131499, 6483.4164925156965, 5907.025340818888, 6137.60105593135, 6599.406053455212, 6604.240512341143, 6605.669716514251, 6220.440160385587, 6240.157578726814, 6328.873422531616, 6417.2299324081405, 6422.3577642235405], "value": 6159.709191186947, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [793.1156760212365, 832.2177153475733, 854.3464249848864, 880.55972013993, 980.7401038724744, 33785.9864204074, 39688.80155599221, 41408.58591414085, 42276.0, 42317.7884110579, 40811.59188408115, 40980.385294220614, 41077.383839242364, 41109.0, 41193.176136477254, 8318.958405207995, 8478.703245386412, 11229.943850280779, 11479.827802582968, 11711.882881171206, 11394.373309467976, 11471.0, 11515.596954106608, 11518.0, 11523.962843344098, 11467.19729618927, 11483.827742583868, 11498.482568284448, 11520.020798232119, 11523.19337646365, 9541.71374858752, 11424.971752542227, 11449.828252576219, 11457.0, 11473.770524589545, 11197.66407007791, 11224.326540407601, 11255.943720281428, 11259.380734059623, 11273.60542381021, 35719.928402147976, 35801.28397432063, 36065.0, 36141.0, 36163.638363616294, 3933.940990885139, 3952.170044290702, 3962.841486340543, 3999.24014437256, 4037.8990525237105, 26226.86886565574, 26388.81250343739, 26560.60159097615, 27849.02528411496, 27999.0, 3201.8719251230154, 3252.284497410566, 3273.770836041478, 3295.307985323085, 3400.1669590950128, 357914.0, 360781.0, 374970.0, 379776.0, 432758.67241327814, 35829.8208508961, 36935.63064369322, 45498.09003819905, 45592.54407455883, 45640.771796140805, 38774.6122538771, 47444.7627761859, 47633.76183119062, 47705.0, 47845.04309913852, 12080.637580872588, 14607.342669579895, 14651.487197948234, 14653.853461465249, 14712.411503539952, 12657.114002019955, 12751.298678572583, 12757.48970041188, 12765.42555585001, 12775.425105870072, 12217.633470995885, 12302.876971230353, 12354.0734444917, 12371.814422783666, 12425.689357765947, 5706.205373426707, 5736.13957906309, 5737.3975732548315, 5766.0, 5786.623869448473, 1041.8123339393098, 1095.8794532601407, 1105.309181761399, 1129.3562669278433, 1155.607093588184, 24372.634410483857, 27719.584206236923, 28099.0, 28377.006804761735, 28431.71568284332, 1096.9067629251444, 1178.1693905796392, 1196.7427003194355, 1197.3235122155954, 1212.4119801896074, 13025.088243822842, 14722.484713034992, 14825.555233343017, 15155.408682088442, 15861.762073568905, 318984.0, 346624.0, 353422.4657753441, 397607.0, 398337.0, 51192.23211651828, 51425.0, 51637.22544161841, 51767.741161293954, 51910.0, 1531.4410240262223, 1556.8209655889718, 1560.3134620766825, 1565.107888503542, 1571.0102635339663, 30094.84952575223, 31730.778845480596, 31899.202519937186, 32138.0, 32413.86551470687, 50094.24858627196, 50200.748996254784, 50243.74878125586, 50335.74832125816, 50545.49454505555, 465593.0, 466662.0, 467177.0, 468154.3184568247, 468306.0, 30819.845900770353, 31389.372212555165, 38036.42945355877, 38193.04517387087, 38361.4245786319, 37450.43824342582, 47275.76362118167, 47658.28512572245, 48069.75965120152, 48839.5116048835, 32480.35039299248, 32794.0, 32863.0, 33027.0, 34485.82757086299, 27031.459370812867, 32659.020229393158, 32906.341873161924, 33088.1727956803, 33674.49488257727, 32683.50974735333, 38025.0, 38391.808040959615, 38850.6114938847, 38859.611403885596, 32423.837880810446, 32500.0, 32587.837060814545, 33123.33753324872, 33634.66365336315, 24290.87854560716, 25877.353066173495, 26107.347316317246, 26221.082262120734, 26310.21069367922], "value": 19256.290099020476, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [439.5823967231131, 448.45960617455967, 455.33066392403157, 463.1824829176503, 464.1969392950196, 21769.0, 22580.870956452174, 22850.657240141412, 23671.526569468642, 23674.88162559187, 23449.882750586265, 23489.647655285185, 24059.278221653334, 24226.39434014146, 24381.024759009662, 6654.500912431563, 6725.8991115133185, 6846.315368463163, 6921.446284297269, 6945.826354341131, 6802.115724955774, 6935.202451718042, 6937.514373993822, 7463.067116610424, 7787.766367008999, 7880.133185349636, 7924.088729796061, 7933.484323518982, 7936.642851071687, 7944.721934732285, 7335.77992660221, 7660.923390766105, 7756.1468238494, 7802.492837965514, 7811.4922530035365, 6537.888558944943, 7361.5583065015735, 7487.064116985377, 7498.800191969259, 7516.774496765106, 27324.86337568319, 27596.10240924694, 27994.16017519478, 28184.43631127366, 28287.292817679518, 3021.078571035833, 3050.5729197912087, 3079.1532328609587, 3142.4972004479173, 3157.526371044362, 16102.919485402615, 17058.2088880667, 17080.231389587498, 17113.058781767, 17440.91279543607, 2101.9264325748527, 2128.1381040678393, 2129.5208578069964, 2133.925312614051, 2139.1443422631073, 117109.41445292805, 139665.0, 166189.0, 167032.0, 176049.23950760457, 16985.575360616083, 20612.793872061087, 21595.352139435843, 21699.915004249724, 21807.345779626638, 26631.600525992126, 33511.83244083764, 33519.83240083784, 33594.98430094202, 34132.65867341345, 10483.319288157498, 10858.402787846744, 11173.217874748852, 11225.831612525819, 11341.489632966537, 9368.156865882103, 9448.716538503855, 9511.143997040299, 9612.51937403127, 9616.423014619146, 8108.270255677017, 9818.45998470091, 10090.394576325443, 10121.520227544152, 10223.846642300372, 4427.092446048552, 4429.446319210098, 4467.642588592905, 4471.754053527022, 4473.619742321939, 873.2620935309653, 963.0562049191691, 1014.3356101753363, 1025.3540269630084, 1076.923076923077, 19506.414807555797, 21348.466288342606, 21518.784812151993, 21863.12547498082, 22675.88662056679, 888.7911340834899, 889.9154580314989, 905.3255324782947, 915.3729695158837, 990.9355891867006, 11338.263012904135, 11533.615966083957, 11670.0, 11772.0, 11788.646340609796, 761895.0, 762263.0, 776083.0, 792522.0, 805969.0, 169600.0, 176774.0, 177025.0, 177877.0, 184493.0, 1245.2341809786915, 1254.7239607286383, 1275.7002104505434, 1278.6483716977718, 1281.301690578637, 17517.912410437868, 19066.332678356746, 19375.224991000767, 19378.740381875134, 19612.509687257934, 53935.46064539305, 66855.66572167107, 66883.66558167178, 67360.6631966837, 67419.97740067806, 87219.0, 87589.68615470901, 100865.0, 101015.0, 101403.0, 11340.94329528347, 11827.408629568316, 11860.05119590448, 11964.401779911144, 12308.815367769312, 13286.136401133896, 13430.597082087554, 13686.384112715152, 13790.448382065006, 13802.792958105436, 10761.38003540825, 11565.306081635128, 11709.063274938155, 11824.349660768741, 12057.0, 11033.007029367396, 11043.668689939315, 11102.833457497982, 11112.22214444981, 11141.331520108819, 11554.59558915434, 11693.941530292293, 11812.468438920097, 12084.939575302067, 12092.972097371821, 11840.1711880171, 11954.701132471759, 12024.819627705416, 12308.630741077783, 12330.876691232972, 8582.442141260799, 11736.471858766547, 11765.882341176479, 11866.0, 12135.757284854077], "value": 12174.43319544237, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1443.9133651980883, 1535.33980388433, 1626.3332033866118, 1641.7209074457342, 1648.2088597473216, 10946.0, 11179.776404471906, 11544.65366039018, 11687.311918258794, 11699.707507312321, 10971.396573188471, 11131.554737810478, 11139.832902506469, 11140.832887506675, 11151.498182581783, 2474.480359124586, 2511.510255500178, 2512.773850353468, 2514.0823599386276, 2516.5973444248925, 2446.0338166424253, 2457.4225057111566, 2478.6405971134177, 2485.440775825443, 2490.950180996379, 2453.619688948207, 2463.2117722328862, 2478.3060742991993, 2481.2432208176497, 2490.5641512735288, 2432.6107822748277, 2442.242904699549, 2459.32368598635, 2467.629855521668, 2474.1464194852747, 2419.1049311754587, 2460.0159936025734, 2472.1223965492172, 2478.8884500197355, 2487.8756062197, 11467.885321146765, 11552.422378881023, 11567.88432115685, 11574.768504629861, 11574.826377604342, 5971.373005834412, 6129.601575897597, 6133.723982420802, 6137.324894261627, 6149.815505534841, 3299.2081900343983, 3649.7627654202392, 3743.625637436262, 3763.2661630981697, 3815.313243616148, 1231.5197073141483, 1244.7883859743865, 1272.5100836178046, 1287.7295767888754, 1291.8062290656383, 13817.480077191602, 13955.092918960236, 14185.361658725382, 15030.849691503163, 15159.0, 3661.8352174151696, 4219.535851056381, 4905.509449055137, 4917.901641967141, 4937.259411088366, 10765.0, 11507.597234096766, 11879.0, 12102.939485302693, 12129.575464858688, 6038.094285857156, 6041.003234466351, 6042.848928776816, 6044.879102418015, 6052.182955301066, 5353.892922141536, 5489.0, 5555.416681248411, 5587.1339942309005, 5587.7485513152005, 4013.6588389986596, 4113.382992551141, 4131.8967025824595, 4132.070284186034, 4155.044339801862, 1082.6697857153645, 1083.9891601084046, 1093.4860615510697, 1094.8467214589964, 1102.895224953628, 931.6180366049887, 936.864154697565, 942.6323733743845, 948.7106432538142, 960.4861399151421, 6314.400131987454, 6354.745810167634, 6425.228972523327, 6494.123293355431, 6585.9012114818315, 973.5716284834791, 1003.207466101786, 1009.1926458833051, 1013.1793247469426, 1047.4291511126455, 3510.8771193008124, 3530.6645868642436, 3984.203159368138, 4115.5678653741525, 4185.581441855851, 13518.0, 14058.22679752603, 14090.929545352412, 14096.929515352358, 14116.717665646629, 14047.78928316076, 14057.78913316301, 14083.718325633636, 14086.929565352108, 14130.293485325692, 9937.155341795884, 10359.119474844572, 10527.21045921559, 10550.0, 10565.471726413802, 6697.564658297195, 6726.966365168143, 6774.0, 6880.8967865483055, 6972.337627925339, 11830.231034982698, 12261.87738122643, 12538.811917821058, 12592.496300147892, 12826.422810973341, 10889.401082940514, 11048.116150708085, 12602.87397126017, 12746.872531274568, 12801.871981280068, 9023.593938272663, 9275.489848058422, 9352.0, 9358.906410935804, 9366.906330936603, 10208.591656333663, 10227.130693891102, 10250.641227557, 10320.845187322046, 10343.0, 8721.269916718766, 8731.083236260101, 8801.603927823391, 8823.558822059, 8830.116988301119, 7820.983272174809, 7982.84034319322, 8458.74623761288, 8603.95698021531, 8661.653533858575, 7756.495827771177, 8027.5584842834205, 8062.55655938929, 8187.549684767395, 8231.46495477792, 5998.550108741949, 5998.610090344114, 6003.189569408074, 6079.270487541523, 6116.8776624468155, 5905.320888097924, 6096.817095487142, 6161.075838624242, 6164.075388691732, 6166.290876549254], "value": 5223.32866222566, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.9751716138451, 396.09492310071494, 398.3327925724411, 403.7153806566373, 407.6249850137872, 4279.737477444153, 5091.898162036757, 5120.769565369554, 5550.501364631547, 5693.43065693431, 4928.778204980776, 5017.673851199669, 5365.570754339653, 5436.592255580827, 5446.2375267462485, 4165.208610364036, 4688.640294314645, 4709.811607535695, 4738.952610473885, 4757.976210118944, 3842.943190622573, 3855.0, 3958.5447673517624, 4110.0752330725645, 4367.890802729941, 3623.927521449569, 3893.240818040483, 4418.100216906729, 4433.027302850233, 4485.416895803541, 3597.712183025352, 3657.4513822926506, 3907.960920390788, 4403.779811009436, 4529.214533062143, 3744.8876533704033, 3831.965369350288, 3922.3528117860806, 4090.6909788867574, 4144.41978123063, 4366.628836548897, 4373.0, 4413.845515406945, 4966.6771659842, 5236.764345604457, 4199.895002624929, 4611.21609326412, 4802.951970480285, 4856.198727210006, 5155.639105262633, 5103.591712663015, 5601.859953501154, 5675.275867583583, 5863.325717542494, 5871.91369596626, 3950.466686997276, 3958.990457433365, 4289.605878089602, 4549.203889319356, 4627.213373726475, 5628.971855140739, 5785.710714464302, 5809.564282678816, 5967.12137110877, 5972.522198224132, 3804.6113168693228, 4295.463067116611, 4361.258586040381, 4513.751743654065, 4725.480197178307, 4471.217536931024, 4626.930596041062, 4824.34871292371, 4826.396700412449, 4876.0, 4069.877903662895, 4435.157320109169, 4438.335624140947, 4863.392075990501, 5145.3568303962, 5412.079946409041, 5563.721813909288, 5767.942320576824, 5790.654784812158, 5796.739396543537, 1541.768734689783, 1563.6090977255685, 1582.6389305197522, 1617.7088124137533, 1637.5333030086367, 1021.9948900255451, 1102.404701461218, 1136.2614300704608, 1160.4313886195703, 1255.5731051442554, 4924.3352147460355, 5126.384833819965, 5342.706151161646, 5429.904954176081, 5441.211024401439, 4994.900101998012, 5066.417362003417, 5280.577553795687, 5959.28488581373, 6003.549733769897, 5255.634964266095, 5260.29149449637, 5331.81338653153, 5359.946400536022, 5431.827269092395, 2709.105995021638, 2757.462294852525, 2802.9159125226274, 2967.9487499562943, 2972.7621790256726, 4889.875328674354, 5697.9430205698245, 6033.78881739137, 6083.8174854754425, 6184.5052395809125, 4876.878078048077, 5394.865128371822, 5735.512481439041, 5980.43185897339, 6026.885026270154, 5665.999999999917, 5818.31268932005, 6077.723678027575, 6154.846128846814, 6267.655278959701, 4952.257161425815, 5067.986402719397, 5100.515451032146, 5164.767585458739, 5864.74506648797, 5126.641135120506, 5285.577153827608, 5791.362950075572, 5889.852753681021, 5907.291125064848, 4950.960298337245, 5420.403755586961, 5718.285214348207, 6054.820264704791, 6183.578741200064, 3871.245107204068, 4639.211334073283, 4641.234196357564, 4677.789499472464, 4679.719216847, 5632.5212356950115, 5851.970740146442, 6021.42796434338, 6023.0, 6027.517798576016, 5280.630355875205, 5762.740676669644, 5776.249087618583, 5821.679807610452, 5825.70871456434, 5703.231998080609, 5824.94175058244, 5835.0, 5846.970765146147, 5874.236349274738, 4543.568361005699, 4787.0559605842855, 4821.614270858253, 5195.6622819516615, 5361.51746342831, 4847.2729090636685, 5436.592255580927, 5563.248961390323, 5817.272840894888, 5898.852528686645, 4969.204927211629, 5157.916837464175, 5520.355689945021, 5771.307443106854, 5793.0], "value": 4314.279607558534, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [767.8502691975063, 770.5800338815345, 805.931495822855, 815.5962798414782, 930.5161316115618, 15734.606634834136, 17868.0, 18898.24407023719, 19053.428397148073, 19256.13347399367, 18031.639367212647, 18772.061396930134, 18891.433257002278, 18914.432567022977, 18927.33754318599, 698.3435570563679, 812.8943237379133, 998.366037566147, 1022.616518805448, 1027.5889644142364, 986.6546708651986, 1015.8984101589818, 1016.1819735113231, 1018.8981101889826, 1020.9387436753782, 1060.331990845766, 1069.48664640972, 1071.8177909755361, 1072.0726571515622, 1074.6023971130653, 1046.1735229168946, 1064.0636240108731, 1064.1327318235612, 1064.7018834726289, 1066.568039943824, 779.3959681247022, 788.6648174525861, 789.9921000789976, 791.5250849490317, 792.2671528835862, 14003.789943150861, 16484.0, 17604.0, 17905.925644461244, 18047.27810887563, 14670.633234169125, 14678.706425871424, 14795.778063329059, 14814.333354998944, 14868.62828429287, 12073.758524829542, 12102.697432564168, 12143.0, 12253.571125010581, 12330.75338493234, 3409.8406541775666, 3794.2221844522082, 3874.244522318149, 3937.5865534118793, 3988.0428697112775, 107327.0, 111350.0, 111357.44321278342, 118729.0, 122222.0, 11054.062216852959, 11071.16966227536, 11434.142439317084, 11636.650900473, 11740.58907938218, 24225.39436514066, 24432.0, 24467.510649786906, 24492.755072449403, 24577.631335529983, 8133.75598732039, 8196.754097376968, 8290.668373265002, 8298.294644955125, 8466.365022623328, 4487.483939346952, 4546.202932680079, 4617.2612382018715, 4654.906901862011, 4664.696794708333, 3671.57776855665, 3744.6068162843053, 3773.754705944105, 3790.7915064671706, 3806.1055651921774, 662.460095022555, 670.1790306874137, 671.633959492078, 685.1333063674456, 685.6605980039913, 607.4897086447405, 688.6074937285799, 693.3655705029842, 722.7687140115113, 753.8416932444138, 9635.662751803653, 10479.266451348485, 10607.522661480098, 10930.508127134299, 11054.447277636085, 711.839836036898, 717.1322699533519, 721.2823240875258, 756.6254703921484, 759.8442319324525, 4388.912221755547, 5100.540951314325, 5177.58579313654, 5232.424433312332, 5251.422343542284, 83055.92360190922, 85528.0, 91624.87688054054, 95930.52034739782, 97389.0, 65937.34062659313, 75743.62128189324, 83406.58296708477, 86985.0, 90179.54910225407, 74730.0, 75930.0, 76904.23095769083, 78841.60579197068, 79088.0, 10923.399213043358, 11188.384638844942, 11215.60745373908, 11518.712032199262, 11539.538418463168, 45051.648450546534, 45976.0, 48852.755736221094, 50138.145526514294, 50219.74890125526, 66900.0, 67682.66158669176, 69340.26649333807, 69680.0, 71700.0, 13222.933885330513, 13327.866721332663, 13490.055696101179, 13567.59297221085, 13680.931595342357, 15461.381544738084, 15746.685066298382, 15902.363905443653, 15952.680946381239, 16343.019418834909, 14013.299335033003, 14444.277786110442, 14586.0, 14596.562103136499, 14642.633934151732, 13260.270685112411, 14205.928970355082, 14314.0, 14517.128972261698, 14611.92694036523, 13428.244490347628, 14261.714765704835, 14291.356888939814, 14299.428022878968, 14403.855961440671, 10090.0, 10191.7452063699, 10439.791204176026, 10543.31468454548, 10758.569657213624, 11373.31760094397, 11406.087512999105, 11619.47712352963, 11647.59233426826, 11674.649760507198], "value": 8013.107099622157, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1530.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 121.0, 118.0, 121.0, 124.0, 99.0, 169.0, 177.0, 132.0, 117.0], "value": 128.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 109.0, 124.0, 179.0, 116.0, 114.0, 113.0, 126.0, 159.0, 138.0], "value": 131.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [255.0, 253.0, 193.0, 196.0, 196.0, 241.0, 277.0, 199.0, 195.0, 197.0], "value": 220.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 62.0, 81.0, 77.0, 76.0, 54.0, 58.0, 65.0, 99.0, 86.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 70.0, 85.0, 70.0, 72.0, 69.0, 82.0, 71.0, 71.0, 82.0], "value": 75.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 88.0, 88.0, 93.0, 88.0, 96.0, 137.0, 85.0, 122.0], "value": 97.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 133.0, 151.0, 95.0, 95.0, 95.0, 95.0, 99.0, 96.0, 94.0], "value": 110.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 90.0, 91.0, 91.0, 91.0, 90.0, 89.0, 90.0, 91.0, 90.0], "value": 90.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 66.0, 66.0, 67.0, 69.0, 67.0, 67.0, 68.0, 67.0], "value": 66.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 57.0, 60.0, 63.0, 58.0, 57.0, 52.0, 61.0, 60.0, 60.0], "value": 59.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 82.0, 84.0, 85.0, 86.0, 86.0, 98.0, 104.0, 101.0, 82.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 140.0, 143.0, 145.0, 133.0, 136.0, 160.0, 159.0, 199.0, 147.0], "value": 149.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 162.0, 163.0, 168.0, 166.0, 165.0, 166.0, 162.0, 192.0, 219.0], "value": 173.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 62.0, 65.0, 64.0, 65.0, 64.0, 65.0, 62.0, 64.0, 64.0], "value": 64.0, "unit": "score"}]}]} 06:26:16 INFO - Return code: 0 06:26:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:26:16 ERROR - # TBPL SUCCESS # 06:26:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:26:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:26:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:26:16 INFO - 2017-06-01 06:26:16.106 screenresolution[1815:11129] starting screenresolution argv=screenresolution get 06:26:16 INFO - 2017-06-01 06:26:16.108 screenresolution[1815:11129] Display 0: 1600x1200x32@60 06:26:16 INFO - 2017-06-01 06:26:16.114 screenresolution[1816:11132] starting screenresolution argv=screenresolution list 06:26:16 INFO - Available Modes on Display 0 06:26:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:26:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:26:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:26:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:26:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:26:16 INFO - Intel Iris: 06:26:16 INFO - Chipset Model: Intel Iris 06:26:16 INFO - Type: GPU 06:26:16 INFO - Bus: Built-In 06:26:16 INFO - VRAM (Dynamic, Max): 1536 MB 06:26:16 INFO - Vendor: Intel (0x8086) 06:26:16 INFO - Device ID: 0x0a2e 06:26:16 INFO - Revision ID: 0x0009 06:26:16 INFO - Displays: 06:26:16 INFO - Display: 06:26:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:26:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:26:16 INFO - Display Serial Number: 1600x1200 60 06:26:16 INFO - Main Display: Yes 06:26:16 INFO - Mirror: Off 06:26:16 INFO - Online: Yes 06:26:16 INFO - Rotation: Supported 06:26:16 INFO - Return code: 0 06:26:16 INFO - Running post-action listener: _resource_record_post_action 06:26:16 INFO - [mozharness: 2017-06-01 13:26:16.351408Z] Finished run-tests step (success) 06:26:16 INFO - Running post-run listener: _resource_record_post_run 06:26:16 INFO - Total resource usage - Wall time: 1192s; CPU: 55.0%; Read bytes: 40050688; Write bytes: 433221632; Read time: 281; Write time: 1438 06:26:16 INFO - TinderboxPrint: CPU usage
54.5% 06:26:16 INFO - TinderboxPrint: I/O read bytes / time
40,050,688 / 281 06:26:16 INFO - TinderboxPrint: I/O write bytes / time
433,221,632 / 1,438 06:26:16 INFO - TinderboxPrint: CPU idle
2,168.6 (45.5%) 06:26:16 INFO - TinderboxPrint: CPU system
961.6 (20.2%) 06:26:16 INFO - TinderboxPrint: CPU user
1,639.3 (34.4%) 06:26:16 INFO - TinderboxPrint: Swap in / out
157,941,760 / 0 06:26:16 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 108943872; Write bytes: 106784768; Read time: 9800; Write time: 231 06:26:16 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 39047168; Write bytes: 305596416; Read time: 269; Write time: 1146 06:26:16 INFO - Running post-run listener: _upload_blobber_files 06:26:16 INFO - Blob upload gear active. 06:26:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:26:16 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:26:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:26:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:26:17 INFO - (blobuploader) - INFO - Open directory for files ... 06:26:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:26:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:26:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:26:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:26:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:26:18 INFO - (blobuploader) - INFO - Done attempting. 06:26:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:26:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:26:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:26:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:26:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:26:19 INFO - (blobuploader) - INFO - Done attempting. 06:26:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:26:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:26:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:26:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:26:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:26:20 INFO - (blobuploader) - INFO - Done attempting. 06:26:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:26:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:26:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:26:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:26:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:26:22 INFO - (blobuploader) - INFO - Done attempting. 06:26:22 INFO - (blobuploader) - INFO - Iteration through files over. 06:26:22 INFO - Return code: 0 06:26:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:26:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:26:22 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/94a67cd8c2c395c26e4dad07dcd3cccfc4d67855a6e3f42497f0bac8baa8402f991f422c4b1353ee845db2a9d50639a8fe70d5f1bb3a6b198fed73a145173377", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ccca7cd8a8df91dc7ab19e89b709dfd460a31bf5cc00e113bbece9f5f9c53958b95623ab47903478c48e652d492c21813a36f8f8a4553fee94c98e71a3e733c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/821146f940a04eafebd2ff681129d4810ff951c5f14499f0369c3cf7db24212ad5755faea80317ef81239fcedca68b913bc3b152bd9b9100c42cad96633a29e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c7315d68c23289ac7989abb5828cc48028beb1702db00c2b02c8c82bee2d03cc23b25787854b2c6ae4152a3df72a18abdcd41ba8823412cb7de32d251330af84"} 06:26:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:26:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:26:22 INFO - Contents: 06:26:22 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/94a67cd8c2c395c26e4dad07dcd3cccfc4d67855a6e3f42497f0bac8baa8402f991f422c4b1353ee845db2a9d50639a8fe70d5f1bb3a6b198fed73a145173377", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ccca7cd8a8df91dc7ab19e89b709dfd460a31bf5cc00e113bbece9f5f9c53958b95623ab47903478c48e652d492c21813a36f8f8a4553fee94c98e71a3e733c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/821146f940a04eafebd2ff681129d4810ff951c5f14499f0369c3cf7db24212ad5755faea80317ef81239fcedca68b913bc3b152bd9b9100c42cad96633a29e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c7315d68c23289ac7989abb5828cc48028beb1702db00c2b02c8c82bee2d03cc23b25787854b2c6ae4152a3df72a18abdcd41ba8823412cb7de32d251330af84"} 06:26:22 INFO - Running post-run listener: copy_logs_to_upload_dir 06:26:22 INFO - Copying logs to upload dir... 06:26:22 INFO - mkdir: /builds/slave/test/build/upload/logs 06:26:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.557993 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-06-01 06:26:22.148353) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 06:26:22.152188) ========= 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.vI65GjoyDY/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.mibAUaBPHM/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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/94a67cd8c2c395c26e4dad07dcd3cccfc4d67855a6e3f42497f0bac8baa8402f991f422c4b1353ee845db2a9d50639a8fe70d5f1bb3a6b198fed73a145173377", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ccca7cd8a8df91dc7ab19e89b709dfd460a31bf5cc00e113bbece9f5f9c53958b95623ab47903478c48e652d492c21813a36f8f8a4553fee94c98e71a3e733c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/821146f940a04eafebd2ff681129d4810ff951c5f14499f0369c3cf7db24212ad5755faea80317ef81239fcedca68b913bc3b152bd9b9100c42cad96633a29e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c7315d68c23289ac7989abb5828cc48028beb1702db00c2b02c8c82bee2d03cc23b25787854b2c6ae4152a3df72a18abdcd41ba8823412cb7de32d251330af84"} build_url:https://queue.taskcluster.net/v1/task/aFidrUb3QfWGY_qZyH4Arw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011183 build_url: 'https://queue.taskcluster.net/v1/task/aFidrUb3QfWGY_qZyH4Arw/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/94a67cd8c2c395c26e4dad07dcd3cccfc4d67855a6e3f42497f0bac8baa8402f991f422c4b1353ee845db2a9d50639a8fe70d5f1bb3a6b198fed73a145173377", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ccca7cd8a8df91dc7ab19e89b709dfd460a31bf5cc00e113bbece9f5f9c53958b95623ab47903478c48e652d492c21813a36f8f8a4553fee94c98e71a3e733c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/821146f940a04eafebd2ff681129d4810ff951c5f14499f0369c3cf7db24212ad5755faea80317ef81239fcedca68b913bc3b152bd9b9100c42cad96633a29e3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c7315d68c23289ac7989abb5828cc48028beb1702db00c2b02c8c82bee2d03cc23b25787854b2c6ae4152a3df72a18abdcd41ba8823412cb7de32d251330af84"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 06:26:22.187429) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 06:26:22.187762) ========= 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.vI65GjoyDY/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.mibAUaBPHM/Listeners TMPDIR=/var/folders/q6/ldsh2j4d0v57vmzgg283h63800000w/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.005096 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 06:26:22.237333) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 06:26:22.237803) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 06:26:22.238256) ========= ========= Total master_lag: 0.18 =========