builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0346 starttime: 1494881186.73 results: success (0) buildid: 20170515124824 builduid: 22a1e907c65646b39acef577808e1dcc revision: 9bef2dfbd584c0f3ae52953bfc3aed64e308960d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-15 13:46:26.729967) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-15 13:46:26.730376) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-15 13:46:26.730705) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-15 13:46:26.754091) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 13:46:26.754356) ========= 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.uXNOuyS30e/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.IaDvfO0o3c/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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-15 13:46:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.03M=0.001s 2017-05-15 13:46:26 (8.03 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.132028 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 13:46:26.908482) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 13:46:26.908793) ========= 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.uXNOuyS30e/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.IaDvfO0o3c/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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.020899 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 13:46:26.957331) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-15 13:46:26.957695) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9bef2dfbd584c0f3ae52953bfc3aed64e308960d --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 9bef2dfbd584c0f3ae52953bfc3aed64e308960d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uXNOuyS30e/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.IaDvfO0o3c/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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-15 13:46:27,050 truncating revision to first 12 chars 2017-05-15 13:46:27,050 Setting DEBUG logging. 2017-05-15 13:46:27,050 attempt 1/10 2017-05-15 13:46:27,050 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/9bef2dfbd584?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-15 13:46:28,943 unpacking tar archive at: mozilla-beta-9bef2dfbd584/testing/mozharness/ program finished with exit code 0 elapsedTime=2.151809 ========= master_lag: 0.52 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-15 13:46:29.631313) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-15 13:46:29.631659) ========= script_repo_revision: 9bef2dfbd584c0f3ae52953bfc3aed64e308960d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-15 13:46:29.632037) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-15 13:46:29.632300) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-15 13:46:29.651487) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-05-15 13:46:29.651756) ========= /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.uXNOuyS30e/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.IaDvfO0o3c/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 13:46:29 INFO - MultiFileLogger online at 20170515 13:46:29 in /builds/slave/test 13:46:29 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 13:46:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:46:29 INFO - {'append_to_log': False, 13:46:29 INFO - 'base_work_dir': '/builds/slave/test', 13:46:29 INFO - 'blob_upload_branch': 'Mozilla-Beta', 13:46:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:46:29 INFO - 'branch': 'Mozilla-Beta', 13:46:29 INFO - 'buildbot_json_path': 'buildprops.json', 13:46:29 INFO - 'config_files': ('talos/mac_config.py',), 13:46:29 INFO - 'default_actions': ('clobber', 13:46:29 INFO - 'read-buildbot-config', 13:46:29 INFO - 'download-and-extract', 13:46:29 INFO - 'populate-webroot', 13:46:29 INFO - 'create-virtualenv', 13:46:29 INFO - 'install', 13:46:29 INFO - 'run-tests'), 13:46:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:46:29 INFO - 'download_minidump_stackwalk': True, 13:46:29 INFO - 'download_symbols': 'ondemand', 13:46:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:46:29 INFO - 'tooltool.py': '/tools/tooltool.py', 13:46:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:46:29 INFO - '/tools/misc-python/virtualenv.py')}, 13:46:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:46:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:46:29 INFO - 'gecko_profile': False, 13:46:29 INFO - 'gecko_profile_interval': 0, 13:46:29 INFO - 'installer_path': 'installer.exe', 13:46:29 INFO - 'log_level': 'info', 13:46:29 INFO - 'log_name': 'talos', 13:46:29 INFO - 'log_to_console': True, 13:46:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:46:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:46:29 INFO - 'opt_config_files': (), 13:46:29 INFO - 'pip_index': False, 13:46:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:46:29 INFO - 'cmd': ('bash', 13:46:29 INFO - '-c', 13:46:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:46:29 INFO - 'enabled': True, 13:46:29 INFO - 'halt_on_failure': False, 13:46:29 INFO - 'name': 'check_screen_resolution'},), 13:46:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:46:29 INFO - 'cmd': ('bash', 13:46:29 INFO - '-c', 13:46:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:46:29 INFO - 'enabled': True, 13:46:29 INFO - 'halt_on_failure': False, 13:46:29 INFO - 'name': 'check_screen_resolution'},), 13:46:29 INFO - 'run_cmd_checks_enabled': True, 13:46:29 INFO - 'suite': 'dromaeojs-e10s', 13:46:29 INFO - 'system_bits': '32', 13:46:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:46:29 INFO - 'title': 't-yosemite-r7-0346', 13:46:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:46:29 INFO - 'use_talos_json': True, 13:46:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:46:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:46:29 INFO - 'work_dir': 'build'} 13:46:29 INFO - [mozharness: 2017-05-15 20:46:29.859072Z] Running clobber step. 13:46:29 INFO - Running pre-action listener: _resource_record_pre_action 13:46:29 INFO - Running main action method: clobber 13:46:29 INFO - rmtree: /builds/slave/test/build 13:46:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:46:30 INFO - Running post-action listener: _resource_record_post_action 13:46:30 INFO - [mozharness: 2017-05-15 20:46:30.017908Z] Finished clobber step (success) 13:46:30 INFO - [mozharness: 2017-05-15 20:46:30.018060Z] Running read-buildbot-config step. 13:46:30 INFO - Running pre-action listener: _resource_record_pre_action 13:46:30 INFO - Running main action method: read_buildbot_config 13:46:30 INFO - Using buildbot properties: 13:46:30 INFO - { 13:46:30 INFO - "project": "", 13:46:30 INFO - "product": "firefox", 13:46:30 INFO - "script_repo_revision": "production", 13:46:30 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 13:46:30 INFO - "repository": "", 13:46:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 13:46:30 INFO - "buildid": "20170515124824", 13:46:30 INFO - "pgo_build": "False", 13:46:30 INFO - "basedir": "/builds/slave/test", 13:46:30 INFO - "buildnumber": 5, 13:46:30 INFO - "slavename": "t-yosemite-r7-0346", 13:46:30 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 13:46:30 INFO - "platform": "yosemite_r7", 13:46:30 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 13:46:30 INFO - "branch": "mozilla-beta", 13:46:30 INFO - "repo_path": "releases/mozilla-beta", 13:46:30 INFO - "revision": "9bef2dfbd584c0f3ae52953bfc3aed64e308960d", 13:46:30 INFO - "stage_platform": "macosx64", 13:46:30 INFO - "builduid": "22a1e907c65646b39acef577808e1dcc", 13:46:30 INFO - "slavebuilddir": "test" 13:46:30 INFO - } 13:46:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/Hi-PxhAQRTe_z90JbXNwLQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 13:46:30 INFO - Running post-action listener: _resource_record_post_action 13:46:30 INFO - [mozharness: 2017-05-15 20:46:30.021316Z] Finished read-buildbot-config step (success) 13:46:30 INFO - [mozharness: 2017-05-15 20:46:30.021453Z] Running download-and-extract step. 13:46:30 INFO - Running pre-action listener: _resource_record_pre_action 13:46:30 INFO - Running main action method: download_and_extract 13:46:30 INFO - mkdir: /builds/slave/test/build/tests 13:46:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:46:30 INFO - trying https://queue.taskcluster.net/v1/task/Hi-PxhAQRTe_z90JbXNwLQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 13:46:30 INFO - Downloading https://queue.taskcluster.net/v1/task/Hi-PxhAQRTe_z90JbXNwLQ/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 13:46:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hi-PxhAQRTe_z90JbXNwLQ/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 13:46:30 INFO - Downloaded 1287 bytes. 13:46:30 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 13:46:30 INFO - Using the following test package requirements: 13:46:30 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:46:30 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 13:46:30 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 13:46:30 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:46:30 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 13:46:30 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:46:30 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 13:46:30 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 13:46:30 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:46:30 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 13:46:30 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 13:46:30 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:46:30 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 13:46:30 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:46:30 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 13:46:30 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:46:30 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 13:46:30 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 13:46:30 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 13:46:30 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 13:46:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hi-PxhAQRTe_z90JbXNwLQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 13:46:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hi-PxhAQRTe_z90JbXNwLQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 13:46:30 INFO - Fetch https://queue.taskcluster.net/v1/task/Hi-PxhAQRTe_z90JbXNwLQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 13:46:32 INFO - Expected file size: 51224693 13:46:32 INFO - Obtained file size: 51224693 13:46:48 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 13:46:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hi-PxhAQRTe_z90JbXNwLQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 13:46:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hi-PxhAQRTe_z90JbXNwLQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 13:46:48 INFO - Fetch https://queue.taskcluster.net/v1/task/Hi-PxhAQRTe_z90JbXNwLQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 13:46:51 INFO - Expected file size: 51224693 13:46:51 INFO - Obtained file size: 51224693 13:47:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hi-PxhAQRTe_z90JbXNwLQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 13:47:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hi-PxhAQRTe_z90JbXNwLQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 13:47:05 INFO - Fetch https://queue.taskcluster.net/v1/task/Hi-PxhAQRTe_z90JbXNwLQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 13:47:06 INFO - Expected file size: 14404449 13:47:06 INFO - Obtained file size: 14404449 13:47:07 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')]} 13:47:07 INFO - trying https://queue.taskcluster.net/v1/task/Hi-PxhAQRTe_z90JbXNwLQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 13:47:07 INFO - Downloading https://queue.taskcluster.net/v1/task/Hi-PxhAQRTe_z90JbXNwLQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 13:47:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hi-PxhAQRTe_z90JbXNwLQ/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 13:47:08 INFO - Downloaded 59541342 bytes. 13:47:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Hi-PxhAQRTe_z90JbXNwLQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 13:47:08 INFO - mkdir: /builds/slave/test/properties 13:47:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:47:08 INFO - Writing to file /builds/slave/test/properties/build_url 13:47:08 INFO - Contents: 13:47:08 INFO - build_url:https://queue.taskcluster.net/v1/task/Hi-PxhAQRTe_z90JbXNwLQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 13:47:09 INFO - Running post-action listener: _resource_record_post_action 13:47:09 INFO - Running post-action listener: set_extra_try_arguments 13:47:09 INFO - [mozharness: 2017-05-15 20:47:09.575427Z] Finished download-and-extract step (success) 13:47:09 INFO - [mozharness: 2017-05-15 20:47:09.575588Z] Running populate-webroot step. 13:47:09 INFO - Running pre-action listener: _resource_record_pre_action 13:47:09 INFO - Running main action method: populate_webroot 13:47:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:47:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:47:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:47:09 INFO - '--tppagecycles', 13:47:09 INFO - '7'], 13:47:09 INFO - 'tests': ['tsvgm']}}, 13:47:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:47:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:47:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:47:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:47:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:47:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:47:09 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:47:09 INFO - 'talos_options': ['--disable-e10s'], 13:47:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:47:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:47:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:47:09 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:47:09 INFO - 'talos_options': ['--disable-e10s'], 13:47:09 INFO - 'tests': ['damp', 'tps']}, 13:47:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:47:09 INFO - 'tests': ['damp', 'tps']}, 13:47:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:47:09 INFO - 'tests': ['dromaeo_dom']}, 13:47:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:47:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:47:09 INFO - 'tests': ['basic_compositor_video']}, 13:47:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:47:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:47:09 INFO - 'tests': ['a11yr', 13:47:09 INFO - 'ts_paint', 13:47:09 INFO - 'tpaint', 13:47:09 INFO - 'sessionrestore', 13:47:09 INFO - 'sessionrestore_no_auto_restore', 13:47:09 INFO - 'tabpaint']}, 13:47:09 INFO - 'other-e10s': {'tests': ['a11yr', 13:47:09 INFO - 'ts_paint', 13:47:09 INFO - 'tpaint', 13:47:09 INFO - 'sessionrestore', 13:47:09 INFO - 'sessionrestore_no_auto_restore', 13:47:09 INFO - 'tabpaint']}, 13:47:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:47:09 INFO - 'ts_paint', 13:47:09 INFO - 'tpaint', 13:47:09 INFO - 'sessionrestore', 13:47:09 INFO - 'sessionrestore_no_auto_restore', 13:47:09 INFO - 'tabpaint']}, 13:47:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:47:09 INFO - 'ts_paint', 13:47:09 INFO - 'tpaint', 13:47:09 INFO - 'sessionrestore', 13:47:09 INFO - 'sessionrestore_no_auto_restore', 13:47:09 INFO - 'tabpaint']}, 13:47:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:47:09 INFO - 'tests': ['a11yr', 13:47:09 INFO - 'ts_paint', 13:47:09 INFO - 'tpaint', 13:47:09 INFO - 'sessionrestore', 13:47:09 INFO - 'sessionrestore_no_auto_restore', 13:47:09 INFO - 'tabpaint']}, 13:47:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:47:09 INFO - 'tests': ['a11yr', 13:47:09 INFO - 'ts_paint', 13:47:09 INFO - 'tpaint', 13:47:09 INFO - 'sessionrestore', 13:47:09 INFO - 'sessionrestore_no_auto_restore', 13:47:09 INFO - 'tabpaint']}, 13:47:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:47:09 INFO - 'tests': ['tsvgx', 13:47:09 INFO - 'tsvgr_opacity', 13:47:09 INFO - 'tart', 13:47:09 INFO - 'tscrollx', 13:47:09 INFO - 'cart', 13:47:09 INFO - 'tsvg_static']}, 13:47:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:47:09 INFO - 'tsvgr_opacity', 13:47:09 INFO - 'tart', 13:47:09 INFO - 'tscrollx', 13:47:09 INFO - 'cart', 13:47:09 INFO - 'tsvg_static']}, 13:47:09 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:47:09 INFO - 'talos_options': ['--disable-e10s'], 13:47:09 INFO - 'tests': ['tp5o']}, 13:47:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:47:09 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:47:09 INFO - 'talos_options': ['--disable-e10s', 13:47:09 INFO - '--xperf_path', 13:47:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:47:09 INFO - 'tests': ['tp5n']}, 13:47:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:47:09 INFO - 'talos_options': ['--xperf_path', 13:47:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:47:09 INFO - 'tests': ['tp5n']}}, 13:47:09 INFO - 'talos.zip': {'path': '', 13:47:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:47:09 INFO - Running post-action listener: _resource_record_post_action 13:47:09 INFO - [mozharness: 2017-05-15 20:47:09.594382Z] Finished populate-webroot step (success) 13:47:09 INFO - [mozharness: 2017-05-15 20:47:09.594515Z] Running create-virtualenv step. 13:47:09 INFO - Running pre-action listener: _resource_record_pre_action 13:47:09 INFO - Running main action method: create_virtualenv 13:47:09 INFO - Creating virtualenv /builds/slave/test/build/venv 13:47:09 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 13:47:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:47:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:47:09 INFO - Using real prefix '/tools/python27' 13:47:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:47:10 INFO - Installing distribute.............................................................................................................................................................................................done. 13:47:13 INFO - Installing pip.................done. 13:47:13 INFO - Return code: 0 13:47:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:47:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:47:13 INFO - Reading from file tmpfile_stdout 13:47:13 INFO - Output received: 13:47:13 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) 13:47:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:47:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:47:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:47:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:13 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 0x106ae8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106aeb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a235b5880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068c6d30>, '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 0x1067eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068bbbe8>, '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.uXNOuyS30e/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.IaDvfO0o3c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 13:47:13 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 13:47:13 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 13:47:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uXNOuyS30e/Render', 13:47:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:13 INFO - 'HOME': '/Users/cltbld', 13:47:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:13 INFO - 'LOGNAME': 'cltbld', 13:47:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:13 INFO - 'MOZ_NO_REMOTE': '1', 13:47:13 INFO - 'NO_EM_RESTART': '1', 13:47:13 INFO - 'PAGER': '/bin/cat', 13:47:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:13 INFO - 'PWD': '/builds/slave/test', 13:47:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:13 INFO - 'SHELL': '/bin/bash', 13:47:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IaDvfO0o3c/Listeners', 13:47:13 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 13:47:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:13 INFO - 'USER': 'cltbld', 13:47:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:13 INFO - 'XPC_FLAGS': '0x0', 13:47:13 INFO - 'XPC_SERVICE_NAME': '0', 13:47:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:13 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 13:47:13 INFO - Cleaning up... 13:47:13 INFO - Return code: 0 13:47:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:47:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:47:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:47:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:13 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 0x106ae8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106aeb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a235b5880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068c6d30>, '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 0x1067eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068bbbe8>, '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.uXNOuyS30e/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.IaDvfO0o3c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 13:47:13 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 13:47:13 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 13:47:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uXNOuyS30e/Render', 13:47:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:13 INFO - 'HOME': '/Users/cltbld', 13:47:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:13 INFO - 'LOGNAME': 'cltbld', 13:47:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:13 INFO - 'MOZ_NO_REMOTE': '1', 13:47:13 INFO - 'NO_EM_RESTART': '1', 13:47:13 INFO - 'PAGER': '/bin/cat', 13:47:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:13 INFO - 'PWD': '/builds/slave/test', 13:47:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:13 INFO - 'SHELL': '/bin/bash', 13:47:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IaDvfO0o3c/Listeners', 13:47:13 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 13:47:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:13 INFO - 'USER': 'cltbld', 13:47:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:13 INFO - 'XPC_FLAGS': '0x0', 13:47:13 INFO - 'XPC_SERVICE_NAME': '0', 13:47:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:14 INFO - Downloading/unpacking psutil>=3.1.1 13:47:14 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 13:47:14 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 13:47:14 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 13:47:14 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 13:47:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:47:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:47:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:47:16 INFO - Installing collected packages: psutil 13:47:16 INFO - Running setup.py install for psutil 13:47:16 INFO - building 'psutil._psutil_osx' extension 13:47:16 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 13:47:16 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 13:47:16 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 13:47:16 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 13:47:16 INFO - building 'psutil._psutil_posix' extension 13:47:16 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 13:47:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:47:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:47:16 INFO - ^ 13:47:16 INFO - 1 warning generated. 13:47:16 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 13:47:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:47:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:47:16 INFO - Successfully installed psutil 13:47:16 INFO - Cleaning up... 13:47:16 INFO - Return code: 0 13:47:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:47: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')]} 13:47:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47: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')]} 13:47:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106ae8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106aeb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a235b5880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068c6d30>, '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 0x1067eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068bbbe8>, '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.uXNOuyS30e/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.IaDvfO0o3c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 13:47: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:47: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 mozsystemmonitor==0.3 13:47:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uXNOuyS30e/Render', 13:47:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:16 INFO - 'HOME': '/Users/cltbld', 13:47:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:16 INFO - 'LOGNAME': 'cltbld', 13:47:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:16 INFO - 'MOZ_NO_REMOTE': '1', 13:47:16 INFO - 'NO_EM_RESTART': '1', 13:47:16 INFO - 'PAGER': '/bin/cat', 13:47:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:16 INFO - 'PWD': '/builds/slave/test', 13:47:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:16 INFO - 'SHELL': '/bin/bash', 13:47:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IaDvfO0o3c/Listeners', 13:47:16 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 13:47:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:16 INFO - 'USER': 'cltbld', 13:47:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:16 INFO - 'XPC_FLAGS': '0x0', 13:47:16 INFO - 'XPC_SERVICE_NAME': '0', 13:47:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:47:17 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 13:47:17 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 13:47:17 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 13:47:17 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 13:47:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:47:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:47:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:47:18 INFO - Installing collected packages: mozsystemmonitor 13:47:18 INFO - Running setup.py install for mozsystemmonitor 13:47:18 INFO - Successfully installed mozsystemmonitor 13:47:18 INFO - Cleaning up... 13:47:18 INFO - Return code: 0 13:47:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:47:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:47:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:18 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 0x106ae8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106aeb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a235b5880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068c6d30>, '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 0x1067eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068bbbe8>, '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.uXNOuyS30e/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.IaDvfO0o3c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 13:47:18 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 13:47:18 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 13:47:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uXNOuyS30e/Render', 13:47:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:18 INFO - 'HOME': '/Users/cltbld', 13:47:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:18 INFO - 'LOGNAME': 'cltbld', 13:47:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:18 INFO - 'MOZ_NO_REMOTE': '1', 13:47:18 INFO - 'NO_EM_RESTART': '1', 13:47:18 INFO - 'PAGER': '/bin/cat', 13:47:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:18 INFO - 'PWD': '/builds/slave/test', 13:47:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:18 INFO - 'SHELL': '/bin/bash', 13:47:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IaDvfO0o3c/Listeners', 13:47:18 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 13:47:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:18 INFO - 'USER': 'cltbld', 13:47:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:18 INFO - 'XPC_FLAGS': '0x0', 13:47:18 INFO - 'XPC_SERVICE_NAME': '0', 13:47:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:19 INFO - Downloading/unpacking jsonschema==2.5.1 13:47:19 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 13:47:19 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 13:47:19 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 13:47:19 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 13:47:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:47:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:47: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 13:47: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 13:47: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 13:47: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 13:47:20 INFO - Downloading functools32-3.2.3-2.tar.gz 13:47:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:47:20 INFO - warning: no files found matching '*.txt' 13:47:20 INFO - no previously-included directories found matching 'build' 13:47:20 INFO - no previously-included directories found matching 'dist' 13:47:20 INFO - no previously-included directories found matching '.git*' 13:47:20 INFO - Installing collected packages: jsonschema, functools32 13:47:21 INFO - Running setup.py install for functools32 13:47:21 INFO - warning: no files found matching '*.txt' 13:47:21 INFO - no previously-included directories found matching 'build' 13:47:21 INFO - no previously-included directories found matching 'dist' 13:47:21 INFO - no previously-included directories found matching '.git*' 13:47:21 INFO - Successfully installed jsonschema functools32 13:47:21 INFO - Cleaning up... 13:47:21 INFO - Return code: 0 13:47:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:47:21 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')]} 13:47:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:21 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')]} 13:47:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:21 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 0x106ae8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106aeb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a235b5880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068c6d30>, '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 0x1067eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068bbbe8>, '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.uXNOuyS30e/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.IaDvfO0o3c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 13:47:21 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 13:47:21 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 13:47:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uXNOuyS30e/Render', 13:47:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:21 INFO - 'HOME': '/Users/cltbld', 13:47:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:21 INFO - 'LOGNAME': 'cltbld', 13:47:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:21 INFO - 'MOZ_NO_REMOTE': '1', 13:47:21 INFO - 'NO_EM_RESTART': '1', 13:47:21 INFO - 'PAGER': '/bin/cat', 13:47:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:21 INFO - 'PWD': '/builds/slave/test', 13:47:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:21 INFO - 'SHELL': '/bin/bash', 13:47:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IaDvfO0o3c/Listeners', 13:47:21 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 13:47:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:21 INFO - 'USER': 'cltbld', 13:47:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:21 INFO - 'XPC_FLAGS': '0x0', 13:47:21 INFO - 'XPC_SERVICE_NAME': '0', 13:47:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:47:21 INFO - Cleaning up... 13:47:21 INFO - Return code: 0 13:47:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:47:21 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')]} 13:47:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:21 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')]} 13:47:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:21 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 0x106ae8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106aeb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a235b5880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068c6d30>, '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 0x1067eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068bbbe8>, '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.uXNOuyS30e/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.IaDvfO0o3c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 13:47:21 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 13:47:21 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 13:47:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uXNOuyS30e/Render', 13:47:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:21 INFO - 'HOME': '/Users/cltbld', 13:47:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:21 INFO - 'LOGNAME': 'cltbld', 13:47:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:21 INFO - 'MOZ_NO_REMOTE': '1', 13:47:21 INFO - 'NO_EM_RESTART': '1', 13:47:21 INFO - 'PAGER': '/bin/cat', 13:47:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:21 INFO - 'PWD': '/builds/slave/test', 13:47:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:21 INFO - 'SHELL': '/bin/bash', 13:47:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IaDvfO0o3c/Listeners', 13:47:21 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 13:47:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:21 INFO - 'USER': 'cltbld', 13:47:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:21 INFO - 'XPC_FLAGS': '0x0', 13:47:21 INFO - 'XPC_SERVICE_NAME': '0', 13:47:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:21 INFO - Downloading/unpacking blobuploader==1.2.4 13:47:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:47:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:47:23 INFO - Downloading blobuploader-1.2.4.tar.gz 13:47:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:47:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:47: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 13:47: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 13:47: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 13:47: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 13:47:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:47:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:47: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 13:47: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 13:47: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 13:47: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 13:47:24 INFO - Downloading docopt-0.6.1.tar.gz 13:47:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:47:24 INFO - Installing collected packages: blobuploader, requests, docopt 13:47:24 INFO - Running setup.py install for blobuploader 13:47:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:47:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:47:24 INFO - Running setup.py install for requests 13:47:24 INFO - Running setup.py install for docopt 13:47:24 INFO - Successfully installed blobuploader requests docopt 13:47:24 INFO - Cleaning up... 13:47:24 INFO - Return code: 0 13:47:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:47:24 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')]} 13:47:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47: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')]} 13:47:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:25 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 0x106ae8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106aeb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a235b5880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068c6d30>, '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 0x1067eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068bbbe8>, '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.uXNOuyS30e/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.IaDvfO0o3c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 13:47:25 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 13:47:25 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 13:47:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uXNOuyS30e/Render', 13:47:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:25 INFO - 'HOME': '/Users/cltbld', 13:47:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:25 INFO - 'LOGNAME': 'cltbld', 13:47:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:25 INFO - 'MOZ_NO_REMOTE': '1', 13:47:25 INFO - 'NO_EM_RESTART': '1', 13:47:25 INFO - 'PAGER': '/bin/cat', 13:47:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:25 INFO - 'PWD': '/builds/slave/test', 13:47:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:25 INFO - 'SHELL': '/bin/bash', 13:47:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IaDvfO0o3c/Listeners', 13:47:25 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 13:47:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:25 INFO - 'USER': 'cltbld', 13:47:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:25 INFO - 'XPC_FLAGS': '0x0', 13:47:25 INFO - 'XPC_SERVICE_NAME': '0', 13:47:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:47:26 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-O5DDwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:47:26 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-tkkTFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:47:26 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-xLIH8i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:47:26 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-UJT42Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:47:26 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-s_MRGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:47:26 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-UJbBOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:47:26 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-kbYAYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:47:26 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-9BDvvC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:47:26 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-AyOFtL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:47:27 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-2W6lXG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:47:27 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-zn93lC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:47:27 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-aUEisG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:47:27 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-Bbed5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:47:27 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-WlW5lA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:47:27 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-0zSifl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:47:27 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-ddi191-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:47:27 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-FDUQTc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:47:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:47:27 INFO - Running setup.py install for manifestparser 13:47:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:47:28 INFO - Running setup.py install for mozcrash 13:47:28 INFO - Running setup.py install for mozdebug 13:47:28 INFO - Running setup.py install for mozdevice 13:47:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:47:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:47:28 INFO - Running setup.py install for mozfile 13:47:28 INFO - Running setup.py install for mozhttpd 13:47:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:47:28 INFO - Running setup.py install for mozinfo 13:47:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:47:28 INFO - Running setup.py install for mozInstall 13:47:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:47:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:47:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:47:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:47:29 INFO - Running setup.py install for mozleak 13:47:29 INFO - Running setup.py install for mozlog 13:47:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:47:29 INFO - Running setup.py install for moznetwork 13:47:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:47:29 INFO - Running setup.py install for mozprocess 13:47:29 INFO - Running setup.py install for mozprofile 13:47:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:47:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:47:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:47:29 INFO - Running setup.py install for mozrunner 13:47:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:47:30 INFO - Running setup.py install for mozscreenshot 13:47:30 INFO - Running setup.py install for moztest 13:47:30 INFO - Running setup.py install for mozversion 13:47:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:47:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:47:30 INFO - Cleaning up... 13:47:30 INFO - Return code: 0 13:47:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:47:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:47:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:30 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 0x106ae8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106aeb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a235b5880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068c6d30>, '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 0x1067eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068bbbe8>, '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.uXNOuyS30e/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.IaDvfO0o3c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 13:47:30 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 13:47:30 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 13:47:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uXNOuyS30e/Render', 13:47:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:30 INFO - 'HOME': '/Users/cltbld', 13:47:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:30 INFO - 'LOGNAME': 'cltbld', 13:47:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:30 INFO - 'MOZ_NO_REMOTE': '1', 13:47:30 INFO - 'NO_EM_RESTART': '1', 13:47:30 INFO - 'PAGER': '/bin/cat', 13:47:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:30 INFO - 'PWD': '/builds/slave/test', 13:47:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:30 INFO - 'SHELL': '/bin/bash', 13:47:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IaDvfO0o3c/Listeners', 13:47:30 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 13:47:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:30 INFO - 'USER': 'cltbld', 13:47:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:30 INFO - 'XPC_FLAGS': '0x0', 13:47:30 INFO - 'XPC_SERVICE_NAME': '0', 13:47:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:47:31 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-UhzzMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:47:31 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)) 13:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:47:31 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-arHCEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:47:31 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)) 13:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:47:31 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-D6tNtw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:47:31 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)) 13:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:47:31 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-BGSo5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:47:31 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)) 13:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:47:31 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-LAnoX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:47:31 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)) 13:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:47:31 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-WqhBGy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:47:31 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)) 13:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:47:31 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-QEjFRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:47:31 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)) 13:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:47:31 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-1Q9wtf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:47:32 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)) 13:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:47:32 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-MkvILj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:47:32 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)) 13:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:47:32 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-YZrSps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:47:32 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)) 13:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:47:32 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-eKcDoV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:47:32 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)) 13:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:47:32 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-LEv9eJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:47:32 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)) 13:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:47:32 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-Jwp8Z3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:47:32 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)) 13:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:47:32 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-uJilAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:47:32 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)) 13:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:47:32 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-qYfgfc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:47:32 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)) 13:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:47:32 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-L9GnTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:47:32 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)) 13:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:47:32 INFO - Running setup.py (path:/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/pip-salM7D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:47:33 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)) 13:47:33 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)) 13:47:33 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)) 13:47:33 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)) 13:47:33 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)) 13:47:33 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)) 13:47:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:47:33 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 13:47:33 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 13:47:33 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 13:47:33 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 13:47:34 INFO - Downloading blessings-1.6.tar.gz 13:47:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:47:35 INFO - Installing collected packages: blessings 13:47:35 INFO - Running setup.py install for blessings 13:47:35 INFO - Successfully installed blessings 13:47:35 INFO - Cleaning up... 13:47:35 INFO - Return code: 0 13:47:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:47:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:47:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:47:35 INFO - Reading from file tmpfile_stdout 13:47:35 INFO - Current package versions: 13:47:35 INFO - blessings == 1.6 13:47:35 INFO - blobuploader == 1.2.4 13:47:35 INFO - docopt == 0.6.1 13:47:35 INFO - functools32 == 3.2.3-2 13:47:35 INFO - jsonschema == 2.5.1 13:47:35 INFO - manifestparser == 1.1 13:47:35 INFO - mozInstall == 1.12 13:47:35 INFO - mozcrash == 1.0 13:47:35 INFO - mozdebug == 0.1 13:47:35 INFO - mozdevice == 0.49 13:47:35 INFO - mozfile == 1.2 13:47:35 INFO - mozhttpd == 0.7 13:47:35 INFO - mozinfo == 0.9 13:47:35 INFO - mozleak == 0.1 13:47:35 INFO - mozlog == 3.4 13:47:35 INFO - moznetwork == 0.27 13:47:35 INFO - mozprocess == 0.25 13:47:35 INFO - mozprofile == 0.28 13:47:35 INFO - mozrunner == 6.13 13:47:35 INFO - mozscreenshot == 0.1 13:47:35 INFO - mozsystemmonitor == 0.3 13:47:35 INFO - moztest == 0.8 13:47:35 INFO - mozversion == 1.4 13:47:35 INFO - psutil == 3.1.1 13:47:35 INFO - requests == 1.2.3 13:47:35 INFO - wsgiref == 0.1.2 13:47:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:47:35 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')]} 13:47:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:35 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')]} 13:47:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:35 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 0x106ae8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106aeb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a235b5880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068c6d30>, '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 0x1067eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068bbbe8>, '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.uXNOuyS30e/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.IaDvfO0o3c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 13:47:35 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 13:47:35 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 13:47:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uXNOuyS30e/Render', 13:47:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:35 INFO - 'HOME': '/Users/cltbld', 13:47:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:35 INFO - 'LOGNAME': 'cltbld', 13:47:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:35 INFO - 'MOZ_NO_REMOTE': '1', 13:47:35 INFO - 'NO_EM_RESTART': '1', 13:47:35 INFO - 'PAGER': '/bin/cat', 13:47:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:35 INFO - 'PWD': '/builds/slave/test', 13:47:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:35 INFO - 'SHELL': '/bin/bash', 13:47:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IaDvfO0o3c/Listeners', 13:47:35 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 13:47:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:35 INFO - 'USER': 'cltbld', 13:47:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:35 INFO - 'XPC_FLAGS': '0x0', 13:47:35 INFO - 'XPC_SERVICE_NAME': '0', 13:47:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:35 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)) 13:47:35 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)) 13:47:35 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)) 13:47:35 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)) 13:47:35 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)) 13:47:35 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)) 13:47:35 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)) 13:47:35 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)) 13:47:35 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)) 13:47:35 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)) 13:47:35 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)) 13:47:35 INFO - Cleaning up... 13:47:35 INFO - Return code: 0 13:47:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:47:35 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')]} 13:47:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:47:35 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')]} 13:47:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:47:35 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 0x106ae8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106aeb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a235b5880>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1068c6d30>, '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 0x1067eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068bbbe8>, '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.uXNOuyS30e/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.IaDvfO0o3c/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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 13:47:35 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 13:47:35 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 13:47:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uXNOuyS30e/Render', 13:47:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:35 INFO - 'HOME': '/Users/cltbld', 13:47:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:35 INFO - 'LOGNAME': 'cltbld', 13:47:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:35 INFO - 'MOZ_NO_REMOTE': '1', 13:47:35 INFO - 'NO_EM_RESTART': '1', 13:47:35 INFO - 'PAGER': '/bin/cat', 13:47:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:35 INFO - 'PWD': '/builds/slave/test', 13:47:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:35 INFO - 'SHELL': '/bin/bash', 13:47:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IaDvfO0o3c/Listeners', 13:47:35 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 13:47:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:35 INFO - 'USER': 'cltbld', 13:47:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:35 INFO - 'XPC_FLAGS': '0x0', 13:47:35 INFO - 'XPC_SERVICE_NAME': '0', 13:47:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:47:36 INFO - Cleaning up... 13:47:36 INFO - Return code: 0 13:47:36 INFO - Running post-action listener: _resource_record_post_action 13:47:36 INFO - Running post-action listener: _start_resource_monitoring 13:47:36 INFO - Starting resource monitoring. 13:47:36 INFO - [mozharness: 2017-05-15 20:47:36.074183Z] Finished create-virtualenv step (success) 13:47:36 INFO - [mozharness: 2017-05-15 20:47:36.075154Z] Running install step. 13:47:36 INFO - Running pre-action listener: _resource_record_pre_action 13:47:36 INFO - Running main action method: install 13:47:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:47:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:47:36 INFO - Reading from file tmpfile_stdout 13:47:36 INFO - Detecting whether we're running mozinstall >=1.0... 13:47:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:47:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:47:36 INFO - Reading from file tmpfile_stdout 13:47:36 INFO - Output received: 13:47:36 INFO - Usage: mozinstall [options] installer 13:47:36 INFO - Options: 13:47:36 INFO - -h, --help show this help message and exit 13:47:36 INFO - -d DEST, --destination=DEST 13:47:36 INFO - Directory to install application into. [default: 13:47:36 INFO - "/builds/slave/test"] 13:47:36 INFO - --app=APP Application being installed. [default: firefox] 13:47:36 INFO - mkdir: /builds/slave/test/build/application 13:47:36 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'] 13:47:36 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 13:47:51 INFO - Reading from file tmpfile_stdout 13:47:51 INFO - Output received: 13:47:51 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 13:47:51 INFO - Running post-action listener: _resource_record_post_action 13:47:51 INFO - [mozharness: 2017-05-15 20:47:51.269808Z] Finished install step (success) 13:47:51 INFO - [mozharness: 2017-05-15 20:47:51.269924Z] Running run-tests step. 13:47:51 INFO - Running pre-action listener: _resource_record_pre_action 13:47:51 INFO - Running main action method: run_tests 13:47:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:47:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:47:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:47:51 INFO - 2017-05-15 13:47:51.284 screenresolution[2302:17583] starting screenresolution argv=screenresolution get 13:47:51 INFO - 2017-05-15 13:47:51.287 screenresolution[2302:17583] Display 0: 1600x1200x32@60 13:47:51 INFO - 2017-05-15 13:47:51.293 screenresolution[2303:17585] starting screenresolution argv=screenresolution list 13:47:51 INFO - Available Modes on Display 0 13:47:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:47:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:47:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:47:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:47:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:47:51 INFO - Intel Iris: 13:47:51 INFO - Chipset Model: Intel Iris 13:47:51 INFO - Type: GPU 13:47:51 INFO - Bus: Built-In 13:47:51 INFO - VRAM (Dynamic, Max): 1536 MB 13:47:51 INFO - Vendor: Intel (0x8086) 13:47:51 INFO - Device ID: 0x0a2e 13:47:51 INFO - Revision ID: 0x0009 13:47:51 INFO - Displays: 13:47:51 INFO - Display: 13:47:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:47:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:47:51 INFO - Display Serial Number: 1600x1200 60 13:47:51 INFO - Main Display: Yes 13:47:51 INFO - Mirror: Off 13:47:51 INFO - Online: Yes 13:47:51 INFO - Rotation: Supported 13:47:51 INFO - Return code: 0 13:47:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:47:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:47:51 INFO - Python 2.7.3 13:47:51 INFO - Return code: 0 13:47:51 INFO - grabbing minidump binary from tooltool 13:47:51 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')]} 13:47:51 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 0x1068c6d30>, '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 0x1067eddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1068bbbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:47:51 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 13:47:51 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 13:47:51 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 13:47:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:47:51 INFO - Return code: 0 13:47:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:47:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:47:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:47:51 INFO - ENV: RUST_BACKTRACE is now 1 13:47:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:47:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:47:51 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/Hi-PxhAQRTe_z90JbXNwLQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0346', '--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 13:47:51 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/Hi-PxhAQRTe_z90JbXNwLQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0346 --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 13:47:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uXNOuyS30e/Render', 13:47:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:47:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:47:51 INFO - 'HOME': '/Users/cltbld', 13:47:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:47:51 INFO - 'LOGNAME': 'cltbld', 13:47:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:47:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:47:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:47:51 INFO - 'MOZ_NO_REMOTE': '1', 13:47:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:47:51 INFO - 'NO_EM_RESTART': '1', 13:47:51 INFO - 'PAGER': '/bin/cat', 13:47:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:47:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:47:51 INFO - 'PWD': '/builds/slave/test', 13:47:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:47:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:47:51 INFO - 'RUST_BACKTRACE': '1', 13:47:51 INFO - 'SHELL': '/bin/bash', 13:47:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IaDvfO0o3c/Listeners', 13:47:51 INFO - 'TMPDIR': '/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/', 13:47:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:47:51 INFO - 'USER': 'cltbld', 13:47:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:47:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:47:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:47:51 INFO - 'XPC_FLAGS': '0x0', 13:47:51 INFO - 'XPC_SERVICE_NAME': '0', 13:47:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:47:51 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/Hi-PxhAQRTe_z90JbXNwLQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0346', '--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 13:47:51 INFO - mozversion application_buildid: 20170515124824 13:47:51 INFO - mozversion application_changeset: 9bef2dfbd584c0f3ae52953bfc3aed64e308960d 13:47:51 INFO - mozversion application_display_name: Firefox 13:47:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:47:51 INFO - mozversion application_name: Firefox 13:47:51 INFO - mozversion application_remotingname: firefox 13:47:51 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 13:47:51 INFO - mozversion application_vendor: Mozilla 13:47:51 INFO - mozversion application_version: 54.0 13:47:51 INFO - mozversion platform_buildid: 20170515124824 13:47:51 INFO - mozversion platform_changeset: 9bef2dfbd584c0f3ae52953bfc3aed64e308960d 13:47:51 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 13:47:51 INFO - mozversion platform_version: 54.0 13:47:51 INFO - using testdate: 1494881271 13:47:51 INFO - actual date: 1494881271 13:47:51 INFO - starting webserver on 'localhost:49670' 13:47:51 INFO - SUITE-START | Running 2 tests 13:47:51 INFO - TEST-START | dromaeo_css 13:47:51 INFO - operating with platform_type : mac_ 13:47:51 INFO - Initialising browser for dromaeo_css test... 13:47:51 INFO - TEST-INFO | started process 2313 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmpUKwaCN/profile http://localhost:49670/getInfo.html) 13:47:54 INFO - PROCESS | 2313 | 2017-05-15 13:47:54.466 plugin-container[2314:17725] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e4b, name = 'com.apple.tsm.portname' 13:47:54 INFO - PROCESS | 2313 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:47:54 INFO - PROCESS | 2313 | 2017-05-15 13:47:54.467 plugin-container[2314:17725] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c33, name = 'com.apple.CFPasteboardClient' 13:47:54 INFO - PROCESS | 2313 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:47:54 INFO - PROCESS | 2313 | 2017-05-15 13:47:54.467 plugin-container[2314:17725] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:47:54 INFO - PROCESS | 2313 | __metrics Screen width/height:1600/1200 13:47:54 INFO - PROCESS | 2313 | colorDepth:24 13:47:54 INFO - PROCESS | 2313 | Browser inner width/height: 1024/689 13:47:54 INFO - PROCESS | 2313 | __metrics 13:47:54 ERROR - PROCESS | 2313 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 13:47:55 INFO - TEST-INFO | 2313: exit 0 13:47:55 INFO - Browser initialized. 13:47:55 INFO - Running cycle 1/1 for dromaeo_css test... 13:47:55 INFO - TEST-INFO | started process 2316 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmpUKwaCN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:47:57 INFO - PROCESS | 2316 | 2017-05-15 13:47:57.419 plugin-container[2317:17869] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 13:47:57 INFO - PROCESS | 2316 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:47:57 INFO - PROCESS | 2316 | 2017-05-15 13:47:57.420 plugin-container[2317:17869] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b13, name = 'com.apple.CFPasteboardClient' 13:47:57 INFO - PROCESS | 2316 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:47:57 INFO - PROCESS | 2316 | 2017-05-15 13:47:57.420 plugin-container[2317:17869] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:48:07 INFO - PROCESS | 2316 | RSS: Main: 174100480 13:48:07 INFO - PROCESS | 2316 | 13:51:10 INFO - PROCESS | 2316 | Cycle 1(1): loaded http://localhost:49670/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49670/tests/dromaeo/cssquery-ext.html) 13:51:11 INFO - PROCESS | 2316 | RSS: Main: 189124608 13:51:11 INFO - PROCESS | 2316 | 13:54:14 INFO - PROCESS | 2316 | Cycle 1(1): loaded http://localhost:49670/tests/dromaeo/cssquery-ext.html (next: http://localhost:49670/tests/dromaeo/cssquery-jquery.html) 13:54:14 INFO - PROCESS | 2316 | RSS: Main: 202838016 13:54:14 INFO - PROCESS | 2316 | 13:57:16 INFO - PROCESS | 2316 | Cycle 1(1): loaded http://localhost:49670/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49670/tests/dromaeo/cssquery-mootools.html) 13:57:17 INFO - PROCESS | 2316 | RSS: Main: 204324864 13:57:17 INFO - PROCESS | 2316 | 14:00:19 INFO - PROCESS | 2316 | Cycle 1(1): loaded http://localhost:49670/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49670/tests/dromaeo/cssquery-prototype.html) 14:00:19 INFO - PROCESS | 2316 | RSS: Main: 207405056 14:00:19 INFO - PROCESS | 2316 | 14:03:22 INFO - PROCESS | 2316 | Cycle 1(1): loaded http://localhost:49670/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49670/tests/dromaeo/cssquery-yui.html) 14:03:23 INFO - PROCESS | 2316 | RSS: Main: 209911808 14:03:23 INFO - PROCESS | 2316 | 14:06:26 INFO - PROCESS | 2316 | Cycle 1(1): loaded http://localhost:49670/tests/dromaeo/cssquery-yui.html (next: http://localhost:49670/tests/dromaeo/cssquery-dojo.html) 14:06:26 INFO - PROCESS | 2316 | RSS: Main: 208064512 14:06:26 INFO - PROCESS | 2316 | 14:06:26 INFO - PROCESS | 2316 | __start_tp_report 14:06:26 INFO - PROCESS | 2316 | _x_x_mozilla_page_load 14:06:26 INFO - PROCESS | 2316 | _x_x_mozilla_page_load_details 14:06:26 INFO - PROCESS | 2316 | |i|pagename|runs| 14:06:26 INFO - PROCESS | 2316 | |0;dojo.html;3212.630547487039;3595.6044835068124;3630.0743310455837;3642.471841582972;3675.558932928049;6167.854394258927;6233.812985610428;6269.090981807635;6298.968505157479;6299.496040316775;5116.386033675954;6174.536909731777;6179.196704428418;6200.007998720205;6209.192804935364;5539.889202215953;5607.859803504904;5610.915836262459;5611.102223644218;5679.4036626154275;5691.630044047144;5694.886102277952;5702.942970570303;5703.971480142614;5763.337216220126;5494.340679118512;5502.5597952163735;5679.460451257124;5684.346300175471;5690.487856092951;5585.301837270341;5592.944070559303;5608.55131589476;5608.943910560883;5617.129344951519;5449.264349312832;5614.326280846324;5640.520555752766;5647.491725744661;5709;6216.844578885519;6243.906341404882;6248.843778905518;6269.529785266123;6302.023186406114;6306.684665766739;6414.8717025659225;6531.8973487593485;6594.868102637968;6611.371919667624;6510.772122975721;6526.445252153572;6570.737170513174;6602.1087153234175;6650.135482387307;6286.214223222098;6330.556861019776;6345.6827158642345;6356.904646430307;6368.331325210833;5858.828234353146;6870.31296870309;6893.551919125241;6904.930950690529;6907.34380233887;5352.9732351338;6292.370762923762;6344.238691357066;6411.422971932455;6470.45001174896;6683.699233534503;6702.229243637044;6713.462922966151;6721.059051732761;6728.226253980757;6539.901901471481;6567.934320656732;6607.0419789131265;6611.570247933869;6615.536912416181;5584.888302233932;6226.62640241587;6345.0482427636225;6588.769393071219;6617.139771829728;5236.16221404581;5305.44292849253;5339.546138578265;5354.732263386815;5378.273933019071;4709.693869898446;4717.127331443625;4729.385179926588;4778.617710583145;4783.593394561502;6238.563300569007;6364.936350636527;6366.013267943476;6368.42684158428;6397.6801159941815;6400.135981642512;6678.83302917431;6718.932810671929;6720.495962802809;6742.258351581322;6245.375462453809;6249.500039996789;6291.1192433059405;6365.522585806083;6402.6158430494315;5905.202797622352;5918.585699001029;5944;5956.136360227742;5977.910331345033;6843.931560684329;6866.004429357715;6895.172579290517;6901.1718593769065;6918.481113916477;5373.194020896741;6383.489320854228;6484.513661475503;6495.253045899593;6547.083408322934;5511.283533140666;6893.931060689329;6903.689333979883;6928.307169283032;6929.653517324013;6570.342965703391;6642;6655.500837437115;6656.500762442739;6659.467242620675;6414.583052101599;6484.059811327425;6578.111954886029;6682.9331706682315;6733.831654208684;6444.742210311535;6507.219133703986;6511.674416279262;6521.9673901630185;6525.771597994048;6506.316836732264;6524.2823289439075;6525.119108920235;6527.869442611216;6528.151340325917;5444.3194600674915;6233.781817636361;6274.968625156845;6280.277768056732;6334.9366506334345;6299.590526615755;6456.192975878015;6556.114924485133;6568.770093046721;6572.901406479003;6379.489640828815;6382.7766028190645;6398.616083035033;6400.807975760735;6434.581752186093 14:06:26 INFO - PROCESS | 2316 | |1;ext.html;693.9583624982502;776.2431629161565;835.2107258640585;969.2052516936116;982.1602529836994;32182.51726224103;38689;39405.60594394055;39936.00159995998;39953.80023099881;38474.615253847456;38572.421413678814;38573.228535429276;38607.42088868669;38704.80647596758;8240.464369815958;11153.888461115406;11405.942970285178;11551.075913926908;11586.536538538448;11142;11241.943790281037;11263.54945802167;11280.943595282011;11290.548378064868;11229.60696375627;11254.774904501905;11260.831087533694;11266.605668801594;11287.435628218598;11196.66410007697;11220.887791122148;11230;11234.045106165986;11242.269252498601;10904.127669786398;10971.341719496855;10985.285956412887;10994.560217591286;11021.889781102167;33650.326993460236;33814.32371352559;33958;33963.15092122717;33989.490157647655;3313.7349012078976;3324.96675033249;3905.023744063984;3916.8629097981434;3974.82113304902;24806.627900581505;25243;25702.614460783105;27260.59109113365;27307;3108.364498314345;3181.840907954593;3266.8203248821305;3319.336132773455;3389.0171850163424;375662;376977.1151144254;377873;378626;379460;44661.55338446574;44869.10261794746;45177.774111129234;45275.54724452779;45386.546134538235;45506.54493455089;45970.54029459663;46430.07139857184;46486;46543.06913861704;14615.050021748551;14642.707145857023;14725.779113313094;14733.484328048682;14770.335334909954;12454;12634.178778379375;12665.556705515262;12673.936630316974;12681.619551413472;10705.946470267754;11956.641300760992;11965.760684786255;11966.64100076999;12039.518419263131;5082.542571168538;5600.215969764236;5727.8568035799435;5777.2379424276;5807.216025836543;1131.3990703155107;1154.4458659843322;1159.5999380213814;1166.8191430328313;1192.1059205595802;26739.732602674114;26881.865590672314;27141.778619962148;27163.86418067897;27244.318892027462;1258.213616489694;1283.1787655900232;1295.533607901155;1309.155594641451;1311.0560396514495;13540.322983850769;13873.653158670912;14052.85947140536;14114.50599229022;14146.717065658835;194461.08308375385;195302.0469795284;195440;195724;196106.0194699046;48014.03971920612;48718.512814872105;56030;56560;56704;1433.5269361110809;1457.3878970832277;1474.7124310759514;1477.2392218007735;1478.4973109142943;21304.147834086463;26018.21945341567;26216.8689156553;26628.201153965412;27099.322516936445;40094.1981160381;40211.396829047;40400.79799600983;40434.595654043085;40445;487910;488012;488384;488719;490675;23094.307170784905;28132.71867281383;28818;28841.42317153687;29069.7093029067;31633.841830790698;32264.83867580741;32451.837740811145;32578;32592.185195370304;22019.449513762283;26195.476090478463;28837.56743648805;28840.423191536473;29068.7093129066;28610.712517936325;28841.134765957057;29021.564676529448;29304.12087637374;29642.85178574093;29727.662255199;31632.841835790674;33408.665913340556;33582.832085839415;33630.66369336275;25470.235892923243;28019.31884086961;29138.417231655672;29544.852275739344;29628.259293517833;23011.654825177302;23161.189358372376;23510;23543.529129416973;24258.51482970366 14:06:26 INFO - PROCESS | 2316 | |2;jquery.html;424.4884913679017;432.80307460105655;439.27738869559573;447.7201748906931;453.52153478080623;20329;21424.571508569818;22648.320550383472;22807.859607019625;22919.42701432463;22607.886960565214;23254.41863953402;23343.8328083596;23574.764252357472;24089.87955060222;6748.39264466198;6844.2129155147295;6996.930030699691;7263.273672632721;7273.7090516379285;6143.385661433843;7069.187043490013;7108.60902650354;7176.138863336381;7199.820004499903;7150.499465037449;7521.849563008736;7852.685892564291;7994.960025199865;8007.559584222866;7738.961305193466;7898.60506974649;7961.920380796176;8001.359891208689;8055.355571554262;7482.85034299311;7693.038370203724;7776.494527855728;7781.494202876795;7788.961055194744;21359.466013349636;21793;26737.19788406351;27415.588766168523;27704;2955.970440295591;2962.4519463899032;2964.6442426908693;2979.1360505453395;2988.282812125096;15758.76361854573;15938.28277727505;16363.181840908026;16978.23597938096;17016.82983170165;1704.5397742609425;1957.5889063296725;2075.3670130610158;2091.362134548962;2111.989440052805;137080.31459842736;138701;152392.2380388091;177718.99999999872;178588;17809.84236024654;20239.392818215478;20261.696074558895;21585.784142158376;21606.891965540388;33211.16972075718;33611.83194084014;33837.66162338345;33897.66102338945;33911.15222119418;10881;10892.782144357068;11101.278416902875;11342.886571134182;11437.199396042364;7981;9088.81822363549;9254.583543740548;9358.391704539183;9395.577199025924;9408.811823763486;9764.609415623438;9776.853347199798;9842.261830362608;9859.112679858847;4189.601987811153;4203.222403855297;4233.97883010583;4241.97879010603;4251.404803327537;997.8602995580625;1027.6814187601867;1038.3562191441204;1054.9661331894633;1059.761553650438;17474.82525174732;21558.461038474165;22030;22065.889670551544;22122;1047.9056884880397;1051.30613794895;1077.3589714120087;1090;1092.7814437112609;10493.842592361121;11155.944220279009;11310.4910279036;11333.773324533464;11362.318260904372;636409;640308;641456;705283;778202;162794.1860290691;163323;164727;169046;170324.1483792598;1340.8793208611078;1350.6758377989213;1395.5115709501592;1399.54514782694;1407.3737186951707;14261.786073208703;20700.654457460216;21181.6822747662;21554.92225388693;21589.596676215995;49472.50527494679;64039;67070.32929670835;67459.66270168617;69415;67497.66251168713;90446;91290;93025.53487232521;94652.05347946432;9659.275554333593;11724.296542207494;11838.763224735629;11850.940745296219;12026.579069732867;12665.113442059335;12887.355632218165;12897.968162547162;12976.545820896223;13226.338683066;11054.944725276322;11101.5559377624;11206.573403505407;11341.829872552084;11515.654530364103;8185.631646575799;10173.593056277667;10213.08082272599;10330.431826249629;10385.896141038493;11212;11239.381833999209;11409;11472.254303470248;11477.885221148015;10525.894741052798;10843;11453.828192576952;11528.827067593826;11861.881381186422;10771.946140269249;10948.507317170586;11043.779124417628;11136;11347.886521134684 14:06:26 INFO - PROCESS | 2316 | |3;mootools.html;1498.97751533727;1621.221813529505;1639.7128254320362;1652.73556231003;1653.3056116431098;11106.6112686056;11455.427228638577;11809.586664466757;11823.822642660367;11852.82220766688;11528.769424611502;11579;11690.766184676302;11727.53089876406;11746.060315174787;2466.740992195816;2488;2503.8748062596796;2505.0856437400307;2510.8619025953567;2444.168982545934;2504.3989442533752;2512.560301947161;2516.8867400967;2518.848869067853;2169.566086782642;2453.264020793764;2471.6910386201725;2478.7273399925994;2502.8998840046374;2447.9265622031367;2448.485817978227;2455.1161581830615;2480.5038992201544;2480.5659009673236;2466.666999955019;2480.268320845355;2488.365466805971;2488.552060629086;2489.9377515562255;9596.760080997961;11339.829902551468;11550.960413562736;11561.479733412016;11603.361815100166;5992.550558708071;6095.664738439384;6169.938300616981;6178.38216178383;6188.947878860605;3519.419295816187;3536.416491278936;3559.6440355964196;3792.753471024402;3852.5184351956004;1078.417654466587;1248.400767631533;1257.2833484913604;1310.0174868848364;1310.4168644953;14318;14648.853511464962;15201.087934723952;15934.840651593451;16090.83909160905;4220.53574106848;4856.198727210041;4954.455009948902;4962.305277261186;4991.151504244287;12030.879691202976;12232.633021009384;12252.81620775689;12258.877411225953;12266.509339626493;5869.6771677558145;6045.153678485016;6063.54523410746;6080.179175811209;6116.082587611805;5388.030154572136;5548.472895075042;5556.77772889088;5599.944000559943;5640.153976903415;3950.802459877052;4075.1849630074103;4118.649914757219;4174.519930208065;4189.937150942738;1089.9073578745738;1091.2961140064615;1093.0654290581438;1102.08526922654;1110.7833972375308;925.4729696001651;937.2174234514121;938.7230766923774;943.5518128888723;985.2019863910256;6838.897416538756;6870.175578930461;6878.931210687929;7126.928730712731;7174.856502869913;993.9254555908336;1042.181887218545;1042.890496497872;1051.106559424483;1064.2177999170603;4353.216421044242;4364.8254069837485;4374.693771436033;4379.978100109479;4502.887427814266;13697.794533082011;14181;14244.359003844851;14275;14312.785308220386;10821.891781082246;13692.383842727102;13920.164790112625;14042;14150.221737804519;10294.073533381881;10514.106300964504;10531.526081326512;10541.525631346454;10599.682009539727;6830.760923367857;7090.929090709234;7131.750388736369;7217.242189570229;7244.420446364384;12421.813672794735;12529.874701252871;12696.492140314283;12751.979841612834;12814;11619.186656933933;12228.02175825914;12862.485500579873;12917.806232906702;13127.409266583218;9495.85841129835;9543.236541076836;9568.995255498072;9604.85592716096;9672.274579406432;10458.215633827342;10475;10580.41807700584;10589.4705264738;10596.31123976939;8959.910400895908;8988.910110899069;9038.141376569216;9132.954335228282;9193.172614464731;7392.149902761252;8820.25027872638;8934.329925255503;8949.926008878976;8957.686480973134;7839.294463498312;8012.799680008046;8133.715319963773;8159;8176.754697359089;6068.908966365596;6079.544034197541;6082.330943596289;6125.448709616156;6196.876062478816;5361.812336568201;5911.320198177093;6064.090386442069;6247.812565623039;6250.9062364065485 14:06:26 INFO - PROCESS | 2316 | |4;prototype.html;362.65185421994886;377.45834727166516;389.09535330356925;392.3017029687158;401.2636811450987;4969.577585905198;5352.785888564463;5497.251374312844;5508.476694714006;5529.391766905642;4628.4677262114865;4935.6545041847085;5127.33344665193;5301.787928482866;5346.331708536433;3772.4727379899746;4375.278079116942;4416.536263692315;4432.578905004019;4459;3564.7326450516184;3790.4124860646643;4102.323116685743;4446.777136287515;4474.060447306069;3822.636849499293;4055.614716601918;4251.978740106295;4320.805563749623;4358.694891357591;3578.570571531433;3888.6500214980506;4061.6953728470335;4367.454068241485;4423.712458690175;3476.3221171871496;4083.2650122977852;4090.611391917763;4100.712950093494;4108.5275193352845;3920.2747491714126;5054.418741844698;5083.288339632454;5124.20652771527;5160.535551800356;4378.934315985231;4842.878928026827;4862.759996200982;5098.940635618636;5104.897902041976;5220.92168617471;5634.549236061105;5682.0680968470915;5692.260006199209;5713.542916566665;3668.926621467582;3975.4235635832642;4274.337477690963;4308.870733877958;4480.574345437179;5163.561097306733;5191.377034755816;5473.452654734535;5747.930744931821;5862.501687356537;4028.8992775180855;4070.5725898780793;4235.851745188966;4425.535318791545;4604.58558729716;4690.249560070441;4697.342372067913;4739.857804265877;4741.5021422750115;4763.404574428197;4019.2363450944226;4190.742777166838;4579.267317229227;5028.547430731252;5106.080905437057;4758.738269395217;5134.871628209325;5586.581006424534;5599.8240369522855;5650.858728531737;1392.7771556550904;1445.2990299704634;1473.4823132173879;1529.831718510963;1596.888217824741;1097.3361116524547;1112.7273817914663;1196.3539688568076;1208.033573141501;1229.64738787335;4571.702839315434;4748.6438517111355;4750;4815.036992601494;4990.575801056878;4942.604591632661;5126.179811230185;5421.322334708161;5610.775568977277;5875.20684707559;4389.561043895585;5259.552938000313;5266.735983363965;5268.578513718893;5284.9958507883375;2662.440887413626;2759.268793769653;3010.533367328024;3043.969560304413;3044.58898048765;5010.799568017239;5431;5771.624844385102;5879;6134.877302454015;5433.673979561239;5549.528290095306;5652.604317697804;5917.940820591825;5975.910361344583;5146.356705411824;5407.567394608501;5810.09943458773;5977.581569290108;6124.85630029495;4902.117618828645;5186.844394668166;5629.88740225185;5857.677827719516;5875.559333050124;4787.880302992453;4833.637477189154;5177.119889618699;5208.322918020633;5804.76780928775;5343.492368225013;5455.645383050089;5534.308211473566;5849.415058494286;6092.324610731906;4217.957820421757;4419.237976264834;4588.931166032446;4626.514216007271;4651.720896746206;4924.606031517542;5283;5707.942920570741;5912.526997840077;5955.225820643289;5642.025291148066;5686.77252909879;5697.3448053474385;5714.714264286686;5731.799387021434;4988.501149884983;5038.271678957618;5515.917261241165;5698.715064246854;5719.656820590778;3931.194105208368;4912.705237685607;4963.890346602751;5326.6537675050995;5574.777008919597;4912.606991440604;4976.477469865612;5408.350453111681;5679.801206957736;5765.33210829604;4576.313552967082;4721.315409265706;5166.509181627739;5480.808171713971;5540.146824385525 14:06:26 INFO - PROCESS | 2316 | |5;yui.html;728.9271072892708;744.1962680305271;758.7609902880592;825.0470706334186;875.7110153649296;15826.604334891634;16906.40827571032;16959.74560381592;17010.659786804255;17145.142742862852;13011;15579.610509737262;15770.684586308267;15777.763333549978;15778.21108944551;843.5529169540154;965.9130678238957;999.9350042247268;1007.5919252702668;1010.8685870836817;857.4083882121347;1008.6620981971012;1014.5383850348111;1014.6854475112701;1015.4516561057058;1057.9418132002736;1058.2380685906135;1059.099765199582;1060.7083052160679;1061.2093989977477;1044.6552637629563;1052.5105825791006;1052.9105026072793;1054.1672079057535;1055.530289021386;776.9223077692242;777.1606664802;777.3780975219858;777.801660576555;779.123486078162;16263.268152933146;16711.83288167115;16771;17006.3197472101;17117;12055.698607534794;14738.631534211623;14810.629734256621;14819.40722371104;14949.327280272306;11519.94240028803;11840.171188016842;11878.762424751456;11880.584179553674;11939.34333611651;3722.4355675204856;3830.827612757405;3840.711946603988;3847.461355410245;3864.845406183749;115095.42452287686;116334.83665163325;118606;119324;119506.40246798798;11150.49822757978;11341.489632966537;11359.204855660024;11566.826497602542;11934.164608477497;23048.88475557645;23386.18148364799;23672.408189795395;23707.40731481727;23761.524769504515;6760.7295708172105;6896.7586134486055;8004.719834805754;8094.31198348147;8153.1439198884445;4567.566081222279;4582.610478109332;4635.768211589407;4637.837675681402;4703.952960470351;3662.066173125836;3735.5704094029534;3763.5860055393887;3766.3785475396253;3777.338965680995;531.3703261634975;566.8441178675897;600.3066458240739;673.1585518102372;680.5338343234899;712.654362634122;726.7020521586124;731.3198725185522;733.2887099513464;750.9286617771303;10531.262811603268;10583.31208471447;10645.148388129086;10827.78344433107;11112.16658750597;697.2325155730301;716.9964150179215;727.7380143148463;751.3463286940397;753.5817621220166;5096.184610462308;5152.639315247897;5201.271821944931;5206.297149884717;5208.270842082112;89064;90962.54518727497;100885;101167.49416252872;101356.98643013476;63411.04883426752;78626;78703.60648196722;80128.59935700284;81918;42343;66434.6713065736;70319.64840175766;74419;76729.23270767221;10249.846252306072;10757.408342541235;11196;11247.437628118725;11331.376774277494;49980.75048123826;50610;50683.74658126686;51479.485205147466;51756.48243517667;62183;62351;62871.37128628655;63339.73320533655;63515.364846350945;11416.486258118572;12888.48446062147;13339.19964802115;13372.465101396225;13457.663558411117;14859;15197;15205.771913421086;15557.45548905783;15727.292271847566;13768.173909565458;13853.37659805291;14145.646358841112;14631.92684036573;14804.333804978587;13707.794383084462;14226.502072427415;14625.195614241318;14791.260436977893;14831.629209269855;13409;13516.459341626225;14223.288835558387;14449.277536123362;14593.197374144524;9947.353422027545;10404.68785936423;10644.520996555018;10894.237403381687;10994.120470362192;11167.329960202414;11403.600873969372;11437;11633.767324653629;11667.416629168678 14:06:26 INFO - PROCESS | 2316 | __end_tp_report 14:06:26 INFO - PROCESS | 2316 | __start_cc_report 14:06:26 INFO - PROCESS | 2316 | _x_x_mozilla_cycle_collect,399 14:06:26 INFO - PROCESS | 2316 | __end_cc_report 14:06:26 INFO - PROCESS | 2316 | __startTimestamp1494882386596__endTimestamp 14:06:26 INFO - PROCESS | 2316 | 14:06:26 INFO - PROCESS | 2316 | ------- Summary: start ------- 14:06:26 INFO - PROCESS | 2316 | Number of tests: 6 14:06:26 INFO - PROCESS | 2316 | 14:06:26 INFO - PROCESS | 2316 | [#0] dojo.html Cycles:165 Average:6131.89 Median:6345.68 stddev:684.98 (10.8%) stddev-sans-first:647.66 14:06:26 INFO - PROCESS | 2316 | Values: 3212.6 3595.6 3630.1 3642.5 3675.6 6167.9 6233.8 6269.1 6299.0 6299.5 5116.4 6174.5 6179.2 6200.0 6209.2 5539.9 5607.9 5610.9 5611.1 5679.4 5691.6 5694.9 5702.9 5704.0 5763.3 5494.3 5502.6 5679.5 5684.3 5690.5 5585.3 5592.9 5608.6 5608.9 5617.1 5449.3 5614.3 5640.5 5647.5 5709.0 6216.8 6243.9 6248.8 6269.5 6302.0 6306.7 6414.9 6531.9 6594.9 6611.4 6510.8 6526.4 6570.7 6602.1 6650.1 6286.2 6330.6 6345.7 6356.9 6368.3 5858.8 6870.3 6893.6 6904.9 6907.3 5353.0 6292.4 6344.2 6411.4 6470.5 6683.7 6702.2 6713.5 6721.1 6728.2 6539.9 6567.9 6607.0 6611.6 6615.5 5584.9 6226.6 6345.0 6588.8 6617.1 5236.2 5305.4 5339.5 5354.7 5378.3 4709.7 4717.1 4729.4 4778.6 4783.6 6238.6 6364.9 6366.0 6368.4 6397.7 6400.1 6678.8 6718.9 6720.5 6742.3 6245.4 6249.5 6291.1 6365.5 6402.6 5905.2 5918.6 5944.0 5956.1 5977.9 6843.9 6866.0 6895.2 6901.2 6918.5 5373.2 6383.5 6484.5 6495.3 6547.1 5511.3 6893.9 6903.7 6928.3 6929.7 6570.3 6642.0 6655.5 6656.5 6659.5 6414.6 6484.1 6578.1 6682.9 6733.8 6444.7 6507.2 6511.7 6522.0 6525.8 6506.3 6524.3 6525.1 6527.9 6528.2 5444.3 6233.8 6275.0 6280.3 6334.9 6299.6 6456.2 6556.1 6568.8 6572.9 6379.5 6382.8 6398.6 6400.8 6434.6 14:06:26 INFO - PROCESS | 2316 | 14:06:26 INFO - PROCESS | 2316 | [#1] ext.html Cycles:180 Average:48936.46 Median:28714.36 stddev:99649.68 (347.0%) stddev-sans-first:99863.39 14:06:26 INFO - PROCESS | 2316 | Values: 694.0 776.2 835.2 969.2 982.2 32182.5 38689.0 39405.6 39936.0 39953.8 38474.6 38572.4 38573.2 38607.4 38704.8 8240.5 11153.9 11405.9 11551.1 11586.5 11142.0 11241.9 11263.5 11280.9 11290.5 11229.6 11254.8 11260.8 11266.6 11287.4 11196.7 11220.9 11230.0 11234.0 11242.3 10904.1 10971.3 10985.3 10994.6 11021.9 33650.3 33814.3 33958.0 33963.2 33989.5 3313.7 3325.0 3905.0 3916.9 3974.8 24806.6 25243.0 25702.6 27260.6 27307.0 3108.4 3181.8 3266.8 3319.3 3389.0 375662.0 376977.1 377873.0 378626.0 379460.0 44661.6 44869.1 45177.8 45275.5 45386.5 45506.5 45970.5 46430.1 46486.0 46543.1 14615.1 14642.7 14725.8 14733.5 14770.3 12454.0 12634.2 12665.6 12673.9 12681.6 10705.9 11956.6 11965.8 11966.6 12039.5 5082.5 5600.2 5727.9 5777.2 5807.2 1131.4 1154.4 1159.6 1166.8 1192.1 26739.7 26881.9 27141.8 27163.9 27244.3 1258.2 1283.2 1295.5 1309.2 1311.1 13540.3 13873.7 14052.9 14114.5 14146.7 194461.1 195302.0 195440.0 195724.0 196106.0 48014.0 48718.5 56030.0 56560.0 56704.0 1433.5 1457.4 1474.7 1477.2 1478.5 21304.1 26018.2 26216.9 26628.2 27099.3 40094.2 40211.4 40400.8 40434.6 40445.0 487910.0 488012.0 488384.0 488719.0 490675.0 23094.3 28132.7 28818.0 28841.4 29069.7 31633.8 32264.8 32451.8 32578.0 32592.2 22019.4 26195.5 28837.6 28840.4 29068.7 28610.7 28841.1 29021.6 29304.1 29642.9 29727.7 31632.8 33408.7 33582.8 33630.7 25470.2 28019.3 29138.4 29544.9 29628.3 23011.7 23161.2 23510.0 23543.5 24258.5 14:06:26 INFO - PROCESS | 2316 | 14:06:26 INFO - PROCESS | 2316 | [#2] jquery.html Cycles:180 Average:41844.14 Median:21598.24 stddev:114967.13 (532.3%) stddev-sans-first:115247.58 14:06:26 INFO - PROCESS | 2316 | Values: 424.5 432.8 439.3 447.7 453.5 20329.0 21424.6 22648.3 22807.9 22919.4 22607.9 23254.4 23343.8 23574.8 24089.9 6748.4 6844.2 6996.9 7263.3 7273.7 6143.4 7069.2 7108.6 7176.1 7199.8 7150.5 7521.8 7852.7 7995.0 8007.6 7739.0 7898.6 7961.9 8001.4 8055.4 7482.9 7693.0 7776.5 7781.5 7789.0 21359.5 21793.0 26737.2 27415.6 27704.0 2956.0 2962.5 2964.6 2979.1 2988.3 15758.8 15938.3 16363.2 16978.2 17016.8 1704.5 1957.6 2075.4 2091.4 2112.0 137080.3 138701.0 152392.2 177719.0 178588.0 17809.8 20239.4 20261.7 21585.8 21606.9 33211.2 33611.8 33837.7 33897.7 33911.2 10881.0 10892.8 11101.3 11342.9 11437.2 7981.0 9088.8 9254.6 9358.4 9395.6 9408.8 9764.6 9776.9 9842.3 9859.1 4189.6 4203.2 4234.0 4242.0 4251.4 997.9 1027.7 1038.4 1055.0 1059.8 17474.8 21558.5 22030.0 22065.9 22122.0 1047.9 1051.3 1077.4 1090.0 1092.8 10493.8 11155.9 11310.5 11333.8 11362.3 636409.0 640308.0 641456.0 705283.0 778202.0 162794.2 163323.0 164727.0 169046.0 170324.1 1340.9 1350.7 1395.5 1399.5 1407.4 14261.8 20700.7 21181.7 21554.9 21589.6 49472.5 64039.0 67070.3 67459.7 69415.0 67497.7 90446.0 91290.0 93025.5 94652.1 9659.3 11724.3 11838.8 11850.9 12026.6 12665.1 12887.4 12898.0 12976.5 13226.3 11054.9 11101.6 11206.6 11341.8 11515.7 8185.6 10173.6 10213.1 10330.4 10385.9 11212.0 11239.4 11409.0 11472.3 11477.9 10525.9 10843.0 11453.8 11528.8 11861.9 10771.9 10948.5 11043.8 11136.0 11347.9 14:06:26 INFO - PROCESS | 2316 | 14:06:26 INFO - PROCESS | 2316 | [#3] mootools.html Cycles:180 Average:6943.24 Median:2503.39 stddev:4295.29 (171.6%) stddev-sans-first:4287.86 14:06:26 INFO - PROCESS | 2316 | Values: 1499.0 1621.2 1639.7 1652.7 1653.3 11106.6 11455.4 11809.6 11823.8 11852.8 11528.8 11579.0 11690.8 11727.5 11746.1 2466.7 2488.0 2503.9 2505.1 2510.9 2444.2 2504.4 2512.6 2516.9 2518.8 2169.6 2453.3 2471.7 2478.7 2502.9 2447.9 2448.5 2455.1 2480.5 2480.6 2466.7 2480.3 2488.4 2488.6 2489.9 9596.8 11339.8 11551.0 11561.5 11603.4 5992.6 6095.7 6169.9 6178.4 6188.9 3519.4 3536.4 3559.6 3792.8 3852.5 1078.4 1248.4 1257.3 1310.0 1310.4 14318.0 14648.9 15201.1 15934.8 16090.8 4220.5 4856.2 4954.5 4962.3 4991.2 12030.9 12232.6 12252.8 12258.9 12266.5 5869.7 6045.2 6063.5 6080.2 6116.1 5388.0 5548.5 5556.8 5599.9 5640.2 3950.8 4075.2 4118.6 4174.5 4189.9 1089.9 1091.3 1093.1 1102.1 1110.8 925.5 937.2 938.7 943.6 985.2 6838.9 6870.2 6878.9 7126.9 7174.9 993.9 1042.2 1042.9 1051.1 1064.2 4353.2 4364.8 4374.7 4380.0 4502.9 13697.8 14181.0 14244.4 14275.0 14312.8 10821.9 13692.4 13920.2 14042.0 14150.2 10294.1 10514.1 10531.5 10541.5 10599.7 6830.8 7090.9 7131.8 7217.2 7244.4 12421.8 12529.9 12696.5 12752.0 12814.0 11619.2 12228.0 12862.5 12917.8 13127.4 9495.9 9543.2 9569.0 9604.9 9672.3 10458.2 10475.0 10580.4 10589.5 10596.3 8959.9 8988.9 9038.1 9133.0 9193.2 7392.1 8820.3 8934.3 8949.9 8957.7 7839.3 8012.8 8133.7 8159.0 8176.8 6068.9 6079.5 6082.3 6125.4 6196.9 5361.8 5911.3 6064.1 6247.8 6250.9 14:06:26 INFO - PROCESS | 2316 | 14:06:26 INFO - PROCESS | 2316 | [#4] prototype.html Cycles:180 Average:4610.35 Median:4966.73 stddev:1299.70 (26.2%) stddev-sans-first:1263.64 14:06:26 INFO - PROCESS | 2316 | Values: 362.7 377.5 389.1 392.3 401.3 4969.6 5352.8 5497.3 5508.5 5529.4 4628.5 4935.7 5127.3 5301.8 5346.3 3772.5 4375.3 4416.5 4432.6 4459.0 3564.7 3790.4 4102.3 4446.8 4474.1 3822.6 4055.6 4252.0 4320.8 4358.7 3578.6 3888.7 4061.7 4367.5 4423.7 3476.3 4083.3 4090.6 4100.7 4108.5 3920.3 5054.4 5083.3 5124.2 5160.5 4378.9 4842.9 4862.8 5098.9 5104.9 5220.9 5634.5 5682.1 5692.3 5713.5 3668.9 3975.4 4274.3 4308.9 4480.6 5163.6 5191.4 5473.5 5747.9 5862.5 4028.9 4070.6 4235.9 4425.5 4604.6 4690.2 4697.3 4739.9 4741.5 4763.4 4019.2 4190.7 4579.3 5028.5 5106.1 4758.7 5134.9 5586.6 5599.8 5650.9 1392.8 1445.3 1473.5 1529.8 1596.9 1097.3 1112.7 1196.4 1208.0 1229.6 4571.7 4748.6 4750.0 4815.0 4990.6 4942.6 5126.2 5421.3 5610.8 5875.2 4389.6 5259.6 5266.7 5268.6 5285.0 2662.4 2759.3 3010.5 3044.0 3044.6 5010.8 5431.0 5771.6 5879.0 6134.9 5433.7 5549.5 5652.6 5917.9 5975.9 5146.4 5407.6 5810.1 5977.6 6124.9 4902.1 5186.8 5629.9 5857.7 5875.6 4787.9 4833.6 5177.1 5208.3 5804.8 5343.5 5455.6 5534.3 5849.4 6092.3 4218.0 4419.2 4588.9 4626.5 4651.7 4924.6 5283.0 5707.9 5912.5 5955.2 5642.0 5686.8 5697.3 5714.7 5731.8 4988.5 5038.3 5515.9 5698.7 5719.7 3931.2 4912.7 4963.9 5326.7 5574.8 4912.6 4976.5 5408.4 5679.8 5765.3 4576.3 4721.3 5166.5 5480.8 5540.1 14:06:26 INFO - PROCESS | 2316 | 14:06:26 INFO - PROCESS | 2316 | [#5] yui.html Cycles:180 Average:20219.78 Median:16741.42 stddev:28437.70 (169.9%) stddev-sans-first:28479.81 14:06:26 INFO - PROCESS | 2316 | Values: 728.9 744.2 758.8 825.0 875.7 15826.6 16906.4 16959.7 17010.7 17145.1 13011.0 15579.6 15770.7 15777.8 15778.2 843.6 965.9 999.9 1007.6 1010.9 857.4 1008.7 1014.5 1014.7 1015.5 1057.9 1058.2 1059.1 1060.7 1061.2 1044.7 1052.5 1052.9 1054.2 1055.5 776.9 777.2 777.4 777.8 779.1 16263.3 16711.8 16771.0 17006.3 17117.0 12055.7 14738.6 14810.6 14819.4 14949.3 11519.9 11840.2 11878.8 11880.6 11939.3 3722.4 3830.8 3840.7 3847.5 3864.8 115095.4 116334.8 118606.0 119324.0 119506.4 11150.5 11341.5 11359.2 11566.8 11934.2 23048.9 23386.2 23672.4 23707.4 23761.5 6760.7 6896.8 8004.7 8094.3 8153.1 4567.6 4582.6 4635.8 4637.8 4704.0 3662.1 3735.6 3763.6 3766.4 3777.3 531.4 566.8 600.3 673.2 680.5 712.7 726.7 731.3 733.3 750.9 10531.3 10583.3 10645.1 10827.8 11112.2 697.2 717.0 727.7 751.3 753.6 5096.2 5152.6 5201.3 5206.3 5208.3 89064.0 90962.5 100885.0 101167.5 101357.0 63411.0 78626.0 78703.6 80128.6 81918.0 42343.0 66434.7 70319.6 74419.0 76729.2 10249.8 10757.4 11196.0 11247.4 11331.4 49980.8 50610.0 50683.7 51479.5 51756.5 62183.0 62351.0 62871.4 63339.7 63515.4 11416.5 12888.5 13339.2 13372.5 13457.7 14859.0 15197.0 15205.8 15557.5 15727.3 13768.2 13853.4 14145.6 14631.9 14804.3 13707.8 14226.5 14625.2 14791.3 14831.6 13409.0 13516.5 14223.3 14449.3 14593.2 9947.4 10404.7 10644.5 10894.2 10994.1 11167.3 11403.6 11437.0 11633.8 11667.4 14:06:26 INFO - PROCESS | 2316 | -------- Summary: end -------- 14:06:26 INFO - PROCESS | 2316 | 14:06:27 INFO - TEST-INFO | 2316: exit 0 14:06:27 INFO - TEST-OK | dromaeo_css | took 1115324ms 14:06:27 INFO - TEST-START | kraken 14:06:27 INFO - operating with platform_type : mac_ 14:06:27 INFO - Initialising browser for kraken test... 14:06:27 INFO - TEST-INFO | started process 2322 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmpSPx9mt/profile http://localhost:49670/getInfo.html) 14:06:29 INFO - PROCESS | 2322 | 2017-05-15 14:06:29.669 plugin-container[2324:20298] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 14:06:29 INFO - PROCESS | 2322 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:06:29 INFO - PROCESS | 2322 | 2017-05-15 14:06:29.670 plugin-container[2324:20298] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8433, name = 'com.apple.CFPasteboardClient' 14:06:29 INFO - PROCESS | 2322 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:06:29 INFO - PROCESS | 2322 | 2017-05-15 14:06:29.670 plugin-container[2324:20298] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:06:29 INFO - PROCESS | 2322 | __metrics Screen width/height:1600/1200 14:06:29 INFO - PROCESS | 2322 | colorDepth:24 14:06:29 INFO - PROCESS | 2322 | Browser inner width/height: 1024/689 14:06:29 INFO - PROCESS | 2322 | __metrics 14:06:29 ERROR - PROCESS | 2322 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 14:06:30 INFO - TEST-INFO | 2322: exit 0 14:06:30 INFO - Browser initialized. 14:06:30 INFO - Running cycle 1/1 for kraken test... 14:06:30 INFO - TEST-INFO | started process 2325 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/T/tmpSPx9mt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:06:32 INFO - PROCESS | 2325 | 2017-05-15 14:06:32.943 plugin-container[2326:20417] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 14:06:32 INFO - PROCESS | 2325 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:06:32 INFO - PROCESS | 2325 | 2017-05-15 14:06:32.944 plugin-container[2326:20417] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0f, name = 'com.apple.CFPasteboardClient' 14:06:32 INFO - PROCESS | 2325 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:06:32 INFO - PROCESS | 2325 | 2017-05-15 14:06:32.944 plugin-container[2326:20417] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:06:42 INFO - PROCESS | 2325 | RSS: Main: 174161920 14:06:42 INFO - PROCESS | 2325 | 14:06:45 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:49670/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49670/tests/kraken/driver.html?testName=audio-beat-detection) 14:06:46 INFO - PROCESS | 2325 | RSS: Main: 187801600 14:06:46 INFO - PROCESS | 2325 | 14:06:49 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:49670/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49670/tests/kraken/driver.html?testName=audio-dft) 14:06:49 INFO - PROCESS | 2325 | RSS: Main: 195805184 14:06:49 INFO - PROCESS | 2325 | 14:06:53 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:49670/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49670/tests/kraken/driver.html?testName=audio-fft) 14:06:53 INFO - PROCESS | 2325 | RSS: Main: 199598080 14:06:53 INFO - PROCESS | 2325 | 14:06:55 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:49670/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49670/tests/kraken/driver.html?testName=audio-oscillator) 14:06:56 INFO - PROCESS | 2325 | RSS: Main: 199913472 14:06:56 INFO - PROCESS | 2325 | 14:06:58 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:49670/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49670/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:06:58 INFO - PROCESS | 2325 | RSS: Main: 201826304 14:06:58 INFO - PROCESS | 2325 | 14:07:01 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:49670/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49670/tests/kraken/driver.html?testName=imaging-darkroom) 14:07:02 INFO - PROCESS | 2325 | RSS: Main: 205295616 14:07:02 INFO - PROCESS | 2325 | 14:07:05 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:49670/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49670/tests/kraken/driver.html?testName=imaging-desaturate) 14:07:06 INFO - PROCESS | 2325 | RSS: Main: 204374016 14:07:06 INFO - PROCESS | 2325 | 14:07:09 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:49670/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49670/tests/kraken/driver.html?testName=json-parse-financial) 14:07:10 INFO - PROCESS | 2325 | RSS: Main: 205152256 14:07:10 INFO - PROCESS | 2325 | 14:07:11 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:49670/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49670/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:07:12 INFO - PROCESS | 2325 | RSS: Main: 204943360 14:07:12 INFO - PROCESS | 2325 | 14:07:14 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:49670/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49670/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:07:14 INFO - PROCESS | 2325 | RSS: Main: 204439552 14:07:14 INFO - PROCESS | 2325 | 14:07:17 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:49670/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49670/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:07:17 INFO - PROCESS | 2325 | RSS: Main: 204468224 14:07:17 INFO - PROCESS | 2325 | 14:07:20 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:49670/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49670/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:07:21 INFO - PROCESS | 2325 | RSS: Main: 204820480 14:07:21 INFO - PROCESS | 2325 | 14:07:24 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:49670/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49670/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:07:24 INFO - PROCESS | 2325 | RSS: Main: 204894208 14:07:24 INFO - PROCESS | 2325 | 14:07:26 INFO - PROCESS | 2325 | Cycle 1(1): loaded http://localhost:49670/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49670/tests/kraken/driver.html?testName=ai-astar) 14:07:27 INFO - PROCESS | 2325 | RSS: Main: 207097856 14:07:27 INFO - PROCESS | 2325 | 14:07:27 INFO - PROCESS | 2325 | __start_tp_report 14:07:27 INFO - PROCESS | 2325 | _x_x_mozilla_page_load 14:07:27 INFO - PROCESS | 2325 | _x_x_mozilla_page_load_details 14:07:27 INFO - PROCESS | 2325 | |i|pagename|runs| 14:07:27 INFO - PROCESS | 2325 | |0;ai-astar;106;175;119;118;118;118;118;119;118;120 14:07:27 INFO - PROCESS | 2325 | |1;audio-beat-detection;141;112;120;143;109;140;139;124;134;118 14:07:27 INFO - PROCESS | 2325 | |2;audio-dft;200;194;192;236;291;200;199;196;195;197 14:07:27 INFO - PROCESS | 2325 | |3;audio-fft;83;60;60;85;59;61;60;59;71;61 14:07:27 INFO - PROCESS | 2325 | |4;audio-oscillator;88;71;84;73;71;58;93;101;55;93 14:07:27 INFO - PROCESS | 2325 | |5;imaging-gaussian-blur;95;91;90;90;93;99;92;91;92;91 14:07:27 INFO - PROCESS | 2325 | |6;imaging-darkroom;97;140;126;102;152;153;98;97;98;98 14:07:27 INFO - PROCESS | 2325 | |7;imaging-desaturate;90;101;90;90;94;81;143;143;87;148 14:07:27 INFO - PROCESS | 2325 | |8;json-parse-financial;66;65;66;69;73;68;68;70;83;99 14:07:27 INFO - PROCESS | 2325 | |9;json-stringify-tinderbox;62;62;64;72;62;59;59;68;84;87 14:07:27 INFO - PROCESS | 2325 | |10;stanford-crypto-aes;81;87;105;103;101;79;85;87;85;88 14:07:27 INFO - PROCESS | 2325 | |11;stanford-crypto-ccm;149;151;116;135;144;147;144;143;180;135 14:07:27 INFO - PROCESS | 2325 | |12;stanford-crypto-pbkdf2;237;166;167;153;216;222;176;163;164;167 14:07:27 INFO - PROCESS | 2325 | |13;stanford-crypto-sha256-iterative;73;78;84;83;86;73;65;64;66;65 14:07:27 INFO - PROCESS | 2325 | __end_tp_report 14:07:27 INFO - PROCESS | 2325 | __start_cc_report 14:07:27 INFO - PROCESS | 2325 | _x_x_mozilla_cycle_collect,983 14:07:27 INFO - PROCESS | 2325 | __end_cc_report 14:07:27 INFO - PROCESS | 2325 | __startTimestamp1494882447037__endTimestamp 14:07:27 INFO - PROCESS | 2325 | 14:07:27 INFO - PROCESS | 2325 | ------- Summary: start ------- 14:07:27 INFO - PROCESS | 2325 | Number of tests: 14 14:07:27 INFO - PROCESS | 2325 | 14:07:27 INFO - PROCESS | 2325 | [#0] ai-astar Cycles:10 Average:122.90 Median:118.50 stddev:18.73 (15.8%) stddev-sans-first:18.85 14:07:27 INFO - PROCESS | 2325 | Values: 106.0 175.0 119.0 118.0 118.0 118.0 118.0 119.0 118.0 120.0 14:07:27 INFO - PROCESS | 2325 | 14:07:27 INFO - PROCESS | 2325 | [#1] audio-beat-detection Cycles:10 Average:128.00 Median:136.50 stddev:12.88 (9.4%) stddev-sans-first:12.77 14:07:27 INFO - PROCESS | 2325 | Values: 141.0 112.0 120.0 143.0 109.0 140.0 139.0 124.0 134.0 118.0 14:07:27 INFO - PROCESS | 2325 | 14:07:27 INFO - PROCESS | 2325 | [#2] audio-dft Cycles:10 Average:210.00 Median:199.50 stddev:31.14 (15.6%) stddev-sans-first:32.82 14:07:27 INFO - PROCESS | 2325 | Values: 200.0 194.0 192.0 236.0 291.0 200.0 199.0 196.0 195.0 197.0 14:07:27 INFO - PROCESS | 2325 | 14:07:27 INFO - PROCESS | 2325 | [#3] audio-fft Cycles:10 Average:65.90 Median:61.00 stddev:10.17 (16.7%) stddev-sans-first:8.70 14:07:27 INFO - PROCESS | 2325 | Values: 83.0 60.0 60.0 85.0 59.0 61.0 60.0 59.0 71.0 61.0 14:07:27 INFO - PROCESS | 2325 | 14:07:27 INFO - PROCESS | 2325 | [#4] audio-oscillator Cycles:10 Average:78.70 Median:78.50 stddev:15.50 (19.7%) stddev-sans-first:16.07 14:07:27 INFO - PROCESS | 2325 | Values: 88.0 71.0 84.0 73.0 71.0 58.0 93.0 101.0 55.0 93.0 14:07:27 INFO - PROCESS | 2325 | 14:07:27 INFO - PROCESS | 2325 | [#5] imaging-gaussian-blur Cycles:10 Average:92.40 Median:92.00 stddev:2.76 (3.0%) stddev-sans-first:2.76 14:07:27 INFO - PROCESS | 2325 | Values: 95.0 91.0 90.0 90.0 93.0 99.0 92.0 91.0 92.0 91.0 14:07:27 INFO - PROCESS | 2325 | 14:07:27 INFO - PROCESS | 2325 | [#6] imaging-darkroom Cycles:10 Average:116.10 Median:97.00 stddev:24.11 (24.9%) stddev-sans-first:24.56 14:07:27 INFO - PROCESS | 2325 | Values: 97.0 140.0 126.0 102.0 152.0 153.0 98.0 97.0 98.0 98.0 14:07:27 INFO - PROCESS | 2325 | 14:07:27 INFO - PROCESS | 2325 | [#7] imaging-desaturate Cycles:10 Average:106.70 Median:88.50 stddev:26.71 (30.2%) stddev-sans-first:27.64 14:07:27 INFO - PROCESS | 2325 | Values: 90.0 101.0 90.0 90.0 94.0 81.0 143.0 143.0 87.0 148.0 14:07:27 INFO - PROCESS | 2325 | 14:07:27 INFO - PROCESS | 2325 | [#8] json-parse-financial Cycles:10 Average:72.70 Median:69.50 stddev:10.60 (15.3%) stddev-sans-first:10.97 14:07:27 INFO - PROCESS | 2325 | Values: 66.0 65.0 66.0 69.0 73.0 68.0 68.0 70.0 83.0 99.0 14:07:27 INFO - PROCESS | 2325 | 14:07:27 INFO - PROCESS | 2325 | [#9] json-stringify-tinderbox Cycles:10 Average:67.90 Median:66.00 stddev:10.10 (15.3%) stddev-sans-first:10.49 14:07:27 INFO - PROCESS | 2325 | Values: 62.0 62.0 64.0 72.0 62.0 59.0 59.0 68.0 84.0 87.0 14:07:27 INFO - PROCESS | 2325 | 14:07:27 INFO - PROCESS | 2325 | [#10] stanford-crypto-aes Cycles:10 Average:90.10 Median:85.00 stddev:9.36 (11.0%) stddev-sans-first:9.33 14:07:27 INFO - PROCESS | 2325 | Values: 81.0 87.0 105.0 103.0 101.0 79.0 85.0 87.0 85.0 88.0 14:07:27 INFO - PROCESS | 2325 | 14:07:27 INFO - PROCESS | 2325 | [#11] stanford-crypto-ccm Cycles:10 Average:144.40 Median:145.50 stddev:16.07 (11.0%) stddev-sans-first:16.96 14:07:27 INFO - PROCESS | 2325 | Values: 149.0 151.0 116.0 135.0 144.0 147.0 144.0 143.0 180.0 135.0 14:07:27 INFO - PROCESS | 2325 | 14:07:27 INFO - PROCESS | 2325 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:183.10 Median:171.50 stddev:29.88 (17.4%) stddev-sans-first:24.52 14:07:27 INFO - PROCESS | 2325 | Values: 237.0 166.0 167.0 153.0 216.0 222.0 176.0 163.0 164.0 167.0 14:07:27 INFO - PROCESS | 2325 | 14:07:27 INFO - PROCESS | 2325 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.70 Median:75.50 stddev:8.62 (11.4%) stddev-sans-first:9.13 14:07:27 INFO - PROCESS | 2325 | Values: 73.0 78.0 84.0 83.0 86.0 73.0 65.0 64.0 66.0 65.0 14:07:27 INFO - PROCESS | 2325 | -------- Summary: end -------- 14:07:27 INFO - PROCESS | 2325 | 14:07:27 INFO - TEST-INFO | 2325: exit 0 14:07:27 INFO - TEST-OK | kraken | took 60659ms 14:07:27 INFO - SUITE-END | took 1175s 14:07:28 INFO - Completed test suite (00:19:36) 14:07:28 INFO - javascript benchmark 14:07:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7829.00545323889, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3212.630547487039, 3595.6044835068124, 3630.0743310455837, 3642.471841582972, 3675.558932928049, 6167.854394258927, 6233.812985610428, 6269.090981807635, 6298.968505157479, 6299.496040316775, 5116.386033675954, 6174.536909731777, 6179.196704428418, 6200.007998720205, 6209.192804935364, 5539.889202215953, 5607.859803504904, 5610.915836262459, 5611.102223644218, 5679.4036626154275, 5691.630044047144, 5694.886102277952, 5702.942970570303, 5703.971480142614, 5763.337216220126, 5494.340679118512, 5502.5597952163735, 5679.460451257124, 5684.346300175471, 5690.487856092951, 5585.301837270341, 5592.944070559303, 5608.55131589476, 5608.943910560883, 5617.129344951519, 5449.264349312832, 5614.326280846324, 5640.520555752766, 5647.491725744661, 5709.0, 6216.844578885519, 6243.906341404882, 6248.843778905518, 6269.529785266123, 6302.023186406114, 6306.684665766739, 6414.8717025659225, 6531.8973487593485, 6594.868102637968, 6611.371919667624, 6510.772122975721, 6526.445252153572, 6570.737170513174, 6602.1087153234175, 6650.135482387307, 6286.214223222098, 6330.556861019776, 6345.6827158642345, 6356.904646430307, 6368.331325210833, 5858.828234353146, 6870.31296870309, 6893.551919125241, 6904.930950690529, 6907.34380233887, 5352.9732351338, 6292.370762923762, 6344.238691357066, 6411.422971932455, 6470.45001174896, 6683.699233534503, 6702.229243637044, 6713.462922966151, 6721.059051732761, 6728.226253980757, 6539.901901471481, 6567.934320656732, 6607.0419789131265, 6611.570247933869, 6615.536912416181, 5584.888302233932, 6226.62640241587, 6345.0482427636225, 6588.769393071219, 6617.139771829728, 5236.16221404581, 5305.44292849253, 5339.546138578265, 5354.732263386815, 5378.273933019071, 4709.693869898446, 4717.127331443625, 4729.385179926588, 4778.617710583145, 4783.593394561502, 6238.563300569007, 6364.936350636527, 6366.013267943476, 6368.42684158428, 6397.6801159941815, 6400.135981642512, 6678.83302917431, 6718.932810671929, 6720.495962802809, 6742.258351581322, 6245.375462453809, 6249.500039996789, 6291.1192433059405, 6365.522585806083, 6402.6158430494315, 5905.202797622352, 5918.585699001029, 5944.0, 5956.136360227742, 5977.910331345033, 6843.931560684329, 6866.004429357715, 6895.172579290517, 6901.1718593769065, 6918.481113916477, 5373.194020896741, 6383.489320854228, 6484.513661475503, 6495.253045899593, 6547.083408322934, 5511.283533140666, 6893.931060689329, 6903.689333979883, 6928.307169283032, 6929.653517324013, 6570.342965703391, 6642.0, 6655.500837437115, 6656.500762442739, 6659.467242620675, 6414.583052101599, 6484.059811327425, 6578.111954886029, 6682.9331706682315, 6733.831654208684, 6444.742210311535, 6507.219133703986, 6511.674416279262, 6521.9673901630185, 6525.771597994048, 6506.316836732264, 6524.2823289439075, 6525.119108920235, 6527.869442611216, 6528.151340325917, 5444.3194600674915, 6233.781817636361, 6274.968625156845, 6280.277768056732, 6334.9366506334345, 6299.590526615755, 6456.192975878015, 6556.114924485133, 6568.770093046721, 6572.901406479003, 6379.489640828815, 6382.7766028190645, 6398.616083035033, 6400.807975760735, 6434.581752186093], "value": 6089.907748992605, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [693.9583624982502, 776.2431629161565, 835.2107258640585, 969.2052516936116, 982.1602529836994, 32182.51726224103, 38689.0, 39405.60594394055, 39936.00159995998, 39953.80023099881, 38474.615253847456, 38572.421413678814, 38573.228535429276, 38607.42088868669, 38704.80647596758, 8240.464369815958, 11153.888461115406, 11405.942970285178, 11551.075913926908, 11586.536538538448, 11142.0, 11241.943790281037, 11263.54945802167, 11280.943595282011, 11290.548378064868, 11229.60696375627, 11254.774904501905, 11260.831087533694, 11266.605668801594, 11287.435628218598, 11196.66410007697, 11220.887791122148, 11230.0, 11234.045106165986, 11242.269252498601, 10904.127669786398, 10971.341719496855, 10985.285956412887, 10994.560217591286, 11021.889781102167, 33650.326993460236, 33814.32371352559, 33958.0, 33963.15092122717, 33989.490157647655, 3313.7349012078976, 3324.96675033249, 3905.023744063984, 3916.8629097981434, 3974.82113304902, 24806.627900581505, 25243.0, 25702.614460783105, 27260.59109113365, 27307.0, 3108.364498314345, 3181.840907954593, 3266.8203248821305, 3319.336132773455, 3389.0171850163424, 375662.0, 376977.1151144254, 377873.0, 378626.0, 379460.0, 44661.55338446574, 44869.10261794746, 45177.774111129234, 45275.54724452779, 45386.546134538235, 45506.54493455089, 45970.54029459663, 46430.07139857184, 46486.0, 46543.06913861704, 14615.050021748551, 14642.707145857023, 14725.779113313094, 14733.484328048682, 14770.335334909954, 12454.0, 12634.178778379375, 12665.556705515262, 12673.936630316974, 12681.619551413472, 10705.946470267754, 11956.641300760992, 11965.760684786255, 11966.64100076999, 12039.518419263131, 5082.542571168538, 5600.215969764236, 5727.8568035799435, 5777.2379424276, 5807.216025836543, 1131.3990703155107, 1154.4458659843322, 1159.5999380213814, 1166.8191430328313, 1192.1059205595802, 26739.732602674114, 26881.865590672314, 27141.778619962148, 27163.86418067897, 27244.318892027462, 1258.213616489694, 1283.1787655900232, 1295.533607901155, 1309.155594641451, 1311.0560396514495, 13540.322983850769, 13873.653158670912, 14052.85947140536, 14114.50599229022, 14146.717065658835, 194461.08308375385, 195302.0469795284, 195440.0, 195724.0, 196106.0194699046, 48014.03971920612, 48718.512814872105, 56030.0, 56560.0, 56704.0, 1433.5269361110809, 1457.3878970832277, 1474.7124310759514, 1477.2392218007735, 1478.4973109142943, 21304.147834086463, 26018.21945341567, 26216.8689156553, 26628.201153965412, 27099.322516936445, 40094.1981160381, 40211.396829047, 40400.79799600983, 40434.595654043085, 40445.0, 487910.0, 488012.0, 488384.0, 488719.0, 490675.0, 23094.307170784905, 28132.71867281383, 28818.0, 28841.42317153687, 29069.7093029067, 31633.841830790698, 32264.83867580741, 32451.837740811145, 32578.0, 32592.185195370304, 22019.449513762283, 26195.476090478463, 28837.56743648805, 28840.423191536473, 29068.7093129066, 28610.712517936325, 28841.134765957057, 29021.564676529448, 29304.12087637374, 29642.85178574093, 29727.662255199, 31632.841835790674, 33408.665913340556, 33582.832085839415, 33630.66369336275, 25470.235892923243, 28019.31884086961, 29138.417231655672, 29544.852275739344, 29628.259293517833, 23011.654825177302, 23161.189358372376, 23510.0, 23543.529129416973, 24258.51482970366], "value": 17947.998559978823, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.4884913679017, 432.80307460105655, 439.27738869559573, 447.7201748906931, 453.52153478080623, 20329.0, 21424.571508569818, 22648.320550383472, 22807.859607019625, 22919.42701432463, 22607.886960565214, 23254.41863953402, 23343.8328083596, 23574.764252357472, 24089.87955060222, 6748.39264466198, 6844.2129155147295, 6996.930030699691, 7263.273672632721, 7273.7090516379285, 6143.385661433843, 7069.187043490013, 7108.60902650354, 7176.138863336381, 7199.820004499903, 7150.499465037449, 7521.849563008736, 7852.685892564291, 7994.960025199865, 8007.559584222866, 7738.961305193466, 7898.60506974649, 7961.920380796176, 8001.359891208689, 8055.355571554262, 7482.85034299311, 7693.038370203724, 7776.494527855728, 7781.494202876795, 7788.961055194744, 21359.466013349636, 21793.0, 26737.19788406351, 27415.588766168523, 27704.0, 2955.970440295591, 2962.4519463899032, 2964.6442426908693, 2979.1360505453395, 2988.282812125096, 15758.76361854573, 15938.28277727505, 16363.181840908026, 16978.23597938096, 17016.82983170165, 1704.5397742609425, 1957.5889063296725, 2075.3670130610158, 2091.362134548962, 2111.989440052805, 137080.31459842736, 138701.0, 152392.2380388091, 177718.99999999872, 178588.0, 17809.84236024654, 20239.392818215478, 20261.696074558895, 21585.784142158376, 21606.891965540388, 33211.16972075718, 33611.83194084014, 33837.66162338345, 33897.66102338945, 33911.15222119418, 10881.0, 10892.782144357068, 11101.278416902875, 11342.886571134182, 11437.199396042364, 7981.0, 9088.81822363549, 9254.583543740548, 9358.391704539183, 9395.577199025924, 9408.811823763486, 9764.609415623438, 9776.853347199798, 9842.261830362608, 9859.112679858847, 4189.601987811153, 4203.222403855297, 4233.97883010583, 4241.97879010603, 4251.404803327537, 997.8602995580625, 1027.6814187601867, 1038.3562191441204, 1054.9661331894633, 1059.761553650438, 17474.82525174732, 21558.461038474165, 22030.0, 22065.889670551544, 22122.0, 1047.9056884880397, 1051.30613794895, 1077.3589714120087, 1090.0, 1092.7814437112609, 10493.842592361121, 11155.944220279009, 11310.4910279036, 11333.773324533464, 11362.318260904372, 636409.0, 640308.0, 641456.0, 705283.0, 778202.0, 162794.1860290691, 163323.0, 164727.0, 169046.0, 170324.1483792598, 1340.8793208611078, 1350.6758377989213, 1395.5115709501592, 1399.54514782694, 1407.3737186951707, 14261.786073208703, 20700.654457460216, 21181.6822747662, 21554.92225388693, 21589.596676215995, 49472.50527494679, 64039.0, 67070.32929670835, 67459.66270168617, 69415.0, 67497.66251168713, 90446.0, 91290.0, 93025.53487232521, 94652.05347946432, 9659.275554333593, 11724.296542207494, 11838.763224735629, 11850.940745296219, 12026.579069732867, 12665.113442059335, 12887.355632218165, 12897.968162547162, 12976.545820896223, 13226.338683066, 11054.944725276322, 11101.5559377624, 11206.573403505407, 11341.829872552084, 11515.654530364103, 8185.631646575799, 10173.593056277667, 10213.08082272599, 10330.431826249629, 10385.896141038493, 11212.0, 11239.381833999209, 11409.0, 11472.254303470248, 11477.885221148015, 10525.894741052798, 10843.0, 11453.828192576952, 11528.827067593826, 11861.881381186422, 10771.946140269249, 10948.507317170586, 11043.779124417628, 11136.0, 11347.886521134684], "value": 11931.879254712088, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1498.97751533727, 1621.221813529505, 1639.7128254320362, 1652.73556231003, 1653.3056116431098, 11106.6112686056, 11455.427228638577, 11809.586664466757, 11823.822642660367, 11852.82220766688, 11528.769424611502, 11579.0, 11690.766184676302, 11727.53089876406, 11746.060315174787, 2466.740992195816, 2488.0, 2503.8748062596796, 2505.0856437400307, 2510.8619025953567, 2444.168982545934, 2504.3989442533752, 2512.560301947161, 2516.8867400967, 2518.848869067853, 2169.566086782642, 2453.264020793764, 2471.6910386201725, 2478.7273399925994, 2502.8998840046374, 2447.9265622031367, 2448.485817978227, 2455.1161581830615, 2480.5038992201544, 2480.5659009673236, 2466.666999955019, 2480.268320845355, 2488.365466805971, 2488.552060629086, 2489.9377515562255, 9596.760080997961, 11339.829902551468, 11550.960413562736, 11561.479733412016, 11603.361815100166, 5992.550558708071, 6095.664738439384, 6169.938300616981, 6178.38216178383, 6188.947878860605, 3519.419295816187, 3536.416491278936, 3559.6440355964196, 3792.753471024402, 3852.5184351956004, 1078.417654466587, 1248.400767631533, 1257.2833484913604, 1310.0174868848364, 1310.4168644953, 14318.0, 14648.853511464962, 15201.087934723952, 15934.840651593451, 16090.83909160905, 4220.53574106848, 4856.198727210041, 4954.455009948902, 4962.305277261186, 4991.151504244287, 12030.879691202976, 12232.633021009384, 12252.81620775689, 12258.877411225953, 12266.509339626493, 5869.6771677558145, 6045.153678485016, 6063.54523410746, 6080.179175811209, 6116.082587611805, 5388.030154572136, 5548.472895075042, 5556.77772889088, 5599.944000559943, 5640.153976903415, 3950.802459877052, 4075.1849630074103, 4118.649914757219, 4174.519930208065, 4189.937150942738, 1089.9073578745738, 1091.2961140064615, 1093.0654290581438, 1102.08526922654, 1110.7833972375308, 925.4729696001651, 937.2174234514121, 938.7230766923774, 943.5518128888723, 985.2019863910256, 6838.897416538756, 6870.175578930461, 6878.931210687929, 7126.928730712731, 7174.856502869913, 993.9254555908336, 1042.181887218545, 1042.890496497872, 1051.106559424483, 1064.2177999170603, 4353.216421044242, 4364.8254069837485, 4374.693771436033, 4379.978100109479, 4502.887427814266, 13697.794533082011, 14181.0, 14244.359003844851, 14275.0, 14312.785308220386, 10821.891781082246, 13692.383842727102, 13920.164790112625, 14042.0, 14150.221737804519, 10294.073533381881, 10514.106300964504, 10531.526081326512, 10541.525631346454, 10599.682009539727, 6830.760923367857, 7090.929090709234, 7131.750388736369, 7217.242189570229, 7244.420446364384, 12421.813672794735, 12529.874701252871, 12696.492140314283, 12751.979841612834, 12814.0, 11619.186656933933, 12228.02175825914, 12862.485500579873, 12917.806232906702, 13127.409266583218, 9495.85841129835, 9543.236541076836, 9568.995255498072, 9604.85592716096, 9672.274579406432, 10458.215633827342, 10475.0, 10580.41807700584, 10589.4705264738, 10596.31123976939, 8959.910400895908, 8988.910110899069, 9038.141376569216, 9132.954335228282, 9193.172614464731, 7392.149902761252, 8820.25027872638, 8934.329925255503, 8949.926008878976, 8957.686480973134, 7839.294463498312, 8012.799680008046, 8133.715319963773, 8159.0, 8176.754697359089, 6068.908966365596, 6079.544034197541, 6082.330943596289, 6125.448709616156, 6196.876062478816, 5361.812336568201, 5911.320198177093, 6064.090386442069, 6247.812565623039, 6250.9062364065485], "value": 5313.995334353622, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.65185421994886, 377.45834727166516, 389.09535330356925, 392.3017029687158, 401.2636811450987, 4969.577585905198, 5352.785888564463, 5497.251374312844, 5508.476694714006, 5529.391766905642, 4628.4677262114865, 4935.6545041847085, 5127.33344665193, 5301.787928482866, 5346.331708536433, 3772.4727379899746, 4375.278079116942, 4416.536263692315, 4432.578905004019, 4459.0, 3564.7326450516184, 3790.4124860646643, 4102.323116685743, 4446.777136287515, 4474.060447306069, 3822.636849499293, 4055.614716601918, 4251.978740106295, 4320.805563749623, 4358.694891357591, 3578.570571531433, 3888.6500214980506, 4061.6953728470335, 4367.454068241485, 4423.712458690175, 3476.3221171871496, 4083.2650122977852, 4090.611391917763, 4100.712950093494, 4108.5275193352845, 3920.2747491714126, 5054.418741844698, 5083.288339632454, 5124.20652771527, 5160.535551800356, 4378.934315985231, 4842.878928026827, 4862.759996200982, 5098.940635618636, 5104.897902041976, 5220.92168617471, 5634.549236061105, 5682.0680968470915, 5692.260006199209, 5713.542916566665, 3668.926621467582, 3975.4235635832642, 4274.337477690963, 4308.870733877958, 4480.574345437179, 5163.561097306733, 5191.377034755816, 5473.452654734535, 5747.930744931821, 5862.501687356537, 4028.8992775180855, 4070.5725898780793, 4235.851745188966, 4425.535318791545, 4604.58558729716, 4690.249560070441, 4697.342372067913, 4739.857804265877, 4741.5021422750115, 4763.404574428197, 4019.2363450944226, 4190.742777166838, 4579.267317229227, 5028.547430731252, 5106.080905437057, 4758.738269395217, 5134.871628209325, 5586.581006424534, 5599.8240369522855, 5650.858728531737, 1392.7771556550904, 1445.2990299704634, 1473.4823132173879, 1529.831718510963, 1596.888217824741, 1097.3361116524547, 1112.7273817914663, 1196.3539688568076, 1208.033573141501, 1229.64738787335, 4571.702839315434, 4748.6438517111355, 4750.0, 4815.036992601494, 4990.575801056878, 4942.604591632661, 5126.179811230185, 5421.322334708161, 5610.775568977277, 5875.20684707559, 4389.561043895585, 5259.552938000313, 5266.735983363965, 5268.578513718893, 5284.9958507883375, 2662.440887413626, 2759.268793769653, 3010.533367328024, 3043.969560304413, 3044.58898048765, 5010.799568017239, 5431.0, 5771.624844385102, 5879.0, 6134.877302454015, 5433.673979561239, 5549.528290095306, 5652.604317697804, 5917.940820591825, 5975.910361344583, 5146.356705411824, 5407.567394608501, 5810.09943458773, 5977.581569290108, 6124.85630029495, 4902.117618828645, 5186.844394668166, 5629.88740225185, 5857.677827719516, 5875.559333050124, 4787.880302992453, 4833.637477189154, 5177.119889618699, 5208.322918020633, 5804.76780928775, 5343.492368225013, 5455.645383050089, 5534.308211473566, 5849.415058494286, 6092.324610731906, 4217.957820421757, 4419.237976264834, 4588.931166032446, 4626.514216007271, 4651.720896746206, 4924.606031517542, 5283.0, 5707.942920570741, 5912.526997840077, 5955.225820643289, 5642.025291148066, 5686.77252909879, 5697.3448053474385, 5714.714264286686, 5731.799387021434, 4988.501149884983, 5038.271678957618, 5515.917261241165, 5698.715064246854, 5719.656820590778, 3931.194105208368, 4912.705237685607, 4963.890346602751, 5326.6537675050995, 5574.777008919597, 4912.606991440604, 4976.477469865612, 5408.350453111681, 5679.801206957736, 5765.33210829604, 4576.313552967082, 4721.315409265706, 5166.509181627739, 5480.808171713971, 5540.146824385525], "value": 4235.260530893687, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [728.9271072892708, 744.1962680305271, 758.7609902880592, 825.0470706334186, 875.7110153649296, 15826.604334891634, 16906.40827571032, 16959.74560381592, 17010.659786804255, 17145.142742862852, 13011.0, 15579.610509737262, 15770.684586308267, 15777.763333549978, 15778.21108944551, 843.5529169540154, 965.9130678238957, 999.9350042247268, 1007.5919252702668, 1010.8685870836817, 857.4083882121347, 1008.6620981971012, 1014.5383850348111, 1014.6854475112701, 1015.4516561057058, 1057.9418132002736, 1058.2380685906135, 1059.099765199582, 1060.7083052160679, 1061.2093989977477, 1044.6552637629563, 1052.5105825791006, 1052.9105026072793, 1054.1672079057535, 1055.530289021386, 776.9223077692242, 777.1606664802, 777.3780975219858, 777.801660576555, 779.123486078162, 16263.268152933146, 16711.83288167115, 16771.0, 17006.3197472101, 17117.0, 12055.698607534794, 14738.631534211623, 14810.629734256621, 14819.40722371104, 14949.327280272306, 11519.94240028803, 11840.171188016842, 11878.762424751456, 11880.584179553674, 11939.34333611651, 3722.4355675204856, 3830.827612757405, 3840.711946603988, 3847.461355410245, 3864.845406183749, 115095.42452287686, 116334.83665163325, 118606.0, 119324.0, 119506.40246798798, 11150.49822757978, 11341.489632966537, 11359.204855660024, 11566.826497602542, 11934.164608477497, 23048.88475557645, 23386.18148364799, 23672.408189795395, 23707.40731481727, 23761.524769504515, 6760.7295708172105, 6896.7586134486055, 8004.719834805754, 8094.31198348147, 8153.1439198884445, 4567.566081222279, 4582.610478109332, 4635.768211589407, 4637.837675681402, 4703.952960470351, 3662.066173125836, 3735.5704094029534, 3763.5860055393887, 3766.3785475396253, 3777.338965680995, 531.3703261634975, 566.8441178675897, 600.3066458240739, 673.1585518102372, 680.5338343234899, 712.654362634122, 726.7020521586124, 731.3198725185522, 733.2887099513464, 750.9286617771303, 10531.262811603268, 10583.31208471447, 10645.148388129086, 10827.78344433107, 11112.16658750597, 697.2325155730301, 716.9964150179215, 727.7380143148463, 751.3463286940397, 753.5817621220166, 5096.184610462308, 5152.639315247897, 5201.271821944931, 5206.297149884717, 5208.270842082112, 89064.0, 90962.54518727497, 100885.0, 101167.49416252872, 101356.98643013476, 63411.04883426752, 78626.0, 78703.60648196722, 80128.59935700284, 81918.0, 42343.0, 66434.6713065736, 70319.64840175766, 74419.0, 76729.23270767221, 10249.846252306072, 10757.408342541235, 11196.0, 11247.437628118725, 11331.376774277494, 49980.75048123826, 50610.0, 50683.74658126686, 51479.485205147466, 51756.48243517667, 62183.0, 62351.0, 62871.37128628655, 63339.73320533655, 63515.364846350945, 11416.486258118572, 12888.48446062147, 13339.19964802115, 13372.465101396225, 13457.663558411117, 14859.0, 15197.0, 15205.771913421086, 15557.45548905783, 15727.292271847566, 13768.173909565458, 13853.37659805291, 14145.646358841112, 14631.92684036573, 14804.333804978587, 13707.794383084462, 14226.502072427415, 14625.195614241318, 14791.260436977893, 14831.629209269855, 13409.0, 13516.459341626225, 14223.288835558387, 14449.277536123362, 14593.197374144524, 9947.353422027545, 10404.68785936423, 10644.520996555018, 10894.237403381687, 10994.120470362192, 11167.329960202414, 11403.600873969372, 11437.0, 11633.767324653629, 11667.416629168678], "value": 7844.476887683355, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1552.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 175.0, 119.0, 118.0, 118.0, 118.0, 118.0, 119.0, 118.0, 120.0], "value": 122.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 112.0, 120.0, 143.0, 109.0, 140.0, 139.0, 124.0, 134.0, 118.0], "value": 128.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 194.0, 192.0, 236.0, 291.0, 200.0, 199.0, 196.0, 195.0, 197.0], "value": 210.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 60.0, 60.0, 85.0, 59.0, 61.0, 60.0, 59.0, 71.0, 61.0], "value": 65.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 84.0, 73.0, 71.0, 58.0, 93.0, 101.0, 55.0, 93.0], "value": 78.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 91.0, 90.0, 90.0, 93.0, 99.0, 92.0, 91.0, 92.0, 91.0], "value": 92.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 140.0, 126.0, 102.0, 152.0, 153.0, 98.0, 97.0, 98.0, 98.0], "value": 116.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 101.0, 90.0, 90.0, 94.0, 81.0, 143.0, 143.0, 87.0, 148.0], "value": 106.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 69.0, 73.0, 68.0, 68.0, 70.0, 83.0, 99.0], "value": 72.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 64.0, 72.0, 62.0, 59.0, 59.0, 68.0, 84.0, 87.0], "value": 67.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 87.0, 105.0, 103.0, 101.0, 79.0, 85.0, 87.0, 85.0, 88.0], "value": 90.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 151.0, 116.0, 135.0, 144.0, 147.0, 144.0, 143.0, 180.0, 135.0], "value": 144.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [237.0, 166.0, 167.0, 153.0, 216.0, 222.0, 176.0, 163.0, 164.0, 167.0], "value": 183.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 78.0, 84.0, 83.0, 86.0, 73.0, 65.0, 64.0, 66.0, 65.0], "value": 73.7, "unit": "score"}]}]} 14:07:28 INFO - Return code: 0 14:07:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:07:28 ERROR - # TBPL SUCCESS # 14:07:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:07:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:07:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:07:28 INFO - 2017-05-15 14:07:28.403 screenresolution[2330:20797] starting screenresolution argv=screenresolution get 14:07:28 INFO - 2017-05-15 14:07:28.406 screenresolution[2330:20797] Display 0: 1600x1200x32@60 14:07:28 INFO - 2017-05-15 14:07:28.412 screenresolution[2331:20801] starting screenresolution argv=screenresolution list 14:07:28 INFO - Available Modes on Display 0 14:07:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:07:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:07:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:07:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:07:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:07:28 INFO - Intel Iris: 14:07:28 INFO - Chipset Model: Intel Iris 14:07:28 INFO - Type: GPU 14:07:28 INFO - Bus: Built-In 14:07:28 INFO - VRAM (Dynamic, Max): 1536 MB 14:07:28 INFO - Vendor: Intel (0x8086) 14:07:28 INFO - Device ID: 0x0a2e 14:07:28 INFO - Revision ID: 0x0009 14:07:28 INFO - Displays: 14:07:28 INFO - Display: 14:07:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:07:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:07:28 INFO - Display Serial Number: 1600x1200 60 14:07:28 INFO - Main Display: Yes 14:07:28 INFO - Mirror: Off 14:07:28 INFO - Online: Yes 14:07:28 INFO - Rotation: Supported 14:07:28 INFO - Return code: 0 14:07:28 INFO - Running post-action listener: _resource_record_post_action 14:07:28 INFO - [mozharness: 2017-05-15 21:07:28.648052Z] Finished run-tests step (success) 14:07:28 INFO - Running post-run listener: _resource_record_post_run 14:07:28 INFO - Total resource usage - Wall time: 1192s; CPU: 54.0%; Read bytes: 1593856; Write bytes: 426550784; Read time: 15; Write time: 1448 14:07:28 INFO - TinderboxPrint: CPU usage
54.3% 14:07:28 INFO - TinderboxPrint: I/O read bytes / time
1,593,856 / 15 14:07:28 INFO - TinderboxPrint: I/O write bytes / time
426,550,784 / 1,448 14:07:28 INFO - TinderboxPrint: CPU idle
2,180.8 (45.7%) 14:07:28 INFO - TinderboxPrint: CPU system
956.7 (20.1%) 14:07:28 INFO - TinderboxPrint: CPU user
1,632.2 (34.2%) 14:07:28 INFO - TinderboxPrint: Swap in / out
124,076,032 / 0 14:07:28 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 121410048; Write bytes: 116160512; Read time: 11141; Write time: 268 14:07:28 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 1593344; Write bytes: 300342784; Read time: 14; Write time: 1141 14:07:29 INFO - Running post-run listener: _upload_blobber_files 14:07:29 INFO - Blob upload gear active. 14:07:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:07:29 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:07:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:07:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:07:29 INFO - (blobuploader) - INFO - Open directory for files ... 14:07:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:07:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:07:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:07:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:07:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:07:32 INFO - (blobuploader) - INFO - Done attempting. 14:07:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:07:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:07:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:07:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:07:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:07:34 INFO - (blobuploader) - INFO - Done attempting. 14:07:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:07:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:07:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:07:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:07:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:07:36 INFO - (blobuploader) - INFO - Done attempting. 14:07:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:07:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:07:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:07:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:07:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:07:38 INFO - (blobuploader) - INFO - Done attempting. 14:07:38 INFO - (blobuploader) - INFO - Iteration through files over. 14:07:38 INFO - Return code: 0 14:07:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:07:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:07:38 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d40e4eb8b0d87bb4b16f222fa7efda7254897f3b33ee0a183104b1c65463ddb568f9578037915c0cc99b795e3b05620adc88ff50854b7c7756f53ad44e85b762", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7d6e653c20dc1dee887c2a0739a46dccb65467aff54969b83701686bf524e07aef1d67a53a176d63de9cc9470c99301628d95090e371bb6c364d02fe8049f142", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/acf54de83875db5045d0baad23a6ae1ec6a611099b2d63ae98bcb65c734157966025502d6d6b1249c6c3cb43b1735b547376bad0e8d785b9742d2af5f75a4486", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ca6263a2ab1d8abbf276ad58b6c12edbaa773c99680e9e12292b61dfd7a0a4a19eeb060702e7fa75d40062c603401f0cfc7b0d781c471fe4dff7f59b7489823"} 14:07:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:07:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:07:38 INFO - Contents: 14:07:38 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d40e4eb8b0d87bb4b16f222fa7efda7254897f3b33ee0a183104b1c65463ddb568f9578037915c0cc99b795e3b05620adc88ff50854b7c7756f53ad44e85b762", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7d6e653c20dc1dee887c2a0739a46dccb65467aff54969b83701686bf524e07aef1d67a53a176d63de9cc9470c99301628d95090e371bb6c364d02fe8049f142", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/acf54de83875db5045d0baad23a6ae1ec6a611099b2d63ae98bcb65c734157966025502d6d6b1249c6c3cb43b1735b547376bad0e8d785b9742d2af5f75a4486", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ca6263a2ab1d8abbf276ad58b6c12edbaa773c99680e9e12292b61dfd7a0a4a19eeb060702e7fa75d40062c603401f0cfc7b0d781c471fe4dff7f59b7489823"} 14:07:38 INFO - Running post-run listener: copy_logs_to_upload_dir 14:07:38 INFO - Copying logs to upload dir... 14:07:38 INFO - mkdir: /builds/slave/test/build/upload/logs 14:07:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.071391 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-05-15 14:07:38.760909) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-15 14:07:38.764794) ========= 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.uXNOuyS30e/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.IaDvfO0o3c/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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/d40e4eb8b0d87bb4b16f222fa7efda7254897f3b33ee0a183104b1c65463ddb568f9578037915c0cc99b795e3b05620adc88ff50854b7c7756f53ad44e85b762", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7d6e653c20dc1dee887c2a0739a46dccb65467aff54969b83701686bf524e07aef1d67a53a176d63de9cc9470c99301628d95090e371bb6c364d02fe8049f142", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/acf54de83875db5045d0baad23a6ae1ec6a611099b2d63ae98bcb65c734157966025502d6d6b1249c6c3cb43b1735b547376bad0e8d785b9742d2af5f75a4486", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ca6263a2ab1d8abbf276ad58b6c12edbaa773c99680e9e12292b61dfd7a0a4a19eeb060702e7fa75d40062c603401f0cfc7b0d781c471fe4dff7f59b7489823"} build_url:https://queue.taskcluster.net/v1/task/Hi-PxhAQRTe_z90JbXNwLQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011168 build_url: 'https://queue.taskcluster.net/v1/task/Hi-PxhAQRTe_z90JbXNwLQ/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/d40e4eb8b0d87bb4b16f222fa7efda7254897f3b33ee0a183104b1c65463ddb568f9578037915c0cc99b795e3b05620adc88ff50854b7c7756f53ad44e85b762", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7d6e653c20dc1dee887c2a0739a46dccb65467aff54969b83701686bf524e07aef1d67a53a176d63de9cc9470c99301628d95090e371bb6c364d02fe8049f142", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/acf54de83875db5045d0baad23a6ae1ec6a611099b2d63ae98bcb65c734157966025502d6d6b1249c6c3cb43b1735b547376bad0e8d785b9742d2af5f75a4486", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2ca6263a2ab1d8abbf276ad58b6c12edbaa773c99680e9e12292b61dfd7a0a4a19eeb060702e7fa75d40062c603401f0cfc7b0d781c471fe4dff7f59b7489823"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-15 14:07:38.797661) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 14:07:38.797946) ========= 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.uXNOuyS30e/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.IaDvfO0o3c/Listeners TMPDIR=/var/folders/gk/8kgw34ms467fqz8hdjk7nkb400000w/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.005098 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 14:07:38.845855) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-15 14:07:38.846134) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-15 14:07:38.846428) ========= ========= Total master_lag: 0.67 =========