builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0022 starttime: 1495564887.85 results: success (0) buildid: 20170523100428 builduid: e1f0bd5becdd4836ab86ed8a34346e4f revision: a2d407b9da248932f50c95533d13cd8043ca2e21 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-23 11:41:27.850373) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-23 11:41:27.850856) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-23 11:41:27.851170) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-23 11:41:27.876828) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 11:41:27.877244) ========= 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.tRumbXgakB/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.3yi6qFgpnq/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-23 11:41:27-- 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% 23.2M=0.001s 2017-05-23 11:41:28 (23.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.175820 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 11:41:28.075534) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 11:41:28.075868) ========= 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.tRumbXgakB/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.3yi6qFgpnq/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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.030153 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 11:41:28.126533) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-23 11:41:28.126932) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a2d407b9da248932f50c95533d13cd8043ca2e21 --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 a2d407b9da248932f50c95533d13cd8043ca2e21 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tRumbXgakB/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.3yi6qFgpnq/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-23 11:41:28,235 truncating revision to first 12 chars 2017-05-23 11:41:28,235 Setting DEBUG logging. 2017-05-23 11:41:28,236 attempt 1/10 2017-05-23 11:41:28,236 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/a2d407b9da24?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-23 11:41:30,369 unpacking tar archive at: mozilla-beta-a2d407b9da24/testing/mozharness/ program finished with exit code 0 elapsedTime=2.416613 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-23 11:41:30.568664) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-23 11:41:30.569050) ========= script_repo_revision: a2d407b9da248932f50c95533d13cd8043ca2e21 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-23 11:41:30.569465) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-23 11:41:30.569913) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-23 11:41:30.588743) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-05-23 11:41:30.589211) ========= /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.tRumbXgakB/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.3yi6qFgpnq/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 11:41:30 INFO - MultiFileLogger online at 20170523 11:41:30 in /builds/slave/test 11:41:30 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 11:41:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:41:30 INFO - {'append_to_log': False, 11:41:30 INFO - 'base_work_dir': '/builds/slave/test', 11:41:30 INFO - 'blob_upload_branch': 'Mozilla-Beta', 11:41:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:41:30 INFO - 'branch': 'Mozilla-Beta', 11:41:30 INFO - 'buildbot_json_path': 'buildprops.json', 11:41:30 INFO - 'config_files': ('talos/mac_config.py',), 11:41:30 INFO - 'default_actions': ('clobber', 11:41:30 INFO - 'read-buildbot-config', 11:41:30 INFO - 'download-and-extract', 11:41:30 INFO - 'populate-webroot', 11:41:30 INFO - 'create-virtualenv', 11:41:30 INFO - 'install', 11:41:30 INFO - 'run-tests'), 11:41:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:41:30 INFO - 'download_minidump_stackwalk': True, 11:41:30 INFO - 'download_symbols': 'ondemand', 11:41:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:41:30 INFO - 'tooltool.py': '/tools/tooltool.py', 11:41:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:41:30 INFO - '/tools/misc-python/virtualenv.py')}, 11:41:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:41:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:41:30 INFO - 'gecko_profile': False, 11:41:30 INFO - 'gecko_profile_interval': 0, 11:41:30 INFO - 'installer_path': 'installer.exe', 11:41:30 INFO - 'log_level': 'info', 11:41:30 INFO - 'log_name': 'talos', 11:41:30 INFO - 'log_to_console': True, 11:41:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:41:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:41:30 INFO - 'opt_config_files': (), 11:41:30 INFO - 'pip_index': False, 11:41:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:41:30 INFO - 'cmd': ('bash', 11:41:30 INFO - '-c', 11:41:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:41:30 INFO - 'enabled': True, 11:41:30 INFO - 'halt_on_failure': False, 11:41:30 INFO - 'name': 'check_screen_resolution'},), 11:41:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:41:30 INFO - 'cmd': ('bash', 11:41:30 INFO - '-c', 11:41:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:41:30 INFO - 'enabled': True, 11:41:30 INFO - 'halt_on_failure': False, 11:41:30 INFO - 'name': 'check_screen_resolution'},), 11:41:30 INFO - 'run_cmd_checks_enabled': True, 11:41:30 INFO - 'suite': 'dromaeojs-e10s', 11:41:30 INFO - 'system_bits': '32', 11:41:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:41:30 INFO - 'title': 't-yosemite-r7-0022', 11:41:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:41:30 INFO - 'use_talos_json': True, 11:41:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:41:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:41:30 INFO - 'work_dir': 'build'} 11:41:30 INFO - [mozharness: 2017-05-23 18:41:30.804248Z] Running clobber step. 11:41:30 INFO - Running pre-action listener: _resource_record_pre_action 11:41:30 INFO - Running main action method: clobber 11:41:30 INFO - rmtree: /builds/slave/test/build 11:41:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:41:33 INFO - Running post-action listener: _resource_record_post_action 11:41:33 INFO - [mozharness: 2017-05-23 18:41:33.405344Z] Finished clobber step (success) 11:41:33 INFO - [mozharness: 2017-05-23 18:41:33.405505Z] Running read-buildbot-config step. 11:41:33 INFO - Running pre-action listener: _resource_record_pre_action 11:41:33 INFO - Running main action method: read_buildbot_config 11:41:33 INFO - Using buildbot properties: 11:41:33 INFO - { 11:41:33 INFO - "project": "", 11:41:33 INFO - "product": "firefox", 11:41:33 INFO - "script_repo_revision": "production", 11:41:33 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 11:41:33 INFO - "repository": "", 11:41:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 11:41:33 INFO - "buildid": "20170523100428", 11:41:33 INFO - "pgo_build": "False", 11:41:33 INFO - "basedir": "/builds/slave/test", 11:41:33 INFO - "buildnumber": 0, 11:41:33 INFO - "slavename": "t-yosemite-r7-0022", 11:41:33 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 11:41:33 INFO - "platform": "yosemite_r7", 11:41:33 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 11:41:33 INFO - "branch": "mozilla-beta", 11:41:33 INFO - "repo_path": "releases/mozilla-beta", 11:41:33 INFO - "revision": "a2d407b9da248932f50c95533d13cd8043ca2e21", 11:41:33 INFO - "stage_platform": "macosx64", 11:41:33 INFO - "builduid": "e1f0bd5becdd4836ab86ed8a34346e4f", 11:41:33 INFO - "slavebuilddir": "test" 11:41:33 INFO - } 11:41:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 11:41:33 INFO - Running post-action listener: _resource_record_post_action 11:41:33 INFO - [mozharness: 2017-05-23 18:41:33.408887Z] Finished read-buildbot-config step (success) 11:41:33 INFO - [mozharness: 2017-05-23 18:41:33.408999Z] Running download-and-extract step. 11:41:33 INFO - Running pre-action listener: _resource_record_pre_action 11:41:33 INFO - Running main action method: download_and_extract 11:41:33 INFO - mkdir: /builds/slave/test/build/tests 11:41:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:41:33 INFO - trying https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 11:41:33 INFO - Downloading https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/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 11:41:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/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 11:41:33 INFO - Downloaded 1287 bytes. 11:41:33 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 11:41:33 INFO - Using the following test package requirements: 11:41:33 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:41:33 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 11:41:33 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 11:41:33 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:41:33 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 11:41:33 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:41:33 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 11:41:33 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 11:41:33 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:41:33 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 11:41:33 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 11:41:33 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:41:33 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 11:41:33 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:41:33 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 11:41:33 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:41:33 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 11:41:33 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:41:33 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 11:41:33 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 11:41:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 11:41:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 11:41:33 INFO - Fetch https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 11:41:35 INFO - Expected file size: 51397043 11:41:35 INFO - Obtained file size: 51397043 11:41:51 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 11:41:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 11:41:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 11:41:51 INFO - Fetch https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 11:41:53 INFO - Expected file size: 51397043 11:41:53 INFO - Obtained file size: 51397043 11:42:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 11:42:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 11:42:08 INFO - Fetch https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 11:42:08 INFO - Expected file size: 14404449 11:42:08 INFO - Obtained file size: 14404449 11:42:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:42:09 INFO - trying https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 11:42:09 INFO - Downloading https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 11:42:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/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 11:42:11 INFO - Downloaded 59649797 bytes. 11:42:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 11:42:11 INFO - mkdir: /builds/slave/test/properties 11:42:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:42:11 INFO - Writing to file /builds/slave/test/properties/build_url 11:42:11 INFO - Contents: 11:42:11 INFO - build_url:https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 11:42:12 INFO - Running post-action listener: _resource_record_post_action 11:42:12 INFO - Running post-action listener: set_extra_try_arguments 11:42:12 INFO - [mozharness: 2017-05-23 18:42:12.210431Z] Finished download-and-extract step (success) 11:42:12 INFO - [mozharness: 2017-05-23 18:42:12.210573Z] Running populate-webroot step. 11:42:12 INFO - Running pre-action listener: _resource_record_pre_action 11:42:12 INFO - Running main action method: populate_webroot 11:42:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:42:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:42:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:42:12 INFO - '--tppagecycles', 11:42:12 INFO - '7'], 11:42:12 INFO - 'tests': ['tsvgm']}}, 11:42:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:42:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:42:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:42:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:42:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:42:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:42:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:42:12 INFO - 'talos_options': ['--disable-e10s'], 11:42:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:42:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:42:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:42:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:42:12 INFO - 'talos_options': ['--disable-e10s'], 11:42:12 INFO - 'tests': ['damp', 'tps']}, 11:42:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:42:12 INFO - 'tests': ['damp', 'tps']}, 11:42:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:42:12 INFO - 'tests': ['dromaeo_dom']}, 11:42:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:42:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:42:12 INFO - 'tests': ['basic_compositor_video']}, 11:42:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:42:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:42:12 INFO - 'tests': ['a11yr', 11:42:12 INFO - 'ts_paint', 11:42:12 INFO - 'tpaint', 11:42:12 INFO - 'sessionrestore', 11:42:12 INFO - 'sessionrestore_no_auto_restore', 11:42:12 INFO - 'tabpaint']}, 11:42:12 INFO - 'other-e10s': {'tests': ['a11yr', 11:42:12 INFO - 'ts_paint', 11:42:12 INFO - 'tpaint', 11:42:12 INFO - 'sessionrestore', 11:42:12 INFO - 'sessionrestore_no_auto_restore', 11:42:12 INFO - 'tabpaint']}, 11:42:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:42:12 INFO - 'ts_paint', 11:42:12 INFO - 'tpaint', 11:42:12 INFO - 'sessionrestore', 11:42:12 INFO - 'sessionrestore_no_auto_restore', 11:42:12 INFO - 'tabpaint']}, 11:42:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:42:12 INFO - 'ts_paint', 11:42:12 INFO - 'tpaint', 11:42:12 INFO - 'sessionrestore', 11:42:12 INFO - 'sessionrestore_no_auto_restore', 11:42:12 INFO - 'tabpaint']}, 11:42:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:42:12 INFO - 'tests': ['a11yr', 11:42:12 INFO - 'ts_paint', 11:42:12 INFO - 'tpaint', 11:42:12 INFO - 'sessionrestore', 11:42:12 INFO - 'sessionrestore_no_auto_restore', 11:42:12 INFO - 'tabpaint']}, 11:42:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:42:12 INFO - 'tests': ['a11yr', 11:42:12 INFO - 'ts_paint', 11:42:12 INFO - 'tpaint', 11:42:12 INFO - 'sessionrestore', 11:42:12 INFO - 'sessionrestore_no_auto_restore', 11:42:12 INFO - 'tabpaint']}, 11:42:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:42:12 INFO - 'tests': ['tsvgx', 11:42:12 INFO - 'tsvgr_opacity', 11:42:12 INFO - 'tart', 11:42:12 INFO - 'tscrollx', 11:42:12 INFO - 'cart', 11:42:12 INFO - 'tsvg_static']}, 11:42:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:42:12 INFO - 'tsvgr_opacity', 11:42:12 INFO - 'tart', 11:42:12 INFO - 'tscrollx', 11:42:12 INFO - 'cart', 11:42:12 INFO - 'tsvg_static']}, 11:42:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:42:12 INFO - 'talos_options': ['--disable-e10s'], 11:42:12 INFO - 'tests': ['tp5o']}, 11:42:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:42:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:42:12 INFO - 'talos_options': ['--disable-e10s', 11:42:12 INFO - '--xperf_path', 11:42:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:42:12 INFO - 'tests': ['tp5n']}, 11:42:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:42:12 INFO - 'talos_options': ['--xperf_path', 11:42:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:42:12 INFO - 'tests': ['tp5n']}}, 11:42:12 INFO - 'talos.zip': {'path': '', 11:42:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:42:12 INFO - Running post-action listener: _resource_record_post_action 11:42:12 INFO - [mozharness: 2017-05-23 18:42:12.228682Z] Finished populate-webroot step (success) 11:42:12 INFO - [mozharness: 2017-05-23 18:42:12.228811Z] Running create-virtualenv step. 11:42:12 INFO - Running pre-action listener: _resource_record_pre_action 11:42:12 INFO - Running main action method: create_virtualenv 11:42:12 INFO - Creating virtualenv /builds/slave/test/build/venv 11:42:12 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:42:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:42:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:42:12 INFO - Using real prefix '/tools/python27' 11:42:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:42:13 INFO - Installing distribute.............................................................................................................................................................................................done. 11:42:15 INFO - Installing pip.................done. 11:42:15 INFO - Return code: 0 11:42:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:42:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:42:16 INFO - Reading from file tmpfile_stdout 11:42:16 INFO - Output received: 11:42:16 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 11:42:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:42:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:42:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:42:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:42:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:42:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c8b9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8bc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8668cc5620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c697d30>, '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 0x10c5bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c68cbe8>, '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.tRumbXgakB/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.3yi6qFgpnq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 11:42:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:42:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:42:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tRumbXgakB/Render', 11:42:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:42:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:42:16 INFO - 'HOME': '/Users/cltbld', 11:42:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:42:16 INFO - 'LOGNAME': 'cltbld', 11:42:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:42:16 INFO - 'MOZ_NO_REMOTE': '1', 11:42:16 INFO - 'NO_EM_RESTART': '1', 11:42:16 INFO - 'PAGER': '/bin/cat', 11:42:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:42:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:42:16 INFO - 'PWD': '/builds/slave/test', 11:42:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:42:16 INFO - 'SHELL': '/bin/bash', 11:42:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3yi6qFgpnq/Listeners', 11:42:16 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 11:42:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:42:16 INFO - 'USER': 'cltbld', 11:42:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:42:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:42:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:42:16 INFO - 'XPC_FLAGS': '0x0', 11:42:16 INFO - 'XPC_SERVICE_NAME': '0', 11:42:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:42:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:42:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 11:42:16 INFO - Cleaning up... 11:42:16 INFO - Return code: 0 11:42:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:42:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:42:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:42:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:42:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:42:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c8b9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8bc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8668cc5620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c697d30>, '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 0x10c5bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c68cbe8>, '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.tRumbXgakB/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.3yi6qFgpnq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 11:42:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:42:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:42:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tRumbXgakB/Render', 11:42:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:42:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:42:16 INFO - 'HOME': '/Users/cltbld', 11:42:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:42:16 INFO - 'LOGNAME': 'cltbld', 11:42:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:42:16 INFO - 'MOZ_NO_REMOTE': '1', 11:42:16 INFO - 'NO_EM_RESTART': '1', 11:42:16 INFO - 'PAGER': '/bin/cat', 11:42:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:42:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:42:16 INFO - 'PWD': '/builds/slave/test', 11:42:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:42:16 INFO - 'SHELL': '/bin/bash', 11:42:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3yi6qFgpnq/Listeners', 11:42:16 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 11:42:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:42:16 INFO - 'USER': 'cltbld', 11:42:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:42:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:42:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:42:16 INFO - 'XPC_FLAGS': '0x0', 11:42:16 INFO - 'XPC_SERVICE_NAME': '0', 11:42:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:42:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:42:16 INFO - Downloading/unpacking psutil>=3.1.1 11:42:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:42:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:42:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:42:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:42:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:42:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:42:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:42:18 INFO - Installing collected packages: psutil 11:42:18 INFO - Running setup.py install for psutil 11:42:18 INFO - building 'psutil._psutil_osx' extension 11:42:18 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 11:42:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 11:42:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 11:42:19 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 11:42:19 INFO - building 'psutil._psutil_posix' extension 11:42:19 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 11:42:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:42:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:42:19 INFO - ^ 11:42:19 INFO - 1 warning generated. 11:42:19 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 11:42:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:42:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:42:19 INFO - Successfully installed psutil 11:42:19 INFO - Cleaning up... 11:42:19 INFO - Return code: 0 11:42:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:42:19 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')]} 11:42:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:42:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:42:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:42:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c8b9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8bc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8668cc5620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c697d30>, '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 0x10c5bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c68cbe8>, '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.tRumbXgakB/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.3yi6qFgpnq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 11:42:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:42:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:42:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tRumbXgakB/Render', 11:42:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:42:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:42:20 INFO - 'HOME': '/Users/cltbld', 11:42:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:42:20 INFO - 'LOGNAME': 'cltbld', 11:42:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:42:20 INFO - 'MOZ_NO_REMOTE': '1', 11:42:20 INFO - 'NO_EM_RESTART': '1', 11:42:20 INFO - 'PAGER': '/bin/cat', 11:42:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:42:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:42:20 INFO - 'PWD': '/builds/slave/test', 11:42:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:42:20 INFO - 'SHELL': '/bin/bash', 11:42:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3yi6qFgpnq/Listeners', 11:42:20 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 11:42:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:42:20 INFO - 'USER': 'cltbld', 11:42:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:42:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:42:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:42:20 INFO - 'XPC_FLAGS': '0x0', 11:42:20 INFO - 'XPC_SERVICE_NAME': '0', 11:42:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:42:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:42:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:42:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:42:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:42:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:42:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:42:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:42:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:42:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:42:22 INFO - Installing collected packages: mozsystemmonitor 11:42:22 INFO - Running setup.py install for mozsystemmonitor 11:42:22 INFO - Successfully installed mozsystemmonitor 11:42:22 INFO - Cleaning up... 11:42:22 INFO - Return code: 0 11:42:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:42:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:42:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:42:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:42:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:42:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c8b9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8bc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8668cc5620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c697d30>, '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 0x10c5bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c68cbe8>, '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.tRumbXgakB/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.3yi6qFgpnq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 11:42:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:42:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:42:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tRumbXgakB/Render', 11:42:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:42:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:42:22 INFO - 'HOME': '/Users/cltbld', 11:42:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:42:22 INFO - 'LOGNAME': 'cltbld', 11:42:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:42:22 INFO - 'MOZ_NO_REMOTE': '1', 11:42:22 INFO - 'NO_EM_RESTART': '1', 11:42:22 INFO - 'PAGER': '/bin/cat', 11:42:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:42:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:42:22 INFO - 'PWD': '/builds/slave/test', 11:42:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:42:22 INFO - 'SHELL': '/bin/bash', 11:42:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3yi6qFgpnq/Listeners', 11:42:22 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 11:42:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:42:22 INFO - 'USER': 'cltbld', 11:42:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:42:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:42:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:42:22 INFO - 'XPC_FLAGS': '0x0', 11:42:22 INFO - 'XPC_SERVICE_NAME': '0', 11:42:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:42:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:42:23 INFO - Downloading/unpacking jsonschema==2.5.1 11:42:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:42:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:42:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:42:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:42:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:42:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:42:24 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 11:42:24 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 11:42:24 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 11:42:24 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 11:42:24 INFO - Downloading functools32-3.2.3-2.tar.gz 11:42:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:42:24 INFO - warning: no files found matching '*.txt' 11:42:24 INFO - no previously-included directories found matching 'build' 11:42:24 INFO - no previously-included directories found matching 'dist' 11:42:24 INFO - no previously-included directories found matching '.git*' 11:42:24 INFO - Installing collected packages: jsonschema, functools32 11:42:25 INFO - Running setup.py install for functools32 11:42:25 INFO - warning: no files found matching '*.txt' 11:42:25 INFO - no previously-included directories found matching 'build' 11:42:25 INFO - no previously-included directories found matching 'dist' 11:42:25 INFO - no previously-included directories found matching '.git*' 11:42:25 INFO - Successfully installed jsonschema functools32 11:42:25 INFO - Cleaning up... 11:42:25 INFO - Return code: 0 11:42:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:42:25 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')]} 11:42:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:42:25 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')]} 11:42:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:42:25 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 0x10c8b9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8bc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8668cc5620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c697d30>, '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 0x10c5bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c68cbe8>, '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.tRumbXgakB/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.3yi6qFgpnq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 11:42:25 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 11:42:25 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 11:42:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tRumbXgakB/Render', 11:42:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:42:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:42:25 INFO - 'HOME': '/Users/cltbld', 11:42:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:42:25 INFO - 'LOGNAME': 'cltbld', 11:42:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:42:25 INFO - 'MOZ_NO_REMOTE': '1', 11:42:25 INFO - 'NO_EM_RESTART': '1', 11:42:25 INFO - 'PAGER': '/bin/cat', 11:42:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:42:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:42:25 INFO - 'PWD': '/builds/slave/test', 11:42:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:42:25 INFO - 'SHELL': '/bin/bash', 11:42:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3yi6qFgpnq/Listeners', 11:42:25 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 11:42:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:42:25 INFO - 'USER': 'cltbld', 11:42:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:42:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:42:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:42:25 INFO - 'XPC_FLAGS': '0x0', 11:42:25 INFO - 'XPC_SERVICE_NAME': '0', 11:42:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:42:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:42:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:42:25 INFO - Cleaning up... 11:42:25 INFO - Return code: 0 11:42:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:42:25 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')]} 11:42:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:42:25 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')]} 11:42:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:42:25 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 0x10c8b9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8bc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8668cc5620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c697d30>, '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 0x10c5bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c68cbe8>, '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.tRumbXgakB/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.3yi6qFgpnq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 11:42:25 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 11:42:25 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 11:42:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tRumbXgakB/Render', 11:42:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:42:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:42:25 INFO - 'HOME': '/Users/cltbld', 11:42:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:42:25 INFO - 'LOGNAME': 'cltbld', 11:42:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:42:25 INFO - 'MOZ_NO_REMOTE': '1', 11:42:25 INFO - 'NO_EM_RESTART': '1', 11:42:25 INFO - 'PAGER': '/bin/cat', 11:42:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:42:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:42:25 INFO - 'PWD': '/builds/slave/test', 11:42:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:42:25 INFO - 'SHELL': '/bin/bash', 11:42:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3yi6qFgpnq/Listeners', 11:42:25 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 11:42:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:42:25 INFO - 'USER': 'cltbld', 11:42:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:42:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:42:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:42:25 INFO - 'XPC_FLAGS': '0x0', 11:42:25 INFO - 'XPC_SERVICE_NAME': '0', 11:42:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:42:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:42:25 INFO - Downloading/unpacking blobuploader==1.2.4 11:42:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:42:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:42:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:42:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:42:27 INFO - Downloading blobuploader-1.2.4.tar.gz 11:42:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:42:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:42:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:42:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:42:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:42:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:42:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:42:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:42:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:42:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:42:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:42:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:42:28 INFO - Downloading docopt-0.6.1.tar.gz 11:42:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:42:28 INFO - Installing collected packages: blobuploader, requests, docopt 11:42:28 INFO - Running setup.py install for blobuploader 11:42:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:42:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:42:28 INFO - Running setup.py install for requests 11:42:28 INFO - Running setup.py install for docopt 11:42:28 INFO - Successfully installed blobuploader requests docopt 11:42:28 INFO - Cleaning up... 11:42:28 INFO - Return code: 0 11:42:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:42:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:42:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:42:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:42:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:42:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c8b9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8bc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8668cc5620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c697d30>, '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 0x10c5bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c68cbe8>, '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.tRumbXgakB/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.3yi6qFgpnq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 11:42:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:42:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:42:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tRumbXgakB/Render', 11:42:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:42:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:42:28 INFO - 'HOME': '/Users/cltbld', 11:42:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:42:28 INFO - 'LOGNAME': 'cltbld', 11:42:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:42:28 INFO - 'MOZ_NO_REMOTE': '1', 11:42:28 INFO - 'NO_EM_RESTART': '1', 11:42:28 INFO - 'PAGER': '/bin/cat', 11:42:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:42:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:42:28 INFO - 'PWD': '/builds/slave/test', 11:42:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:42:28 INFO - 'SHELL': '/bin/bash', 11:42:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3yi6qFgpnq/Listeners', 11:42:28 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 11:42:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:42:28 INFO - 'USER': 'cltbld', 11:42:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:42:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:42:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:42:28 INFO - 'XPC_FLAGS': '0x0', 11:42:28 INFO - 'XPC_SERVICE_NAME': '0', 11:42:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:42:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:42:29 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-9Hul9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:42:29 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-Y5R8wp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:42:29 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-TqnEIx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:42:29 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-sBVrtM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:42:29 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-R6cWmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:42:29 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-S7XtKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:42:29 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-FnZq5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:42:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:42:29 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-MQM9k8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:42:30 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-xOVlcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:42:30 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-Ophjg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:42:30 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-HrezYH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:42:30 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-WVrpWb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:42:30 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-Dk6rOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:42:30 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-mi3T4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:42:30 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-sLG63l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:42:30 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-Fbm6_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:42:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:42:30 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-u5Xgyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:42:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:42:31 INFO - Running setup.py install for manifestparser 11:42:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:42:31 INFO - Running setup.py install for mozcrash 11:42:31 INFO - Running setup.py install for mozdebug 11:42:31 INFO - Running setup.py install for mozdevice 11:42:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:42:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:42:31 INFO - Running setup.py install for mozfile 11:42:31 INFO - Running setup.py install for mozhttpd 11:42:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:42:31 INFO - Running setup.py install for mozinfo 11:42:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:42:32 INFO - Running setup.py install for mozInstall 11:42:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:42:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:42:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:42:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:42:32 INFO - Running setup.py install for mozleak 11:42:32 INFO - Running setup.py install for mozlog 11:42:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:42:32 INFO - Running setup.py install for moznetwork 11:42:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:42:32 INFO - Running setup.py install for mozprocess 11:42:32 INFO - Running setup.py install for mozprofile 11:42:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:42:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:42:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:42:33 INFO - Running setup.py install for mozrunner 11:42:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:42:33 INFO - Running setup.py install for mozscreenshot 11:42:33 INFO - Running setup.py install for moztest 11:42:33 INFO - Running setup.py install for mozversion 11:42:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:42:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:42:33 INFO - Cleaning up... 11:42:33 INFO - Return code: 0 11:42:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:42:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:42:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:42:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:42:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:42:33 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 0x10c8b9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8bc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8668cc5620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c697d30>, '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 0x10c5bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c68cbe8>, '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.tRumbXgakB/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.3yi6qFgpnq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 11:42:33 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 11:42:33 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 11:42:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tRumbXgakB/Render', 11:42:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:42:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:42:33 INFO - 'HOME': '/Users/cltbld', 11:42:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:42:33 INFO - 'LOGNAME': 'cltbld', 11:42:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:42:33 INFO - 'MOZ_NO_REMOTE': '1', 11:42:33 INFO - 'NO_EM_RESTART': '1', 11:42:33 INFO - 'PAGER': '/bin/cat', 11:42:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:42:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:42:33 INFO - 'PWD': '/builds/slave/test', 11:42:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:42:33 INFO - 'SHELL': '/bin/bash', 11:42:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3yi6qFgpnq/Listeners', 11:42:33 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 11:42:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:42:33 INFO - 'USER': 'cltbld', 11:42:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:42:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:42:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:42:33 INFO - 'XPC_FLAGS': '0x0', 11:42:33 INFO - 'XPC_SERVICE_NAME': '0', 11:42:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:42:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:42:33 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-C3mxmQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:42:34 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)) 11:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:42:34 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-hlZPaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:42:34 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)) 11:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:42:34 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-ka4AfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:42:34 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)) 11:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:42:34 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-SfZPjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:42:34 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)) 11:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:42:34 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-JY9TjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:42:34 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)) 11:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:42:34 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-8kZrgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:42:34 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)) 11:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:42:34 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-K5YH3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:42:34 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)) 11:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:42:34 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-m23AQk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:42:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:42:34 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-1Z6ap7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:42:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:42:34 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-Anygtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:42:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:42:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:42:34 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-KxB4am-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:42:35 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)) 11:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:42:35 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-X860jA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:42:35 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)) 11:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:42:35 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-7u8l7l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:42:35 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)) 11:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:42:35 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-BTwgkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:42:35 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)) 11:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:42:35 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-I1Qjtk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:42:35 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)) 11:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:42:35 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-oVmyme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:42:35 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)) 11:42:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:42:35 INFO - Running setup.py (path:/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/pip-16P0qY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:42:35 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)) 11:42:35 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)) 11:42:35 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)) 11:42:35 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)) 11:42:35 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)) 11:42:35 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)) 11:42:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:42:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:42:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:42:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:42:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:42:37 INFO - Downloading blessings-1.6.tar.gz 11:42:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:42:37 INFO - Installing collected packages: blessings 11:42:37 INFO - Running setup.py install for blessings 11:42:37 INFO - Successfully installed blessings 11:42:37 INFO - Cleaning up... 11:42:37 INFO - Return code: 0 11:42:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:42:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:42:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:42:37 INFO - Reading from file tmpfile_stdout 11:42:37 INFO - Current package versions: 11:42:37 INFO - blessings == 1.6 11:42:37 INFO - blobuploader == 1.2.4 11:42:37 INFO - docopt == 0.6.1 11:42:37 INFO - functools32 == 3.2.3-2 11:42:37 INFO - jsonschema == 2.5.1 11:42:37 INFO - manifestparser == 1.1 11:42:37 INFO - mozInstall == 1.12 11:42:37 INFO - mozcrash == 1.0 11:42:37 INFO - mozdebug == 0.1 11:42:37 INFO - mozdevice == 0.49 11:42:37 INFO - mozfile == 1.2 11:42:37 INFO - mozhttpd == 0.7 11:42:37 INFO - mozinfo == 0.9 11:42:37 INFO - mozleak == 0.1 11:42:37 INFO - mozlog == 3.4 11:42:37 INFO - moznetwork == 0.27 11:42:37 INFO - mozprocess == 0.25 11:42:37 INFO - mozprofile == 0.28 11:42:37 INFO - mozrunner == 6.13 11:42:37 INFO - mozscreenshot == 0.1 11:42:37 INFO - mozsystemmonitor == 0.3 11:42:37 INFO - moztest == 0.8 11:42:37 INFO - mozversion == 1.4 11:42:37 INFO - psutil == 3.1.1 11:42:37 INFO - requests == 1.2.3 11:42:37 INFO - wsgiref == 0.1.2 11:42:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:42:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:42:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:42:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:42:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:42:37 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 0x10c8b9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8bc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8668cc5620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c697d30>, '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 0x10c5bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c68cbe8>, '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.tRumbXgakB/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.3yi6qFgpnq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 11:42:37 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 11:42:37 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 11:42:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tRumbXgakB/Render', 11:42:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:42:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:42:37 INFO - 'HOME': '/Users/cltbld', 11:42:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:42:37 INFO - 'LOGNAME': 'cltbld', 11:42:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:42:37 INFO - 'MOZ_NO_REMOTE': '1', 11:42:37 INFO - 'NO_EM_RESTART': '1', 11:42:37 INFO - 'PAGER': '/bin/cat', 11:42:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:42:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:42:37 INFO - 'PWD': '/builds/slave/test', 11:42:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:42:37 INFO - 'SHELL': '/bin/bash', 11:42:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3yi6qFgpnq/Listeners', 11:42:37 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 11:42:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:42:37 INFO - 'USER': 'cltbld', 11:42:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:42:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:42:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:42:37 INFO - 'XPC_FLAGS': '0x0', 11:42:37 INFO - 'XPC_SERVICE_NAME': '0', 11:42:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:42:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:42:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:42:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:42:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:42:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:42:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:42:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:42:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:42:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:42:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:42:37 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:42:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:42:37 INFO - Cleaning up... 11:42:37 INFO - Return code: 0 11:42:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:42:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:42:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:42:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:42:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:42:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c8b9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8bc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8668cc5620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c697d30>, '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 0x10c5bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c68cbe8>, '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.tRumbXgakB/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.3yi6qFgpnq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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 11:42:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:42:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:42:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tRumbXgakB/Render', 11:42:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:42:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:42:37 INFO - 'HOME': '/Users/cltbld', 11:42:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:42:37 INFO - 'LOGNAME': 'cltbld', 11:42:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:42:37 INFO - 'MOZ_NO_REMOTE': '1', 11:42:37 INFO - 'NO_EM_RESTART': '1', 11:42:37 INFO - 'PAGER': '/bin/cat', 11:42:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:42:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:42:37 INFO - 'PWD': '/builds/slave/test', 11:42:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:42:37 INFO - 'SHELL': '/bin/bash', 11:42:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3yi6qFgpnq/Listeners', 11:42:37 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 11:42:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:42:37 INFO - 'USER': 'cltbld', 11:42:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:42:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:42:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:42:37 INFO - 'XPC_FLAGS': '0x0', 11:42:37 INFO - 'XPC_SERVICE_NAME': '0', 11:42:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:42:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:42:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:42:38 INFO - Cleaning up... 11:42:38 INFO - Return code: 0 11:42:38 INFO - Running post-action listener: _resource_record_post_action 11:42:38 INFO - Running post-action listener: _start_resource_monitoring 11:42:38 INFO - Starting resource monitoring. 11:42:38 INFO - [mozharness: 2017-05-23 18:42:38.276917Z] Finished create-virtualenv step (success) 11:42:38 INFO - [mozharness: 2017-05-23 18:42:38.277919Z] Running install step. 11:42:38 INFO - Running pre-action listener: _resource_record_pre_action 11:42:38 INFO - Running main action method: install 11:42:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:42:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:42:38 INFO - Reading from file tmpfile_stdout 11:42:38 INFO - Detecting whether we're running mozinstall >=1.0... 11:42:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:42:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:42:38 INFO - Reading from file tmpfile_stdout 11:42:38 INFO - Output received: 11:42:38 INFO - Usage: mozinstall [options] installer 11:42:38 INFO - Options: 11:42:38 INFO - -h, --help show this help message and exit 11:42:38 INFO - -d DEST, --destination=DEST 11:42:38 INFO - Directory to install application into. [default: 11:42:38 INFO - "/builds/slave/test"] 11:42:38 INFO - --app=APP Application being installed. [default: firefox] 11:42:38 INFO - mkdir: /builds/slave/test/build/application 11:42:38 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'] 11:42:38 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 11:42:53 INFO - Reading from file tmpfile_stdout 11:42:53 INFO - Output received: 11:42:53 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 11:42:53 INFO - Running post-action listener: _resource_record_post_action 11:42:53 INFO - [mozharness: 2017-05-23 18:42:53.447221Z] Finished install step (success) 11:42:53 INFO - [mozharness: 2017-05-23 18:42:53.447344Z] Running run-tests step. 11:42:53 INFO - Running pre-action listener: _resource_record_pre_action 11:42:53 INFO - Running main action method: run_tests 11:42:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:42:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:42:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:42:53 INFO - 2017-05-23 11:42:53.461 screenresolution[1623:7477] starting screenresolution argv=screenresolution get 11:42:53 INFO - 2017-05-23 11:42:53.464 screenresolution[1623:7477] Display 0: 1600x1200x32@60 11:42:53 INFO - 2017-05-23 11:42:53.469 screenresolution[1624:7479] starting screenresolution argv=screenresolution list 11:42:53 INFO - Available Modes on Display 0 11:42:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:42:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:42:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:42:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:42:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:42:53 INFO - Intel Iris: 11:42:53 INFO - Chipset Model: Intel Iris 11:42:53 INFO - Type: GPU 11:42:53 INFO - Bus: Built-In 11:42:53 INFO - VRAM (Dynamic, Max): 1536 MB 11:42:53 INFO - Vendor: Intel (0x8086) 11:42:53 INFO - Device ID: 0x0a2e 11:42:53 INFO - Revision ID: 0x0009 11:42:53 INFO - Displays: 11:42:53 INFO - Display: 11:42:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:42:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:42:53 INFO - Display Serial Number: 1600x1200 60 11:42:53 INFO - Main Display: Yes 11:42:53 INFO - Mirror: Off 11:42:53 INFO - Online: Yes 11:42:53 INFO - Rotation: Supported 11:42:53 INFO - Return code: 0 11:42:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:42:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:42:53 INFO - Python 2.7.3 11:42:53 INFO - Return code: 0 11:42:53 INFO - grabbing minidump binary from tooltool 11:42:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:42:53 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 0x10c697d30>, '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 0x10c5bedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c68cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:42:53 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 11:42:53 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 11:42:53 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 11:42:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:42:53 INFO - Return code: 0 11:42:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:42:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:42:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:42:53 INFO - ENV: RUST_BACKTRACE is now 1 11:42:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:42:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:42:53 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/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0022', '--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 11:42:53 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/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0022 --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 11:42:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tRumbXgakB/Render', 11:42:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:42:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:42:53 INFO - 'HOME': '/Users/cltbld', 11:42:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:42:53 INFO - 'LOGNAME': 'cltbld', 11:42:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:42:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:42:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:42:53 INFO - 'MOZ_NO_REMOTE': '1', 11:42:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:42:53 INFO - 'NO_EM_RESTART': '1', 11:42:53 INFO - 'PAGER': '/bin/cat', 11:42:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:42:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:42:53 INFO - 'PWD': '/builds/slave/test', 11:42:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:42:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:42:53 INFO - 'RUST_BACKTRACE': '1', 11:42:53 INFO - 'SHELL': '/bin/bash', 11:42:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3yi6qFgpnq/Listeners', 11:42:53 INFO - 'TMPDIR': '/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/', 11:42:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:42:53 INFO - 'USER': 'cltbld', 11:42:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:42:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:42:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:42:53 INFO - 'XPC_FLAGS': '0x0', 11:42:53 INFO - 'XPC_SERVICE_NAME': '0', 11:42:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:42:53 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/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0022', '--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 11:42:54 INFO - mozversion application_buildid: 20170523100428 11:42:54 INFO - mozversion application_changeset: a2d407b9da248932f50c95533d13cd8043ca2e21 11:42:54 INFO - mozversion application_display_name: Firefox 11:42:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:42:54 INFO - mozversion application_name: Firefox 11:42:54 INFO - mozversion application_remotingname: firefox 11:42:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 11:42:54 INFO - mozversion application_vendor: Mozilla 11:42:54 INFO - mozversion application_version: 54.0 11:42:54 INFO - mozversion platform_buildid: 20170523100428 11:42:54 INFO - mozversion platform_changeset: a2d407b9da248932f50c95533d13cd8043ca2e21 11:42:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 11:42:54 INFO - mozversion platform_version: 54.0 11:42:54 INFO - using testdate: 1495564974 11:42:54 INFO - actual date: 1495564974 11:42:54 INFO - starting webserver on 'localhost:49227' 11:42:54 INFO - SUITE-START | Running 2 tests 11:42:54 INFO - TEST-START | dromaeo_css 11:42:54 INFO - operating with platform_type : mac_ 11:42:54 INFO - Initialising browser for dromaeo_css test... 11:42:54 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/tmpDzjVll/profile http://localhost:49227/getInfo.html) 11:42:56 INFO - PROCESS | 1634 | 2017-05-23 11:42:56.819 plugin-container[1637:7646] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e4b, name = 'com.apple.tsm.portname' 11:42:56 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:42:56 INFO - PROCESS | 1634 | 2017-05-23 11:42:56.819 plugin-container[1637:7646] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930f, name = 'com.apple.CFPasteboardClient' 11:42:56 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:42:56 INFO - PROCESS | 1634 | 2017-05-23 11:42:56.819 plugin-container[1637:7646] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:42:56 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 11:42:56 INFO - PROCESS | 1634 | colorDepth:24 11:42:56 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 11:42:56 INFO - PROCESS | 1634 | __metrics 11:42:56 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 11:42:57 INFO - TEST-INFO | 1634: exit 0 11:42:57 INFO - Browser initialized. 11:42:57 INFO - Running cycle 1/1 for dromaeo_css test... 11:42:57 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/tmpDzjVll/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:42:59 INFO - PROCESS | 1638 | 2017-05-23 11:42:59.778 plugin-container[1639:7780] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833f, name = 'com.apple.tsm.portname' 11:42:59 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:42:59 INFO - PROCESS | 1638 | 2017-05-23 11:42:59.779 plugin-container[1639:7780] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient' 11:42:59 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:42:59 INFO - PROCESS | 1638 | 2017-05-23 11:42:59.779 plugin-container[1639:7780] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:43:09 INFO - PROCESS | 1638 | RSS: Main: 174215168 11:43:09 INFO - PROCESS | 1638 | 11:46:12 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49227/tests/dromaeo/cssquery-ext.html) 11:46:14 INFO - PROCESS | 1638 | RSS: Main: 192430080 11:46:14 INFO - PROCESS | 1638 | 11:49:16 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-ext.html (next: http://localhost:49227/tests/dromaeo/cssquery-jquery.html) 11:49:17 INFO - PROCESS | 1638 | RSS: Main: 203702272 11:49:17 INFO - PROCESS | 1638 | 11:52:19 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49227/tests/dromaeo/cssquery-mootools.html) 11:52:19 INFO - PROCESS | 1638 | RSS: Main: 205217792 11:52:19 INFO - PROCESS | 1638 | 11:55:21 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49227/tests/dromaeo/cssquery-prototype.html) 11:55:22 INFO - PROCESS | 1638 | RSS: Main: 208277504 11:55:22 INFO - PROCESS | 1638 | 11:58:25 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49227/tests/dromaeo/cssquery-yui.html) 11:58:26 INFO - PROCESS | 1638 | RSS: Main: 210755584 11:58:26 INFO - PROCESS | 1638 | 12:01:28 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-yui.html (next: http://localhost:49227/tests/dromaeo/cssquery-dojo.html) 12:01:29 INFO - PROCESS | 1638 | RSS: Main: 208949248 12:01:29 INFO - PROCESS | 1638 | 12:01:29 INFO - PROCESS | 1638 | __start_tp_report 12:01:29 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 12:01:29 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 12:01:29 INFO - PROCESS | 1638 | |i|pagename|runs| 12:01:29 INFO - PROCESS | 1638 | |0;dojo.html;3477.356689012531;3645.2345007548397;3652.0687224757685;3668.1746607013415;3677.797721125337;6201.984355084599;6210.316865144836;6228.065790131483;6232.813015609528;6266.248050233967;6236.8184682874025;6237.438630523252;6239.844003899905;6253.781117660883;6278.215223097113;5696.8860622787515;5730.656160630376;5742.425757424266;5778.219940308047;5793.942060579404;4766.380370551823;4842.539958703917;5737.053386191274;5802.970985145068;5984.581079324449;5524.1989911463015;5568.470995255465;5680.772769089232;5732.111522713986;5788.855278618026;5730.341010783772;5777.075667893117;5784.392638772932;5810.389909059551;5886.264216972879;5598.10430331145;5704.600677952545;5716.628419152742;5723.284589426322;5776.768929242868;4962.454130045692;6089.908651370189;6093.664848433335;6197.16338294329;6254.499640028832;6448.097266382711;6461.063145843873;6505.772297969548;6528.902066469007;6576.605403675747;6769.390754832039;6771.221309549415;6776.1191045164305;6786.01602767596;6791.354821292019;6268.310485846553;6310.021946598331;6336.334684858116;6343.714532846033;6357.4914006879335;6591.110200123018;6648.53460257787;6795.52431329812;6819.931800682029;6932.965335173292;6553.475721942234;6575.967120164464;6578.177727784027;6584.7366105356205;6588.374104460068;6602.7028783704845;6709.396154346132;6747.257801641816;6767.796966091025;6773.491988100912;6330.651814150174;6367.872642547216;6455;6663.233728121328;6664.633445160466;6331.5251356147555;6375.904361434582;6597.109390232353;6641.767538136142;6645.966770166118;5343.225232341289;5347.652402593819;5364.249005139283;5389.83830485086;5394.32570928634;4611.16998940194;4735.266033764772;4760.833370832073;4787.234042553245;4792.257200133985;6164.3835616438;6200.410960958701;6217.533684973645;6253.312135665074;6331.746730130743;6714.227863795726;6767.458603311723;6791.762288319885;6792.864142717217;6798.456123510107;6322.114903913363;6334.144890439732;6342;6343.460805831463;6371.171747672865;5773.624714393551;5819.010768169422;5822.970885145547;5946.435088666656;5957.523398128139;6848.897266541006;6852.725890964306;6871.381575658214;6878.828029299308;6888.276730943179;6663.067170596218;6761.154855643044;6761.357671021246;6771.729130834711;6787.762428314985;6700.731970721116;6977.162740471176;7005.509614326948;7025;7038.507304488637;5755.223044888887;6465.806025819233;6549.279579246374;6573.211214654081;6575.506836987341;6761.391474767294;6768.661566921536;6768.898466523105;6769.4922880785125;6808.93191068083;6453.257875344396;6546.901796472961;6560.081588577507;6591.538592298685;6604.240512341143;6274.6548939808745;6314.684265786784;6379.840503987438;6383.776567820104;6397.264314603694;5642.971785141048;5788.044972579645;5921.14143449206;6354.618722876642;6418.518611104092;6272.74909003653;6293.842653933688;6370.840728981812;6503.349665033648;6546.116274303098;5867.73843623623;5983.252093488314;6340.49276057905;6387.776427825004;6440.645764482999 12:01:29 INFO - PROCESS | 1638 | |1;ext.html;763.9579823109726;792.1840504280591;798.2217338095361;848.262012049517;978.4080631218118;39247.82256532305;41662.791686041564;41713.58286417135;41718.374224386585;41751.582484175226;40313.59686403135;40580.79709601448;40585.391219131736;40611.39082913751;40684;8352.331813454939;11444.427778611036;11673.357965311905;11859.347735874524;11861.169718119734;11555.884441155607;11649.650510484698;11650.941745291262;11676.824847627293;11683.474243659013;11639.592614258503;11640.883591164065;11644.883551164507;11662.300261984308;11687.181897267195;11540.961313481785;11579.247348922378;11601;11608.24546404481;11614.767704645945;11348.546058157664;11409;11412.20114591979;11423.485943132579;11433.942830285878;35876;36169.73405930806;36174.27651446983;36266.274674506625;36269.45595816065;3959.960400395988;3988.501437320335;4020.356742921148;4029.7783621900785;4038.757674539537;26148.477030459286;26324.341891452867;26467.338316542242;27233.72766272332;27769.583456248172;3194.4250034993693;3300.7029367357054;3309.933801323984;3431.039308993486;3435.553378060861;334191;341524;352307;357528;430637;35320.646793531734;44003;44310;44496.3325550117;44634.33048504275;46093.07813843704;46209.76895115503;46296.7685161572;46321;46452;14372.281385930663;14485.20331381763;14485.637859053608;14498.420063197354;14522.782158267635;12515.56195533152;12708.237505749685;12718.427670754838;12736.299503527394;12749.49002039908;11988.520459181535;12079.637610871689;12090.576829810914;12165.635030949086;12176.269423834598;5747.712614369265;5774.480296773227;5781.624194427349;5810.88378232433;5811.360750317462;1135.0579019413867;1165.2076587920126;1170.9355985420884;1196.6051203102954;1219.3110892345974;24500.877495612407;25768.613470797955;26216.73783262143;26994.865025674746;27376.58935115975;1106;1108.1991763384501;1250.493550112206;1274.5029438518984;1317.3347459533065;12123.211661317946;14075.648108797157;14150.787738183935;14409.13545187292;14451.92774036123;401666.9916650398;402900;403261;403754;403954;56049;56297.43702563004;56302.43697563054;56370;56426.589335266945;1430.3348942741468;1456.4465503108963;1486.8922652623864;1493.2608358862433;1512.2589930933764;23460.709660968812;26526.7347326532;27209;28452.004179853604;28599.14202573926;38141;38207.808960955015;38414.615853841104;38428;38443.654472093345;437775;439910;440278;440372;441251;25053.74946250514;25594.61608075843;29292.853535732185;30157.246068848453;30295.545566816076;37914.810425948795;38170.809145954096;38383.42424863573;38590.80704596571;38906.80546597249;27774.722252777214;27855.303617409725;28337.574936376383;28586;29193;28205.15384538467;28215.43569128647;28372.57441138426;28917.42165156727;28975.985840495483;31588.36823263476;32106.83946580252;32589.18527036748;32782;33009;28254.85872570624;28521.857390712914;28644;28776;28889.42221155523;22029.559408812056;22943.426414339774;23063.750136898692;23217;23275.88362058179 12:01:29 INFO - PROCESS | 1638 | |2;jquery.html;422.50355831897514;431.91373695156665;437.21738088821013;444.1295061679107;447.0500187102407;21119.366419007416;21927.0132844022;22078.558428831435;22247.998840052194;22626.88686556567;22032;22813.771862281374;22873.97067131979;23126.537469250605;23154.88422557889;7057;7337.2662733726465;7337.559746415232;7796.6491507882;8291.543965081919;7710.653020614086;8062.19378062192;8139.267465928066;8144.714934977277;8182.918170818275;8108.918910810905;8145.714899978502;8174.918250817475;8194.647883099282;8201.835963280731;7810.49231799934;8002.599870006506;8013.11855695873;8023.919760802376;8054.7180848670605;5840.4807456953195;7676.923230767677;7830.6084695764985;7931.762047138595;7981.880271795928;26774.5983810243;27140.72859271402;27534.586981195298;27714;31093.22266943322;2927.34134819666;3067.1871953932227;3100.906972790797;3244.1889527618096;3275.7215636670912;17625.38311159116;18244.179011944496;18480.722789158175;18757;18956.052197390076;2257.81937445004;2282.8972696228543;2300.8389412741117;2312.491251924574;2354.3878591566317;152500;165735;166426;167784.16107919504;169282.1535892325;26192;26206.606900896502;26948;27037;27424.862875685492;42867.785661071495;43308.91727706833;43910.56089439129;44658.10683786306;44688.77655611701;10979.396133212591;11167.162462815322;11855.229410088317;12058.517659293706;12123.030157587373;9825.361351512129;9836.606535738634;9839.458829764431;10029.949850250849;10071.949640251752;11588.36264005471;11932.343721095423;11945.581904633296;12006.259092431548;12047.518099276105;4891.388576427947;5033.874153146127;5044.470330615306;5059.342285502861;5064.18972964324;981.8610411922136;1030.2685093583639;1063.872335319751;1068.2415485005577;1076.3380520979697;21949;22173.002214900047;22180.556388872134;22201;22285;925.4077390470104;1087.7498175419698;1101.1686176936362;1116.2744216259496;1134.757440602539;10942.069924056545;11707.531698731955;11713.941430292964;11724.941375293069;11747.588834390755;743948;766242;767968;783233;793858;143038.5696143046;162784;163769.3623063778;169262;169789;1306.6929271621161;1323.67569945364;1379.4689044717654;1386.4869998100567;1389.9861001389856;20185.59628807445;20253;20875;20876.164953401694;20879.164833406492;57380;61636.69181654063;64198;66597.33402665911;67069;60562.09156862563;78936;87707.56146219229;88762;90377;11587.942060289983;11707.88292117068;11745;11863.525458981889;12086;13223.471061157446;13245.470181192644;13463.528776492776;13595.388207530486;13612.979026572848;11479.655610331369;11482.885171148182;11577.6526704199;11755.000824929977;11768.235064720766;9353.345265831327;10667.839982400425;10675.412852292888;10925.962033606795;10937.343759374464;9812.803743925224;11549.249298795552;11636.88363116358;11767.411629418666;11808.291502509877;8774.649014039367;11205.43972801373;11330.546778128783;11610.477528511406;11892.46483908209;9824.607015719292;10458.372497650165;10748.355098694103;10889.727756805827;11261.66215013551 12:01:29 INFO - PROCESS | 1638 | |3;mootools.html;1432.8638779315963;1602.6474175681346;1642.7289497232957;1647.5551601067712;1648.4807285704994;9860.408375497474;10166.64416745414;11200.159988000902;11877.881221187774;11925.045996320285;9714.805703885919;9787.265955053364;11065.612703555376;11249.943750281238;11424.543018279279;2435.744246854081;2461.4092617771785;2478.8512689238614;2479.7396273391305;2480.590702534089;2410.770976757205;2451.7180524239675;2452.2152911068465;2457.8648174350405;2461.2985299189736;2427.5994460914017;2430.9392265193333;2432.3311089450367;2439.1584903208363;2441.548313561988;2265.127925748582;2361.9409514762096;2376.4534157143867;2389.498205376873;2391.497785465054;2354.6350315701093;2378.096323397115;2384.7257565380028;2388.581998150334;2395.3173345596597;11456.911593398698;11665.59170429031;11683.123765717604;11703.18077734559;11707.590234341842;5801.564882633819;5888.5877988540815;5927.585069045169;5997.070454079625;6003.369646187131;3549.2723991581665;3608.4948107265004;3651.6713495785507;3656.8171591420323;3713.795741234231;1025.3693978203426;1257.415301884628;1289.8452185737679;1312.0422091872986;1312.61277923012;15797.21013949298;15814;15832.20838958059;15847.286872090783;15848.683026339524;4454.3986561813745;4819.542143496362;4830.516948305141;4962.15643340633;4996.375453068366;13436.261005644588;13785.655358616115;13852.722945541032;13856;13856.653583660489;5993.550483713739;6024.548158888101;6060.2424696912885;6065.939340606626;6079.939200607937;4635.397398338262;5344.225087362311;5376.489233522809;5446.91829622556;5447.700376479332;4097.078157414618;4140.254754144283;4219.472565929259;4236.322188449833;4243.787810609457;1105.3644154611131;1120.8318752187074;1129.8305254211934;1134.4101067444883;1138.8348689439986;964.7250533597891;980.2794945714894;1000.1148983149819;1009.8131845608586;1022.5500779656927;6653.401193892573;6665.300143484961;6692.966535167293;6881.13985751781;6915.239323674392;874.9737507874773;1001.9048190421898;1008.863803386548;1008.9192864570816;1036.5801850250662;3536.8231588420476;3819.809009549567;4151.937720934188;4213.620774130343;4321.9135617287475;10649.84025239622;13589.320533973261;14290.57128286153;14306.785398219035;14331.211783352015;13032.80450793239;14046.087004344685;14068.929655351658;14248.786268205986;14408.495702650356;9867.506624668738;10118.42832323601;10158.034986676254;10190.1338386238;10200.948995254976;6102.298235702951;6241.282252541016;6481.962885938416;6619.966900165661;6656.068150459037;10264.435456049989;11816.46825892854;12224.144309898222;12226.32755198437;12493.500259989498;12024.879751202376;12217.144799863925;12324.753504930031;12464.376781161087;12495.062870284582;8388.454750441202;8636.481811091355;9083.954580227057;9264.305177111879;10555.736106597396;10867.619633312795;10885.836712449478;11026.173037022416;11125.276857004268;11142.72143196394;9106.772330691785;9122;9142.040085791163;9197.988221295785;9293.070692930653;7357.411407087528;8840.867386989072;8891.4665120093;9081.364304498622;9116.08839116083;6869;7552.509086909334;7704.6918123274445;7775.98912141418;8238.382121340803;5985.491233245223;6078.908816367846;6094.329623741296;6162.691865406801;6180.721867515883;5714.828555143353;5742.1673857289625;5786.884262314646;5885.852853678693;5886.764529418775 12:01:29 INFO - PROCESS | 1638 | |4;prototype.html;379.3929712460064;398.4600865826803;398.6571548468319;401.19560281635324;406.43505527317024;4566.908661826761;4802.351682522867;4816.386510518972;5478.698671573062;5625.746841392137;4808.302796094564;4946.3817022872145;5389.299391079164;5448.7548060337285;5463.726813659311;4035.9910022494378;4674.55591718787;4730.8067054738085;4733.337332773414;4757.476677565465;4431.601155895969;4537.364768932335;4638.675292729494;4781.521847815208;4784.3780308559835;4403.229434848889;4651.976040724766;4682.484926658065;4722.173619616553;4725.322510508771;4211.515675697304;4229.649493390863;4236.326650972879;4340.027081449201;4515.819367225307;4022.0950286185666;4233.386159006957;4337.154254920292;4370.606645401929;4580.381648477446;4769.165396055711;4935.679180853233;5230.843074707765;5250.789968401259;5372.570194384479;4826.975865120687;4875.5860800367855;5471.890562188773;5479.3424789025175;5510.476504732044;5124.103281925648;5189.636725429222;5310.70791106489;5778.88442231153;5874;3938.330483817758;4241.787910604488;4312.935305970413;4612.838550650711;4637.837675681369;5454.854480559087;5811.564132690023;5880;5934.792282270143;6002.579819412643;3667.8166091695307;4200.592801411562;4630.236011058138;4678.111158879802;4702.224133018082;4763.666543341933;4764.38063051808;4765.8093676252565;4831.782569784368;4901.215805471179;4899.828506002273;5119.769610367543;5127.846164615067;5130.846074617767;5233.895322093613;5761.625494342854;5796.62321949072;5854.955423179923;5855.502282305966;5877.824435112994;1440.6686462113773;1632.1349684667146;1641.3106495272013;1652.3803573659877;1654.5119189839027;1061.3950048472298;1136.9431528423545;1215.4773447417695;1220.413462498748;1249.6063739921894;4940.752962351868;5106.617003724736;5299.788008479694;5374.247605335257;5458.672479651154;5275.048232154128;5430.728463576806;5456.699881506555;5686.886262274731;6043.456088952015;4864.516322521594;4977.730678676914;5130.640855140179;5407.351117865881;5485.61600687956;2913.3299341151655;2992.371601963612;2999.2351950252546;3004.459197344499;3107.456195165837;5309.7079660618265;5652.7738890443925;5671.602987790898;5938.6436813791315;6277.081157261895;5312.9090600237605;5978;6149.815505534841;6163.3528479509905;6186.876262474815;5652.434756524397;5967.91048134287;6248.812535623939;6272.247330320391;6276.027215781562;5333.839984800462;5651.598070819803;5950;5974.355643057818;6030.487408570145;5322.308099946982;5646.435356464321;5924.126798502809;5932.79235226765;5948.315943666534;5613.775448981995;5619.8595035124445;6187.721552530057;6195.628262304276;6249.25008998923;4037.6971727119994;4514.7291162530355;4698.271767875985;4732.225415469199;4753.405824271966;5675.2758675835;5970.850728731816;6017.488513476404;6033.547483938634;6065.939340606537;5149.6910185389;5172.120739474373;5733.881893030819;5818.5453636590855;5869.708664093892;5224.738763061908;5396.946030539644;5411.485908838654;5813.970930145491;5848.859472402841;5067.721275329893;5190.584753219658;5545.890821835568;5644.85887852807;5720.570957178145;4879.95120048795;5272.340957380327;5373.704446255493;5836.503897168618;5917.970410147922;5074.367432397838;5416.079266524779;5703.315602127771;5721.828345149652;5740.483356497935 12:01:29 INFO - PROCESS | 1638 | |5;yui.html;761.2463660975633;762.5539059650105;799.7081065411126;801.2868546993174;926.0924294191694;16327.83672163278;18091.457256282316;18867.433976980712;18986.240550378003;19051.618967620638;17962.461126166203;18834.71747923782;18982.240710371603;19034.33379831706;19205.711914321295;705.4673721340381;946.5740416812428;999.4059475137144;1023.4268809466686;1035.896410358962;1003.673806013045;1017.4099022566893;1018.1803648063307;1022.6471867205802;1024.0425202435708;1055.2982266792574;1073.404260635347;1075.5482697267128;1075.7794652096302;1078.7896360209763;1057.9365238085738;1066.2163309967168;1069.7753471770936;1070.1064611049783;1073.6081330314457;780.4029917113413;787.5038725602891;792.3067316098413;794.1026639896937;796.2992566541465;17519.824801751947;17552.210150543255;17693.557661058447;18247.996360200184;18479;14721;14940.775888361683;14980.47568335114;15020.474283400137;15138.167400792952;12020.39898005105;12211.26732396059;12213.633590992196;12216.694582635506;12409;3359.81521016344;3824.177801772604;3899.025243689078;4066.9593304066875;4075;114079;115562.42218788936;119222;120623;121083;11454.921608215658;11455.678207475232;11506.712332191595;11654.475548600334;11999.580014699444;23595;24069.037238510613;24257.272281831574;24290.635640465407;24356.87821560881;8191.0580283267;8257.628406721711;8306.169383061646;8320.916790832136;8333.125021872616;4583.931241031387;4585.197590421662;4651.976740116278;4686.554777296152;4811.49479304675;3640.9453858192146;3702.1670124221832;3719.590845007047;3741.6819570336825;3752.81235938202;645.8611398549334;684.6234570985938;715.7280233511336;719.9784006479814;723.8552289542113;676.8612434450926;725.4776560876161;733.5232099135604;765.4106338119617;765.464175077448;10079.496025198858;10715.43691390657;10770.838437423445;10884.455777211107;10934.549718760616;707.6567864585672;718.396546900606;724.5833645653664;728.5356433568566;744.7281742164071;4580.931286030712;4732.100900828831;4926.655134140576;4934.950650493449;5177.197534382177;104445;106478;107445.46277268564;108076.45961770297;109280;94980.52509737546;95066.04933950571;95158.52420737852;97217;97443;82004;82780.58609707034;83026;83822.58088709517;84738;10048.949755251177;10110.747231319276;10114.696559103239;10145.797084058426;10299.485025748832;40545.797271014635;46147.538524615666;52458.73770631122;54080.64798380072;54658.726706366215;65687;67948;67948.64102718017;68579;70347.18611255492;13151.60545183646;13443.663908402368;13503.257320847448;13739.725205496034;13971.952103592068;13789.793153102912;14267.357968891632;14831.258437078319;15304.770538017941;15691.843081569496;13601.251931143486;13938.721225575635;14365.7845132321;14503.85496145025;14863.628409289855;13907.513237036655;13982.300884955508;14057.578272651837;14277.357518911882;14361.9281903594;13908.095973761672;14246.28768561547;14435.781780525967;14508.202048887413;14762.335694893542;10654.57381704723;10770.569177233136;11008.944955275492;11043.171762117712;11292.378919159524;11638.6508404748;11683.824742629038;11795.705107372385;11944.880551194377;11952.402379881145 12:01:29 INFO - PROCESS | 1638 | __end_tp_report 12:01:29 INFO - PROCESS | 1638 | __start_cc_report 12:01:29 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,393 12:01:29 INFO - PROCESS | 1638 | __end_cc_report 12:01:29 INFO - PROCESS | 1638 | __startTimestamp1495566089117__endTimestamp 12:01:29 INFO - PROCESS | 1638 | 12:01:29 INFO - PROCESS | 1638 | ------- Summary: start ------- 12:01:29 INFO - PROCESS | 1638 | Number of tests: 6 12:01:29 INFO - PROCESS | 1638 | 12:01:29 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:6169.77 Median:6340.49 stddev:689.90 (10.9%) stddev-sans-first:658.89 12:01:29 INFO - PROCESS | 1638 | Values: 3477.4 3645.2 3652.1 3668.2 3677.8 6202.0 6210.3 6228.1 6232.8 6266.2 6236.8 6237.4 6239.8 6253.8 6278.2 5696.9 5730.7 5742.4 5778.2 5793.9 4766.4 4842.5 5737.1 5803.0 5984.6 5524.2 5568.5 5680.8 5732.1 5788.9 5730.3 5777.1 5784.4 5810.4 5886.3 5598.1 5704.6 5716.6 5723.3 5776.8 4962.5 6089.9 6093.7 6197.2 6254.5 6448.1 6461.1 6505.8 6528.9 6576.6 6769.4 6771.2 6776.1 6786.0 6791.4 6268.3 6310.0 6336.3 6343.7 6357.5 6591.1 6648.5 6795.5 6819.9 6933.0 6553.5 6576.0 6578.2 6584.7 6588.4 6602.7 6709.4 6747.3 6767.8 6773.5 6330.7 6367.9 6455.0 6663.2 6664.6 6331.5 6375.9 6597.1 6641.8 6646.0 5343.2 5347.7 5364.2 5389.8 5394.3 4611.2 4735.3 4760.8 4787.2 4792.3 6164.4 6200.4 6217.5 6253.3 6331.7 6714.2 6767.5 6791.8 6792.9 6798.5 6322.1 6334.1 6342.0 6343.5 6371.2 5773.6 5819.0 5823.0 5946.4 5957.5 6848.9 6852.7 6871.4 6878.8 6888.3 6663.1 6761.2 6761.4 6771.7 6787.8 6700.7 6977.2 7005.5 7025.0 7038.5 5755.2 6465.8 6549.3 6573.2 6575.5 6761.4 6768.7 6768.9 6769.5 6808.9 6453.3 6546.9 6560.1 6591.5 6604.2 6274.7 6314.7 6379.8 6383.8 6397.3 5643.0 5788.0 5921.1 6354.6 6418.5 6272.7 6293.8 6370.8 6503.3 6546.1 5867.7 5983.3 6340.5 6387.8 6440.6 12:01:29 INFO - PROCESS | 1638 | 12:01:29 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:53416.56 Median:28710.00 stddev:106957.27 (372.5%) stddev-sans-first:107184.25 12:01:29 INFO - PROCESS | 1638 | Values: 764.0 792.2 798.2 848.3 978.4 39247.8 41662.8 41713.6 41718.4 41751.6 40313.6 40580.8 40585.4 40611.4 40684.0 8352.3 11444.4 11673.4 11859.3 11861.2 11555.9 11649.7 11650.9 11676.8 11683.5 11639.6 11640.9 11644.9 11662.3 11687.2 11541.0 11579.2 11601.0 11608.2 11614.8 11348.5 11409.0 11412.2 11423.5 11433.9 35876.0 36169.7 36174.3 36266.3 36269.5 3960.0 3988.5 4020.4 4029.8 4038.8 26148.5 26324.3 26467.3 27233.7 27769.6 3194.4 3300.7 3309.9 3431.0 3435.6 334191.0 341524.0 352307.0 357528.0 430637.0 35320.6 44003.0 44310.0 44496.3 44634.3 46093.1 46209.8 46296.8 46321.0 46452.0 14372.3 14485.2 14485.6 14498.4 14522.8 12515.6 12708.2 12718.4 12736.3 12749.5 11988.5 12079.6 12090.6 12165.6 12176.3 5747.7 5774.5 5781.6 5810.9 5811.4 1135.1 1165.2 1170.9 1196.6 1219.3 24500.9 25768.6 26216.7 26994.9 27376.6 1106.0 1108.2 1250.5 1274.5 1317.3 12123.2 14075.6 14150.8 14409.1 14451.9 401667.0 402900.0 403261.0 403754.0 403954.0 56049.0 56297.4 56302.4 56370.0 56426.6 1430.3 1456.4 1486.9 1493.3 1512.3 23460.7 26526.7 27209.0 28452.0 28599.1 38141.0 38207.8 38414.6 38428.0 38443.7 437775.0 439910.0 440278.0 440372.0 441251.0 25053.7 25594.6 29292.9 30157.2 30295.5 37914.8 38170.8 38383.4 38590.8 38906.8 27774.7 27855.3 28337.6 28586.0 29193.0 28205.2 28215.4 28372.6 28917.4 28976.0 31588.4 32106.8 32589.2 32782.0 33009.0 28254.9 28521.9 28644.0 28776.0 28889.4 22029.6 22943.4 23063.8 23217.0 23275.9 12:01:29 INFO - PROCESS | 1638 | 12:01:29 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:45147.55 Median:12271.41 stddev:128698.24 (1048.8%) stddev-sans-first:129015.46 12:01:29 INFO - PROCESS | 1638 | Values: 422.5 431.9 437.2 444.1 447.1 21119.4 21927.0 22078.6 22248.0 22626.9 22032.0 22813.8 22874.0 23126.5 23154.9 7057.0 7337.3 7337.6 7796.6 8291.5 7710.7 8062.2 8139.3 8144.7 8182.9 8108.9 8145.7 8174.9 8194.6 8201.8 7810.5 8002.6 8013.1 8023.9 8054.7 5840.5 7676.9 7830.6 7931.8 7981.9 26774.6 27140.7 27534.6 27714.0 31093.2 2927.3 3067.2 3100.9 3244.2 3275.7 17625.4 18244.2 18480.7 18757.0 18956.1 2257.8 2282.9 2300.8 2312.5 2354.4 152500.0 165735.0 166426.0 167784.2 169282.2 26192.0 26206.6 26948.0 27037.0 27424.9 42867.8 43308.9 43910.6 44658.1 44688.8 10979.4 11167.2 11855.2 12058.5 12123.0 9825.4 9836.6 9839.5 10029.9 10071.9 11588.4 11932.3 11945.6 12006.3 12047.5 4891.4 5033.9 5044.5 5059.3 5064.2 981.9 1030.3 1063.9 1068.2 1076.3 21949.0 22173.0 22180.6 22201.0 22285.0 925.4 1087.7 1101.2 1116.3 1134.8 10942.1 11707.5 11713.9 11724.9 11747.6 743948.0 766242.0 767968.0 783233.0 793858.0 143038.6 162784.0 163769.4 169262.0 169789.0 1306.7 1323.7 1379.5 1386.5 1390.0 20185.6 20253.0 20875.0 20876.2 20879.2 57380.0 61636.7 64198.0 66597.3 67069.0 60562.1 78936.0 87707.6 88762.0 90377.0 11587.9 11707.9 11745.0 11863.5 12086.0 13223.5 13245.5 13463.5 13595.4 13613.0 11479.7 11482.9 11577.7 11755.0 11768.2 9353.3 10667.8 10675.4 10926.0 10937.3 9812.8 11549.2 11636.9 11767.4 11808.3 8774.6 11205.4 11330.5 11610.5 11892.5 9824.6 10458.4 10748.4 10889.7 11261.7 12:01:29 INFO - PROCESS | 1638 | 12:01:29 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:6864.57 Median:2446.63 stddev:4338.88 (177.3%) stddev-sans-first:4331.85 12:01:29 INFO - PROCESS | 1638 | Values: 1432.9 1602.6 1642.7 1647.6 1648.5 9860.4 10166.6 11200.2 11877.9 11925.0 9714.8 9787.3 11065.6 11249.9 11424.5 2435.7 2461.4 2478.9 2479.7 2480.6 2410.8 2451.7 2452.2 2457.9 2461.3 2427.6 2430.9 2432.3 2439.2 2441.5 2265.1 2361.9 2376.5 2389.5 2391.5 2354.6 2378.1 2384.7 2388.6 2395.3 11456.9 11665.6 11683.1 11703.2 11707.6 5801.6 5888.6 5927.6 5997.1 6003.4 3549.3 3608.5 3651.7 3656.8 3713.8 1025.4 1257.4 1289.8 1312.0 1312.6 15797.2 15814.0 15832.2 15847.3 15848.7 4454.4 4819.5 4830.5 4962.2 4996.4 13436.3 13785.7 13852.7 13856.0 13856.7 5993.6 6024.5 6060.2 6065.9 6079.9 4635.4 5344.2 5376.5 5446.9 5447.7 4097.1 4140.3 4219.5 4236.3 4243.8 1105.4 1120.8 1129.8 1134.4 1138.8 964.7 980.3 1000.1 1009.8 1022.6 6653.4 6665.3 6693.0 6881.1 6915.2 875.0 1001.9 1008.9 1008.9 1036.6 3536.8 3819.8 4151.9 4213.6 4321.9 10649.8 13589.3 14290.6 14306.8 14331.2 13032.8 14046.1 14068.9 14248.8 14408.5 9867.5 10118.4 10158.0 10190.1 10200.9 6102.3 6241.3 6482.0 6620.0 6656.1 10264.4 11816.5 12224.1 12226.3 12493.5 12024.9 12217.1 12324.8 12464.4 12495.1 8388.5 8636.5 9084.0 9264.3 10555.7 10867.6 10885.8 11026.2 11125.3 11142.7 9106.8 9122.0 9142.0 9198.0 9293.1 7357.4 8840.9 8891.5 9081.4 9116.1 6869.0 7552.5 7704.7 7776.0 8238.4 5985.5 6078.9 6094.3 6162.7 6180.7 5714.8 5742.2 5786.9 5885.9 5886.8 12:01:29 INFO - PROCESS | 1638 | 12:01:29 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4842.42 Median:5207.66 stddev:1338.31 (25.7%) stddev-sans-first:1299.47 12:01:29 INFO - PROCESS | 1638 | Values: 379.4 398.5 398.7 401.2 406.4 4566.9 4802.4 4816.4 5478.7 5625.7 4808.3 4946.4 5389.3 5448.8 5463.7 4036.0 4674.6 4730.8 4733.3 4757.5 4431.6 4537.4 4638.7 4781.5 4784.4 4403.2 4652.0 4682.5 4722.2 4725.3 4211.5 4229.6 4236.3 4340.0 4515.8 4022.1 4233.4 4337.2 4370.6 4580.4 4769.2 4935.7 5230.8 5250.8 5372.6 4827.0 4875.6 5471.9 5479.3 5510.5 5124.1 5189.6 5310.7 5778.9 5874.0 3938.3 4241.8 4312.9 4612.8 4637.8 5454.9 5811.6 5880.0 5934.8 6002.6 3667.8 4200.6 4630.2 4678.1 4702.2 4763.7 4764.4 4765.8 4831.8 4901.2 4899.8 5119.8 5127.8 5130.8 5233.9 5761.6 5796.6 5855.0 5855.5 5877.8 1440.7 1632.1 1641.3 1652.4 1654.5 1061.4 1136.9 1215.5 1220.4 1249.6 4940.8 5106.6 5299.8 5374.2 5458.7 5275.0 5430.7 5456.7 5686.9 6043.5 4864.5 4977.7 5130.6 5407.4 5485.6 2913.3 2992.4 2999.2 3004.5 3107.5 5309.7 5652.8 5671.6 5938.6 6277.1 5312.9 5978.0 6149.8 6163.4 6186.9 5652.4 5967.9 6248.8 6272.2 6276.0 5333.8 5651.6 5950.0 5974.4 6030.5 5322.3 5646.4 5924.1 5932.8 5948.3 5613.8 5619.9 6187.7 6195.6 6249.3 4037.7 4514.7 4698.3 4732.2 4753.4 5675.3 5970.9 6017.5 6033.5 6065.9 5149.7 5172.1 5733.9 5818.5 5869.7 5224.7 5396.9 5411.5 5814.0 5848.9 5067.7 5190.6 5545.9 5644.9 5720.6 4880.0 5272.3 5373.7 5836.5 5918.0 5074.4 5416.1 5703.3 5721.8 5740.5 12:01:29 INFO - PROCESS | 1638 | 12:01:29 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:21887.35 Median:17828.01 stddev:31359.96 (175.9%) stddev-sans-first:31407.81 12:01:29 INFO - PROCESS | 1638 | Values: 761.2 762.6 799.7 801.3 926.1 16327.8 18091.5 18867.4 18986.2 19051.6 17962.5 18834.7 18982.2 19034.3 19205.7 705.5 946.6 999.4 1023.4 1035.9 1003.7 1017.4 1018.2 1022.6 1024.0 1055.3 1073.4 1075.5 1075.8 1078.8 1057.9 1066.2 1069.8 1070.1 1073.6 780.4 787.5 792.3 794.1 796.3 17519.8 17552.2 17693.6 18248.0 18479.0 14721.0 14940.8 14980.5 15020.5 15138.2 12020.4 12211.3 12213.6 12216.7 12409.0 3359.8 3824.2 3899.0 4067.0 4075.0 114079.0 115562.4 119222.0 120623.0 121083.0 11454.9 11455.7 11506.7 11654.5 11999.6 23595.0 24069.0 24257.3 24290.6 24356.9 8191.1 8257.6 8306.2 8320.9 8333.1 4583.9 4585.2 4652.0 4686.6 4811.5 3640.9 3702.2 3719.6 3741.7 3752.8 645.9 684.6 715.7 720.0 723.9 676.9 725.5 733.5 765.4 765.5 10079.5 10715.4 10770.8 10884.5 10934.5 707.7 718.4 724.6 728.5 744.7 4580.9 4732.1 4926.7 4935.0 5177.2 104445.0 106478.0 107445.5 108076.5 109280.0 94980.5 95066.0 95158.5 97217.0 97443.0 82004.0 82780.6 83026.0 83822.6 84738.0 10048.9 10110.7 10114.7 10145.8 10299.5 40545.8 46147.5 52458.7 54080.6 54658.7 65687.0 67948.0 67948.6 68579.0 70347.2 13151.6 13443.7 13503.3 13739.7 13972.0 13789.8 14267.4 14831.3 15304.8 15691.8 13601.3 13938.7 14365.8 14503.9 14863.6 13907.5 13982.3 14057.6 14277.4 14361.9 13908.1 14246.3 14435.8 14508.2 14762.3 10654.6 10770.6 11008.9 11043.2 11292.4 11638.7 11683.8 11795.7 11944.9 11952.4 12:01:29 INFO - PROCESS | 1638 | -------- Summary: end -------- 12:01:29 INFO - PROCESS | 1638 | 12:01:29 INFO - TEST-INFO | 1638: exit 0 12:01:29 INFO - TEST-OK | dromaeo_css | took 1115608ms 12:01:29 INFO - TEST-START | kraken 12:01:29 INFO - operating with platform_type : mac_ 12:01:29 INFO - Initialising browser for kraken test... 12:01:29 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/tmpc2mqJG/profile http://localhost:49227/getInfo.html) 12:01:32 INFO - PROCESS | 1650 | 2017-05-23 12:01:32.210 plugin-container[1652:10118] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8437, name = 'com.apple.tsm.portname' 12:01:32 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:01:32 INFO - PROCESS | 1650 | 2017-05-23 12:01:32.211 plugin-container[1652:10118] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843b, name = 'com.apple.CFPasteboardClient' 12:01:32 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:01:32 INFO - PROCESS | 1650 | 2017-05-23 12:01:32.211 plugin-container[1652:10118] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:01:32 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 12:01:32 INFO - PROCESS | 1650 | colorDepth:24 12:01:32 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 12:01:32 INFO - PROCESS | 1650 | __metrics 12:01:32 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 12:01:33 INFO - TEST-INFO | 1650: exit 0 12:01:33 INFO - Browser initialized. 12:01:33 INFO - Running cycle 1/1 for kraken test... 12:01:33 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/T/tmpc2mqJG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:01:35 INFO - PROCESS | 1653 | 2017-05-23 12:01:35.384 plugin-container[1654:10240] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 12:01:35 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:01:35 INFO - PROCESS | 1653 | 2017-05-23 12:01:35.384 plugin-container[1654:10240] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 12:01:35 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:01:35 INFO - PROCESS | 1653 | 2017-05-23 12:01:35.384 plugin-container[1654:10240] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:01:45 INFO - PROCESS | 1653 | RSS: Main: 174256128 12:01:45 INFO - PROCESS | 1653 | 12:01:48 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection) 12:01:49 INFO - PROCESS | 1653 | RSS: Main: 187977728 12:01:49 INFO - PROCESS | 1653 | 12:01:51 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-dft) 12:01:52 INFO - PROCESS | 1653 | RSS: Main: 195895296 12:01:52 INFO - PROCESS | 1653 | 12:01:55 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-fft) 12:01:56 INFO - PROCESS | 1653 | RSS: Main: 199647232 12:01:56 INFO - PROCESS | 1653 | 12:01:58 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator) 12:01:58 INFO - PROCESS | 1653 | RSS: Main: 201043968 12:01:58 INFO - PROCESS | 1653 | 12:02:00 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:02:01 INFO - PROCESS | 1653 | RSS: Main: 201367552 12:02:01 INFO - PROCESS | 1653 | 12:02:04 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom) 12:02:05 INFO - PROCESS | 1653 | RSS: Main: 205099008 12:02:05 INFO - PROCESS | 1653 | 12:02:08 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate) 12:02:08 INFO - PROCESS | 1653 | RSS: Main: 204959744 12:02:08 INFO - PROCESS | 1653 | 12:02:12 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial) 12:02:12 INFO - PROCESS | 1653 | RSS: Main: 204931072 12:02:12 INFO - PROCESS | 1653 | 12:02:14 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:02:15 INFO - PROCESS | 1653 | RSS: Main: 204599296 12:02:15 INFO - PROCESS | 1653 | 12:02:16 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:02:17 INFO - PROCESS | 1653 | RSS: Main: 204591104 12:02:17 INFO - PROCESS | 1653 | 12:02:19 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:02:20 INFO - PROCESS | 1653 | RSS: Main: 204648448 12:02:20 INFO - PROCESS | 1653 | 12:02:23 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:02:24 INFO - PROCESS | 1653 | RSS: Main: 205033472 12:02:24 INFO - PROCESS | 1653 | 12:02:26 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:02:27 INFO - PROCESS | 1653 | RSS: Main: 204730368 12:02:27 INFO - PROCESS | 1653 | 12:02:29 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49227/tests/kraken/driver.html?testName=ai-astar) 12:02:29 INFO - PROCESS | 1653 | RSS: Main: 206839808 12:02:29 INFO - PROCESS | 1653 | 12:02:29 INFO - PROCESS | 1653 | __start_tp_report 12:02:29 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 12:02:29 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 12:02:29 INFO - PROCESS | 1653 | |i|pagename|runs| 12:02:29 INFO - PROCESS | 1653 | |0;ai-astar;106;118;120;120;135;177;159;120;122;118 12:02:29 INFO - PROCESS | 1653 | |1;audio-beat-detection;153;141;130;147;111;112;116;113;131;107 12:02:29 INFO - PROCESS | 1653 | |2;audio-dft;198;195;195;194;194;197;196;195;199;192 12:02:29 INFO - PROCESS | 1653 | |3;audio-fft;84;59;62;92;77;85;82;59;72;60 12:02:29 INFO - PROCESS | 1653 | |4;audio-oscillator;83;86;117;102;104;79;81;72;71;82 12:02:29 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;87;90;88;87;89;108;87;91;83;137 12:02:29 INFO - PROCESS | 1653 | |6;imaging-darkroom;106;117;95;95;101;154;153;95;95;109 12:02:29 INFO - PROCESS | 1653 | |7;imaging-desaturate;92;94;92;80;142;144;136;76;144;146 12:02:29 INFO - PROCESS | 1653 | |8;json-parse-financial;65;65;67;67;69;69;68;69;75;96 12:02:29 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;60;64;61;62;63;53;75;76;87;86 12:02:29 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;91;110;101;85;86;85;82;83;82;89 12:02:29 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;142;154;141;143;163;155;158;184;184;155 12:02:29 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;162;164;163;160;165;164;161;163;161;165 12:02:29 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;66;66;64;64;69;76;83;86;85;86 12:02:29 INFO - PROCESS | 1653 | __end_tp_report 12:02:29 INFO - PROCESS | 1653 | __start_cc_report 12:02:29 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,986 12:02:29 INFO - PROCESS | 1653 | __end_cc_report 12:02:29 INFO - PROCESS | 1653 | __startTimestamp1495566149645__endTimestamp 12:02:29 INFO - PROCESS | 1653 | 12:02:29 INFO - PROCESS | 1653 | ------- Summary: start ------- 12:02:29 INFO - PROCESS | 1653 | Number of tests: 14 12:02:29 INFO - PROCESS | 1653 | 12:02:29 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:129.50 Median:121.00 stddev:21.86 (18.1%) stddev-sans-first:21.47 12:02:29 INFO - PROCESS | 1653 | Values: 106.0 118.0 120.0 120.0 135.0 177.0 159.0 120.0 122.0 118.0 12:02:29 INFO - PROCESS | 1653 | 12:02:29 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:126.10 Median:130.50 stddev:16.62 (12.7%) stddev-sans-first:14.50 12:02:29 INFO - PROCESS | 1653 | Values: 153.0 141.0 130.0 147.0 111.0 112.0 116.0 113.0 131.0 107.0 12:02:29 INFO - PROCESS | 1653 | 12:02:29 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:195.50 Median:195.50 stddev:2.07 (1.1%) stddev-sans-first:1.99 12:02:29 INFO - PROCESS | 1653 | Values: 198.0 195.0 195.0 194.0 194.0 197.0 196.0 195.0 199.0 192.0 12:02:29 INFO - PROCESS | 1653 | 12:02:29 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:73.20 Median:79.50 stddev:12.50 (15.7%) stddev-sans-first:12.63 12:02:29 INFO - PROCESS | 1653 | Values: 84.0 59.0 62.0 92.0 77.0 85.0 82.0 59.0 72.0 60.0 12:02:29 INFO - PROCESS | 1653 | 12:02:29 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:87.70 Median:80.00 stddev:15.03 (18.8%) stddev-sans-first:15.84 12:02:29 INFO - PROCESS | 1653 | Values: 83.0 86.0 117.0 102.0 104.0 79.0 81.0 72.0 71.0 82.0 12:02:29 INFO - PROCESS | 1653 | 12:02:29 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:94.70 Median:87.50 stddev:16.31 (18.6%) stddev-sans-first:17.06 12:02:29 INFO - PROCESS | 1653 | Values: 87.0 90.0 88.0 87.0 89.0 108.0 87.0 91.0 83.0 137.0 12:02:29 INFO - PROCESS | 1653 | 12:02:29 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:112.00 Median:124.50 stddev:23.07 (18.5%) stddev-sans-first:24.37 12:02:29 INFO - PROCESS | 1653 | Values: 106.0 117.0 95.0 95.0 101.0 154.0 153.0 95.0 95.0 109.0 12:02:29 INFO - PROCESS | 1653 | 12:02:29 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:114.60 Median:78.00 stddev:29.92 (38.4%) stddev-sans-first:30.60 12:02:29 INFO - PROCESS | 1653 | Values: 92.0 94.0 92.0 80.0 142.0 144.0 136.0 76.0 144.0 146.0 12:02:29 INFO - PROCESS | 1653 | 12:02:29 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:71.00 Median:69.00 stddev:9.23 (13.4%) stddev-sans-first:9.53 12:02:29 INFO - PROCESS | 1653 | Values: 65.0 65.0 67.0 67.0 69.0 69.0 68.0 69.0 75.0 96.0 12:02:29 INFO - PROCESS | 1653 | 12:02:29 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.50 stddev:11.59 (16.7%) stddev-sans-first:11.85 12:02:29 INFO - PROCESS | 1653 | Values: 60.0 64.0 61.0 62.0 63.0 53.0 75.0 76.0 87.0 86.0 12:02:29 INFO - PROCESS | 1653 | 12:02:29 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:89.40 Median:85.00 stddev:9.20 (10.8%) stddev-sans-first:9.74 12:02:29 INFO - PROCESS | 1653 | Values: 91.0 110.0 101.0 85.0 86.0 85.0 82.0 83.0 82.0 89.0 12:02:29 INFO - PROCESS | 1653 | 12:02:29 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:157.90 Median:156.50 stddev:15.57 (9.9%) stddev-sans-first:15.41 12:02:29 INFO - PROCESS | 1653 | Values: 142.0 154.0 141.0 143.0 163.0 155.0 158.0 184.0 184.0 155.0 12:02:29 INFO - PROCESS | 1653 | 12:02:29 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.80 Median:163.50 stddev:1.75 (1.1%) stddev-sans-first:1.83 12:02:29 INFO - PROCESS | 1653 | Values: 162.0 164.0 163.0 160.0 165.0 164.0 161.0 163.0 161.0 165.0 12:02:29 INFO - PROCESS | 1653 | 12:02:29 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.50 Median:79.50 stddev:9.69 (12.2%) stddev-sans-first:9.77 12:02:29 INFO - PROCESS | 1653 | Values: 66.0 66.0 64.0 64.0 69.0 76.0 83.0 86.0 85.0 86.0 12:02:29 INFO - PROCESS | 1653 | -------- Summary: end -------- 12:02:29 INFO - PROCESS | 1653 | 12:02:30 INFO - TEST-INFO | 1653: exit 0 12:02:30 INFO - TEST-OK | kraken | took 60787ms 12:02:30 INFO - SUITE-END | took 1176s 12:02:30 INFO - Completed test suite (00:19:36) 12:02:30 INFO - javascript benchmark 12:02:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8050.388213107351, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3477.356689012531, 3645.2345007548397, 3652.0687224757685, 3668.1746607013415, 3677.797721125337, 6201.984355084599, 6210.316865144836, 6228.065790131483, 6232.813015609528, 6266.248050233967, 6236.8184682874025, 6237.438630523252, 6239.844003899905, 6253.781117660883, 6278.215223097113, 5696.8860622787515, 5730.656160630376, 5742.425757424266, 5778.219940308047, 5793.942060579404, 4766.380370551823, 4842.539958703917, 5737.053386191274, 5802.970985145068, 5984.581079324449, 5524.1989911463015, 5568.470995255465, 5680.772769089232, 5732.111522713986, 5788.855278618026, 5730.341010783772, 5777.075667893117, 5784.392638772932, 5810.389909059551, 5886.264216972879, 5598.10430331145, 5704.600677952545, 5716.628419152742, 5723.284589426322, 5776.768929242868, 4962.454130045692, 6089.908651370189, 6093.664848433335, 6197.16338294329, 6254.499640028832, 6448.097266382711, 6461.063145843873, 6505.772297969548, 6528.902066469007, 6576.605403675747, 6769.390754832039, 6771.221309549415, 6776.1191045164305, 6786.01602767596, 6791.354821292019, 6268.310485846553, 6310.021946598331, 6336.334684858116, 6343.714532846033, 6357.4914006879335, 6591.110200123018, 6648.53460257787, 6795.52431329812, 6819.931800682029, 6932.965335173292, 6553.475721942234, 6575.967120164464, 6578.177727784027, 6584.7366105356205, 6588.374104460068, 6602.7028783704845, 6709.396154346132, 6747.257801641816, 6767.796966091025, 6773.491988100912, 6330.651814150174, 6367.872642547216, 6455.0, 6663.233728121328, 6664.633445160466, 6331.5251356147555, 6375.904361434582, 6597.109390232353, 6641.767538136142, 6645.966770166118, 5343.225232341289, 5347.652402593819, 5364.249005139283, 5389.83830485086, 5394.32570928634, 4611.16998940194, 4735.266033764772, 4760.833370832073, 4787.234042553245, 4792.257200133985, 6164.3835616438, 6200.410960958701, 6217.533684973645, 6253.312135665074, 6331.746730130743, 6714.227863795726, 6767.458603311723, 6791.762288319885, 6792.864142717217, 6798.456123510107, 6322.114903913363, 6334.144890439732, 6342.0, 6343.460805831463, 6371.171747672865, 5773.624714393551, 5819.010768169422, 5822.970885145547, 5946.435088666656, 5957.523398128139, 6848.897266541006, 6852.725890964306, 6871.381575658214, 6878.828029299308, 6888.276730943179, 6663.067170596218, 6761.154855643044, 6761.357671021246, 6771.729130834711, 6787.762428314985, 6700.731970721116, 6977.162740471176, 7005.509614326948, 7025.0, 7038.507304488637, 5755.223044888887, 6465.806025819233, 6549.279579246374, 6573.211214654081, 6575.506836987341, 6761.391474767294, 6768.661566921536, 6768.898466523105, 6769.4922880785125, 6808.93191068083, 6453.257875344396, 6546.901796472961, 6560.081588577507, 6591.538592298685, 6604.240512341143, 6274.6548939808745, 6314.684265786784, 6379.840503987438, 6383.776567820104, 6397.264314603694, 5642.971785141048, 5788.044972579645, 5921.14143449206, 6354.618722876642, 6418.518611104092, 6272.74909003653, 6293.842653933688, 6370.840728981812, 6503.349665033648, 6546.116274303098, 5867.73843623623, 5983.252093488314, 6340.49276057905, 6387.776427825004, 6440.645764482999], "value": 6126.587157614461, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [763.9579823109726, 792.1840504280591, 798.2217338095361, 848.262012049517, 978.4080631218118, 39247.82256532305, 41662.791686041564, 41713.58286417135, 41718.374224386585, 41751.582484175226, 40313.59686403135, 40580.79709601448, 40585.391219131736, 40611.39082913751, 40684.0, 8352.331813454939, 11444.427778611036, 11673.357965311905, 11859.347735874524, 11861.169718119734, 11555.884441155607, 11649.650510484698, 11650.941745291262, 11676.824847627293, 11683.474243659013, 11639.592614258503, 11640.883591164065, 11644.883551164507, 11662.300261984308, 11687.181897267195, 11540.961313481785, 11579.247348922378, 11601.0, 11608.24546404481, 11614.767704645945, 11348.546058157664, 11409.0, 11412.20114591979, 11423.485943132579, 11433.942830285878, 35876.0, 36169.73405930806, 36174.27651446983, 36266.274674506625, 36269.45595816065, 3959.960400395988, 3988.501437320335, 4020.356742921148, 4029.7783621900785, 4038.757674539537, 26148.477030459286, 26324.341891452867, 26467.338316542242, 27233.72766272332, 27769.583456248172, 3194.4250034993693, 3300.7029367357054, 3309.933801323984, 3431.039308993486, 3435.553378060861, 334191.0, 341524.0, 352307.0, 357528.0, 430637.0, 35320.646793531734, 44003.0, 44310.0, 44496.3325550117, 44634.33048504275, 46093.07813843704, 46209.76895115503, 46296.7685161572, 46321.0, 46452.0, 14372.281385930663, 14485.20331381763, 14485.637859053608, 14498.420063197354, 14522.782158267635, 12515.56195533152, 12708.237505749685, 12718.427670754838, 12736.299503527394, 12749.49002039908, 11988.520459181535, 12079.637610871689, 12090.576829810914, 12165.635030949086, 12176.269423834598, 5747.712614369265, 5774.480296773227, 5781.624194427349, 5810.88378232433, 5811.360750317462, 1135.0579019413867, 1165.2076587920126, 1170.9355985420884, 1196.6051203102954, 1219.3110892345974, 24500.877495612407, 25768.613470797955, 26216.73783262143, 26994.865025674746, 27376.58935115975, 1106.0, 1108.1991763384501, 1250.493550112206, 1274.5029438518984, 1317.3347459533065, 12123.211661317946, 14075.648108797157, 14150.787738183935, 14409.13545187292, 14451.92774036123, 401666.9916650398, 402900.0, 403261.0, 403754.0, 403954.0, 56049.0, 56297.43702563004, 56302.43697563054, 56370.0, 56426.589335266945, 1430.3348942741468, 1456.4465503108963, 1486.8922652623864, 1493.2608358862433, 1512.2589930933764, 23460.709660968812, 26526.7347326532, 27209.0, 28452.004179853604, 28599.14202573926, 38141.0, 38207.808960955015, 38414.615853841104, 38428.0, 38443.654472093345, 437775.0, 439910.0, 440278.0, 440372.0, 441251.0, 25053.74946250514, 25594.61608075843, 29292.853535732185, 30157.246068848453, 30295.545566816076, 37914.810425948795, 38170.809145954096, 38383.42424863573, 38590.80704596571, 38906.80546597249, 27774.722252777214, 27855.303617409725, 28337.574936376383, 28586.0, 29193.0, 28205.15384538467, 28215.43569128647, 28372.57441138426, 28917.42165156727, 28975.985840495483, 31588.36823263476, 32106.83946580252, 32589.18527036748, 32782.0, 33009.0, 28254.85872570624, 28521.857390712914, 28644.0, 28776.0, 28889.42221155523, 22029.559408812056, 22943.426414339774, 23063.750136898692, 23217.0, 23275.88362058179], "value": 18574.32525005652, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.50355831897514, 431.91373695156665, 437.21738088821013, 444.1295061679107, 447.0500187102407, 21119.366419007416, 21927.0132844022, 22078.558428831435, 22247.998840052194, 22626.88686556567, 22032.0, 22813.771862281374, 22873.97067131979, 23126.537469250605, 23154.88422557889, 7057.0, 7337.2662733726465, 7337.559746415232, 7796.6491507882, 8291.543965081919, 7710.653020614086, 8062.19378062192, 8139.267465928066, 8144.714934977277, 8182.918170818275, 8108.918910810905, 8145.714899978502, 8174.918250817475, 8194.647883099282, 8201.835963280731, 7810.49231799934, 8002.599870006506, 8013.11855695873, 8023.919760802376, 8054.7180848670605, 5840.4807456953195, 7676.923230767677, 7830.6084695764985, 7931.762047138595, 7981.880271795928, 26774.5983810243, 27140.72859271402, 27534.586981195298, 27714.0, 31093.22266943322, 2927.34134819666, 3067.1871953932227, 3100.906972790797, 3244.1889527618096, 3275.7215636670912, 17625.38311159116, 18244.179011944496, 18480.722789158175, 18757.0, 18956.052197390076, 2257.81937445004, 2282.8972696228543, 2300.8389412741117, 2312.491251924574, 2354.3878591566317, 152500.0, 165735.0, 166426.0, 167784.16107919504, 169282.1535892325, 26192.0, 26206.606900896502, 26948.0, 27037.0, 27424.862875685492, 42867.785661071495, 43308.91727706833, 43910.56089439129, 44658.10683786306, 44688.77655611701, 10979.396133212591, 11167.162462815322, 11855.229410088317, 12058.517659293706, 12123.030157587373, 9825.361351512129, 9836.606535738634, 9839.458829764431, 10029.949850250849, 10071.949640251752, 11588.36264005471, 11932.343721095423, 11945.581904633296, 12006.259092431548, 12047.518099276105, 4891.388576427947, 5033.874153146127, 5044.470330615306, 5059.342285502861, 5064.18972964324, 981.8610411922136, 1030.2685093583639, 1063.872335319751, 1068.2415485005577, 1076.3380520979697, 21949.0, 22173.002214900047, 22180.556388872134, 22201.0, 22285.0, 925.4077390470104, 1087.7498175419698, 1101.1686176936362, 1116.2744216259496, 1134.757440602539, 10942.069924056545, 11707.531698731955, 11713.941430292964, 11724.941375293069, 11747.588834390755, 743948.0, 766242.0, 767968.0, 783233.0, 793858.0, 143038.5696143046, 162784.0, 163769.3623063778, 169262.0, 169789.0, 1306.6929271621161, 1323.67569945364, 1379.4689044717654, 1386.4869998100567, 1389.9861001389856, 20185.59628807445, 20253.0, 20875.0, 20876.164953401694, 20879.164833406492, 57380.0, 61636.69181654063, 64198.0, 66597.33402665911, 67069.0, 60562.09156862563, 78936.0, 87707.56146219229, 88762.0, 90377.0, 11587.942060289983, 11707.88292117068, 11745.0, 11863.525458981889, 12086.0, 13223.471061157446, 13245.470181192644, 13463.528776492776, 13595.388207530486, 13612.979026572848, 11479.655610331369, 11482.885171148182, 11577.6526704199, 11755.000824929977, 11768.235064720766, 9353.345265831327, 10667.839982400425, 10675.412852292888, 10925.962033606795, 10937.343759374464, 9812.803743925224, 11549.249298795552, 11636.88363116358, 11767.411629418666, 11808.291502509877, 8774.649014039367, 11205.43972801373, 11330.546778128783, 11610.477528511406, 11892.46483908209, 9824.607015719292, 10458.372497650165, 10748.355098694103, 10889.727756805827, 11261.66215013551], "value": 12610.830954612125, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1432.8638779315963, 1602.6474175681346, 1642.7289497232957, 1647.5551601067712, 1648.4807285704994, 9860.408375497474, 10166.64416745414, 11200.159988000902, 11877.881221187774, 11925.045996320285, 9714.805703885919, 9787.265955053364, 11065.612703555376, 11249.943750281238, 11424.543018279279, 2435.744246854081, 2461.4092617771785, 2478.8512689238614, 2479.7396273391305, 2480.590702534089, 2410.770976757205, 2451.7180524239675, 2452.2152911068465, 2457.8648174350405, 2461.2985299189736, 2427.5994460914017, 2430.9392265193333, 2432.3311089450367, 2439.1584903208363, 2441.548313561988, 2265.127925748582, 2361.9409514762096, 2376.4534157143867, 2389.498205376873, 2391.497785465054, 2354.6350315701093, 2378.096323397115, 2384.7257565380028, 2388.581998150334, 2395.3173345596597, 11456.911593398698, 11665.59170429031, 11683.123765717604, 11703.18077734559, 11707.590234341842, 5801.564882633819, 5888.5877988540815, 5927.585069045169, 5997.070454079625, 6003.369646187131, 3549.2723991581665, 3608.4948107265004, 3651.6713495785507, 3656.8171591420323, 3713.795741234231, 1025.3693978203426, 1257.415301884628, 1289.8452185737679, 1312.0422091872986, 1312.61277923012, 15797.21013949298, 15814.0, 15832.20838958059, 15847.286872090783, 15848.683026339524, 4454.3986561813745, 4819.542143496362, 4830.516948305141, 4962.15643340633, 4996.375453068366, 13436.261005644588, 13785.655358616115, 13852.722945541032, 13856.0, 13856.653583660489, 5993.550483713739, 6024.548158888101, 6060.2424696912885, 6065.939340606626, 6079.939200607937, 4635.397398338262, 5344.225087362311, 5376.489233522809, 5446.91829622556, 5447.700376479332, 4097.078157414618, 4140.254754144283, 4219.472565929259, 4236.322188449833, 4243.787810609457, 1105.3644154611131, 1120.8318752187074, 1129.8305254211934, 1134.4101067444883, 1138.8348689439986, 964.7250533597891, 980.2794945714894, 1000.1148983149819, 1009.8131845608586, 1022.5500779656927, 6653.401193892573, 6665.300143484961, 6692.966535167293, 6881.13985751781, 6915.239323674392, 874.9737507874773, 1001.9048190421898, 1008.863803386548, 1008.9192864570816, 1036.5801850250662, 3536.8231588420476, 3819.809009549567, 4151.937720934188, 4213.620774130343, 4321.9135617287475, 10649.84025239622, 13589.320533973261, 14290.57128286153, 14306.785398219035, 14331.211783352015, 13032.80450793239, 14046.087004344685, 14068.929655351658, 14248.786268205986, 14408.495702650356, 9867.506624668738, 10118.42832323601, 10158.034986676254, 10190.1338386238, 10200.948995254976, 6102.298235702951, 6241.282252541016, 6481.962885938416, 6619.966900165661, 6656.068150459037, 10264.435456049989, 11816.46825892854, 12224.144309898222, 12226.32755198437, 12493.500259989498, 12024.879751202376, 12217.144799863925, 12324.753504930031, 12464.376781161087, 12495.062870284582, 8388.454750441202, 8636.481811091355, 9083.954580227057, 9264.305177111879, 10555.736106597396, 10867.619633312795, 10885.836712449478, 11026.173037022416, 11125.276857004268, 11142.72143196394, 9106.772330691785, 9122.0, 9142.040085791163, 9197.988221295785, 9293.070692930653, 7357.411407087528, 8840.867386989072, 8891.4665120093, 9081.364304498622, 9116.08839116083, 6869.0, 7552.509086909334, 7704.6918123274445, 7775.98912141418, 8238.382121340803, 5985.491233245223, 6078.908816367846, 6094.329623741296, 6162.691865406801, 6180.721867515883, 5714.828555143353, 5742.1673857289625, 5786.884262314646, 5885.852853678693, 5886.764529418775], "value": 5236.573526291022, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.3929712460064, 398.4600865826803, 398.6571548468319, 401.19560281635324, 406.43505527317024, 4566.908661826761, 4802.351682522867, 4816.386510518972, 5478.698671573062, 5625.746841392137, 4808.302796094564, 4946.3817022872145, 5389.299391079164, 5448.7548060337285, 5463.726813659311, 4035.9910022494378, 4674.55591718787, 4730.8067054738085, 4733.337332773414, 4757.476677565465, 4431.601155895969, 4537.364768932335, 4638.675292729494, 4781.521847815208, 4784.3780308559835, 4403.229434848889, 4651.976040724766, 4682.484926658065, 4722.173619616553, 4725.322510508771, 4211.515675697304, 4229.649493390863, 4236.326650972879, 4340.027081449201, 4515.819367225307, 4022.0950286185666, 4233.386159006957, 4337.154254920292, 4370.606645401929, 4580.381648477446, 4769.165396055711, 4935.679180853233, 5230.843074707765, 5250.789968401259, 5372.570194384479, 4826.975865120687, 4875.5860800367855, 5471.890562188773, 5479.3424789025175, 5510.476504732044, 5124.103281925648, 5189.636725429222, 5310.70791106489, 5778.88442231153, 5874.0, 3938.330483817758, 4241.787910604488, 4312.935305970413, 4612.838550650711, 4637.837675681369, 5454.854480559087, 5811.564132690023, 5880.0, 5934.792282270143, 6002.579819412643, 3667.8166091695307, 4200.592801411562, 4630.236011058138, 4678.111158879802, 4702.224133018082, 4763.666543341933, 4764.38063051808, 4765.8093676252565, 4831.782569784368, 4901.215805471179, 4899.828506002273, 5119.769610367543, 5127.846164615067, 5130.846074617767, 5233.895322093613, 5761.625494342854, 5796.62321949072, 5854.955423179923, 5855.502282305966, 5877.824435112994, 1440.6686462113773, 1632.1349684667146, 1641.3106495272013, 1652.3803573659877, 1654.5119189839027, 1061.3950048472298, 1136.9431528423545, 1215.4773447417695, 1220.413462498748, 1249.6063739921894, 4940.752962351868, 5106.617003724736, 5299.788008479694, 5374.247605335257, 5458.672479651154, 5275.048232154128, 5430.728463576806, 5456.699881506555, 5686.886262274731, 6043.456088952015, 4864.516322521594, 4977.730678676914, 5130.640855140179, 5407.351117865881, 5485.61600687956, 2913.3299341151655, 2992.371601963612, 2999.2351950252546, 3004.459197344499, 3107.456195165837, 5309.7079660618265, 5652.7738890443925, 5671.602987790898, 5938.6436813791315, 6277.081157261895, 5312.9090600237605, 5978.0, 6149.815505534841, 6163.3528479509905, 6186.876262474815, 5652.434756524397, 5967.91048134287, 6248.812535623939, 6272.247330320391, 6276.027215781562, 5333.839984800462, 5651.598070819803, 5950.0, 5974.355643057818, 6030.487408570145, 5322.308099946982, 5646.435356464321, 5924.126798502809, 5932.79235226765, 5948.315943666534, 5613.775448981995, 5619.8595035124445, 6187.721552530057, 6195.628262304276, 6249.25008998923, 4037.6971727119994, 4514.7291162530355, 4698.271767875985, 4732.225415469199, 4753.405824271966, 5675.2758675835, 5970.850728731816, 6017.488513476404, 6033.547483938634, 6065.939340606537, 5149.6910185389, 5172.120739474373, 5733.881893030819, 5818.5453636590855, 5869.708664093892, 5224.738763061908, 5396.946030539644, 5411.485908838654, 5813.970930145491, 5848.859472402841, 5067.721275329893, 5190.584753219658, 5545.890821835568, 5644.85887852807, 5720.570957178145, 4879.95120048795, 5272.340957380327, 5373.704446255493, 5836.503897168618, 5917.970410147922, 5074.367432397838, 5416.079266524779, 5703.315602127771, 5721.828345149652, 5740.483356497935], "value": 4447.772907694694, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [761.2463660975633, 762.5539059650105, 799.7081065411126, 801.2868546993174, 926.0924294191694, 16327.83672163278, 18091.457256282316, 18867.433976980712, 18986.240550378003, 19051.618967620638, 17962.461126166203, 18834.71747923782, 18982.240710371603, 19034.33379831706, 19205.711914321295, 705.4673721340381, 946.5740416812428, 999.4059475137144, 1023.4268809466686, 1035.896410358962, 1003.673806013045, 1017.4099022566893, 1018.1803648063307, 1022.6471867205802, 1024.0425202435708, 1055.2982266792574, 1073.404260635347, 1075.5482697267128, 1075.7794652096302, 1078.7896360209763, 1057.9365238085738, 1066.2163309967168, 1069.7753471770936, 1070.1064611049783, 1073.6081330314457, 780.4029917113413, 787.5038725602891, 792.3067316098413, 794.1026639896937, 796.2992566541465, 17519.824801751947, 17552.210150543255, 17693.557661058447, 18247.996360200184, 18479.0, 14721.0, 14940.775888361683, 14980.47568335114, 15020.474283400137, 15138.167400792952, 12020.39898005105, 12211.26732396059, 12213.633590992196, 12216.694582635506, 12409.0, 3359.81521016344, 3824.177801772604, 3899.025243689078, 4066.9593304066875, 4075.0, 114079.0, 115562.42218788936, 119222.0, 120623.0, 121083.0, 11454.921608215658, 11455.678207475232, 11506.712332191595, 11654.475548600334, 11999.580014699444, 23595.0, 24069.037238510613, 24257.272281831574, 24290.635640465407, 24356.87821560881, 8191.0580283267, 8257.628406721711, 8306.169383061646, 8320.916790832136, 8333.125021872616, 4583.931241031387, 4585.197590421662, 4651.976740116278, 4686.554777296152, 4811.49479304675, 3640.9453858192146, 3702.1670124221832, 3719.590845007047, 3741.6819570336825, 3752.81235938202, 645.8611398549334, 684.6234570985938, 715.7280233511336, 719.9784006479814, 723.8552289542113, 676.8612434450926, 725.4776560876161, 733.5232099135604, 765.4106338119617, 765.464175077448, 10079.496025198858, 10715.43691390657, 10770.838437423445, 10884.455777211107, 10934.549718760616, 707.6567864585672, 718.396546900606, 724.5833645653664, 728.5356433568566, 744.7281742164071, 4580.931286030712, 4732.100900828831, 4926.655134140576, 4934.950650493449, 5177.197534382177, 104445.0, 106478.0, 107445.46277268564, 108076.45961770297, 109280.0, 94980.52509737546, 95066.04933950571, 95158.52420737852, 97217.0, 97443.0, 82004.0, 82780.58609707034, 83026.0, 83822.58088709517, 84738.0, 10048.949755251177, 10110.747231319276, 10114.696559103239, 10145.797084058426, 10299.485025748832, 40545.797271014635, 46147.538524615666, 52458.73770631122, 54080.64798380072, 54658.726706366215, 65687.0, 67948.0, 67948.64102718017, 68579.0, 70347.18611255492, 13151.60545183646, 13443.663908402368, 13503.257320847448, 13739.725205496034, 13971.952103592068, 13789.793153102912, 14267.357968891632, 14831.258437078319, 15304.770538017941, 15691.843081569496, 13601.251931143486, 13938.721225575635, 14365.7845132321, 14503.85496145025, 14863.628409289855, 13907.513237036655, 13982.300884955508, 14057.578272651837, 14277.357518911882, 14361.9281903594, 13908.095973761672, 14246.28768561547, 14435.781780525967, 14508.202048887413, 14762.335694893542, 10654.57381704723, 10770.569177233136, 11008.944955275492, 11043.171762117712, 11292.378919159524, 11638.6508404748, 11683.824742629038, 11795.705107372385, 11944.880551194377, 11952.402379881145], "value": 8143.2326202374525, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1557.6000000000004, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 118.0, 120.0, 120.0, 135.0, 177.0, 159.0, 120.0, 122.0, 118.0], "value": 129.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 141.0, 130.0, 147.0, 111.0, 112.0, 116.0, 113.0, 131.0, 107.0], "value": 126.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 195.0, 195.0, 194.0, 194.0, 197.0, 196.0, 195.0, 199.0, 192.0], "value": 195.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 59.0, 62.0, 92.0, 77.0, 85.0, 82.0, 59.0, 72.0, 60.0], "value": 73.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 117.0, 102.0, 104.0, 79.0, 81.0, 72.0, 71.0, 82.0], "value": 87.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 90.0, 88.0, 87.0, 89.0, 108.0, 87.0, 91.0, 83.0, 137.0], "value": 94.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 117.0, 95.0, 95.0, 101.0, 154.0, 153.0, 95.0, 95.0, 109.0], "value": 112.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 92.0, 80.0, 142.0, 144.0, 136.0, 76.0, 144.0, 146.0], "value": 114.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 67.0, 67.0, 69.0, 69.0, 68.0, 69.0, 75.0, 96.0], "value": 71.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 61.0, 62.0, 63.0, 53.0, 75.0, 76.0, 87.0, 86.0], "value": 68.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 110.0, 101.0, 85.0, 86.0, 85.0, 82.0, 83.0, 82.0, 89.0], "value": 89.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 154.0, 141.0, 143.0, 163.0, 155.0, 158.0, 184.0, 184.0, 155.0], "value": 157.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 163.0, 160.0, 165.0, 164.0, 161.0, 163.0, 161.0, 165.0], "value": 162.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 64.0, 64.0, 69.0, 76.0, 83.0, 86.0, 85.0, 86.0], "value": 74.5, "unit": "score"}]}]} 12:02:30 INFO - Return code: 0 12:02:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:02:30 ERROR - # TBPL SUCCESS # 12:02:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:02:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:02:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:02:30 INFO - 2017-05-23 12:02:30.951 screenresolution[1658:10623] starting screenresolution argv=screenresolution get 12:02:30 INFO - 2017-05-23 12:02:30.954 screenresolution[1658:10623] Display 0: 1600x1200x32@60 12:02:30 INFO - 2017-05-23 12:02:30.959 screenresolution[1659:10626] starting screenresolution argv=screenresolution list 12:02:30 INFO - Available Modes on Display 0 12:02:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:02:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:02:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:02:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:02:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:02:31 INFO - Intel Iris: 12:02:31 INFO - Chipset Model: Intel Iris 12:02:31 INFO - Type: GPU 12:02:31 INFO - Bus: Built-In 12:02:31 INFO - VRAM (Dynamic, Max): 1536 MB 12:02:31 INFO - Vendor: Intel (0x8086) 12:02:31 INFO - Device ID: 0x0a2e 12:02:31 INFO - Revision ID: 0x0009 12:02:31 INFO - Displays: 12:02:31 INFO - Display: 12:02:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:02:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:02:31 INFO - Display Serial Number: 1600x1200 60 12:02:31 INFO - Main Display: Yes 12:02:31 INFO - Mirror: Off 12:02:31 INFO - Online: Yes 12:02:31 INFO - Rotation: Supported 12:02:31 INFO - Return code: 0 12:02:31 INFO - Running post-action listener: _resource_record_post_action 12:02:31 INFO - [mozharness: 2017-05-23 19:02:31.196007Z] Finished run-tests step (success) 12:02:31 INFO - Running post-run listener: _resource_record_post_run 12:02:31 INFO - Total resource usage - Wall time: 1192s; CPU: 55.0%; Read bytes: 40146944; Write bytes: 424956928; Read time: 397; Write time: 1448 12:02:31 INFO - TinderboxPrint: CPU usage
54.7% 12:02:31 INFO - TinderboxPrint: I/O read bytes / time
40,146,944 / 397 12:02:31 INFO - TinderboxPrint: I/O write bytes / time
424,956,928 / 1,448 12:02:31 INFO - TinderboxPrint: CPU idle
2,162.3 (45.3%) 12:02:31 INFO - TinderboxPrint: CPU system
961.7 (20.2%) 12:02:31 INFO - TinderboxPrint: CPU user
1,645.6 (34.5%) 12:02:31 INFO - TinderboxPrint: Swap in / out
162,066,432 / 0 12:02:31 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 121960960; Write bytes: 116267008; Read time: 11084; Write time: 313 12:02:31 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 36263936; Write bytes: 298478592; Read time: 367; Write time: 1099 12:02:31 INFO - Running post-run listener: _upload_blobber_files 12:02:31 INFO - Blob upload gear active. 12:02:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:02:31 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:02:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:02:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:02:32 INFO - (blobuploader) - INFO - Open directory for files ... 12:02:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:02:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:02:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:02:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:02:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:02:33 INFO - (blobuploader) - INFO - Done attempting. 12:02:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:02:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:02:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:02:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:02:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:02:34 INFO - (blobuploader) - INFO - Done attempting. 12:02:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:02:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:02:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:02:35 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:02:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:02:35 INFO - (blobuploader) - INFO - Done attempting. 12:02:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:02:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:02:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:02:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:02:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:02:37 INFO - (blobuploader) - INFO - Done attempting. 12:02:37 INFO - (blobuploader) - INFO - Iteration through files over. 12:02:37 INFO - Return code: 0 12:02:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:02:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:02:37 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dec2a92321d834932828302b23d5626af90529b57a1db575cf11f4750d084b2eb5b90f146207720240510384d6e8960fd5f2729e4c7024848e4fcdf7abdc1ad2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0a8baec3eb376df8ea46aac5a4cdea2de64c09104efb6fbee4d8cca72cd61939fd9efb4793cb99b26e8f3ade2cb9a8f561f2a9fa8cf478df353933bf68163364", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3c71bfc5510b45b508d97afea5bcccadb4e52ab6bdbf0edd0d635d0ba86820ac0062a56f79e0ce7c12613e8565d2fec1786099c50ec59c818c7d4d6f3bf60b89", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d7ffb94f0114352deaa6a00c3cfab77dd323dd999f65996e08dc915c712fce3e0bec5010cdf6aaa9cb5a5bd1cdfc19d566c5900b10ba79973389190daa575010"} 12:02:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:02:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:02:37 INFO - Contents: 12:02:37 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dec2a92321d834932828302b23d5626af90529b57a1db575cf11f4750d084b2eb5b90f146207720240510384d6e8960fd5f2729e4c7024848e4fcdf7abdc1ad2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0a8baec3eb376df8ea46aac5a4cdea2de64c09104efb6fbee4d8cca72cd61939fd9efb4793cb99b26e8f3ade2cb9a8f561f2a9fa8cf478df353933bf68163364", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3c71bfc5510b45b508d97afea5bcccadb4e52ab6bdbf0edd0d635d0ba86820ac0062a56f79e0ce7c12613e8565d2fec1786099c50ec59c818c7d4d6f3bf60b89", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d7ffb94f0114352deaa6a00c3cfab77dd323dd999f65996e08dc915c712fce3e0bec5010cdf6aaa9cb5a5bd1cdfc19d566c5900b10ba79973389190daa575010"} 12:02:37 INFO - Running post-run listener: copy_logs_to_upload_dir 12:02:37 INFO - Copying logs to upload dir... 12:02:37 INFO - mkdir: /builds/slave/test/build/upload/logs 12:02:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.127756 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-05-23 12:02:37.757535) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-23 12:02:37.765154) ========= 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.tRumbXgakB/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.3yi6qFgpnq/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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/dec2a92321d834932828302b23d5626af90529b57a1db575cf11f4750d084b2eb5b90f146207720240510384d6e8960fd5f2729e4c7024848e4fcdf7abdc1ad2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0a8baec3eb376df8ea46aac5a4cdea2de64c09104efb6fbee4d8cca72cd61939fd9efb4793cb99b26e8f3ade2cb9a8f561f2a9fa8cf478df353933bf68163364", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3c71bfc5510b45b508d97afea5bcccadb4e52ab6bdbf0edd0d635d0ba86820ac0062a56f79e0ce7c12613e8565d2fec1786099c50ec59c818c7d4d6f3bf60b89", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d7ffb94f0114352deaa6a00c3cfab77dd323dd999f65996e08dc915c712fce3e0bec5010cdf6aaa9cb5a5bd1cdfc19d566c5900b10ba79973389190daa575010"} build_url:https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011361 build_url: 'https://queue.taskcluster.net/v1/task/NsTLdmRRRWitQIVTmCNQaA/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/dec2a92321d834932828302b23d5626af90529b57a1db575cf11f4750d084b2eb5b90f146207720240510384d6e8960fd5f2729e4c7024848e4fcdf7abdc1ad2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0a8baec3eb376df8ea46aac5a4cdea2de64c09104efb6fbee4d8cca72cd61939fd9efb4793cb99b26e8f3ade2cb9a8f561f2a9fa8cf478df353933bf68163364", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3c71bfc5510b45b508d97afea5bcccadb4e52ab6bdbf0edd0d635d0ba86820ac0062a56f79e0ce7c12613e8565d2fec1786099c50ec59c818c7d4d6f3bf60b89", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d7ffb94f0114352deaa6a00c3cfab77dd323dd999f65996e08dc915c712fce3e0bec5010cdf6aaa9cb5a5bd1cdfc19d566c5900b10ba79973389190daa575010"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-23 12:02:37.800340) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 12:02:37.800939) ========= 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.tRumbXgakB/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.3yi6qFgpnq/Listeners TMPDIR=/var/folders/5n/sn2p23ks3csg46__d68h6j6m00000w/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.005338 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 12:02:37.851061) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-23 12:02:37.851380) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-23 12:02:37.851723) ========= ========= Total master_lag: 0.18 =========