builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0107 starttime: 1495501338.52 results: success (0) revision: d712c82c59ec5a277047a75d09bec48be4a64b87 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 18:02:18.519765) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 18:02:18.520525) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 18:02:18.520999) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 18:02:18.546639) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 18:02:18.546963) ========= 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.rA7BUJXXJo/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.nkHBCufVlR/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-22 18:02:18-- 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% 27.9M=0s 2017-05-22 18:02:18 (27.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131160 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 18:02:18.702168) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 18:02:18.702651) ========= 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.rA7BUJXXJo/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.nkHBCufVlR/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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.028077 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 18:02:18.753382) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-22 18:02:18.753943) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d712c82c59ec5a277047a75d09bec48be4a64b87 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev d712c82c59ec5a277047a75d09bec48be4a64b87 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rA7BUJXXJo/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.nkHBCufVlR/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-22 18:02:18,846 truncating revision to first 12 chars 2017-05-22 18:02:18,846 Setting DEBUG logging. 2017-05-22 18:02:18,846 attempt 1/10 2017-05-22 18:02:18,847 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d712c82c59ec?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-22 18:02:21,036 unpacking tar archive at: mozilla-central-d712c82c59ec/testing/mozharness/ program finished with exit code 0 elapsedTime=2.456690 ========= master_lag: 0.72 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-22 18:02:21.934479) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 18:02:21.934798) ========= script_repo_revision: d712c82c59ec5a277047a75d09bec48be4a64b87 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 18:02:21.935187) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2017-05-22 18:02:21.935468) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 2 secs) (at 2017-05-22 18:02:24.369420) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-22 18:02:24.369790) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rA7BUJXXJo/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.nkHBCufVlR/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 18:02:24 INFO - MultiFileLogger online at 20170522 18:02:24 in /builds/slave/test 18:02:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 18:02:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:02:24 INFO - {'append_to_log': False, 18:02:24 INFO - 'base_work_dir': '/builds/slave/test', 18:02:24 INFO - 'blob_upload_branch': 'Firefox', 18:02:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:02:24 INFO - 'branch': 'Firefox', 18:02:24 INFO - 'buildbot_json_path': 'buildprops.json', 18:02:24 INFO - 'config_files': ('talos/mac_config.py',), 18:02:24 INFO - 'default_actions': ('clobber', 18:02:24 INFO - 'read-buildbot-config', 18:02:24 INFO - 'download-and-extract', 18:02:24 INFO - 'populate-webroot', 18:02:24 INFO - 'create-virtualenv', 18:02:24 INFO - 'install', 18:02:24 INFO - 'run-tests'), 18:02:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:02:24 INFO - 'download_minidump_stackwalk': True, 18:02:24 INFO - 'download_symbols': 'ondemand', 18:02:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:02:24 INFO - 'tooltool.py': '/tools/tooltool.py', 18:02:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:02:24 INFO - '/tools/misc-python/virtualenv.py')}, 18:02:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:02:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:02:24 INFO - 'gecko_profile': False, 18:02:24 INFO - 'gecko_profile_interval': 0, 18:02:24 INFO - 'installer_path': 'installer.exe', 18:02:24 INFO - 'log_level': 'info', 18:02:24 INFO - 'log_name': 'talos', 18:02:24 INFO - 'log_to_console': True, 18:02:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:02:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:02:24 INFO - 'opt_config_files': (), 18:02:24 INFO - 'pip_index': False, 18:02:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:02:24 INFO - 'cmd': ('bash', 18:02:24 INFO - '-c', 18:02:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:02:24 INFO - 'enabled': True, 18:02:24 INFO - 'halt_on_failure': False, 18:02:24 INFO - 'name': 'check_screen_resolution'},), 18:02:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:02:24 INFO - 'cmd': ('bash', 18:02:24 INFO - '-c', 18:02:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:02:24 INFO - 'enabled': True, 18:02:24 INFO - 'halt_on_failure': False, 18:02:24 INFO - 'name': 'check_screen_resolution'},), 18:02:24 INFO - 'run_cmd_checks_enabled': True, 18:02:24 INFO - 'suite': 'dromaeojs-e10s', 18:02:24 INFO - 'system_bits': '32', 18:02:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:02:24 INFO - 'title': 't-yosemite-r7-0107', 18:02:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:02:24 INFO - 'use_talos_json': True, 18:02:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:02:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:02:24 INFO - 'work_dir': 'build'} 18:02:24 INFO - [mozharness: 2017-05-23 01:02:24.577766Z] Running clobber step. 18:02:24 INFO - Running pre-action listener: _resource_record_pre_action 18:02:24 INFO - Running main action method: clobber 18:02:24 INFO - rmtree: /builds/slave/test/build 18:02:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:02:27 INFO - Running post-action listener: _resource_record_post_action 18:02:27 INFO - [mozharness: 2017-05-23 01:02:27.222409Z] Finished clobber step (success) 18:02:27 INFO - [mozharness: 2017-05-23 01:02:27.222538Z] Running read-buildbot-config step. 18:02:27 INFO - Running pre-action listener: _resource_record_pre_action 18:02:27 INFO - Running main action method: read_buildbot_config 18:02:27 INFO - Using buildbot properties: 18:02:27 INFO - { 18:02:27 INFO - "project": "", 18:02:27 INFO - "product": "firefox", 18:02:27 INFO - "who": "kwierso@gmail.com", 18:02:27 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 18:02:27 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:02:27 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:02:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:02:27 INFO - "stage_platform": "macosx64", 18:02:27 INFO - "basedir": "/builds/slave/test", 18:02:27 INFO - "buildnumber": 77, 18:02:27 INFO - "platform": "yosemite_r7", 18:02:27 INFO - "parent_task_id": "ZJOHXPa5SYO99PH_qG_RHw", 18:02:27 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 18:02:27 INFO - "slavebuilddir": "test", 18:02:27 INFO - "taskId": "UqX4cYbfQva3vo2ywoTr-Q", 18:02:27 INFO - "branch": "mozilla-central", 18:02:27 INFO - "script_repo_revision": "production", 18:02:27 INFO - "revision": "d712c82c59ec5a277047a75d09bec48be4a64b87", 18:02:27 INFO - "slavename": "t-yosemite-r7-0107", 18:02:27 INFO - "repo_path": "mozilla-central" 18:02:27 INFO - } 18:02:27 INFO - Finding installer, test and symbols from parent task. 18:02:27 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UqX4cYbfQva3vo2ywoTr-Q'}, attempt #1 18:02:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UqX4cYbfQva3vo2ywoTr-Q'}, attempt #1 18:02:28 INFO - Task dependencies: TWv07OvwRUSPo6Tmt2KfNw ZJOHXPa5SYO99PH_qG_RHw 18:02:28 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw'}, attempt #1 18:02:28 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:02:28 INFO - Set test_url: https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw/artifacts/public/build/test_packages.json 18:02:28 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw/artifacts/public/build/target.crashreporter-symbols.zip 18:02:28 INFO - Running post-action listener: _resource_record_post_action 18:02:28 INFO - [mozharness: 2017-05-23 01:02:28.937780Z] Finished read-buildbot-config step (success) 18:02:28 INFO - [mozharness: 2017-05-23 01:02:28.937918Z] Running download-and-extract step. 18:02:28 INFO - Running pre-action listener: _resource_record_pre_action 18:02:28 INFO - Running main action method: download_and_extract 18:02:28 INFO - mkdir: /builds/slave/test/build/tests 18:02:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:02:28 INFO - trying https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:02:28 INFO - Downloading https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:02:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 18:02:29 INFO - Downloaded 1325 bytes. 18:02:29 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:02:29 INFO - Using the following test package requirements: 18:02:29 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:02:29 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:02:29 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:02:29 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:02:29 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:02:29 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:02:29 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:02:29 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:02:29 INFO - u'jsshell-mac.zip'], 18:02:29 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:02:29 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:02:29 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:02:29 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:02:29 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:02:29 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:02:29 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:02:29 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:02:29 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 18:02:29 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:02:29 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:02:29 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:02:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:02:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:02:29 INFO - Fetch https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:02:31 INFO - Content-Length response header: 57623798 18:02:31 INFO - Bytes received: 57623798 18:02:49 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:02:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:02:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:02:49 INFO - Fetch https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:02:51 INFO - Content-Length response header: 57623798 18:02:51 INFO - Bytes received: 57623798 18:03:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:03:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:03:08 INFO - Fetch https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:03:09 INFO - Content-Length response header: 14410000 18:03:09 INFO - Bytes received: 14410000 18:03:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:09 INFO - trying https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:03:09 INFO - Downloading https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:03:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 18:03:11 INFO - Downloaded 68008059 bytes. 18:03:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:03:11 INFO - mkdir: /builds/slave/test/properties 18:03:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:03:11 INFO - Writing to file /builds/slave/test/properties/build_url 18:03:11 INFO - Contents: 18:03:11 INFO - build_url:https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:03:11 INFO - Running post-action listener: _resource_record_post_action 18:03:11 INFO - Running post-action listener: set_extra_try_arguments 18:03:11 INFO - [mozharness: 2017-05-23 01:03:11.544002Z] Finished download-and-extract step (success) 18:03:11 INFO - [mozharness: 2017-05-23 01:03:11.544126Z] Running populate-webroot step. 18:03:11 INFO - Running pre-action listener: _resource_record_pre_action 18:03:11 INFO - Running main action method: populate_webroot 18:03:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:03:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:03:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:03:11 INFO - '--tppagecycles', 18:03:11 INFO - '7'], 18:03:11 INFO - 'tests': ['tsvgm']}}, 18:03:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:03:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:03:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:03:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:03:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:03:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:03:11 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:03:11 INFO - 'talos_options': ['--disable-e10s'], 18:03:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:03:11 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:03:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:03:11 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:03:11 INFO - 'talos_options': ['--disable-e10s'], 18:03:11 INFO - 'tests': ['damp', 'tps']}, 18:03:11 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:03:11 INFO - 'tests': ['damp', 'tps']}, 18:03:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:03:11 INFO - 'tests': ['dromaeo_dom']}, 18:03:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:03:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:03:11 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:03:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:03:11 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:03:11 INFO - 'tests': ['ts_paint', 'tp5o']}, 18:03:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:03:11 INFO - 'tests': ['a11yr', 18:03:11 INFO - 'ts_paint', 18:03:11 INFO - 'tpaint', 18:03:11 INFO - 'sessionrestore', 18:03:11 INFO - 'sessionrestore_no_auto_restore', 18:03:11 INFO - 'tabpaint']}, 18:03:11 INFO - 'other-e10s': {'tests': ['a11yr', 18:03:11 INFO - 'ts_paint', 18:03:11 INFO - 'tpaint', 18:03:11 INFO - 'sessionrestore', 18:03:11 INFO - 'sessionrestore_no_auto_restore', 18:03:11 INFO - 'tabpaint']}, 18:03:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:03:11 INFO - 'ts_paint', 18:03:11 INFO - 'tpaint', 18:03:11 INFO - 'sessionrestore', 18:03:11 INFO - 'sessionrestore_no_auto_restore', 18:03:11 INFO - 'tabpaint']}, 18:03:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:03:11 INFO - 'ts_paint', 18:03:11 INFO - 'tpaint', 18:03:11 INFO - 'sessionrestore', 18:03:11 INFO - 'sessionrestore_no_auto_restore', 18:03:11 INFO - 'tabpaint']}, 18:03:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:03:11 INFO - 'tests': ['a11yr', 18:03:11 INFO - 'ts_paint', 18:03:11 INFO - 'tpaint', 18:03:11 INFO - 'sessionrestore', 18:03:11 INFO - 'sessionrestore_no_auto_restore', 18:03:11 INFO - 'tabpaint']}, 18:03:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:03:11 INFO - 'tests': ['a11yr', 18:03:11 INFO - 'ts_paint', 18:03:11 INFO - 'tpaint', 18:03:11 INFO - 'sessionrestore', 18:03:11 INFO - 'sessionrestore_no_auto_restore', 18:03:11 INFO - 'tabpaint']}, 18:03:11 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:03:11 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:03:11 INFO - 'bloom_basic_ref']}, 18:03:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:03:11 INFO - 'tests': ['tsvgx', 18:03:11 INFO - 'tsvgr_opacity', 18:03:11 INFO - 'tart', 18:03:11 INFO - 'tscrollx', 18:03:11 INFO - 'cart', 18:03:11 INFO - 'tsvg_static']}, 18:03:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:03:11 INFO - 'tsvgr_opacity', 18:03:11 INFO - 'tart', 18:03:11 INFO - 'tscrollx', 18:03:11 INFO - 'cart', 18:03:11 INFO - 'tsvg_static']}, 18:03:11 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:03:11 INFO - 'talos_options': ['--disable-e10s'], 18:03:11 INFO - 'tests': ['tp5o']}, 18:03:11 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:03:11 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:03:11 INFO - 'talos_options': ['--disable-e10s', 18:03:11 INFO - '--xperf_path', 18:03:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:03:11 INFO - 'tests': ['tp5n']}, 18:03:11 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:03:11 INFO - 'talos_options': ['--xperf_path', 18:03:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:03:11 INFO - 'tests': ['tp5n']}}, 18:03:11 INFO - 'talos.zip': {'path': '', 18:03:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:03:11 INFO - Running post-action listener: _resource_record_post_action 18:03:11 INFO - [mozharness: 2017-05-23 01:03:11.565574Z] Finished populate-webroot step (success) 18:03:11 INFO - [mozharness: 2017-05-23 01:03:11.565727Z] Running create-virtualenv step. 18:03:11 INFO - Running pre-action listener: _resource_record_pre_action 18:03:11 INFO - Running main action method: create_virtualenv 18:03:11 INFO - Creating virtualenv /builds/slave/test/build/venv 18:03:11 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 18:03:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:03:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:03:11 INFO - Using real prefix '/tools/python27' 18:03:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:03:12 INFO - Installing distribute.............................................................................................................................................................................................done. 18:03:15 INFO - Installing pip.................done. 18:03:15 INFO - Return code: 0 18:03:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:03:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:03:15 INFO - Reading from file tmpfile_stdout 18:03:15 INFO - Output received: 18:03:15 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) 18:03:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:03:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a64b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39945d540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101774d30>, '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 0x101728db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101769be8>, '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.rA7BUJXXJo/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.nkHBCufVlR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 18:03:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:03:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:03:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rA7BUJXXJo/Render', 18:03:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:03:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:03:16 INFO - 'HOME': '/Users/cltbld', 18:03:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:03:16 INFO - 'LOGNAME': 'cltbld', 18:03:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:16 INFO - 'MOZ_NO_REMOTE': '1', 18:03:16 INFO - 'NO_EM_RESTART': '1', 18:03:16 INFO - 'PAGER': '/bin/cat', 18:03:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:03:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:16 INFO - 'PWD': '/builds/slave/test', 18:03:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:03:16 INFO - 'SHELL': '/bin/bash', 18:03:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nkHBCufVlR/Listeners', 18:03:16 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 18:03:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:03:16 INFO - 'USER': 'cltbld', 18:03:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:03:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:03:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:16 INFO - 'XPC_FLAGS': '0x0', 18:03:16 INFO - 'XPC_SERVICE_NAME': '0', 18:03:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:03:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:03:16 INFO - Cleaning up... 18:03:16 INFO - Return code: 0 18:03:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:03: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')]} 18:03:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03: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')]} 18:03:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a64b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39945d540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101774d30>, '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 0x101728db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101769be8>, '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.rA7BUJXXJo/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.nkHBCufVlR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 18:03:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:03:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:03:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rA7BUJXXJo/Render', 18:03:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:03:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:03:16 INFO - 'HOME': '/Users/cltbld', 18:03:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:03:16 INFO - 'LOGNAME': 'cltbld', 18:03:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:16 INFO - 'MOZ_NO_REMOTE': '1', 18:03:16 INFO - 'NO_EM_RESTART': '1', 18:03:16 INFO - 'PAGER': '/bin/cat', 18:03:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:03:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:16 INFO - 'PWD': '/builds/slave/test', 18:03:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:03:16 INFO - 'SHELL': '/bin/bash', 18:03:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nkHBCufVlR/Listeners', 18:03:16 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 18:03:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:03:16 INFO - 'USER': 'cltbld', 18:03:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:03:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:03:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:16 INFO - 'XPC_FLAGS': '0x0', 18:03:16 INFO - 'XPC_SERVICE_NAME': '0', 18:03:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:03:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:17 INFO - Downloading/unpacking psutil>=3.1.1 18:03: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 18:03: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 18:03: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 18:03: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 18:03:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:03:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:03:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:03:19 INFO - Installing collected packages: psutil 18:03:19 INFO - Running setup.py install for psutil 18:03:19 INFO - building 'psutil._psutil_osx' extension 18:03:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 18:03:20 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 18:03:20 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 18:03:20 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 18:03:20 INFO - building 'psutil._psutil_posix' extension 18:03:20 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 18:03:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:03:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:03:20 INFO - ^ 18:03:20 INFO - 1 warning generated. 18:03:20 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 18:03:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:03:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:03:20 INFO - Successfully installed psutil 18:03:20 INFO - Cleaning up... 18:03:20 INFO - Return code: 0 18:03:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:03:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a64b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39945d540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101774d30>, '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 0x101728db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101769be8>, '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.rA7BUJXXJo/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.nkHBCufVlR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 18:03:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:03:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:03:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rA7BUJXXJo/Render', 18:03:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:03:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:03:20 INFO - 'HOME': '/Users/cltbld', 18:03:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:03:20 INFO - 'LOGNAME': 'cltbld', 18:03:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:20 INFO - 'MOZ_NO_REMOTE': '1', 18:03:20 INFO - 'NO_EM_RESTART': '1', 18:03:20 INFO - 'PAGER': '/bin/cat', 18:03:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:03:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:20 INFO - 'PWD': '/builds/slave/test', 18:03:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:03:20 INFO - 'SHELL': '/bin/bash', 18:03:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nkHBCufVlR/Listeners', 18:03:20 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 18:03:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:03:20 INFO - 'USER': 'cltbld', 18:03:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:03:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:03:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:20 INFO - 'XPC_FLAGS': '0x0', 18:03:20 INFO - 'XPC_SERVICE_NAME': '0', 18:03:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:03:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:03: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 18:03: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 18:03: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 18:03: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 18:03:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:03:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:03:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:03:22 INFO - Installing collected packages: mozsystemmonitor 18:03:22 INFO - Running setup.py install for mozsystemmonitor 18:03:22 INFO - Successfully installed mozsystemmonitor 18:03:22 INFO - Cleaning up... 18:03:22 INFO - Return code: 0 18:03:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:03:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a64b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39945d540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101774d30>, '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 0x101728db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101769be8>, '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.rA7BUJXXJo/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.nkHBCufVlR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 18:03:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:03:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:03:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rA7BUJXXJo/Render', 18:03:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:03:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:03:22 INFO - 'HOME': '/Users/cltbld', 18:03:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:03:22 INFO - 'LOGNAME': 'cltbld', 18:03:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:22 INFO - 'MOZ_NO_REMOTE': '1', 18:03:22 INFO - 'NO_EM_RESTART': '1', 18:03:22 INFO - 'PAGER': '/bin/cat', 18:03:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:03:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:22 INFO - 'PWD': '/builds/slave/test', 18:03:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:03:22 INFO - 'SHELL': '/bin/bash', 18:03:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nkHBCufVlR/Listeners', 18:03:22 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 18:03:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:03:22 INFO - 'USER': 'cltbld', 18:03:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:03:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:03:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:22 INFO - 'XPC_FLAGS': '0x0', 18:03:22 INFO - 'XPC_SERVICE_NAME': '0', 18:03:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:03:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:22 INFO - Downloading/unpacking jsonschema==2.5.1 18:03:22 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 18:03:22 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 18:03:22 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 18:03:22 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 18:03:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:03:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:24 INFO - Downloading functools32-3.2.3-2.tar.gz 18:03:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:03:24 INFO - warning: no files found matching '*.txt' 18:03:24 INFO - no previously-included directories found matching 'build' 18:03:24 INFO - no previously-included directories found matching 'dist' 18:03:24 INFO - no previously-included directories found matching '.git*' 18:03:24 INFO - Installing collected packages: jsonschema, functools32 18:03:24 INFO - Running setup.py install for functools32 18:03:24 INFO - warning: no files found matching '*.txt' 18:03:24 INFO - no previously-included directories found matching 'build' 18:03:24 INFO - no previously-included directories found matching 'dist' 18:03:24 INFO - no previously-included directories found matching '.git*' 18:03:24 INFO - Successfully installed jsonschema functools32 18:03:24 INFO - Cleaning up... 18:03:24 INFO - Return code: 0 18:03:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:03: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')]} 18:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03: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')]} 18:03:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:24 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 0x101a64b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39945d540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101774d30>, '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 0x101728db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101769be8>, '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.rA7BUJXXJo/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.nkHBCufVlR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 18:03:24 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 18:03:24 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 18:03:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rA7BUJXXJo/Render', 18:03:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:03:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:03:24 INFO - 'HOME': '/Users/cltbld', 18:03:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:03:24 INFO - 'LOGNAME': 'cltbld', 18:03:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:24 INFO - 'MOZ_NO_REMOTE': '1', 18:03:24 INFO - 'NO_EM_RESTART': '1', 18:03:24 INFO - 'PAGER': '/bin/cat', 18:03:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:03:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:24 INFO - 'PWD': '/builds/slave/test', 18:03:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:03:24 INFO - 'SHELL': '/bin/bash', 18:03:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nkHBCufVlR/Listeners', 18:03:24 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 18:03:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:03:24 INFO - 'USER': 'cltbld', 18:03:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:03:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:03:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:24 INFO - 'XPC_FLAGS': '0x0', 18:03:24 INFO - 'XPC_SERVICE_NAME': '0', 18:03:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:03:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:03:25 INFO - Cleaning up... 18:03:25 INFO - Return code: 0 18:03:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:03: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')]} 18:03:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03: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')]} 18:03:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a64b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39945d540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101774d30>, '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 0x101728db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101769be8>, '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.rA7BUJXXJo/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.nkHBCufVlR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 18:03:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:03:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:03:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rA7BUJXXJo/Render', 18:03:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:03:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:03:25 INFO - 'HOME': '/Users/cltbld', 18:03:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:03:25 INFO - 'LOGNAME': 'cltbld', 18:03:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:25 INFO - 'MOZ_NO_REMOTE': '1', 18:03:25 INFO - 'NO_EM_RESTART': '1', 18:03:25 INFO - 'PAGER': '/bin/cat', 18:03:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:03:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:25 INFO - 'PWD': '/builds/slave/test', 18:03:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:03:25 INFO - 'SHELL': '/bin/bash', 18:03:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nkHBCufVlR/Listeners', 18:03:25 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 18:03:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:03:25 INFO - 'USER': 'cltbld', 18:03:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:03:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:03:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:25 INFO - 'XPC_FLAGS': '0x0', 18:03:25 INFO - 'XPC_SERVICE_NAME': '0', 18:03:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:03:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:25 INFO - Downloading/unpacking blobuploader==1.2.4 18:03:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:26 INFO - Downloading blobuploader-1.2.4.tar.gz 18:03:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:03:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:03:26 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 18:03:26 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 18:03:26 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 18:03:26 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 18:03:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:03:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:03:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:27 INFO - Downloading docopt-0.6.1.tar.gz 18:03:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:03:27 INFO - Installing collected packages: blobuploader, requests, docopt 18:03:27 INFO - Running setup.py install for blobuploader 18:03:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:03:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:03:27 INFO - Running setup.py install for requests 18:03:28 INFO - Running setup.py install for docopt 18:03:28 INFO - Successfully installed blobuploader requests docopt 18:03:28 INFO - Cleaning up... 18:03:28 INFO - Return code: 0 18:03:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:03:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a64b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39945d540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101774d30>, '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 0x101728db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101769be8>, '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.rA7BUJXXJo/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.nkHBCufVlR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 18:03:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:03:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:03:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rA7BUJXXJo/Render', 18:03:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:03:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:03:28 INFO - 'HOME': '/Users/cltbld', 18:03:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:03:28 INFO - 'LOGNAME': 'cltbld', 18:03:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:28 INFO - 'MOZ_NO_REMOTE': '1', 18:03:28 INFO - 'NO_EM_RESTART': '1', 18:03:28 INFO - 'PAGER': '/bin/cat', 18:03:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:03:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:28 INFO - 'PWD': '/builds/slave/test', 18:03:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:03:28 INFO - 'SHELL': '/bin/bash', 18:03:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nkHBCufVlR/Listeners', 18:03:28 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 18:03:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:03:28 INFO - 'USER': 'cltbld', 18:03:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:03:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:03:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:28 INFO - 'XPC_FLAGS': '0x0', 18:03:28 INFO - 'XPC_SERVICE_NAME': '0', 18:03:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:03:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:03:28 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-BsgzKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:03:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:03:28 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-I6K1bt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:03:29 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-JfOI91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:03:29 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-r6VmoR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:03:29 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-0idjE_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:03:29 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-8p6n1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:03:29 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-ibd7ov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:03:29 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-UqxNBw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:03:29 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-yqU2Kw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:03:29 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-mF3jyC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:03:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:03:29 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-aXSjpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:03:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:03:30 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-Llllxr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:03:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:03:30 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-qalrrH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:03:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:03:30 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-6pL8wF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:03:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:03:30 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-rU5vIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:03:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:03:30 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-RK_Yi9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:03:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:03:30 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-mXBBdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:03:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:03:30 INFO - Running setup.py install for manifestparser 18:03:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:03:30 INFO - Running setup.py install for mozcrash 18:03:30 INFO - Running setup.py install for mozdebug 18:03:31 INFO - Running setup.py install for mozdevice 18:03:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:03:31 INFO - Running setup.py install for mozfile 18:03:31 INFO - Running setup.py install for mozhttpd 18:03:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:03:31 INFO - Running setup.py install for mozinfo 18:03:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:03:31 INFO - Running setup.py install for mozInstall 18:03:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:03:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:03:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:03:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:03:31 INFO - Running setup.py install for mozleak 18:03:31 INFO - Running setup.py install for mozlog 18:03:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:03:32 INFO - Running setup.py install for moznetwork 18:03:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:03:32 INFO - Running setup.py install for mozprocess 18:03:32 INFO - Running setup.py install for mozprofile 18:03:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:03:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:03:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:03:32 INFO - Running setup.py install for mozrunner 18:03:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:03:32 INFO - Running setup.py install for mozscreenshot 18:03:32 INFO - Running setup.py install for moztest 18:03:33 INFO - Running setup.py install for mozversion 18:03:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:03:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:03:33 INFO - Cleaning up... 18:03:33 INFO - Return code: 0 18:03:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:03:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a64b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39945d540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101774d30>, '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 0x101728db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101769be8>, '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.rA7BUJXXJo/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.nkHBCufVlR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 18:03:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:03:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:03:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rA7BUJXXJo/Render', 18:03:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:03:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:03:33 INFO - 'HOME': '/Users/cltbld', 18:03:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:03:33 INFO - 'LOGNAME': 'cltbld', 18:03:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:33 INFO - 'MOZ_NO_REMOTE': '1', 18:03:33 INFO - 'NO_EM_RESTART': '1', 18:03:33 INFO - 'PAGER': '/bin/cat', 18:03:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:03:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:33 INFO - 'PWD': '/builds/slave/test', 18:03:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:03:33 INFO - 'SHELL': '/bin/bash', 18:03:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nkHBCufVlR/Listeners', 18:03:33 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 18:03:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:03:33 INFO - 'USER': 'cltbld', 18:03:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:03:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:03:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:33 INFO - 'XPC_FLAGS': '0x0', 18:03:33 INFO - 'XPC_SERVICE_NAME': '0', 18:03:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:03:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:03:33 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-naT0IW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:03:33 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)) 18:03:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:03:33 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-GGOeaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:03:33 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)) 18:03:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:03:33 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-kkdm4u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:03:33 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)) 18:03:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:03:33 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-YYMUyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:03:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 18:03:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:03:33 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-NjXrez-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:03:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:03:34 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-Z7ON6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:03:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:03:34 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-kxYW_i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:03:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:03:34 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-rzgT2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:03:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:03:34 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-yKDc22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:03:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:03:34 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-alAhp6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:03:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:03:34 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-YQclzh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:03:34 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)) 18:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:03:34 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-VuPoBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:03:34 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)) 18:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:03:34 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-on_i_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:03:34 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)) 18:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:03:34 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-DBTdv_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:03:34 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)) 18:03:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:03:34 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-q3lk0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:03:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:03:35 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-NrpWrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:03:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:03:35 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-KWA7lp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:03:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:03:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:03:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:03:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:03:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:03:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:03:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:03:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:03:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:03:36 INFO - Downloading blessings-1.6.tar.gz 18:03:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:03:36 INFO - Installing collected packages: blessings 18:03:36 INFO - Running setup.py install for blessings 18:03:36 INFO - Successfully installed blessings 18:03:36 INFO - Cleaning up... 18:03:37 INFO - Return code: 0 18:03:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:03:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:03:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:03:37 INFO - Reading from file tmpfile_stdout 18:03:37 INFO - Current package versions: 18:03:37 INFO - blessings == 1.6 18:03:37 INFO - blobuploader == 1.2.4 18:03:37 INFO - docopt == 0.6.1 18:03:37 INFO - functools32 == 3.2.3-2 18:03:37 INFO - jsonschema == 2.5.1 18:03:37 INFO - manifestparser == 1.1 18:03:37 INFO - mozInstall == 1.12 18:03:37 INFO - mozcrash == 1.0 18:03:37 INFO - mozdebug == 0.1 18:03:37 INFO - mozdevice == 0.50 18:03:37 INFO - mozfile == 1.2 18:03:37 INFO - mozhttpd == 0.7 18:03:37 INFO - mozinfo == 0.9 18:03:37 INFO - mozleak == 0.1 18:03:37 INFO - mozlog == 3.4 18:03:37 INFO - moznetwork == 0.27 18:03:37 INFO - mozprocess == 0.25 18:03:37 INFO - mozprofile == 0.28 18:03:37 INFO - mozrunner == 6.13 18:03:37 INFO - mozscreenshot == 0.1 18:03:37 INFO - mozsystemmonitor == 0.3 18:03:37 INFO - moztest == 0.8 18:03:37 INFO - mozversion == 1.4 18:03:37 INFO - psutil == 3.1.1 18:03:37 INFO - requests == 1.2.3 18:03:37 INFO - wsgiref == 0.1.2 18:03:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:03:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a64b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39945d540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101774d30>, '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 0x101728db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101769be8>, '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.rA7BUJXXJo/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.nkHBCufVlR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 18:03:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:03:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:03:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rA7BUJXXJo/Render', 18:03:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:03:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:03:37 INFO - 'HOME': '/Users/cltbld', 18:03:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:03:37 INFO - 'LOGNAME': 'cltbld', 18:03:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:37 INFO - 'MOZ_NO_REMOTE': '1', 18:03:37 INFO - 'NO_EM_RESTART': '1', 18:03:37 INFO - 'PAGER': '/bin/cat', 18:03:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:03:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:37 INFO - 'PWD': '/builds/slave/test', 18:03:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:03:37 INFO - 'SHELL': '/bin/bash', 18:03:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nkHBCufVlR/Listeners', 18:03:37 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 18:03:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:03:37 INFO - 'USER': 'cltbld', 18:03:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:03:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:03:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:37 INFO - 'XPC_FLAGS': '0x0', 18:03:37 INFO - 'XPC_SERVICE_NAME': '0', 18:03:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:03:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:03:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:03:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:03:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:03:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:03:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:03:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:03:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:03:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:03:37 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:03:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:03:37 INFO - Cleaning up... 18:03:37 INFO - Return code: 0 18:03:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:03:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:03:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:03:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:03:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a64b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a68768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff39945d540>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101774d30>, '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 0x101728db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101769be8>, '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.rA7BUJXXJo/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.nkHBCufVlR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 18:03:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:03:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:03:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rA7BUJXXJo/Render', 18:03:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:03:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:03:37 INFO - 'HOME': '/Users/cltbld', 18:03:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:03:37 INFO - 'LOGNAME': 'cltbld', 18:03:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:37 INFO - 'MOZ_NO_REMOTE': '1', 18:03:37 INFO - 'NO_EM_RESTART': '1', 18:03:37 INFO - 'PAGER': '/bin/cat', 18:03:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:03:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:37 INFO - 'PWD': '/builds/slave/test', 18:03:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:03:37 INFO - 'SHELL': '/bin/bash', 18:03:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nkHBCufVlR/Listeners', 18:03:37 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 18:03:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:03:37 INFO - 'USER': 'cltbld', 18:03:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:03:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:03:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:37 INFO - 'XPC_FLAGS': '0x0', 18:03:37 INFO - 'XPC_SERVICE_NAME': '0', 18:03:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:03:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:03:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:03:37 INFO - Cleaning up... 18:03:37 INFO - Return code: 0 18:03:37 INFO - Running post-action listener: _resource_record_post_action 18:03:37 INFO - Running post-action listener: _start_resource_monitoring 18:03:37 INFO - Starting resource monitoring. 18:03:37 INFO - [mozharness: 2017-05-23 01:03:37.832241Z] Finished create-virtualenv step (success) 18:03:37 INFO - [mozharness: 2017-05-23 01:03:37.833181Z] Running install step. 18:03:37 INFO - Running pre-action listener: _resource_record_pre_action 18:03:37 INFO - Running main action method: install 18:03:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:03:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:03:38 INFO - Reading from file tmpfile_stdout 18:03:38 INFO - Detecting whether we're running mozinstall >=1.0... 18:03:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:03:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:03:38 INFO - Reading from file tmpfile_stdout 18:03:38 INFO - Output received: 18:03:38 INFO - Usage: mozinstall [options] installer 18:03:38 INFO - Options: 18:03:38 INFO - -h, --help show this help message and exit 18:03:38 INFO - -d DEST, --destination=DEST 18:03:38 INFO - Directory to install application into. [default: 18:03:38 INFO - "/builds/slave/test"] 18:03:38 INFO - --app=APP Application being installed. [default: firefox] 18:03:38 INFO - mkdir: /builds/slave/test/build/application 18:03:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:03:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:03:59 INFO - Reading from file tmpfile_stdout 18:03:59 INFO - Output received: 18:03:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:03:59 INFO - Running post-action listener: _resource_record_post_action 18:03:59 INFO - [mozharness: 2017-05-23 01:03:59.316268Z] Finished install step (success) 18:03:59 INFO - [mozharness: 2017-05-23 01:03:59.316440Z] Running run-tests step. 18:03:59 INFO - Running pre-action listener: _resource_record_pre_action 18:03:59 INFO - Running main action method: run_tests 18:03:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:03:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:03:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:03:59 INFO - 2017-05-22 18:03:59.330 screenresolution[1623:7491] starting screenresolution argv=screenresolution get 18:03:59 INFO - 2017-05-22 18:03:59.333 screenresolution[1623:7491] Display 0: 1600x1200x32@60 18:03:59 INFO - 2017-05-22 18:03:59.339 screenresolution[1624:7494] starting screenresolution argv=screenresolution list 18:03:59 INFO - Available Modes on Display 0 18:03:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:03:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:03:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:03:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:03:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:03:59 INFO - Intel Iris: 18:03:59 INFO - Chipset Model: Intel Iris 18:03:59 INFO - Type: GPU 18:03:59 INFO - Bus: Built-In 18:03:59 INFO - VRAM (Dynamic, Max): 1536 MB 18:03:59 INFO - Vendor: Intel (0x8086) 18:03:59 INFO - Device ID: 0x0a2e 18:03:59 INFO - Revision ID: 0x0009 18:03:59 INFO - Displays: 18:03:59 INFO - Display: 18:03:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:03:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:03:59 INFO - Display Serial Number: 1600x1200 60 18:03:59 INFO - Main Display: Yes 18:03:59 INFO - Mirror: Off 18:03:59 INFO - Online: Yes 18:03:59 INFO - Rotation: Supported 18:03:59 INFO - Return code: 0 18:03:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:03:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:03:59 INFO - Python 2.7.3 18:03:59 INFO - Return code: 0 18:03:59 INFO - grabbing minidump binary from tooltool 18:03:59 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')]} 18:03:59 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 0x101774d30>, '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 0x101728db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101769be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:03:59 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 18:03:59 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 18:03:59 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 18:03:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:03:59 INFO - Return code: 0 18:03:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:03:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:03:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:03:59 INFO - ENV: RUST_BACKTRACE is now 1 18:03:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:03:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:03:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0107', '--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 18:03:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0107 --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 18:03:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rA7BUJXXJo/Render', 18:03:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:03:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:03:59 INFO - 'HOME': '/Users/cltbld', 18:03:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:03:59 INFO - 'LOGNAME': 'cltbld', 18:03:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:03:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:03:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:03:59 INFO - 'MOZ_NO_REMOTE': '1', 18:03:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:03:59 INFO - 'NO_EM_RESTART': '1', 18:03:59 INFO - 'PAGER': '/bin/cat', 18:03:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:03:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:03:59 INFO - 'PWD': '/builds/slave/test', 18:03:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:03:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:03:59 INFO - 'RUST_BACKTRACE': '1', 18:03:59 INFO - 'SHELL': '/bin/bash', 18:03:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.nkHBCufVlR/Listeners', 18:03:59 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 18:03:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:03:59 INFO - 'USER': 'cltbld', 18:03:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:03:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:03:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:03:59 INFO - 'XPC_FLAGS': '0x0', 18:03:59 INFO - 'XPC_SERVICE_NAME': '0', 18:03:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:03:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0107', '--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 18:03:59 INFO - mozversion application_buildid: 20170522162454 18:03:59 INFO - mozversion application_changeset: d712c82c59ec5a277047a75d09bec48be4a64b87 18:03:59 INFO - mozversion application_display_name: Nightly 18:03:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:03:59 INFO - mozversion application_name: Firefox 18:03:59 INFO - mozversion application_remotingname: firefox 18:03:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:03:59 INFO - mozversion application_vendor: Mozilla 18:03:59 INFO - mozversion application_version: 55.0a1 18:03:59 INFO - mozversion platform_buildid: 20170522162454 18:03:59 INFO - mozversion platform_changeset: d712c82c59ec5a277047a75d09bec48be4a64b87 18:03:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:03:59 INFO - mozversion platform_version: 55.0a1 18:03:59 INFO - using testdate: 1495501439 18:03:59 INFO - actual date: 1495501439 18:03:59 INFO - starting webserver on 'localhost:49226' 18:03:59 INFO - SUITE-START | Running 2 tests 18:03:59 INFO - TEST-START | dromaeo_css 18:03:59 INFO - operating with platform_type : mac_ 18:03:59 INFO - Initialising browser for dromaeo_css test... 18:03:59 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmpjT_6FW/profile http://localhost:49226/getInfo.html) 18:04:00 INFO - PID 1634 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2978: String.localeCompare is deprecated; use String.prototype.localeCompare instead 18:04:02 INFO - PID 1634 | 2017-05-22 18:04:02.578 plugin-container[1636:7664] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e4b, name = 'com.apple.tsm.portname' 18:04:02 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:04:02 INFO - PID 1634 | 2017-05-22 18:04:02.581 plugin-container[1636:7664] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa30b, name = 'com.apple.CFPasteboardClient' 18:04:02 INFO - PID 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:04:02 INFO - PID 1634 | 2017-05-22 18:04:02.581 plugin-container[1636:7664] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:04:02 INFO - PID 1634 | __metrics Screen width/height:1600/1200 18:04:02 INFO - PID 1634 | colorDepth:24 18:04:02 INFO - PID 1634 | Browser inner width/height: 1024/691 18:04:02 INFO - PID 1634 | __metrics 18:04:03 INFO - TEST-INFO | 1634: exit 0 18:04:03 INFO - Browser initialized. 18:04:03 INFO - Running cycle 1/1 for dromaeo_css test... 18:04:03 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmpjT_6FW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:04:05 INFO - PID 1638 | 2017-05-22 18:04:05.754 plugin-container[1639:7815] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 18:04:05 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:04:05 INFO - PID 1638 | 2017-05-22 18:04:05.756 plugin-container[1639:7815] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4923, name = 'com.apple.CFPasteboardClient' 18:04:05 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:04:05 INFO - PID 1638 | 2017-05-22 18:04:05.756 plugin-container[1639:7815] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:04:06 INFO - PID 1638 | 2017-05-22 18:04:06.334 plugin-container[1640:7867] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953b, name = 'com.apple.tsm.portname' 18:04:06 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:04:06 INFO - PID 1638 | 2017-05-22 18:04:06.334 plugin-container[1640:7867] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 18:04:06 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:04:06 INFO - PID 1638 | 2017-05-22 18:04:06.334 plugin-container[1640:7867] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:04:15 INFO - PID 1638 | RSS: Main: 243744768 18:04:15 INFO - PID 1638 | 18:07:18 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 18:07:19 INFO - PID 1638 | RSS: Main: 264527872 18:07:19 INFO - PID 1638 | 18:10:21 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 18:10:21 INFO - PID 1638 | RSS: Main: 272904192 18:10:21 INFO - PID 1638 | 18:13:23 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 18:13:24 INFO - PID 1638 | RSS: Main: 277549056 18:13:24 INFO - PID 1638 | 18:16:26 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 18:16:26 INFO - PID 1638 | RSS: Main: 275554304 18:16:26 INFO - PID 1638 | 18:19:29 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 18:19:30 INFO - PID 1638 | RSS: Main: 277143552 18:19:30 INFO - PID 1638 | 18:22:31 INFO - PID 1638 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 18:22:32 INFO - PID 1638 | RSS: Main: 278331392 18:22:32 INFO - PID 1638 | 18:22:32 INFO - PID 1638 | __start_tp_report 18:22:32 INFO - PID 1638 | _x_x_mozilla_page_load 18:22:32 INFO - PID 1638 | _x_x_mozilla_page_load_details 18:22:32 INFO - PID 1638 | |i|pagename|runs| 18:22:32 INFO - PID 1638 | |0;dojo.html;3504.246586983799;3569.714422846171;3594.0475773919898;3594.9999999999995;3621.9456708149382;6119.563485741598;6208.596441231322;6217.253929528457;6248.187735594378;6259.812205633827;6185.56701030928;6197.752089916398;6274.309825919146;6279.497640188785;6292.622442653444;5432.375276843154;5448.673079615216;5512.944870551303;5523.061079616455;5530.419305972875;5542.196381524676;5624.15637654351;5640.182173584828;5667.178259152441;5679.8580035499235;5415.268938693304;5560.6941618210985;5561.805336813192;5609.915851262234;5636.126400407943;4595.747233902134;5490.835274941758;5516.7517461714315;5573.665580065209;5580.218769372291;4245.511766146902;4740.170470167707;5255.816046438356;5275.340582427197;5531.612787104904;5191.247269145991;6173.32093469718;6227.159333489966;6278.4035516625845;6324.968375158141;6476.611403315769;6514.543981921267;6569.901451478232;6711.563748356341;6740.865182696319;6290.842728931768;6786.185657721056;6812.182538095411;6814.727410903558;6849.965750171267;5907.616004959664;6330.461910737639;6356.173697419306;6365.458935990493;6371.585846919936;5579.246801681802;5985.191999080069;6936.271691472423;6972.1981972072845;7007.8598428031155;6388.39310265524;6393.680315984182;6417;6448.903266451007;6455.386738259858;5925.34821169671;6622.668866556653;6806.115205023414;6815.216250131299;6833.3166683332265;5364.6512976656395;6442.35576442352;6490.286068532458;6583;6605.702743376463;5765.472149880285;6348.904766428507;6723;6748.561343512754;6758.83102922421;5375.489328513785;5395.082835917903;5403.081476149064;5409.594280428983;5441.455854414527;4688.156131896223;4737.431508219036;4788.592969597554;4799.784009719571;4868.902621947541;5428.972855135778;6331.335209802996;6348.301686814447;6418.037294405876;6422.775202867877;6037.215162028908;6841.589504629738;6871.690773915186;6876.759313424006;6879.174499060145;6226.439620434183;6291.779787707408;6360.077788720702;6434.646094464756;6457.935420645734;5955.7617695292565;6004.819855404345;6016.578839481282;6050.364711705208;6051.9697401513595;6921.723131074801;6927.515073944977;6983.9650801745665;6995.125609298838;7019.613921234279;6495.772647957298;6754.729810807709;6805.4895882808;6836;6851.794446166424;6789.422899053438;6870.3473170048765;6928.653567321715;6962.094927659372;6970.895436568354;6710.932890671031;6736.5958042517605;6736.5958042517605;6738.35985581369;6738.528303018938;6753.662316884038;6782.220044694924;6817.113775209191;6833.931660683528;6848.828779280558;6340.397662222082;6382.840428989312;6466.094746735367;6544.149260596092;6571.3100124488155;6583.769568065095;6619.404253617197;6635.601863888182;6644.634545100066;6649.2685804562425;6291.276503202007;6344.291324950681;6420.711068001858;6484.870302594016;6504.414602685781;5887.676177810262;6432.614043157426;6527.11883895668;6586.703598338182;6591.604503729792;6431.742730290736;6447.097406363207;6480.384363485461;6483.319251478527;6509.349065093642 18:22:32 INFO - PID 1638 | |1;ext.html;783.8236396810719;801.8195905921168;828.823071238841;1208.4622343057338;1278.1947373154924;37451.438228426596;38160;38374.80812595933;38404.80797596008;38410.8079459603;38147.80926095373;38555;38624.84125476233;38712.80643596778;38734.8063259684;7671.923280767191;7782.961085194566;8061;8276.793080172984;8301.003879534464;8197.631106600218;8283.50298982063;8365.330773538133;8367.995840499148;8385.077641459435;8312;8323.084460709317;8352.955880514935;8371.623276952552;8394.45436046655;8299.336053115736;8330;8336.458130221577;8340.207680270365;8346.874796878052;8113.675452981874;8148.144444833267;8173.918260817375;8191.918080819235;8201.343892488587;35650.28699426023;35808;35871.282574348625;35991;36052.49343832021;3520.559930008749;3522.3659741246565;3558.430651095812;3561.12752375667;3566.375884220251;29393.559096613353;29738.256543586584;29763.851180744176;29774.255643608867;29907;3000.639923209229;3075.5694202811624;3104.984475077633;3126.6248050234117;3145.418097651942;238961;314662.2800657992;320583;324770.3761481201;327691.36154319317;36620;46575.835604110165;46637.766811165726;46679.533204668194;46693;48307;48365.274520882216;49202.50797492051;50160.74598135003;50233;12908.16096953695;13355.599332020054;14128.929355353157;14200;14238.928805355907;11394.430278486043;11505.309681419141;11512.712182195512;11536.25014374063;11536.384906113151;10619.150467962545;12977.480900763863;13074.803877941838;13131.949444044454;13174.341282936006;5391.541718953933;5483.588730845203;5887.705614719247;5902.291724993029;5925.40745925404;964.9035096490435;971;995.4375777685584;999.695092996643;1041.703114612347;17216.827831721523;20907.217518683057;23177.76822231756;23569.292921212393;23965.760342396352;1100.884407137255;1101.6915263726169;1125.2011072138873;1138.6640940922448;1144.3134119528384;11897.107716921093;12111.576094836639;12152.392380380945;12178.75642487163;12223.388830558437;319607;322748;324902;327110;327520;41429.79285103555;50339;50641.987160257326;50652.74673626608;50662;1446.6311090671811;1479.445208046961;1519.088546871868;1519.9696006079816;1536.5236776599068;23137.76862231356;23220.3033908983;23279.41801454977;23283.76716232816;23363.415914602272;33925.83037084898;35745;35878;35902.281954360245;35948;236061;237709;238202.8089859609;238314;238838;32643;32908.67091329056;33036.66963330336;33157.00528984134;33224.83713070128;41150.58849411467;42852;42969.925751856455;42971.78514107409;43059.9235019127;28140.29649258703;28933.56599651049;29790.553141703323;30121.246968825955;30224.69775302219;29178.854105729337;29445.411091778475;29789.10632681023;29829.701702982693;30444.782208711404;35508;35549;35619.64380356163;35735.28529429449;35758.64241357553;29206.853965730035;29214.56178157287;30715.84642076865;31548.842255789492;31574;22771.544569108857;23963.880180598986;23969;23999.80000999978;24329.51340973206 18:22:32 INFO - PID 1638 | |2;jquery.html;425.5595458700246;429.6562252910374;433.24831417490645;443.49663132344784;450.03916637977926;18844.246230150777;20470.590588188246;20858.269960551384;20918.849463279512;21181.68227476589;20415.591688166227;22192.223272185478;22678;22831.42921426965;22903.198388056422;5205.479452054802;5919.822405327847;6156.076588511714;6174.722137503823;6177.752889884399;5550.389457159709;6073.574849760518;6171.321154672983;6189.473894718954;6227.657478838662;6004.849878753044;6177.320494745574;6179.2893817210925;6186.81439556814;6188.288346840126;6048.637081775086;6142.262928448592;6155.415235552616;6182.752689892399;6189.628622282632;4276.9144617107795;5263.920896216305;5454.8909021819345;5482.068048431776;5496.615236933416;27891.02381416641;28425.857870710723;28493.857530712216;28539.28651783722;28951.710482895112;2173.217641649005;2227.52108296717;2244.2818298144603;2246.2587346175724;2248.87631180285;17561;17623.735643965352;18090.276388944425;18248.726269105973;18535.814641853543;1792.6145878636025;2117.2166298469665;2164.285785690718;2166.7508236552685;2170.4573856535867;154718;162494;162805.3719462814;164672;165907;10170.542325595366;11047.613333533287;12279.815802762965;12554.68613284657;12642.2414655121;16132.193390330436;16382.262798174112;16402.09788461646;16425.260863260944;16451.5887102821;8665.220130188314;8936.106389361143;8941.776455588662;9017.63929442828;9136.08639136081;7495.8500829983095;8407.915920840836;8422.915770842214;8457.661693532189;8608.311335093178;7794.844103117905;7907.762767116996;7917.089534703583;7921.801954951057;7923.168067352845;1837.5773572078228;1872.2604571194302;2109.704641350212;2144.828413726898;2308.4921317310163;782.8160382310152;915.4232833314969;962.138885697311;1002.9448380339152;1005.6228914157191;20442.488937776674;20891.68662470064;20975.475613109487;20989.37031889046;21026.053827577794;812.6058861452191;1003.9849402258972;1021.6781713760141;1032.4682788363998;1037.0873631204643;8347;9762.776914350789;10486.003829636325;10743.14054875608;10958.068564171976;543837;546832;552807;560351;560756;120715.39642301733;123743.38822729493;126379;126421.36789315994;127808;1201.0991756182864;1214.3563911127117;1250.6373151786063;1251.1742250115062;1255.8806913343217;20241.493962651053;20626.27808026712;21796.67304990458;21916.89041554782;22297.219597314015;53685;54928.17607735967;55413.44586554083;56247.71876140593;57136.71431642815;74394.51210975643;77947.61026194833;79332.60333698295;79901;80057;8483.703070392507;8773.473591584525;8848.1151884811;8957.910420895707;9053.411528250643;14093;14097.365618547396;14121.364538595582;14333.283335833376;14617.122972621677;10216.846747298945;11852.466639001093;11908.940455297668;11984.940075299917;12063.39683015828;11127.944360278147;11136.276142050741;11193.832092518782;11385.601503947322;11522.82715759281;11752.23610465317;11840.28958262507;12095.334756588472;12223.511059557519;12281.570145044881;7584.5070070447455;8632.100937793675;8815.515146666738;8842.115788421106;8893.155150260714;11683.88316116862;11769;11906.583269585522;11926.224795388272;12011.75976480448 18:22:32 INFO - PID 1638 | |3;mootools.html;1347.7843545032795;1390;1404.571605660274;1406.036864747648;1414.7312010717967;11116;11259.718507037307;11318.547258109676;11831;11845.466953987081;10660.840087398676;11454;11679.416029198548;11685.7662846743;11702.356370399624;2373.810095192385;2559.731228221038;2560.4751026039617;2562.192909233595;2565.9864353580324;2501.8123640726926;2533.5439620868237;2546.2234018624313;2554.8211625186245;2555.6166575013713;2479.7396273391305;2503.5118151960373;2531.3418511187133;2534.0497313507435;2534.3664083979006;2481.4416756229884;2512.133314006665;2527.4439623282847;2541.983206717328;2551.7448255174518;2410.4455975125643;2503.349129226396;2504.47406044729;2507.323022783838;2518.4333524956924;11528.942355288254;11538.480768365444;11603.129765267555;11621;11638.883611163865;6357.872842543124;6593.109930159415;6593.142891424084;6598.769043083469;6618.106555614979;3922.5685174630767;4202.453681021448;4240.830366785325;4275.273203555403;4285.4214681017975;1014.4116412480744;1027.0672957536628;1236.3385588709998;1243.7512497500536;1275.9489620415172;15420;16018.119003454805;16085.356585736556;16215.837841621551;16272.51182464528;5359.5712343012465;5460.863478413072;5517.69652669107;5606.990741666457;5627.212190293362;11328.490217940212;11338.716532086599;11341.489632966537;11343.376114313627;11379.487923043484;6377.7448902044325;6382.872342553216;6384.968075159687;6389.7124629390855;6397.872042559123;5091.389033316026;5827.650340979555;6096.573239873166;6119.388061193934;6133.1413602095745;3780.5652349980105;4221.051571989414;4301;4316.589923957219;4336.002719374498;1257.1200159887933;1281.423359488234;1290.9289989050692;1294.4563283420987;1296.416612524349;769.7767647382309;780.605794073989;791.9920800791919;793.5992323876405;801.7314199743151;6420.26166990802;6527.902081468782;6714.060031595581;6714.462842972648;6740.157480314961;873.8164985353147;894.8076163624852;906.4833045164324;909.1817364372118;909.7634615000147;3836.2519308734527;4004.8598299059977;4124.896877578085;4135.834566617302;4175.728577642443;14132.717345653029;14892.776608350883;14925.17911514877;14932.925335373471;14966.476173333882;14435.639109022148;14685.77971330431;14808.555743327503;14818.851811481747;14851;7331.963340183372;8088.676452941816;8579.571021448903;8595.914040859512;8603.956980215058;5982;6543.967280163759;6745.561538499982;6940.063091482787;7023.578585284695;12628;12903.41934612926;13181.934090329487;13212.801807973081;13294.468221271041;13035.413406396545;13251.271180084803;13434.93282533581;13577.11748736329;13581.93209033988;8271.048829384457;8315.542645154575;8417.62120704582;8442.831143377221;8447.831043379221;10522.368657880552;10562.419066951392;10576.99518545737;10677.89322106769;10738.463076846283;9081.409708368936;9116.632505124284;9131.726048218565;9156.221721153777;9189.989101198997;8782.077881822572;8918.910810891808;8945.73162805117;8948.597313121056;8984.55077246122;7796.532208067534;7958.84082318362;7964.601769911597;7966.0440747110715;7982.521048737094;5795.7971471;5798.971005145116;5799.420057994167;5906.2026626406605;5944.554158438221;5422.240886275846;6037.0642550404755;6057.666828324308;6076.270847498328;6250.593711408744 18:22:32 INFO - PID 1638 | |4;prototype.html;371.8977281247657;390.3792969179005;396.21351617040153;403.3566461493918;404.1371671481387;6154.630722156674;6205.162303089081;6222.813315600528;6286.937130628692;6295.43341099301;5947.988841896877;6099.3290738018895;6111.385903642744;6137.938620613792;6161.938380616204;3872.4578559001693;4366.694331396803;4512.5261847506035;4522.751248681321;4535.63714902807;3791.748722921443;4515.119551687422;4520.2089634314025;4522.8891352771325;4542.704724192933;3749.8312575934146;4495.100979804036;4502.572255635726;4505.684602077856;4513.014273719553;4374.119128774626;4425.5353187915125;4471.23988921884;4508.399518171068;4515.187266292066;3830.2339532093692;3858;3858.5369755629504;3858.6141385861188;3873.2640798248526;5060.507150390644;5199.856031673025;5234.973825130888;5998.25021872266;6076.270847498285;5015.498450154955;5202.895942081175;5323.281357016831;5377.838664840061;5410.1073322901675;5801.593888427812;6109.059656521563;6332.905006424908;6581.703823327962;6633.635150066744;3910.393888947218;4309.525952145293;4362.847300344504;4368.388425620416;4369.3227549729845;5393.676379417208;5633.971830140864;5707.429257074301;6619.5035372347265;7057.5412598181465;4013.5751808108284;4247.702660813713;4386.056997745436;4390.143921935256;4405.493368262691;4030.9922519370157;4202.684798640114;4446.82212711488;4503.279475283939;4514.390557274726;4275.648393383626;4697.318888761179;5104.69371837691;5202.531772140525;5269.209618557247;6610.834729131714;6647.648642842558;6659.966700166468;6700.363465470772;6744.359285867835;1497.9625509362136;1518.6583018820897;1522.7285216843838;1525.4279645133074;1526.63360793411;945.2013048973606;984.5766320482118;996.7358649957768;1001.0890090018048;1016.6594190946116;4618.237990731492;5906.911396329058;6046.699959508726;6208.906866397007;6236.84828734086;6221.097940798559;6262.906056409158;6289.339619339995;6702.296258892844;6801.857414914286;4976.552110310089;5107.3697046021325;5440.591955603346;5877.382874798084;6334.113224148623;2947.985260073729;3569.696575791087;3587.820608969541;3600.963990360115;3625.7099432045375;6860;7007.369336759716;7031.472639552054;7098.432125429953;7219.314165154302;5976.89427455922;7119.110111236096;7119.608421536762;7185.317394847481;7202.459815513857;6960.582365058112;7061.82345441368;7099.787006389816;7210.71157153708;7234.96382518084;6101.176341193882;6937.687804048729;7000.404965577983;7012.792324605196;7026.473014524034;6527.771527996498;6666.999850067392;6741.730330786713;6781.7287308509085;6807.829804254933;5829;6704.664766761545;6734.158230221223;6973.75591854303;7053.470989675692;4988.775505102207;5019.648624596243;5031.270465782368;5044.672096313728;5046.082488654288;5707.286589176353;6027.558838670346;6308.558400911892;6866.725330986705;6873.347032031949;5137.845864624067;6514.185726784152;6615.974523948796;6713.462922966249;6714.045819839255;6550.868982620225;6606.669666516751;6652.368025037614;6694.995750637443;6712.6979285931275;4867.464578896248;5314.256004159498;6440.130582371507;6505.414512693691;6506.4539825796;5617.522510586646;6358.745650173942;6658.533902626963;6705.6647167642395;6719.731210751711;5919.822405327847;6409.551331406757;6526.967365163144;6536.444402225864;6546.279909210079 18:22:32 INFO - PID 1638 | |5;yui.html;834.4075706248564;846.9195426434488;853.5134973065356;867.5141920524507;884;16637.168141592916;17042.74435883463;17109.657806843872;18519.166637501312;19133;17804.46586602404;18354.1740621672;18365.173567189475;18371;18688.06559672018;998.3543609435001;1007.1741172238767;1014.3356101753363;1016.4054028393399;1025.4770067265674;1036.052012408649;1036.7978244242377;1037.3879411147423;1038.7610849504617;1039.9480025998707;1068.642004928346;1083.7940791249637;1087.6356420599109;1095.0090168397574;1096.0738176241064;831.9750407487785;1047.3087762076993;1061.273027975839;1064.9148068154568;1079.9190060745436;748.3003391828657;774.1832366852968;791.3155120820506;794.566961006253;803.1968031968032;17014.149292535323;17036.829631703647;17055.744163837553;17160.742588861176;17219.96680199192;14922.70154596902;16970.66058678832;17105.34867095602;17138.82861171385;17371.392001279895;14258.358373873201;14389.640258993504;14745.410183592643;14746.188959607218;14760.188189649565;3558.430651095838;3589.784612923233;3598.982005089958;3599.6400359964055;3599.6580324869096;127229.36385318107;128651;130555.34722326328;130594.3470282652;131482;12999.415026323839;13170.143940644019;13288.734225315633;13312;13315.134516256412;25288.873555632104;25417.856196471203;25501;25867.96528138891;25950.74049259521;7810.57041862703;7941.64262608184;7994;8038;8059.435839491297;3835.520559930009;3879.3792993120965;3900.90247743803;3908.902277443087;3909.6090390960676;4335.523092459827;4347.065380943112;4399.868003959887;4437.156940181355;4440.311751678495;939.9953000234955;943.0852073488705;958.0802429667458;959.4579062829479;960.4141473701037;531.3809412034936;631.7441436218339;640.8045546108482;651.2998526583941;672.8822456070168;9693.273004524688;11352.716182095348;12027.398630068461;12169.087318451151;12252.326122063372;655.757369773187;665.0921492163162;669.4543946683535;689.6965335252473;691.837418206721;4781.665283430127;5370.785168593291;5623.212750214973;5637.098064309691;5677.744501497443;88474.55762721274;89457.65813512672;107356;112975;117608.82391175978;73707.2629273711;92499.07500925039;100737;102750;104558.43162352571;63453.365466344745;67685.66157169183;69177;75063.62468187624;76096.61951690317;10652.680419587425;10810.405427701551;11288.9435552825;11881.702957426134;12490.562830300896;29709.70290297156;37985.62014379821;41071.589284106776;41634.375484367156;42051;61426.69286653717;63258;73850.52298954099;75220.62389688016;75605.24394755982;13884.444622214998;13895;16529.834701652828;17213.967161970322;17332.39336623212;16842.15789210559;17498.82501174972;17630;18606.255749769858;18612.906935465235;16039;16656.666866662843;16682.833171668615;16743;16771.90982586128;16715.83284167143;16841.2421441033;16854.57863553421;16895.66208675795;16931.746023809406;15607.985480943702;15787.842121578637;16024.439144629881;16192.919035405219;16287.429939952044;11490.368029758443;11560.537578497102;11892.405379730806;12197.756044878875;12233.449494772934;12095.60900496454;12286.38568071575;12635.305058221886;13384.531541396005;13694.65763355924 18:22:32 INFO - PID 1638 | __end_tp_report 18:22:32 INFO - PID 1638 | __start_cc_report 18:22:32 INFO - PID 1638 | _x_x_mozilla_cycle_collect,347 18:22:32 INFO - PID 1638 | __end_cc_report 18:22:32 INFO - PID 1638 | __startTimestamp1495502552024__endTimestamp 18:22:32 INFO - PID 1638 | 18:22:32 INFO - PID 1638 | ------- Summary: start ------- 18:22:32 INFO - PID 1638 | Number of tests: 6 18:22:32 INFO - PID 1638 | 18:22:32 INFO - PID 1638 | [#0] dojo.html Cycles:165 Average:6150.03 Median:6371.59 stddev:746.88 (11.7%) stddev-sans-first:719.75 18:22:32 INFO - PID 1638 | Values: 3504.2 3569.7 3594.0 3595.0 3621.9 6119.6 6208.6 6217.3 6248.2 6259.8 6185.6 6197.8 6274.3 6279.5 6292.6 5432.4 5448.7 5512.9 5523.1 5530.4 5542.2 5624.2 5640.2 5667.2 5679.9 5415.3 5560.7 5561.8 5609.9 5636.1 4595.7 5490.8 5516.8 5573.7 5580.2 4245.5 4740.2 5255.8 5275.3 5531.6 5191.2 6173.3 6227.2 6278.4 6325.0 6476.6 6514.5 6569.9 6711.6 6740.9 6290.8 6786.2 6812.2 6814.7 6850.0 5907.6 6330.5 6356.2 6365.5 6371.6 5579.2 5985.2 6936.3 6972.2 7007.9 6388.4 6393.7 6417.0 6448.9 6455.4 5925.3 6622.7 6806.1 6815.2 6833.3 5364.7 6442.4 6490.3 6583.0 6605.7 5765.5 6348.9 6723.0 6748.6 6758.8 5375.5 5395.1 5403.1 5409.6 5441.5 4688.2 4737.4 4788.6 4799.8 4868.9 5429.0 6331.3 6348.3 6418.0 6422.8 6037.2 6841.6 6871.7 6876.8 6879.2 6226.4 6291.8 6360.1 6434.6 6457.9 5955.8 6004.8 6016.6 6050.4 6052.0 6921.7 6927.5 6984.0 6995.1 7019.6 6495.8 6754.7 6805.5 6836.0 6851.8 6789.4 6870.3 6928.7 6962.1 6970.9 6710.9 6736.6 6736.6 6738.4 6738.5 6753.7 6782.2 6817.1 6833.9 6848.8 6340.4 6382.8 6466.1 6544.1 6571.3 6583.8 6619.4 6635.6 6644.6 6649.3 6291.3 6344.3 6420.7 6484.9 6504.4 5887.7 6432.6 6527.1 6586.7 6591.6 6431.7 6447.1 6480.4 6483.3 6509.3 18:22:32 INFO - PID 1638 | 18:22:32 INFO - PID 1638 | [#1] ext.html Cycles:180 Average:43493.39 Median:33096.84 stddev:76718.22 (231.8%) stddev-sans-first:76866.42 18:22:32 INFO - PID 1638 | Values: 783.8 801.8 828.8 1208.5 1278.2 37451.4 38160.0 38374.8 38404.8 38410.8 38147.8 38555.0 38624.8 38712.8 38734.8 7671.9 7783.0 8061.0 8276.8 8301.0 8197.6 8283.5 8365.3 8368.0 8385.1 8312.0 8323.1 8353.0 8371.6 8394.5 8299.3 8330.0 8336.5 8340.2 8346.9 8113.7 8148.1 8173.9 8191.9 8201.3 35650.3 35808.0 35871.3 35991.0 36052.5 3520.6 3522.4 3558.4 3561.1 3566.4 29393.6 29738.3 29763.9 29774.3 29907.0 3000.6 3075.6 3105.0 3126.6 3145.4 238961.0 314662.3 320583.0 324770.4 327691.4 36620.0 46575.8 46637.8 46679.5 46693.0 48307.0 48365.3 49202.5 50160.7 50233.0 12908.2 13355.6 14128.9 14200.0 14238.9 11394.4 11505.3 11512.7 11536.3 11536.4 10619.2 12977.5 13074.8 13131.9 13174.3 5391.5 5483.6 5887.7 5902.3 5925.4 964.9 971.0 995.4 999.7 1041.7 17216.8 20907.2 23177.8 23569.3 23965.8 1100.9 1101.7 1125.2 1138.7 1144.3 11897.1 12111.6 12152.4 12178.8 12223.4 319607.0 322748.0 324902.0 327110.0 327520.0 41429.8 50339.0 50642.0 50652.7 50662.0 1446.6 1479.4 1519.1 1520.0 1536.5 23137.8 23220.3 23279.4 23283.8 23363.4 33925.8 35745.0 35878.0 35902.3 35948.0 236061.0 237709.0 238202.8 238314.0 238838.0 32643.0 32908.7 33036.7 33157.0 33224.8 41150.6 42852.0 42969.9 42971.8 43059.9 28140.3 28933.6 29790.6 30121.2 30224.7 29178.9 29445.4 29789.1 29829.7 30444.8 35508.0 35549.0 35619.6 35735.3 35758.6 29206.9 29214.6 30715.8 31548.8 31574.0 22771.5 23963.9 23969.0 23999.8 24329.5 18:22:32 INFO - PID 1638 | 18:22:32 INFO - PID 1638 | [#2] jquery.html Cycles:180 Average:35730.84 Median:12262.37 stddev:93907.85 (765.8%) stddev-sans-first:94133.87 18:22:32 INFO - PID 1638 | Values: 425.6 429.7 433.2 443.5 450.0 18844.2 20470.6 20858.3 20918.8 21181.7 20415.6 22192.2 22678.0 22831.4 22903.2 5205.5 5919.8 6156.1 6174.7 6177.8 5550.4 6073.6 6171.3 6189.5 6227.7 6004.8 6177.3 6179.3 6186.8 6188.3 6048.6 6142.3 6155.4 6182.8 6189.6 4276.9 5263.9 5454.9 5482.1 5496.6 27891.0 28425.9 28493.9 28539.3 28951.7 2173.2 2227.5 2244.3 2246.3 2248.9 17561.0 17623.7 18090.3 18248.7 18535.8 1792.6 2117.2 2164.3 2166.8 2170.5 154718.0 162494.0 162805.4 164672.0 165907.0 10170.5 11047.6 12279.8 12554.7 12642.2 16132.2 16382.3 16402.1 16425.3 16451.6 8665.2 8936.1 8941.8 9017.6 9136.1 7495.9 8407.9 8422.9 8457.7 8608.3 7794.8 7907.8 7917.1 7921.8 7923.2 1837.6 1872.3 2109.7 2144.8 2308.5 782.8 915.4 962.1 1002.9 1005.6 20442.5 20891.7 20975.5 20989.4 21026.1 812.6 1004.0 1021.7 1032.5 1037.1 8347.0 9762.8 10486.0 10743.1 10958.1 543837.0 546832.0 552807.0 560351.0 560756.0 120715.4 123743.4 126379.0 126421.4 127808.0 1201.1 1214.4 1250.6 1251.2 1255.9 20241.5 20626.3 21796.7 21916.9 22297.2 53685.0 54928.2 55413.4 56247.7 57136.7 74394.5 77947.6 79332.6 79901.0 80057.0 8483.7 8773.5 8848.1 8957.9 9053.4 14093.0 14097.4 14121.4 14333.3 14617.1 10216.8 11852.5 11908.9 11984.9 12063.4 11127.9 11136.3 11193.8 11385.6 11522.8 11752.2 11840.3 12095.3 12223.5 12281.6 7584.5 8632.1 8815.5 8842.1 8893.2 11683.9 11769.0 11906.6 11926.2 12011.8 18:22:32 INFO - PID 1638 | 18:22:32 INFO - PID 1638 | [#3] mootools.html Cycles:180 Average:6969.88 Median:3808.41 stddev:4391.70 (115.3%) stddev-sans-first:4383.70 18:22:32 INFO - PID 1638 | Values: 1347.8 1390.0 1404.6 1406.0 1414.7 11116.0 11259.7 11318.5 11831.0 11845.5 10660.8 11454.0 11679.4 11685.8 11702.4 2373.8 2559.7 2560.5 2562.2 2566.0 2501.8 2533.5 2546.2 2554.8 2555.6 2479.7 2503.5 2531.3 2534.0 2534.4 2481.4 2512.1 2527.4 2542.0 2551.7 2410.4 2503.3 2504.5 2507.3 2518.4 11528.9 11538.5 11603.1 11621.0 11638.9 6357.9 6593.1 6593.1 6598.8 6618.1 3922.6 4202.5 4240.8 4275.3 4285.4 1014.4 1027.1 1236.3 1243.8 1275.9 15420.0 16018.1 16085.4 16215.8 16272.5 5359.6 5460.9 5517.7 5607.0 5627.2 11328.5 11338.7 11341.5 11343.4 11379.5 6377.7 6382.9 6385.0 6389.7 6397.9 5091.4 5827.7 6096.6 6119.4 6133.1 3780.6 4221.1 4301.0 4316.6 4336.0 1257.1 1281.4 1290.9 1294.5 1296.4 769.8 780.6 792.0 793.6 801.7 6420.3 6527.9 6714.1 6714.5 6740.2 873.8 894.8 906.5 909.2 909.8 3836.3 4004.9 4124.9 4135.8 4175.7 14132.7 14892.8 14925.2 14932.9 14966.5 14435.6 14685.8 14808.6 14818.9 14851.0 7332.0 8088.7 8579.6 8595.9 8604.0 5982.0 6544.0 6745.6 6940.1 7023.6 12628.0 12903.4 13181.9 13212.8 13294.5 13035.4 13251.3 13434.9 13577.1 13581.9 8271.0 8315.5 8417.6 8442.8 8447.8 10522.4 10562.4 10577.0 10677.9 10738.5 9081.4 9116.6 9131.7 9156.2 9190.0 8782.1 8918.9 8945.7 8948.6 8984.6 7796.5 7958.8 7964.6 7966.0 7982.5 5795.8 5799.0 5799.4 5906.2 5944.6 5422.2 6037.1 6057.7 6076.3 6250.6 18:22:32 INFO - PID 1638 | 18:22:32 INFO - PID 1638 | [#4] prototype.html Cycles:180 Average:5274.57 Median:5913.37 stddev:1682.56 (28.5%) stddev-sans-first:1646.55 18:22:32 INFO - PID 1638 | Values: 371.9 390.4 396.2 403.4 404.1 6154.6 6205.2 6222.8 6286.9 6295.4 5948.0 6099.3 6111.4 6137.9 6161.9 3872.5 4366.7 4512.5 4522.8 4535.6 3791.7 4515.1 4520.2 4522.9 4542.7 3749.8 4495.1 4502.6 4505.7 4513.0 4374.1 4425.5 4471.2 4508.4 4515.2 3830.2 3858.0 3858.5 3858.6 3873.3 5060.5 5199.9 5235.0 5998.3 6076.3 5015.5 5202.9 5323.3 5377.8 5410.1 5801.6 6109.1 6332.9 6581.7 6633.6 3910.4 4309.5 4362.8 4368.4 4369.3 5393.7 5634.0 5707.4 6619.5 7057.5 4013.6 4247.7 4386.1 4390.1 4405.5 4031.0 4202.7 4446.8 4503.3 4514.4 4275.6 4697.3 5104.7 5202.5 5269.2 6610.8 6647.6 6660.0 6700.4 6744.4 1498.0 1518.7 1522.7 1525.4 1526.6 945.2 984.6 996.7 1001.1 1016.7 4618.2 5906.9 6046.7 6208.9 6236.8 6221.1 6262.9 6289.3 6702.3 6801.9 4976.6 5107.4 5440.6 5877.4 6334.1 2948.0 3569.7 3587.8 3601.0 3625.7 6860.0 7007.4 7031.5 7098.4 7219.3 5976.9 7119.1 7119.6 7185.3 7202.5 6960.6 7061.8 7099.8 7210.7 7235.0 6101.2 6937.7 7000.4 7012.8 7026.5 6527.8 6667.0 6741.7 6781.7 6807.8 5829.0 6704.7 6734.2 6973.8 7053.5 4988.8 5019.6 5031.3 5044.7 5046.1 5707.3 6027.6 6308.6 6866.7 6873.3 5137.8 6514.2 6616.0 6713.5 6714.0 6550.9 6606.7 6652.4 6695.0 6712.7 4867.5 5314.3 6440.1 6505.4 6506.5 5617.5 6358.7 6658.5 6705.7 6719.7 5919.8 6409.6 6527.0 6536.4 6546.3 18:22:32 INFO - PID 1638 | 18:22:32 INFO - PID 1638 | [#5] yui.html Cycles:180 Average:22042.38 Median:17351.89 stddev:31268.68 (180.2%) stddev-sans-first:31315.85 18:22:32 INFO - PID 1638 | Values: 834.4 846.9 853.5 867.5 884.0 16637.2 17042.7 17109.7 18519.2 19133.0 17804.5 18354.2 18365.2 18371.0 18688.1 998.4 1007.2 1014.3 1016.4 1025.5 1036.1 1036.8 1037.4 1038.8 1039.9 1068.6 1083.8 1087.6 1095.0 1096.1 832.0 1047.3 1061.3 1064.9 1079.9 748.3 774.2 791.3 794.6 803.2 17014.1 17036.8 17055.7 17160.7 17220.0 14922.7 16970.7 17105.3 17138.8 17371.4 14258.4 14389.6 14745.4 14746.2 14760.2 3558.4 3589.8 3599.0 3599.6 3599.7 127229.4 128651.0 130555.3 130594.3 131482.0 12999.4 13170.1 13288.7 13312.0 13315.1 25288.9 25417.9 25501.0 25868.0 25950.7 7810.6 7941.6 7994.0 8038.0 8059.4 3835.5 3879.4 3900.9 3908.9 3909.6 4335.5 4347.1 4399.9 4437.2 4440.3 940.0 943.1 958.1 959.5 960.4 531.4 631.7 640.8 651.3 672.9 9693.3 11352.7 12027.4 12169.1 12252.3 655.8 665.1 669.5 689.7 691.8 4781.7 5370.8 5623.2 5637.1 5677.7 88474.6 89457.7 107356.0 112975.0 117608.8 73707.3 92499.1 100737.0 102750.0 104558.4 63453.4 67685.7 69177.0 75063.6 76096.6 10652.7 10810.4 11288.9 11881.7 12490.6 29709.7 37985.6 41071.6 41634.4 42051.0 61426.7 63258.0 73850.5 75220.6 75605.2 13884.4 13895.0 16529.8 17214.0 17332.4 16842.2 17498.8 17630.0 18606.3 18612.9 16039.0 16656.7 16682.8 16743.0 16771.9 16715.8 16841.2 16854.6 16895.7 16931.7 15608.0 15787.8 16024.4 16192.9 16287.4 11490.4 11560.5 11892.4 12197.8 12233.4 12095.6 12286.4 12635.3 13384.5 13694.7 18:22:32 INFO - PID 1638 | -------- Summary: end -------- 18:22:32 INFO - PID 1638 | 18:22:32 INFO - TEST-INFO | 1638: exit 0 18:22:32 INFO - TEST-OK | dromaeo_css | took 1112835ms 18:22:32 INFO - TEST-START | kraken 18:22:32 INFO - operating with platform_type : mac_ 18:22:32 INFO - Initialising browser for kraken test... 18:22:32 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmpnJWjHe/profile http://localhost:49226/getInfo.html) 18:22:33 INFO - PID 1651 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2978: String.localeCompare is deprecated; use String.prototype.localeCompare instead 18:22:35 INFO - PID 1651 | 2017-05-22 18:22:35.256 plugin-container[1652:10474] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963b, name = 'com.apple.tsm.portname' 18:22:35 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:22:35 INFO - PID 1651 | 2017-05-22 18:22:35.260 plugin-container[1652:10474] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x491f, name = 'com.apple.CFPasteboardClient' 18:22:35 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:22:35 INFO - PID 1651 | 2017-05-22 18:22:35.260 plugin-container[1652:10474] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:22:35 INFO - PID 1651 | __metrics Screen width/height:1600/1200 18:22:35 INFO - PID 1651 | colorDepth:24 18:22:35 INFO - PID 1651 | Browser inner width/height: 1024/691 18:22:35 INFO - PID 1651 | __metrics 18:22:35 INFO - PID 1651 | 2017-05-22 18:22:35.888 plugin-container[1653:10528] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 18:22:35 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:22:35 INFO - PID 1651 | 2017-05-22 18:22:35.888 plugin-container[1653:10528] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 18:22:35 INFO - PID 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:22:35 INFO - PID 1651 | 2017-05-22 18:22:35.889 plugin-container[1653:10528] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:22:36 INFO - TEST-INFO | 1651: exit 0 18:22:36 INFO - Browser initialized. 18:22:36 INFO - Running cycle 1/1 for kraken test... 18:22:36 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmpnJWjHe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:22:38 INFO - PID 1654 | 2017-05-22 18:22:38.528 plugin-container[1655:10619] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9727, name = 'com.apple.tsm.portname' 18:22:38 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:22:38 INFO - PID 1654 | 2017-05-22 18:22:38.529 plugin-container[1655:10619] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 18:22:38 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:22:38 INFO - PID 1654 | 2017-05-22 18:22:38.529 plugin-container[1655:10619] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:22:39 INFO - PID 1654 | 2017-05-22 18:22:39.173 plugin-container[1656:10666] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953b, name = 'com.apple.tsm.portname' 18:22:39 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:22:39 INFO - PID 1654 | 2017-05-22 18:22:39.174 plugin-container[1656:10666] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 18:22:39 INFO - PID 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:22:39 INFO - PID 1654 | 2017-05-22 18:22:39.174 plugin-container[1656:10666] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:22:48 INFO - PID 1654 | RSS: Main: 243179520 18:22:48 INFO - PID 1654 | 18:22:51 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 18:22:52 INFO - PID 1654 | RSS: Main: 254435328 18:22:52 INFO - PID 1654 | 18:22:54 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 18:22:55 INFO - PID 1654 | RSS: Main: 262250496 18:22:55 INFO - PID 1654 | 18:22:58 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 18:22:59 INFO - PID 1654 | RSS: Main: 265183232 18:22:59 INFO - PID 1654 | 18:23:01 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 18:23:01 INFO - PID 1654 | RSS: Main: 266321920 18:23:01 INFO - PID 1654 | 18:23:03 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:23:04 INFO - PID 1654 | RSS: Main: 267976704 18:23:04 INFO - PID 1654 | 18:23:07 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 18:23:07 INFO - PID 1654 | RSS: Main: 277606400 18:23:07 INFO - PID 1654 | 18:23:10 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 18:23:11 INFO - PID 1654 | RSS: Main: 277397504 18:23:11 INFO - PID 1654 | 18:23:14 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 18:23:15 INFO - PID 1654 | RSS: Main: 276889600 18:23:15 INFO - PID 1654 | 18:23:16 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:23:17 INFO - PID 1654 | RSS: Main: 276189184 18:23:17 INFO - PID 1654 | 18:23:19 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:23:19 INFO - PID 1654 | RSS: Main: 275853312 18:23:19 INFO - PID 1654 | 18:23:22 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:23:22 INFO - PID 1654 | RSS: Main: 275554304 18:23:22 INFO - PID 1654 | 18:23:25 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:23:26 INFO - PID 1654 | RSS: Main: 275574784 18:23:26 INFO - PID 1654 | 18:23:29 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:23:29 INFO - PID 1654 | RSS: Main: 275832832 18:23:29 INFO - PID 1654 | 18:23:31 INFO - PID 1654 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 18:23:31 INFO - PID 1654 | RSS: Main: 277737472 18:23:31 INFO - PID 1654 | 18:23:31 INFO - PID 1654 | __start_tp_report 18:23:31 INFO - PID 1654 | _x_x_mozilla_page_load 18:23:31 INFO - PID 1654 | _x_x_mozilla_page_load_details 18:23:31 INFO - PID 1654 | |i|pagename|runs| 18:23:31 INFO - PID 1654 | |0;ai-astar;102;115;113;113;112;115;113;118;116;115 18:23:31 INFO - PID 1654 | |1;audio-beat-detection;145;118;135;140;96;96;143;149;168;112 18:23:31 INFO - PID 1654 | |2;audio-dft;196;200;197;198;200;212;212;197;201;196 18:23:31 INFO - PID 1654 | |3;audio-fft;78;59;62;88;89;86;73;58;81;59 18:23:31 INFO - PID 1654 | |4;audio-oscillator;87;95;115;105;74;71;87;70;68;89 18:23:31 INFO - PID 1654 | |5;imaging-gaussian-blur;102;95;88;92;88;88;93;105;89;102 18:23:31 INFO - PID 1654 | |6;imaging-darkroom;102;95;96;96;96;96;94;125;96;97 18:23:31 INFO - PID 1654 | |7;imaging-desaturate;92;99;97;90;126;94;146;72;139;145 18:23:31 INFO - PID 1654 | |8;json-parse-financial;50;51;51;50;49;50;51;49;50;52 18:23:31 INFO - PID 1654 | |9;json-stringify-tinderbox;61;64;76;84;64;60;56;59;55;57 18:23:31 INFO - PID 1654 | |10;stanford-crypto-aes;74;77;122;107;87;84;86;83;83;83 18:23:31 INFO - PID 1654 | |11;stanford-crypto-ccm;138;151;153;137;133;157;158;161;222;143 18:23:31 INFO - PID 1654 | |12;stanford-crypto-pbkdf2;187;187;182;187;183;191;184;186;183;184 18:23:31 INFO - PID 1654 | |13;stanford-crypto-sha256-iterative;68;70;68;72;71;66;68;67;70;69 18:23:31 INFO - PID 1654 | __end_tp_report 18:23:31 INFO - PID 1654 | __start_cc_report 18:23:31 INFO - PID 1654 | _x_x_mozilla_cycle_collect,909 18:23:31 INFO - PID 1654 | __end_cc_report 18:23:31 INFO - PID 1654 | __startTimestamp1495502611945__endTimestamp 18:23:31 INFO - PID 1654 | 18:23:31 INFO - PID 1654 | ------- Summary: start ------- 18:23:31 INFO - PID 1654 | Number of tests: 14 18:23:31 INFO - PID 1654 | 18:23:31 INFO - PID 1654 | [#0] ai-astar Cycles:10 Average:113.20 Median:115.00 stddev:4.32 (3.8%) stddev-sans-first:1.88 18:23:31 INFO - PID 1654 | Values: 102.0 115.0 113.0 113.0 112.0 115.0 113.0 118.0 116.0 115.0 18:23:31 INFO - PID 1654 | 18:23:31 INFO - PID 1654 | [#1] audio-beat-detection Cycles:10 Average:130.20 Median:147.00 stddev:23.81 (16.2%) stddev-sans-first:24.65 18:23:31 INFO - PID 1654 | Values: 145.0 118.0 135.0 140.0 96.0 96.0 143.0 149.0 168.0 112.0 18:23:31 INFO - PID 1654 | 18:23:31 INFO - PID 1654 | [#2] audio-dft Cycles:10 Average:200.90 Median:200.00 stddev:6.10 (3.1%) stddev-sans-first:6.21 18:23:31 INFO - PID 1654 | Values: 196.0 200.0 197.0 198.0 200.0 212.0 212.0 197.0 201.0 196.0 18:23:31 INFO - PID 1654 | 18:23:31 INFO - PID 1654 | [#3] audio-fft Cycles:10 Average:73.30 Median:79.50 stddev:12.81 (16.1%) stddev-sans-first:13.47 18:23:31 INFO - PID 1654 | Values: 78.0 59.0 62.0 88.0 89.0 86.0 73.0 58.0 81.0 59.0 18:23:31 INFO - PID 1654 | 18:23:31 INFO - PID 1654 | [#4] audio-oscillator Cycles:10 Average:86.10 Median:80.50 stddev:15.79 (19.6%) stddev-sans-first:16.74 18:23:31 INFO - PID 1654 | Values: 87.0 95.0 115.0 105.0 74.0 71.0 87.0 70.0 68.0 89.0 18:23:31 INFO - PID 1654 | 18:23:31 INFO - PID 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:94.20 Median:88.50 stddev:6.56 (7.4%) stddev-sans-first:6.32 18:23:31 INFO - PID 1654 | Values: 102.0 95.0 88.0 92.0 88.0 88.0 93.0 105.0 89.0 102.0 18:23:31 INFO - PID 1654 | 18:23:31 INFO - PID 1654 | [#6] imaging-darkroom Cycles:10 Average:99.30 Median:96.00 stddev:9.27 (9.7%) stddev-sans-first:9.79 18:23:31 INFO - PID 1654 | Values: 102.0 95.0 96.0 96.0 96.0 96.0 94.0 125.0 96.0 97.0 18:23:31 INFO - PID 1654 | 18:23:31 INFO - PID 1654 | [#7] imaging-desaturate Cycles:10 Average:110.00 Median:91.00 stddev:26.52 (29.1%) stddev-sans-first:27.32 18:23:31 INFO - PID 1654 | Values: 92.0 99.0 97.0 90.0 126.0 94.0 146.0 72.0 139.0 145.0 18:23:31 INFO - PID 1654 | 18:23:31 INFO - PID 1654 | [#8] json-parse-financial Cycles:10 Average:50.30 Median:50.50 stddev:0.95 (1.9%) stddev-sans-first:1.00 18:23:31 INFO - PID 1654 | Values: 50.0 51.0 51.0 50.0 49.0 50.0 51.0 49.0 50.0 52.0 18:23:31 INFO - PID 1654 | 18:23:31 INFO - PID 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:63.60 Median:62.50 stddev:9.35 (15.0%) stddev-sans-first:9.87 18:23:31 INFO - PID 1654 | Values: 61.0 64.0 76.0 84.0 64.0 60.0 56.0 59.0 55.0 57.0 18:23:31 INFO - PID 1654 | 18:23:31 INFO - PID 1654 | [#10] stanford-crypto-aes Cycles:10 Average:88.60 Median:83.00 stddev:14.63 (17.6%) stddev-sans-first:14.53 18:23:31 INFO - PID 1654 | Values: 74.0 77.0 122.0 107.0 87.0 84.0 86.0 83.0 83.0 83.0 18:23:31 INFO - PID 1654 | 18:23:31 INFO - PID 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:155.30 Median:155.00 stddev:25.38 (16.4%) stddev-sans-first:26.14 18:23:31 INFO - PID 1654 | Values: 138.0 151.0 153.0 137.0 133.0 157.0 158.0 161.0 222.0 143.0 18:23:31 INFO - PID 1654 | 18:23:31 INFO - PID 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.40 Median:186.50 stddev:2.72 (1.5%) stddev-sans-first:2.82 18:23:31 INFO - PID 1654 | Values: 187.0 187.0 182.0 187.0 183.0 191.0 184.0 186.0 183.0 184.0 18:23:31 INFO - PID 1654 | 18:23:31 INFO - PID 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:69.50 stddev:1.85 (2.7%) stddev-sans-first:1.94 18:23:31 INFO - PID 1654 | Values: 68.0 70.0 68.0 72.0 71.0 66.0 68.0 67.0 70.0 69.0 18:23:31 INFO - PID 1654 | -------- Summary: end -------- 18:23:31 INFO - PID 1654 | 18:23:32 INFO - TEST-INFO | 1654: exit 0 18:23:32 INFO - TEST-OK | kraken | took 59985ms 18:23:32 INFO - SUITE-END | took 1172s 18:23:33 INFO - Completed test suite (00:19:33) 18:23:33 INFO - javascript benchmark 18:23:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7821.478376934958, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3504.246586983799, 3569.714422846171, 3594.0475773919898, 3594.9999999999995, 3621.9456708149382, 6119.563485741598, 6208.596441231322, 6217.253929528457, 6248.187735594378, 6259.812205633827, 6185.56701030928, 6197.752089916398, 6274.309825919146, 6279.497640188785, 6292.622442653444, 5432.375276843154, 5448.673079615216, 5512.944870551303, 5523.061079616455, 5530.419305972875, 5542.196381524676, 5624.15637654351, 5640.182173584828, 5667.178259152441, 5679.8580035499235, 5415.268938693304, 5560.6941618210985, 5561.805336813192, 5609.915851262234, 5636.126400407943, 4595.747233902134, 5490.835274941758, 5516.7517461714315, 5573.665580065209, 5580.218769372291, 4245.511766146902, 4740.170470167707, 5255.816046438356, 5275.340582427197, 5531.612787104904, 5191.247269145991, 6173.32093469718, 6227.159333489966, 6278.4035516625845, 6324.968375158141, 6476.611403315769, 6514.543981921267, 6569.901451478232, 6711.563748356341, 6740.865182696319, 6290.842728931768, 6786.185657721056, 6812.182538095411, 6814.727410903558, 6849.965750171267, 5907.616004959664, 6330.461910737639, 6356.173697419306, 6365.458935990493, 6371.585846919936, 5579.246801681802, 5985.191999080069, 6936.271691472423, 6972.1981972072845, 7007.8598428031155, 6388.39310265524, 6393.680315984182, 6417.0, 6448.903266451007, 6455.386738259858, 5925.34821169671, 6622.668866556653, 6806.115205023414, 6815.216250131299, 6833.3166683332265, 5364.6512976656395, 6442.35576442352, 6490.286068532458, 6583.0, 6605.702743376463, 5765.472149880285, 6348.904766428507, 6723.0, 6748.561343512754, 6758.83102922421, 5375.489328513785, 5395.082835917903, 5403.081476149064, 5409.594280428983, 5441.455854414527, 4688.156131896223, 4737.431508219036, 4788.592969597554, 4799.784009719571, 4868.902621947541, 5428.972855135778, 6331.335209802996, 6348.301686814447, 6418.037294405876, 6422.775202867877, 6037.215162028908, 6841.589504629738, 6871.690773915186, 6876.759313424006, 6879.174499060145, 6226.439620434183, 6291.779787707408, 6360.077788720702, 6434.646094464756, 6457.935420645734, 5955.7617695292565, 6004.819855404345, 6016.578839481282, 6050.364711705208, 6051.9697401513595, 6921.723131074801, 6927.515073944977, 6983.9650801745665, 6995.125609298838, 7019.613921234279, 6495.772647957298, 6754.729810807709, 6805.4895882808, 6836.0, 6851.794446166424, 6789.422899053438, 6870.3473170048765, 6928.653567321715, 6962.094927659372, 6970.895436568354, 6710.932890671031, 6736.5958042517605, 6736.5958042517605, 6738.35985581369, 6738.528303018938, 6753.662316884038, 6782.220044694924, 6817.113775209191, 6833.931660683528, 6848.828779280558, 6340.397662222082, 6382.840428989312, 6466.094746735367, 6544.149260596092, 6571.3100124488155, 6583.769568065095, 6619.404253617197, 6635.601863888182, 6644.634545100066, 6649.2685804562425, 6291.276503202007, 6344.291324950681, 6420.711068001858, 6484.870302594016, 6504.414602685781, 5887.676177810262, 6432.614043157426, 6527.11883895668, 6586.703598338182, 6591.604503729792, 6431.742730290736, 6447.097406363207, 6480.384363485461, 6483.319251478527, 6509.349065093642], "value": 6101.906979271246, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [783.8236396810719, 801.8195905921168, 828.823071238841, 1208.4622343057338, 1278.1947373154924, 37451.438228426596, 38160.0, 38374.80812595933, 38404.80797596008, 38410.8079459603, 38147.80926095373, 38555.0, 38624.84125476233, 38712.80643596778, 38734.8063259684, 7671.923280767191, 7782.961085194566, 8061.0, 8276.793080172984, 8301.003879534464, 8197.631106600218, 8283.50298982063, 8365.330773538133, 8367.995840499148, 8385.077641459435, 8312.0, 8323.084460709317, 8352.955880514935, 8371.623276952552, 8394.45436046655, 8299.336053115736, 8330.0, 8336.458130221577, 8340.207680270365, 8346.874796878052, 8113.675452981874, 8148.144444833267, 8173.918260817375, 8191.918080819235, 8201.343892488587, 35650.28699426023, 35808.0, 35871.282574348625, 35991.0, 36052.49343832021, 3520.559930008749, 3522.3659741246565, 3558.430651095812, 3561.12752375667, 3566.375884220251, 29393.559096613353, 29738.256543586584, 29763.851180744176, 29774.255643608867, 29907.0, 3000.639923209229, 3075.5694202811624, 3104.984475077633, 3126.6248050234117, 3145.418097651942, 238961.0, 314662.2800657992, 320583.0, 324770.3761481201, 327691.36154319317, 36620.0, 46575.835604110165, 46637.766811165726, 46679.533204668194, 46693.0, 48307.0, 48365.274520882216, 49202.50797492051, 50160.74598135003, 50233.0, 12908.16096953695, 13355.599332020054, 14128.929355353157, 14200.0, 14238.928805355907, 11394.430278486043, 11505.309681419141, 11512.712182195512, 11536.25014374063, 11536.384906113151, 10619.150467962545, 12977.480900763863, 13074.803877941838, 13131.949444044454, 13174.341282936006, 5391.541718953933, 5483.588730845203, 5887.705614719247, 5902.291724993029, 5925.40745925404, 964.9035096490435, 971.0, 995.4375777685584, 999.695092996643, 1041.703114612347, 17216.827831721523, 20907.217518683057, 23177.76822231756, 23569.292921212393, 23965.760342396352, 1100.884407137255, 1101.6915263726169, 1125.2011072138873, 1138.6640940922448, 1144.3134119528384, 11897.107716921093, 12111.576094836639, 12152.392380380945, 12178.75642487163, 12223.388830558437, 319607.0, 322748.0, 324902.0, 327110.0, 327520.0, 41429.79285103555, 50339.0, 50641.987160257326, 50652.74673626608, 50662.0, 1446.6311090671811, 1479.445208046961, 1519.088546871868, 1519.9696006079816, 1536.5236776599068, 23137.76862231356, 23220.3033908983, 23279.41801454977, 23283.76716232816, 23363.415914602272, 33925.83037084898, 35745.0, 35878.0, 35902.281954360245, 35948.0, 236061.0, 237709.0, 238202.8089859609, 238314.0, 238838.0, 32643.0, 32908.67091329056, 33036.66963330336, 33157.00528984134, 33224.83713070128, 41150.58849411467, 42852.0, 42969.925751856455, 42971.78514107409, 43059.9235019127, 28140.29649258703, 28933.56599651049, 29790.553141703323, 30121.246968825955, 30224.69775302219, 29178.854105729337, 29445.411091778475, 29789.10632681023, 29829.701702982693, 30444.782208711404, 35508.0, 35549.0, 35619.64380356163, 35735.28529429449, 35758.64241357553, 29206.853965730035, 29214.56178157287, 30715.84642076865, 31548.842255789492, 31574.0, 22771.544569108857, 23963.880180598986, 23969.0, 23999.80000999978, 24329.51340973206], "value": 17036.39741235328, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [425.5595458700246, 429.6562252910374, 433.24831417490645, 443.49663132344784, 450.03916637977926, 18844.246230150777, 20470.590588188246, 20858.269960551384, 20918.849463279512, 21181.68227476589, 20415.591688166227, 22192.223272185478, 22678.0, 22831.42921426965, 22903.198388056422, 5205.479452054802, 5919.822405327847, 6156.076588511714, 6174.722137503823, 6177.752889884399, 5550.389457159709, 6073.574849760518, 6171.321154672983, 6189.473894718954, 6227.657478838662, 6004.849878753044, 6177.320494745574, 6179.2893817210925, 6186.81439556814, 6188.288346840126, 6048.637081775086, 6142.262928448592, 6155.415235552616, 6182.752689892399, 6189.628622282632, 4276.9144617107795, 5263.920896216305, 5454.8909021819345, 5482.068048431776, 5496.615236933416, 27891.02381416641, 28425.857870710723, 28493.857530712216, 28539.28651783722, 28951.710482895112, 2173.217641649005, 2227.52108296717, 2244.2818298144603, 2246.2587346175724, 2248.87631180285, 17561.0, 17623.735643965352, 18090.276388944425, 18248.726269105973, 18535.814641853543, 1792.6145878636025, 2117.2166298469665, 2164.285785690718, 2166.7508236552685, 2170.4573856535867, 154718.0, 162494.0, 162805.3719462814, 164672.0, 165907.0, 10170.542325595366, 11047.613333533287, 12279.815802762965, 12554.68613284657, 12642.2414655121, 16132.193390330436, 16382.262798174112, 16402.09788461646, 16425.260863260944, 16451.5887102821, 8665.220130188314, 8936.106389361143, 8941.776455588662, 9017.63929442828, 9136.08639136081, 7495.8500829983095, 8407.915920840836, 8422.915770842214, 8457.661693532189, 8608.311335093178, 7794.844103117905, 7907.762767116996, 7917.089534703583, 7921.801954951057, 7923.168067352845, 1837.5773572078228, 1872.2604571194302, 2109.704641350212, 2144.828413726898, 2308.4921317310163, 782.8160382310152, 915.4232833314969, 962.138885697311, 1002.9448380339152, 1005.6228914157191, 20442.488937776674, 20891.68662470064, 20975.475613109487, 20989.37031889046, 21026.053827577794, 812.6058861452191, 1003.9849402258972, 1021.6781713760141, 1032.4682788363998, 1037.0873631204643, 8347.0, 9762.776914350789, 10486.003829636325, 10743.14054875608, 10958.068564171976, 543837.0, 546832.0, 552807.0, 560351.0, 560756.0, 120715.39642301733, 123743.38822729493, 126379.0, 126421.36789315994, 127808.0, 1201.0991756182864, 1214.3563911127117, 1250.6373151786063, 1251.1742250115062, 1255.8806913343217, 20241.493962651053, 20626.27808026712, 21796.67304990458, 21916.89041554782, 22297.219597314015, 53685.0, 54928.17607735967, 55413.44586554083, 56247.71876140593, 57136.71431642815, 74394.51210975643, 77947.61026194833, 79332.60333698295, 79901.0, 80057.0, 8483.703070392507, 8773.473591584525, 8848.1151884811, 8957.910420895707, 9053.411528250643, 14093.0, 14097.365618547396, 14121.364538595582, 14333.283335833376, 14617.122972621677, 10216.846747298945, 11852.466639001093, 11908.940455297668, 11984.940075299917, 12063.39683015828, 11127.944360278147, 11136.276142050741, 11193.832092518782, 11385.601503947322, 11522.82715759281, 11752.23610465317, 11840.28958262507, 12095.334756588472, 12223.511059557519, 12281.570145044881, 7584.5070070447455, 8632.100937793675, 8815.515146666738, 8842.115788421106, 8893.155150260714, 11683.88316116862, 11769.0, 11906.583269585522, 11926.224795388272, 12011.75976480448], "value": 10378.769994664624, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1347.7843545032795, 1390.0, 1404.571605660274, 1406.036864747648, 1414.7312010717967, 11116.0, 11259.718507037307, 11318.547258109676, 11831.0, 11845.466953987081, 10660.840087398676, 11454.0, 11679.416029198548, 11685.7662846743, 11702.356370399624, 2373.810095192385, 2559.731228221038, 2560.4751026039617, 2562.192909233595, 2565.9864353580324, 2501.8123640726926, 2533.5439620868237, 2546.2234018624313, 2554.8211625186245, 2555.6166575013713, 2479.7396273391305, 2503.5118151960373, 2531.3418511187133, 2534.0497313507435, 2534.3664083979006, 2481.4416756229884, 2512.133314006665, 2527.4439623282847, 2541.983206717328, 2551.7448255174518, 2410.4455975125643, 2503.349129226396, 2504.47406044729, 2507.323022783838, 2518.4333524956924, 11528.942355288254, 11538.480768365444, 11603.129765267555, 11621.0, 11638.883611163865, 6357.872842543124, 6593.109930159415, 6593.142891424084, 6598.769043083469, 6618.106555614979, 3922.5685174630767, 4202.453681021448, 4240.830366785325, 4275.273203555403, 4285.4214681017975, 1014.4116412480744, 1027.0672957536628, 1236.3385588709998, 1243.7512497500536, 1275.9489620415172, 15420.0, 16018.119003454805, 16085.356585736556, 16215.837841621551, 16272.51182464528, 5359.5712343012465, 5460.863478413072, 5517.69652669107, 5606.990741666457, 5627.212190293362, 11328.490217940212, 11338.716532086599, 11341.489632966537, 11343.376114313627, 11379.487923043484, 6377.7448902044325, 6382.872342553216, 6384.968075159687, 6389.7124629390855, 6397.872042559123, 5091.389033316026, 5827.650340979555, 6096.573239873166, 6119.388061193934, 6133.1413602095745, 3780.5652349980105, 4221.051571989414, 4301.0, 4316.589923957219, 4336.002719374498, 1257.1200159887933, 1281.423359488234, 1290.9289989050692, 1294.4563283420987, 1296.416612524349, 769.7767647382309, 780.605794073989, 791.9920800791919, 793.5992323876405, 801.7314199743151, 6420.26166990802, 6527.902081468782, 6714.060031595581, 6714.462842972648, 6740.157480314961, 873.8164985353147, 894.8076163624852, 906.4833045164324, 909.1817364372118, 909.7634615000147, 3836.2519308734527, 4004.8598299059977, 4124.896877578085, 4135.834566617302, 4175.728577642443, 14132.717345653029, 14892.776608350883, 14925.17911514877, 14932.925335373471, 14966.476173333882, 14435.639109022148, 14685.77971330431, 14808.555743327503, 14818.851811481747, 14851.0, 7331.963340183372, 8088.676452941816, 8579.571021448903, 8595.914040859512, 8603.956980215058, 5982.0, 6543.967280163759, 6745.561538499982, 6940.063091482787, 7023.578585284695, 12628.0, 12903.41934612926, 13181.934090329487, 13212.801807973081, 13294.468221271041, 13035.413406396545, 13251.271180084803, 13434.93282533581, 13577.11748736329, 13581.93209033988, 8271.048829384457, 8315.542645154575, 8417.62120704582, 8442.831143377221, 8447.831043379221, 10522.368657880552, 10562.419066951392, 10576.99518545737, 10677.89322106769, 10738.463076846283, 9081.409708368936, 9116.632505124284, 9131.726048218565, 9156.221721153777, 9189.989101198997, 8782.077881822572, 8918.910810891808, 8945.73162805117, 8948.597313121056, 8984.55077246122, 7796.532208067534, 7958.84082318362, 7964.601769911597, 7966.0440747110715, 7982.521048737094, 5795.7971471, 5798.971005145116, 5799.420057994167, 5906.2026626406605, 5944.554158438221, 5422.240886275846, 6037.0642550404755, 6057.666828324308, 6076.270847498328, 6250.593711408744], "value": 5287.943007230206, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.8977281247657, 390.3792969179005, 396.21351617040153, 403.3566461493918, 404.1371671481387, 6154.630722156674, 6205.162303089081, 6222.813315600528, 6286.937130628692, 6295.43341099301, 5947.988841896877, 6099.3290738018895, 6111.385903642744, 6137.938620613792, 6161.938380616204, 3872.4578559001693, 4366.694331396803, 4512.5261847506035, 4522.751248681321, 4535.63714902807, 3791.748722921443, 4515.119551687422, 4520.2089634314025, 4522.8891352771325, 4542.704724192933, 3749.8312575934146, 4495.100979804036, 4502.572255635726, 4505.684602077856, 4513.014273719553, 4374.119128774626, 4425.5353187915125, 4471.23988921884, 4508.399518171068, 4515.187266292066, 3830.2339532093692, 3858.0, 3858.5369755629504, 3858.6141385861188, 3873.2640798248526, 5060.507150390644, 5199.856031673025, 5234.973825130888, 5998.25021872266, 6076.270847498285, 5015.498450154955, 5202.895942081175, 5323.281357016831, 5377.838664840061, 5410.1073322901675, 5801.593888427812, 6109.059656521563, 6332.905006424908, 6581.703823327962, 6633.635150066744, 3910.393888947218, 4309.525952145293, 4362.847300344504, 4368.388425620416, 4369.3227549729845, 5393.676379417208, 5633.971830140864, 5707.429257074301, 6619.5035372347265, 7057.5412598181465, 4013.5751808108284, 4247.702660813713, 4386.056997745436, 4390.143921935256, 4405.493368262691, 4030.9922519370157, 4202.684798640114, 4446.82212711488, 4503.279475283939, 4514.390557274726, 4275.648393383626, 4697.318888761179, 5104.69371837691, 5202.531772140525, 5269.209618557247, 6610.834729131714, 6647.648642842558, 6659.966700166468, 6700.363465470772, 6744.359285867835, 1497.9625509362136, 1518.6583018820897, 1522.7285216843838, 1525.4279645133074, 1526.63360793411, 945.2013048973606, 984.5766320482118, 996.7358649957768, 1001.0890090018048, 1016.6594190946116, 4618.237990731492, 5906.911396329058, 6046.699959508726, 6208.906866397007, 6236.84828734086, 6221.097940798559, 6262.906056409158, 6289.339619339995, 6702.296258892844, 6801.857414914286, 4976.552110310089, 5107.3697046021325, 5440.591955603346, 5877.382874798084, 6334.113224148623, 2947.985260073729, 3569.696575791087, 3587.820608969541, 3600.963990360115, 3625.7099432045375, 6860.0, 7007.369336759716, 7031.472639552054, 7098.432125429953, 7219.314165154302, 5976.89427455922, 7119.110111236096, 7119.608421536762, 7185.317394847481, 7202.459815513857, 6960.582365058112, 7061.82345441368, 7099.787006389816, 7210.71157153708, 7234.96382518084, 6101.176341193882, 6937.687804048729, 7000.404965577983, 7012.792324605196, 7026.473014524034, 6527.771527996498, 6666.999850067392, 6741.730330786713, 6781.7287308509085, 6807.829804254933, 5829.0, 6704.664766761545, 6734.158230221223, 6973.75591854303, 7053.470989675692, 4988.775505102207, 5019.648624596243, 5031.270465782368, 5044.672096313728, 5046.082488654288, 5707.286589176353, 6027.558838670346, 6308.558400911892, 6866.725330986705, 6873.347032031949, 5137.845864624067, 6514.185726784152, 6615.974523948796, 6713.462922966249, 6714.045819839255, 6550.868982620225, 6606.669666516751, 6652.368025037614, 6694.995750637443, 6712.6979285931275, 4867.464578896248, 5314.256004159498, 6440.130582371507, 6505.414512693691, 6506.4539825796, 5617.522510586646, 6358.745650173942, 6658.533902626963, 6705.6647167642395, 6719.731210751711, 5919.822405327847, 6409.551331406757, 6526.967365163144, 6536.444402225864, 6546.279909210079], "value": 4750.492350734726, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [834.4075706248564, 846.9195426434488, 853.5134973065356, 867.5141920524507, 884.0, 16637.168141592916, 17042.74435883463, 17109.657806843872, 18519.166637501312, 19133.0, 17804.46586602404, 18354.1740621672, 18365.173567189475, 18371.0, 18688.06559672018, 998.3543609435001, 1007.1741172238767, 1014.3356101753363, 1016.4054028393399, 1025.4770067265674, 1036.052012408649, 1036.7978244242377, 1037.3879411147423, 1038.7610849504617, 1039.9480025998707, 1068.642004928346, 1083.7940791249637, 1087.6356420599109, 1095.0090168397574, 1096.0738176241064, 831.9750407487785, 1047.3087762076993, 1061.273027975839, 1064.9148068154568, 1079.9190060745436, 748.3003391828657, 774.1832366852968, 791.3155120820506, 794.566961006253, 803.1968031968032, 17014.149292535323, 17036.829631703647, 17055.744163837553, 17160.742588861176, 17219.96680199192, 14922.70154596902, 16970.66058678832, 17105.34867095602, 17138.82861171385, 17371.392001279895, 14258.358373873201, 14389.640258993504, 14745.410183592643, 14746.188959607218, 14760.188189649565, 3558.430651095838, 3589.784612923233, 3598.982005089958, 3599.6400359964055, 3599.6580324869096, 127229.36385318107, 128651.0, 130555.34722326328, 130594.3470282652, 131482.0, 12999.415026323839, 13170.143940644019, 13288.734225315633, 13312.0, 13315.134516256412, 25288.873555632104, 25417.856196471203, 25501.0, 25867.96528138891, 25950.74049259521, 7810.57041862703, 7941.64262608184, 7994.0, 8038.0, 8059.435839491297, 3835.520559930009, 3879.3792993120965, 3900.90247743803, 3908.902277443087, 3909.6090390960676, 4335.523092459827, 4347.065380943112, 4399.868003959887, 4437.156940181355, 4440.311751678495, 939.9953000234955, 943.0852073488705, 958.0802429667458, 959.4579062829479, 960.4141473701037, 531.3809412034936, 631.7441436218339, 640.8045546108482, 651.2998526583941, 672.8822456070168, 9693.273004524688, 11352.716182095348, 12027.398630068461, 12169.087318451151, 12252.326122063372, 655.757369773187, 665.0921492163162, 669.4543946683535, 689.6965335252473, 691.837418206721, 4781.665283430127, 5370.785168593291, 5623.212750214973, 5637.098064309691, 5677.744501497443, 88474.55762721274, 89457.65813512672, 107356.0, 112975.0, 117608.82391175978, 73707.2629273711, 92499.07500925039, 100737.0, 102750.0, 104558.43162352571, 63453.365466344745, 67685.66157169183, 69177.0, 75063.62468187624, 76096.61951690317, 10652.680419587425, 10810.405427701551, 11288.9435552825, 11881.702957426134, 12490.562830300896, 29709.70290297156, 37985.62014379821, 41071.589284106776, 41634.375484367156, 42051.0, 61426.69286653717, 63258.0, 73850.52298954099, 75220.62389688016, 75605.24394755982, 13884.444622214998, 13895.0, 16529.834701652828, 17213.967161970322, 17332.39336623212, 16842.15789210559, 17498.82501174972, 17630.0, 18606.255749769858, 18612.906935465235, 16039.0, 16656.666866662843, 16682.833171668615, 16743.0, 16771.90982586128, 16715.83284167143, 16841.2421441033, 16854.57863553421, 16895.66208675795, 16931.746023809406, 15607.985480943702, 15787.842121578637, 16024.439144629881, 16192.919035405219, 16287.429939952044, 11490.368029758443, 11560.537578497102, 11892.405379730806, 12197.756044878875, 12233.449494772934, 12095.60900496454, 12286.38568071575, 12635.305058221886, 13384.531541396005, 13694.65763355924], "value": 8446.721192190405, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1519.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 115.0, 113.0, 113.0, 112.0, 115.0, 113.0, 118.0, 116.0, 115.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 118.0, 135.0, 140.0, 96.0, 96.0, 143.0, 149.0, 168.0, 112.0], "value": 130.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 200.0, 197.0, 198.0, 200.0, 212.0, 212.0, 197.0, 201.0, 196.0], "value": 200.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 59.0, 62.0, 88.0, 89.0, 86.0, 73.0, 58.0, 81.0, 59.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 95.0, 115.0, 105.0, 74.0, 71.0, 87.0, 70.0, 68.0, 89.0], "value": 86.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 95.0, 88.0, 92.0, 88.0, 88.0, 93.0, 105.0, 89.0, 102.0], "value": 94.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 95.0, 96.0, 96.0, 96.0, 96.0, 94.0, 125.0, 96.0, 97.0], "value": 99.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 99.0, 97.0, 90.0, 126.0, 94.0, 146.0, 72.0, 139.0, 145.0], "value": 110.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 51.0, 51.0, 50.0, 49.0, 50.0, 51.0, 49.0, 50.0, 52.0], "value": 50.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 76.0, 84.0, 64.0, 60.0, 56.0, 59.0, 55.0, 57.0], "value": 63.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 77.0, 122.0, 107.0, 87.0, 84.0, 86.0, 83.0, 83.0, 83.0], "value": 88.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 151.0, 153.0, 137.0, 133.0, 157.0, 158.0, 161.0, 222.0, 143.0], "value": 155.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 187.0, 182.0, 187.0, 183.0, 191.0, 184.0, 186.0, 183.0, 184.0], "value": 185.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 68.0, 72.0, 71.0, 66.0, 68.0, 67.0, 70.0, 69.0], "value": 68.9, "unit": "score"}]}]} 18:23:33 INFO - Return code: 0 18:23:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:23:33 INFO - # TBPL SUCCESS # 18:23:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:23:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:23:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:23:33 INFO - 2017-05-22 18:23:33.263 screenresolution[1661:11052] starting screenresolution argv=screenresolution get 18:23:33 INFO - 2017-05-22 18:23:33.266 screenresolution[1661:11052] Display 0: 1600x1200x32@60 18:23:33 INFO - 2017-05-22 18:23:33.272 screenresolution[1662:11056] starting screenresolution argv=screenresolution list 18:23:33 INFO - Available Modes on Display 0 18:23:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:23:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:23:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:23:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:23:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:23:33 INFO - Intel Iris: 18:23:33 INFO - Chipset Model: Intel Iris 18:23:33 INFO - Type: GPU 18:23:33 INFO - Bus: Built-In 18:23:33 INFO - VRAM (Dynamic, Max): 1536 MB 18:23:33 INFO - Vendor: Intel (0x8086) 18:23:33 INFO - Device ID: 0x0a2e 18:23:33 INFO - Revision ID: 0x0009 18:23:33 INFO - Displays: 18:23:33 INFO - Display: 18:23:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:23:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:23:33 INFO - Display Serial Number: 1600x1200 60 18:23:33 INFO - Main Display: Yes 18:23:33 INFO - Mirror: Off 18:23:33 INFO - Online: Yes 18:23:33 INFO - Rotation: Supported 18:23:33 INFO - Return code: 0 18:23:33 INFO - Running post-action listener: _resource_record_post_action 18:23:33 INFO - [mozharness: 2017-05-23 01:23:33.518151Z] Finished run-tests step (success) 18:23:33 INFO - Running post-run listener: _resource_record_post_run 18:23:33 INFO - Total resource usage - Wall time: 1195s; CPU: 54.0%; Read bytes: 44895232; Write bytes: 567403008; Read time: 463; Write time: 1956 18:23:33 INFO - TinderboxPrint: CPU usage
54.1% 18:23:33 INFO - TinderboxPrint: I/O read bytes / time
44,895,232 / 463 18:23:33 INFO - TinderboxPrint: I/O write bytes / time
567,403,008 / 1,956 18:23:33 INFO - TinderboxPrint: CPU idle
2,195.8 (45.9%) 18:23:33 INFO - TinderboxPrint: CPU system
960.0 (20.1%) 18:23:33 INFO - TinderboxPrint: CPU user
1,625.8 (34.0%) 18:23:33 INFO - TinderboxPrint: Swap in / out
285,642,752 / 0 18:23:33 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 234258944; Write bytes: 229434880; Read time: 16822; Write time: 652 18:23:33 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 42814464; Write bytes: 318659584; Read time: 440; Write time: 1259 18:23:34 INFO - Running post-run listener: _upload_blobber_files 18:23:34 INFO - Blob upload gear active. 18:23:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:23:34 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 18:23:34 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:23:34 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:23:34 INFO - (blobuploader) - INFO - Open directory for files ... 18:23:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:23:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:23:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:23:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:23:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:23:36 INFO - (blobuploader) - INFO - Done attempting. 18:23:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:23:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:23:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:23:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:23:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:23:37 INFO - (blobuploader) - INFO - Done attempting. 18:23:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:23:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:23:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:23:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:23:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:23:38 INFO - (blobuploader) - INFO - Done attempting. 18:23:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:23:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:23:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:23:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:23:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:23:40 INFO - (blobuploader) - INFO - Done attempting. 18:23:40 INFO - (blobuploader) - INFO - Iteration through files over. 18:23:40 INFO - Return code: 0 18:23:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:23:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:23:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/323b8aa8bfd54fb0141c0529a945c5b6252752cf216fb5acc5ac90eb5ecf56e8cf3418f74aa4368766392e3926f870a945f3d5dfbaec6beccde0dafbc0aa3f57", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af988f789c61c004752fd807218677ac8349695e3591582abb3dfd1b850b6fb354dc3f7f1113d1759c6c8a526e998f7f96ebb0b236243e708d8beef72e3c0847", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aac002c8ba0a814afecb024ac433a4edc0bf36b1cc916d841e3b7f6a0769d6384f0a1ab011bcf8be31c0c4dde7671ddfb789152f459b057e8a6b6b2bb56535ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4fd105aef123078f248555f4c27896d4b3b4cb21275aa519ff3c4998ac6e97b94184c279b5331e2514800ad60d32fcb8f87d74699cf2bd32a57e49a81f7e9b1"} 18:23:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:23:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:23:40 INFO - Contents: 18:23:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/323b8aa8bfd54fb0141c0529a945c5b6252752cf216fb5acc5ac90eb5ecf56e8cf3418f74aa4368766392e3926f870a945f3d5dfbaec6beccde0dafbc0aa3f57", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af988f789c61c004752fd807218677ac8349695e3591582abb3dfd1b850b6fb354dc3f7f1113d1759c6c8a526e998f7f96ebb0b236243e708d8beef72e3c0847", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aac002c8ba0a814afecb024ac433a4edc0bf36b1cc916d841e3b7f6a0769d6384f0a1ab011bcf8be31c0c4dde7671ddfb789152f459b057e8a6b6b2bb56535ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4fd105aef123078f248555f4c27896d4b3b4cb21275aa519ff3c4998ac6e97b94184c279b5331e2514800ad60d32fcb8f87d74699cf2bd32a57e49a81f7e9b1"} 18:23:40 INFO - Running post-run listener: copy_logs_to_upload_dir 18:23:40 INFO - Copying logs to upload dir... 18:23:40 INFO - mkdir: /builds/slave/test/build/upload/logs 18:23:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.736701 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-22 18:23:40.143190) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:40.146290) ========= 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.rA7BUJXXJo/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.nkHBCufVlR/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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/Firefox/sha512/323b8aa8bfd54fb0141c0529a945c5b6252752cf216fb5acc5ac90eb5ecf56e8cf3418f74aa4368766392e3926f870a945f3d5dfbaec6beccde0dafbc0aa3f57", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af988f789c61c004752fd807218677ac8349695e3591582abb3dfd1b850b6fb354dc3f7f1113d1759c6c8a526e998f7f96ebb0b236243e708d8beef72e3c0847", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aac002c8ba0a814afecb024ac433a4edc0bf36b1cc916d841e3b7f6a0769d6384f0a1ab011bcf8be31c0c4dde7671ddfb789152f459b057e8a6b6b2bb56535ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4fd105aef123078f248555f4c27896d4b3b4cb21275aa519ff3c4998ac6e97b94184c279b5331e2514800ad60d32fcb8f87d74699cf2bd32a57e49a81f7e9b1"} build_url:https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010907 build_url: 'https://queue.taskcluster.net/v1/task/ZJOHXPa5SYO99PH_qG_RHw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/323b8aa8bfd54fb0141c0529a945c5b6252752cf216fb5acc5ac90eb5ecf56e8cf3418f74aa4368766392e3926f870a945f3d5dfbaec6beccde0dafbc0aa3f57", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/af988f789c61c004752fd807218677ac8349695e3591582abb3dfd1b850b6fb354dc3f7f1113d1759c6c8a526e998f7f96ebb0b236243e708d8beef72e3c0847", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aac002c8ba0a814afecb024ac433a4edc0bf36b1cc916d841e3b7f6a0769d6384f0a1ab011bcf8be31c0c4dde7671ddfb789152f459b057e8a6b6b2bb56535ee", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e4fd105aef123078f248555f4c27896d4b3b4cb21275aa519ff3c4998ac6e97b94184c279b5331e2514800ad60d32fcb8f87d74699cf2bd32a57e49a81f7e9b1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:40.178538) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:40.178825) ========= 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.rA7BUJXXJo/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.nkHBCufVlR/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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.005013 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:40.226430) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 18:23:40.226702) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 18:23:40.226997) ========= ========= Total master_lag: 0.87 =========