builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0168 starttime: 1495236185.84 results: success (0) revision: 5a8f2dcbeac0677b5aae0f9fd43d6a33309b4a6b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-19 16:23:05.840274) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-19 16:23:05.840632) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2017-05-19 16:23:05.840913) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 4 secs) (at 2017-05-19 16:23:10.041938) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-19 16:23:10.042289) ========= 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.hDpuVgO1WE/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.4IWJ1LFsaJ/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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-19 16:23:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.43M=0.001s 2017-05-19 16:23:10 (9.43 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.111677 ========= master_lag: 3.78 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-19 16:23:13.936672) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 16:23:13.937015) ========= 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.hDpuVgO1WE/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.4IWJ1LFsaJ/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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.027731 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 16:23:13.987748) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-19 16:23:13.988096) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5a8f2dcbeac0677b5aae0f9fd43d6a33309b4a6b --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 5a8f2dcbeac0677b5aae0f9fd43d6a33309b4a6b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hDpuVgO1WE/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.4IWJ1LFsaJ/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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-19 16:23:14,076 truncating revision to first 12 chars 2017-05-19 16:23:14,076 Setting DEBUG logging. 2017-05-19 16:23:14,076 attempt 1/10 2017-05-19 16:23:14,076 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5a8f2dcbeac0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-19 16:23:15,864 unpacking tar archive at: mozilla-central-5a8f2dcbeac0/testing/mozharness/ program finished with exit code 0 elapsedTime=2.057793 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-19 16:23:16.067717) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-19 16:23:16.068038) ========= script_repo_revision: 5a8f2dcbeac0677b5aae0f9fd43d6a33309b4a6b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-19 16:23:16.068408) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-19 16:23:16.068684) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-19 16:23:16.086928) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2017-05-19 16:23:16.087295) ========= /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.hDpuVgO1WE/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.4IWJ1LFsaJ/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 16:23:16 INFO - MultiFileLogger online at 20170519 16:23:16 in /builds/slave/test 16:23:16 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 16:23:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:23:16 INFO - {'append_to_log': False, 16:23:16 INFO - 'base_work_dir': '/builds/slave/test', 16:23:16 INFO - 'blob_upload_branch': 'Firefox', 16:23:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:23:16 INFO - 'branch': 'Firefox', 16:23:16 INFO - 'buildbot_json_path': 'buildprops.json', 16:23:16 INFO - 'config_files': ('talos/mac_config.py',), 16:23:16 INFO - 'default_actions': ('clobber', 16:23:16 INFO - 'read-buildbot-config', 16:23:16 INFO - 'download-and-extract', 16:23:16 INFO - 'populate-webroot', 16:23:16 INFO - 'create-virtualenv', 16:23:16 INFO - 'install', 16:23:16 INFO - 'run-tests'), 16:23:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:23:16 INFO - 'download_minidump_stackwalk': True, 16:23:16 INFO - 'download_symbols': 'ondemand', 16:23:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:23:16 INFO - 'tooltool.py': '/tools/tooltool.py', 16:23:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:23:16 INFO - '/tools/misc-python/virtualenv.py')}, 16:23:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:23:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:23:16 INFO - 'gecko_profile': False, 16:23:16 INFO - 'gecko_profile_interval': 0, 16:23:16 INFO - 'installer_path': 'installer.exe', 16:23:16 INFO - 'log_level': 'info', 16:23:16 INFO - 'log_name': 'talos', 16:23:16 INFO - 'log_to_console': True, 16:23:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:23:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:23:16 INFO - 'opt_config_files': (), 16:23:16 INFO - 'pip_index': False, 16:23:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:23:16 INFO - 'cmd': ('bash', 16:23:16 INFO - '-c', 16:23:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:23:16 INFO - 'enabled': True, 16:23:16 INFO - 'halt_on_failure': False, 16:23:16 INFO - 'name': 'check_screen_resolution'},), 16:23:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:23:16 INFO - 'cmd': ('bash', 16:23:16 INFO - '-c', 16:23:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:23:16 INFO - 'enabled': True, 16:23:16 INFO - 'halt_on_failure': False, 16:23:16 INFO - 'name': 'check_screen_resolution'},), 16:23:16 INFO - 'run_cmd_checks_enabled': True, 16:23:16 INFO - 'suite': 'dromaeojs-e10s', 16:23:16 INFO - 'system_bits': '32', 16:23:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:23:16 INFO - 'title': 't-yosemite-r7-0168', 16:23:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:23:16 INFO - 'use_talos_json': True, 16:23:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:23:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:23:16 INFO - 'work_dir': 'build'} 16:23:16 INFO - [mozharness: 2017-05-19 23:23:16.292402Z] Running clobber step. 16:23:16 INFO - Running pre-action listener: _resource_record_pre_action 16:23:16 INFO - Running main action method: clobber 16:23:16 INFO - rmtree: /builds/slave/test/build 16:23:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:23:18 INFO - Running post-action listener: _resource_record_post_action 16:23:18 INFO - [mozharness: 2017-05-19 23:23:18.912926Z] Finished clobber step (success) 16:23:18 INFO - [mozharness: 2017-05-19 23:23:18.913171Z] Running read-buildbot-config step. 16:23:18 INFO - Running pre-action listener: _resource_record_pre_action 16:23:18 INFO - Running main action method: read_buildbot_config 16:23:18 INFO - Using buildbot properties: 16:23:18 INFO - { 16:23:18 INFO - "project": "", 16:23:18 INFO - "product": "firefox", 16:23:18 INFO - "who": "kwierso@gmail.com", 16:23:18 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 16:23:18 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 16:23:18 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 16:23:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 16:23:18 INFO - "stage_platform": "macosx64", 16:23:18 INFO - "basedir": "/builds/slave/test", 16:23:18 INFO - "buildnumber": 72, 16:23:18 INFO - "platform": "yosemite_r7", 16:23:18 INFO - "parent_task_id": "BjUo9mIvSymQSrVJ2Jynrg", 16:23:18 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 16:23:18 INFO - "slavebuilddir": "test", 16:23:18 INFO - "taskId": "epL5oi9fSbO36k8gSFzntA", 16:23:18 INFO - "branch": "mozilla-central", 16:23:18 INFO - "script_repo_revision": "production", 16:23:18 INFO - "revision": "5a8f2dcbeac0677b5aae0f9fd43d6a33309b4a6b", 16:23:18 INFO - "slavename": "t-yosemite-r7-0168", 16:23:18 INFO - "repo_path": "mozilla-central" 16:23:18 INFO - } 16:23:18 INFO - Finding installer, test and symbols from parent task. 16:23:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/epL5oi9fSbO36k8gSFzntA'}, attempt #1 16:23:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/epL5oi9fSbO36k8gSFzntA'}, attempt #1 16:23:20 INFO - Task dependencies: f10LgUoSQ46JzrfxsrseqA BjUo9mIvSymQSrVJ2Jynrg 16:23:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BjUo9mIvSymQSrVJ2Jynrg'}, attempt #1 16:23:20 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/BjUo9mIvSymQSrVJ2Jynrg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:23:20 INFO - Set test_url: https://queue.taskcluster.net/v1/task/BjUo9mIvSymQSrVJ2Jynrg/artifacts/public/build/test_packages.json 16:23:20 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/BjUo9mIvSymQSrVJ2Jynrg/artifacts/public/build/target.crashreporter-symbols.zip 16:23:20 INFO - Running post-action listener: _resource_record_post_action 16:23:20 INFO - [mozharness: 2017-05-19 23:23:20.645047Z] Finished read-buildbot-config step (success) 16:23:20 INFO - [mozharness: 2017-05-19 23:23:20.645191Z] Running download-and-extract step. 16:23:20 INFO - Running pre-action listener: _resource_record_pre_action 16:23:20 INFO - Running main action method: download_and_extract 16:23:20 INFO - mkdir: /builds/slave/test/build/tests 16:23: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')]} 16:23:20 INFO - trying https://queue.taskcluster.net/v1/task/BjUo9mIvSymQSrVJ2Jynrg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 16:23:20 INFO - Downloading https://queue.taskcluster.net/v1/task/BjUo9mIvSymQSrVJ2Jynrg/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 16:23:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BjUo9mIvSymQSrVJ2Jynrg/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 16:23:21 INFO - Downloaded 1325 bytes. 16:23:21 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 16:23:21 INFO - Using the following test package requirements: 16:23:21 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:23:21 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 16:23:21 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:23:21 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:23:21 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 16:23:21 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:23:21 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 16:23:21 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:23:21 INFO - u'jsshell-mac.zip'], 16:23:21 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:23:21 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 16:23:21 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 16:23:21 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:23:21 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 16:23:21 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:23:21 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 16:23:21 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:23:21 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 16:23:21 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 16:23:21 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 16:23:21 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:23:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BjUo9mIvSymQSrVJ2Jynrg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:23:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BjUo9mIvSymQSrVJ2Jynrg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:23:21 INFO - Fetch https://queue.taskcluster.net/v1/task/BjUo9mIvSymQSrVJ2Jynrg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:23:23 INFO - Content-Length response header: 57623216 16:23:23 INFO - Bytes received: 57623216 16:23:41 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 16:23:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BjUo9mIvSymQSrVJ2Jynrg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 16:23:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BjUo9mIvSymQSrVJ2Jynrg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:23:41 INFO - Fetch https://queue.taskcluster.net/v1/task/BjUo9mIvSymQSrVJ2Jynrg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 16:23:43 INFO - Content-Length response header: 57623216 16:23:43 INFO - Bytes received: 57623216 16:24:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BjUo9mIvSymQSrVJ2Jynrg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 16:24:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BjUo9mIvSymQSrVJ2Jynrg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:24:00 INFO - Fetch https://queue.taskcluster.net/v1/task/BjUo9mIvSymQSrVJ2Jynrg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 16:24:01 INFO - Content-Length response header: 14410000 16:24:01 INFO - Bytes received: 14410000 16:24:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:01 INFO - trying https://queue.taskcluster.net/v1/task/BjUo9mIvSymQSrVJ2Jynrg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:24:01 INFO - Downloading https://queue.taskcluster.net/v1/task/BjUo9mIvSymQSrVJ2Jynrg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 16:24:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BjUo9mIvSymQSrVJ2Jynrg/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 16:24:03 INFO - Downloaded 68005230 bytes. 16:24:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BjUo9mIvSymQSrVJ2Jynrg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:24:03 INFO - mkdir: /builds/slave/test/properties 16:24:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:24:03 INFO - Writing to file /builds/slave/test/properties/build_url 16:24:03 INFO - Contents: 16:24:03 INFO - build_url:https://queue.taskcluster.net/v1/task/BjUo9mIvSymQSrVJ2Jynrg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:24:03 INFO - Running post-action listener: _resource_record_post_action 16:24:03 INFO - Running post-action listener: set_extra_try_arguments 16:24:03 INFO - [mozharness: 2017-05-19 23:24:03.884856Z] Finished download-and-extract step (success) 16:24:03 INFO - [mozharness: 2017-05-19 23:24:03.885001Z] Running populate-webroot step. 16:24:03 INFO - Running pre-action listener: _resource_record_pre_action 16:24:03 INFO - Running main action method: populate_webroot 16:24:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:24:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:24:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:24:03 INFO - '--tppagecycles', 16:24:03 INFO - '7'], 16:24:03 INFO - 'tests': ['tsvgm']}}, 16:24:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:24:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:24:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:24:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:24:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:24:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:24:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:24:03 INFO - 'talos_options': ['--disable-e10s'], 16:24:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:24:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:24:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:24:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:24:03 INFO - 'talos_options': ['--disable-e10s'], 16:24:03 INFO - 'tests': ['damp', 'tps']}, 16:24:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:24:03 INFO - 'tests': ['damp', 'tps']}, 16:24:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:24:03 INFO - 'tests': ['dromaeo_dom']}, 16:24:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:24:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:24:03 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 16:24:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:24:03 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:24:03 INFO - 'tests': ['ts_paint', 'tp5o']}, 16:24:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:24:03 INFO - 'tests': ['a11yr', 16:24:03 INFO - 'ts_paint', 16:24:03 INFO - 'tpaint', 16:24:03 INFO - 'sessionrestore', 16:24:03 INFO - 'sessionrestore_no_auto_restore', 16:24:03 INFO - 'tabpaint']}, 16:24:03 INFO - 'other-e10s': {'tests': ['a11yr', 16:24:03 INFO - 'ts_paint', 16:24:03 INFO - 'tpaint', 16:24:03 INFO - 'sessionrestore', 16:24:03 INFO - 'sessionrestore_no_auto_restore', 16:24:03 INFO - 'tabpaint']}, 16:24:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:24:03 INFO - 'ts_paint', 16:24:03 INFO - 'tpaint', 16:24:03 INFO - 'sessionrestore', 16:24:03 INFO - 'sessionrestore_no_auto_restore', 16:24:03 INFO - 'tabpaint']}, 16:24:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:24:03 INFO - 'ts_paint', 16:24:03 INFO - 'tpaint', 16:24:03 INFO - 'sessionrestore', 16:24:03 INFO - 'sessionrestore_no_auto_restore', 16:24:03 INFO - 'tabpaint']}, 16:24:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:24:03 INFO - 'tests': ['a11yr', 16:24:03 INFO - 'ts_paint', 16:24:03 INFO - 'tpaint', 16:24:03 INFO - 'sessionrestore', 16:24:03 INFO - 'sessionrestore_no_auto_restore', 16:24:03 INFO - 'tabpaint']}, 16:24:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:24:03 INFO - 'tests': ['a11yr', 16:24:03 INFO - 'ts_paint', 16:24:03 INFO - 'tpaint', 16:24:03 INFO - 'sessionrestore', 16:24:03 INFO - 'sessionrestore_no_auto_restore', 16:24:03 INFO - 'tabpaint']}, 16:24:03 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 16:24:03 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 16:24:03 INFO - 'bloom_basic_ref']}, 16:24:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:24:03 INFO - 'tests': ['tsvgx', 16:24:03 INFO - 'tsvgr_opacity', 16:24:03 INFO - 'tart', 16:24:03 INFO - 'tscrollx', 16:24:03 INFO - 'cart', 16:24:03 INFO - 'tsvg_static']}, 16:24:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:24:03 INFO - 'tsvgr_opacity', 16:24:03 INFO - 'tart', 16:24:03 INFO - 'tscrollx', 16:24:03 INFO - 'cart', 16:24:03 INFO - 'tsvg_static']}, 16:24:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:24:03 INFO - 'talos_options': ['--disable-e10s'], 16:24:03 INFO - 'tests': ['tp5o']}, 16:24:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:24:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:24:03 INFO - 'talos_options': ['--disable-e10s', 16:24:03 INFO - '--xperf_path', 16:24:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:24:03 INFO - 'tests': ['tp5n']}, 16:24:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:24:03 INFO - 'talos_options': ['--xperf_path', 16:24:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:24:03 INFO - 'tests': ['tp5n']}}, 16:24:03 INFO - 'talos.zip': {'path': '', 16:24:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:24:03 INFO - Running post-action listener: _resource_record_post_action 16:24:03 INFO - [mozharness: 2017-05-19 23:24:03.906130Z] Finished populate-webroot step (success) 16:24:03 INFO - [mozharness: 2017-05-19 23:24:03.906269Z] Running create-virtualenv step. 16:24:03 INFO - Running pre-action listener: _resource_record_pre_action 16:24:03 INFO - Running main action method: create_virtualenv 16:24:03 INFO - Creating virtualenv /builds/slave/test/build/venv 16:24:03 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 16:24:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:24:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:24:04 INFO - Using real prefix '/tools/python27' 16:24:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:24:04 INFO - Installing distribute.............................................................................................................................................................................................done. 16:24:07 INFO - Installing pip.................done. 16:24:07 INFO - Return code: 0 16:24:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:24:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:24:07 INFO - Reading from file tmpfile_stdout 16:24:07 INFO - Output received: 16:24:07 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) 16:24:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:24:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105174b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105178768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dfa574890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e84d30>, '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 0x104e38db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e79be8>, '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.hDpuVgO1WE/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.4IWJ1LFsaJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 16:24:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:24:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:24:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hDpuVgO1WE/Render', 16:24:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:24:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:24:08 INFO - 'HOME': '/Users/cltbld', 16:24:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:24:08 INFO - 'LOGNAME': 'cltbld', 16:24:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:08 INFO - 'MOZ_NO_REMOTE': '1', 16:24:08 INFO - 'NO_EM_RESTART': '1', 16:24:08 INFO - 'PAGER': '/bin/cat', 16:24:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:24:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:08 INFO - 'PWD': '/builds/slave/test', 16:24:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:24:08 INFO - 'SHELL': '/bin/bash', 16:24:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4IWJ1LFsaJ/Listeners', 16:24:08 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 16:24:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:24:08 INFO - 'USER': 'cltbld', 16:24:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:24:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:24:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:08 INFO - 'XPC_FLAGS': '0x0', 16:24:08 INFO - 'XPC_SERVICE_NAME': '0', 16:24:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:24:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:08 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 16:24:08 INFO - Cleaning up... 16:24:08 INFO - Return code: 0 16:24:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:24:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:09 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 0x105174b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105178768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dfa574890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e84d30>, '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 0x104e38db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e79be8>, '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.hDpuVgO1WE/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.4IWJ1LFsaJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 16:24:09 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 16:24:09 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 16:24:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hDpuVgO1WE/Render', 16:24:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:24:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:24:09 INFO - 'HOME': '/Users/cltbld', 16:24:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:24:09 INFO - 'LOGNAME': 'cltbld', 16:24:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:09 INFO - 'MOZ_NO_REMOTE': '1', 16:24:09 INFO - 'NO_EM_RESTART': '1', 16:24:09 INFO - 'PAGER': '/bin/cat', 16:24:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:24:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:09 INFO - 'PWD': '/builds/slave/test', 16:24:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:24:09 INFO - 'SHELL': '/bin/bash', 16:24:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4IWJ1LFsaJ/Listeners', 16:24:09 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 16:24:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:24:09 INFO - 'USER': 'cltbld', 16:24:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:24:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:24:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:09 INFO - 'XPC_FLAGS': '0x0', 16:24:09 INFO - 'XPC_SERVICE_NAME': '0', 16:24:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:24:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:09 INFO - Downloading/unpacking psutil>=3.1.1 16:24:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:24:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:24:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:24:11 INFO - Installing collected packages: psutil 16:24:11 INFO - Running setup.py install for psutil 16:24:11 INFO - building 'psutil._psutil_osx' extension 16:24:11 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 16:24:12 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 16:24:12 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 16:24:12 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 16:24:12 INFO - building 'psutil._psutil_posix' extension 16:24:12 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 16:24:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:24:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:24:12 INFO - ^ 16:24:12 INFO - 1 warning generated. 16:24:12 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 16:24:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:24:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:24:13 INFO - Successfully installed psutil 16:24:13 INFO - Cleaning up... 16:24:13 INFO - Return code: 0 16:24:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:24:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105174b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105178768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dfa574890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e84d30>, '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 0x104e38db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e79be8>, '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.hDpuVgO1WE/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.4IWJ1LFsaJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 16:24:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:24:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:24:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hDpuVgO1WE/Render', 16:24:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:24:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:24:13 INFO - 'HOME': '/Users/cltbld', 16:24:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:24:13 INFO - 'LOGNAME': 'cltbld', 16:24:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:13 INFO - 'MOZ_NO_REMOTE': '1', 16:24:13 INFO - 'NO_EM_RESTART': '1', 16:24:13 INFO - 'PAGER': '/bin/cat', 16:24:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:24:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:13 INFO - 'PWD': '/builds/slave/test', 16:24:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:24:13 INFO - 'SHELL': '/bin/bash', 16:24:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4IWJ1LFsaJ/Listeners', 16:24:13 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 16:24:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:24:13 INFO - 'USER': 'cltbld', 16:24:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:24:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:24:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:13 INFO - 'XPC_FLAGS': '0x0', 16:24:13 INFO - 'XPC_SERVICE_NAME': '0', 16:24:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:24:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:24:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:24:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:24:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:24:14 INFO - Installing collected packages: mozsystemmonitor 16:24:14 INFO - Running setup.py install for mozsystemmonitor 16:24:14 INFO - Successfully installed mozsystemmonitor 16:24:14 INFO - Cleaning up... 16:24:15 INFO - Return code: 0 16:24:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:24: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')]} 16:24:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24: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')]} 16:24:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105174b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105178768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dfa574890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e84d30>, '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 0x104e38db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e79be8>, '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.hDpuVgO1WE/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.4IWJ1LFsaJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 16:24:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:24:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:24:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hDpuVgO1WE/Render', 16:24:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:24:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:24:15 INFO - 'HOME': '/Users/cltbld', 16:24:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:24:15 INFO - 'LOGNAME': 'cltbld', 16:24:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:15 INFO - 'MOZ_NO_REMOTE': '1', 16:24:15 INFO - 'NO_EM_RESTART': '1', 16:24:15 INFO - 'PAGER': '/bin/cat', 16:24:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:24:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:15 INFO - 'PWD': '/builds/slave/test', 16:24:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:24:15 INFO - 'SHELL': '/bin/bash', 16:24:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4IWJ1LFsaJ/Listeners', 16:24:15 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 16:24:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:24:15 INFO - 'USER': 'cltbld', 16:24:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:24:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:24:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:15 INFO - 'XPC_FLAGS': '0x0', 16:24:15 INFO - 'XPC_SERVICE_NAME': '0', 16:24:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:24:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:15 INFO - Downloading/unpacking jsonschema==2.5.1 16:24:15 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 16:24:15 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 16:24:15 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 16:24:15 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 16:24:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:24:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:24:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:16 INFO - Downloading functools32-3.2.3-2.tar.gz 16:24:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:24:17 INFO - warning: no files found matching '*.txt' 16:24:17 INFO - no previously-included directories found matching 'build' 16:24:17 INFO - no previously-included directories found matching 'dist' 16:24:17 INFO - no previously-included directories found matching '.git*' 16:24:17 INFO - Installing collected packages: jsonschema, functools32 16:24:17 INFO - Running setup.py install for functools32 16:24:17 INFO - warning: no files found matching '*.txt' 16:24:17 INFO - no previously-included directories found matching 'build' 16:24:17 INFO - no previously-included directories found matching 'dist' 16:24:17 INFO - no previously-included directories found matching '.git*' 16:24:17 INFO - Successfully installed jsonschema functools32 16:24:17 INFO - Cleaning up... 16:24:17 INFO - Return code: 0 16:24:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:24:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:17 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 0x105174b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105178768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dfa574890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e84d30>, '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 0x104e38db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e79be8>, '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.hDpuVgO1WE/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.4IWJ1LFsaJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 16:24:17 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 16:24:17 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 16:24:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hDpuVgO1WE/Render', 16:24:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:24:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:24:17 INFO - 'HOME': '/Users/cltbld', 16:24:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:24:17 INFO - 'LOGNAME': 'cltbld', 16:24:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:17 INFO - 'MOZ_NO_REMOTE': '1', 16:24:17 INFO - 'NO_EM_RESTART': '1', 16:24:17 INFO - 'PAGER': '/bin/cat', 16:24:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:24:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:17 INFO - 'PWD': '/builds/slave/test', 16:24:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:24:17 INFO - 'SHELL': '/bin/bash', 16:24:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4IWJ1LFsaJ/Listeners', 16:24:17 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 16:24:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:24:17 INFO - 'USER': 'cltbld', 16:24:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:24:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:24:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:17 INFO - 'XPC_FLAGS': '0x0', 16:24:17 INFO - 'XPC_SERVICE_NAME': '0', 16:24:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:24:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:24:17 INFO - Cleaning up... 16:24:17 INFO - Return code: 0 16:24:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:24:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:17 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 0x105174b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105178768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dfa574890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e84d30>, '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 0x104e38db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e79be8>, '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.hDpuVgO1WE/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.4IWJ1LFsaJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 16:24:17 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 16:24:17 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 16:24:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hDpuVgO1WE/Render', 16:24:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:24:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:24:17 INFO - 'HOME': '/Users/cltbld', 16:24:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:24:17 INFO - 'LOGNAME': 'cltbld', 16:24:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:17 INFO - 'MOZ_NO_REMOTE': '1', 16:24:17 INFO - 'NO_EM_RESTART': '1', 16:24:17 INFO - 'PAGER': '/bin/cat', 16:24:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:24:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:17 INFO - 'PWD': '/builds/slave/test', 16:24:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:24:17 INFO - 'SHELL': '/bin/bash', 16:24:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4IWJ1LFsaJ/Listeners', 16:24:17 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 16:24:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:24:17 INFO - 'USER': 'cltbld', 16:24:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:24:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:24:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:17 INFO - 'XPC_FLAGS': '0x0', 16:24:17 INFO - 'XPC_SERVICE_NAME': '0', 16:24:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:24:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:17 INFO - Downloading/unpacking blobuploader==1.2.4 16:24: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 16:24: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 16:24: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 16:24: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 16:24:19 INFO - Downloading blobuploader-1.2.4.tar.gz 16:24:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:24:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:24:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:24:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:24:20 INFO - Downloading docopt-0.6.1.tar.gz 16:24:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:24:20 INFO - Installing collected packages: blobuploader, requests, docopt 16:24:20 INFO - Running setup.py install for blobuploader 16:24:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:24:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:24:20 INFO - Running setup.py install for requests 16:24:20 INFO - Running setup.py install for docopt 16:24:20 INFO - Successfully installed blobuploader requests docopt 16:24:20 INFO - Cleaning up... 16:24:21 INFO - Return code: 0 16:24:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:24:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:21 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 0x105174b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105178768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dfa574890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e84d30>, '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 0x104e38db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e79be8>, '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.hDpuVgO1WE/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.4IWJ1LFsaJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 16:24:21 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 16:24:21 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 16:24:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hDpuVgO1WE/Render', 16:24:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:24:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:24:21 INFO - 'HOME': '/Users/cltbld', 16:24:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:24:21 INFO - 'LOGNAME': 'cltbld', 16:24:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:21 INFO - 'MOZ_NO_REMOTE': '1', 16:24:21 INFO - 'NO_EM_RESTART': '1', 16:24:21 INFO - 'PAGER': '/bin/cat', 16:24:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:24:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:21 INFO - 'PWD': '/builds/slave/test', 16:24:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:24:21 INFO - 'SHELL': '/bin/bash', 16:24:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4IWJ1LFsaJ/Listeners', 16:24:21 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 16:24:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:24:21 INFO - 'USER': 'cltbld', 16:24:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:24:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:24:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:21 INFO - 'XPC_FLAGS': '0x0', 16:24:21 INFO - 'XPC_SERVICE_NAME': '0', 16:24:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:24:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:24:21 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-s9DrK1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:24:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:24:21 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-IZk2pI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:24:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:24:21 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-jTHvhA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:24:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:24:21 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-BzOFkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:24:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:24:21 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-SiZKDC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:24:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:24:21 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-UqTrK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:24:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:24:21 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-XsQbNp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:24:22 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-7KcKPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:24:22 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-0KInnn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:24:22 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-vCfNWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:24:22 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-F_9Gld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:24:22 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-Iy0aAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:24:22 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-86Qp_W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:24:22 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-0Ewun4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:24:22 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-Aa8q96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:24:22 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-abXEKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:24:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:24:22 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-SYjyjW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:24:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:24:23 INFO - Running setup.py install for manifestparser 16:24:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:24:23 INFO - Running setup.py install for mozcrash 16:24:23 INFO - Running setup.py install for mozdebug 16:24:23 INFO - Running setup.py install for mozdevice 16:24:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:24:23 INFO - Running setup.py install for mozfile 16:24:23 INFO - Running setup.py install for mozhttpd 16:24:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:24:23 INFO - Running setup.py install for mozinfo 16:24:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:24:24 INFO - Running setup.py install for mozInstall 16:24:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:24:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:24:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:24:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:24:24 INFO - Running setup.py install for mozleak 16:24:24 INFO - Running setup.py install for mozlog 16:24:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:24:24 INFO - Running setup.py install for moznetwork 16:24:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:24:24 INFO - Running setup.py install for mozprocess 16:24:24 INFO - Running setup.py install for mozprofile 16:24:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:24:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:24:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:24:24 INFO - Running setup.py install for mozrunner 16:24:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:24:25 INFO - Running setup.py install for mozscreenshot 16:24:25 INFO - Running setup.py install for moztest 16:24:25 INFO - Running setup.py install for mozversion 16:24:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:24:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:24:25 INFO - Cleaning up... 16:24:25 INFO - Return code: 0 16:24:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:24: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')]} 16:24:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24: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')]} 16:24:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:25 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 0x105174b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105178768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dfa574890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e84d30>, '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 0x104e38db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e79be8>, '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.hDpuVgO1WE/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.4IWJ1LFsaJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 16:24:25 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 16:24:25 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 16:24:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hDpuVgO1WE/Render', 16:24:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:24:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:24:25 INFO - 'HOME': '/Users/cltbld', 16:24:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:24:25 INFO - 'LOGNAME': 'cltbld', 16:24:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:25 INFO - 'MOZ_NO_REMOTE': '1', 16:24:25 INFO - 'NO_EM_RESTART': '1', 16:24:25 INFO - 'PAGER': '/bin/cat', 16:24:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:24:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:25 INFO - 'PWD': '/builds/slave/test', 16:24:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:24:25 INFO - 'SHELL': '/bin/bash', 16:24:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4IWJ1LFsaJ/Listeners', 16:24:25 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 16:24:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:24:25 INFO - 'USER': 'cltbld', 16:24:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:24:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:24:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:25 INFO - 'XPC_FLAGS': '0x0', 16:24:25 INFO - 'XPC_SERVICE_NAME': '0', 16:24:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:24:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:24:25 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-nlvxrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:24:25 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)) 16:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:24:26 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-00cAkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:24:26 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)) 16:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:24:26 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-NpoVcZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:24:26 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)) 16:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:24:26 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-qeo0NU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:24:26 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)) 16:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:24:26 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-XFE3A6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:24:26 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)) 16:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:24:26 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-Hz3FcV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:24:26 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)) 16:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:24:26 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-elCDEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:24:26 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)) 16:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:24:26 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-0cLUyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:24:26 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)) 16:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:24:26 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-cE6Pzr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:24:26 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)) 16:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:24:26 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-Tt_Csr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:24:26 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)) 16:24:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:24:26 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-dw1jnn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:24:27 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)) 16:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:24:27 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-wAAxl7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:24:27 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)) 16:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:24:27 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-wYLliM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:24:27 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)) 16:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:24:27 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-CTdd9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:24:27 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)) 16:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:24:27 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-nYN_Zd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:24:27 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)) 16:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:24:27 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-Zzkl9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:24:27 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)) 16:24:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:24:27 INFO - Running setup.py (path:/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/pip-ETMlbE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:24:27 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)) 16:24:27 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)) 16:24:27 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)) 16:24:27 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)) 16:24:27 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)) 16:24:27 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)) 16:24:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:24: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 16:24: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 16:24: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 16:24: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 16:24:29 INFO - Downloading blessings-1.6.tar.gz 16:24:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:24:29 INFO - Installing collected packages: blessings 16:24:29 INFO - Running setup.py install for blessings 16:24:29 INFO - Successfully installed blessings 16:24:29 INFO - Cleaning up... 16:24:29 INFO - Return code: 0 16:24:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:24:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:24:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:24:29 INFO - Reading from file tmpfile_stdout 16:24:29 INFO - Current package versions: 16:24:29 INFO - blessings == 1.6 16:24:29 INFO - blobuploader == 1.2.4 16:24:29 INFO - docopt == 0.6.1 16:24:29 INFO - functools32 == 3.2.3-2 16:24:29 INFO - jsonschema == 2.5.1 16:24:29 INFO - manifestparser == 1.1 16:24:29 INFO - mozInstall == 1.12 16:24:29 INFO - mozcrash == 1.0 16:24:29 INFO - mozdebug == 0.1 16:24:29 INFO - mozdevice == 0.50 16:24:29 INFO - mozfile == 1.2 16:24:29 INFO - mozhttpd == 0.7 16:24:29 INFO - mozinfo == 0.9 16:24:29 INFO - mozleak == 0.1 16:24:29 INFO - mozlog == 3.4 16:24:29 INFO - moznetwork == 0.27 16:24:29 INFO - mozprocess == 0.25 16:24:29 INFO - mozprofile == 0.28 16:24:29 INFO - mozrunner == 6.13 16:24:29 INFO - mozscreenshot == 0.1 16:24:29 INFO - mozsystemmonitor == 0.3 16:24:29 INFO - moztest == 0.8 16:24:29 INFO - mozversion == 1.4 16:24:29 INFO - psutil == 3.1.1 16:24:29 INFO - requests == 1.2.3 16:24:29 INFO - wsgiref == 0.1.2 16:24:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:24:29 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')]} 16:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:29 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')]} 16:24:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:29 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 0x105174b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105178768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dfa574890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e84d30>, '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 0x104e38db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e79be8>, '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.hDpuVgO1WE/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.4IWJ1LFsaJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 16:24:29 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 16:24:29 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 16:24:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hDpuVgO1WE/Render', 16:24:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:24:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:24:29 INFO - 'HOME': '/Users/cltbld', 16:24:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:24:29 INFO - 'LOGNAME': 'cltbld', 16:24:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:29 INFO - 'MOZ_NO_REMOTE': '1', 16:24:29 INFO - 'NO_EM_RESTART': '1', 16:24:29 INFO - 'PAGER': '/bin/cat', 16:24:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:24:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:29 INFO - 'PWD': '/builds/slave/test', 16:24:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:24:29 INFO - 'SHELL': '/bin/bash', 16:24:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4IWJ1LFsaJ/Listeners', 16:24:29 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 16:24:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:24:29 INFO - 'USER': 'cltbld', 16:24:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:24:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:24:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:29 INFO - 'XPC_FLAGS': '0x0', 16:24:29 INFO - 'XPC_SERVICE_NAME': '0', 16:24:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:24:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:29 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)) 16:24:29 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)) 16:24:29 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)) 16:24:29 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)) 16:24:29 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)) 16:24:29 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)) 16:24:29 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)) 16:24:29 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)) 16:24:29 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)) 16:24:29 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)) 16:24:29 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)) 16:24:29 INFO - Cleaning up... 16:24:29 INFO - Return code: 0 16:24:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:24:29 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')]} 16:24:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:24:29 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')]} 16:24:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:24:29 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 0x105174b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105178768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dfa574890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e84d30>, '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 0x104e38db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e79be8>, '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.hDpuVgO1WE/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.4IWJ1LFsaJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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 16:24:29 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 16:24:29 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 16:24:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hDpuVgO1WE/Render', 16:24:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:24:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:24:29 INFO - 'HOME': '/Users/cltbld', 16:24:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:24:29 INFO - 'LOGNAME': 'cltbld', 16:24:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:29 INFO - 'MOZ_NO_REMOTE': '1', 16:24:29 INFO - 'NO_EM_RESTART': '1', 16:24:29 INFO - 'PAGER': '/bin/cat', 16:24:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:24:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:29 INFO - 'PWD': '/builds/slave/test', 16:24:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:24:29 INFO - 'SHELL': '/bin/bash', 16:24:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4IWJ1LFsaJ/Listeners', 16:24:29 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 16:24:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:24:29 INFO - 'USER': 'cltbld', 16:24:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:24:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:24:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:29 INFO - 'XPC_FLAGS': '0x0', 16:24:29 INFO - 'XPC_SERVICE_NAME': '0', 16:24:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:24:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:24:30 INFO - Cleaning up... 16:24:30 INFO - Return code: 0 16:24:30 INFO - Running post-action listener: _resource_record_post_action 16:24:30 INFO - Running post-action listener: _start_resource_monitoring 16:24:30 INFO - Starting resource monitoring. 16:24:30 INFO - [mozharness: 2017-05-19 23:24:30.256054Z] Finished create-virtualenv step (success) 16:24:30 INFO - [mozharness: 2017-05-19 23:24:30.257083Z] Running install step. 16:24:30 INFO - Running pre-action listener: _resource_record_pre_action 16:24:30 INFO - Running main action method: install 16:24:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:24:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:24:30 INFO - Reading from file tmpfile_stdout 16:24:30 INFO - Detecting whether we're running mozinstall >=1.0... 16:24:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:24:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:24:30 INFO - Reading from file tmpfile_stdout 16:24:30 INFO - Output received: 16:24:30 INFO - Usage: mozinstall [options] installer 16:24:30 INFO - Options: 16:24:30 INFO - -h, --help show this help message and exit 16:24:30 INFO - -d DEST, --destination=DEST 16:24:30 INFO - Directory to install application into. [default: 16:24:30 INFO - "/builds/slave/test"] 16:24:30 INFO - --app=APP Application being installed. [default: firefox] 16:24:30 INFO - mkdir: /builds/slave/test/build/application 16:24:30 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'] 16:24:30 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 16:24:51 INFO - Reading from file tmpfile_stdout 16:24:51 INFO - Output received: 16:24:51 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:24:51 INFO - Running post-action listener: _resource_record_post_action 16:24:51 INFO - [mozharness: 2017-05-19 23:24:51.991404Z] Finished install step (success) 16:24:51 INFO - [mozharness: 2017-05-19 23:24:51.991534Z] Running run-tests step. 16:24:51 INFO - Running pre-action listener: _resource_record_pre_action 16:24:51 INFO - Running main action method: run_tests 16:24:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:24:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:24:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:24:52 INFO - 2017-05-19 16:24:52.006 screenresolution[1617:7755] starting screenresolution argv=screenresolution get 16:24:52 INFO - 2017-05-19 16:24:52.009 screenresolution[1617:7755] Display 0: 1600x1200x32@60 16:24:52 INFO - 2017-05-19 16:24:52.015 screenresolution[1618:7757] starting screenresolution argv=screenresolution list 16:24:52 INFO - Available Modes on Display 0 16:24:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:24:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:24:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:24:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:24:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:24:52 INFO - Intel Iris: 16:24:52 INFO - Chipset Model: Intel Iris 16:24:52 INFO - Type: GPU 16:24:52 INFO - Bus: Built-In 16:24:52 INFO - VRAM (Dynamic, Max): 1536 MB 16:24:52 INFO - Vendor: Intel (0x8086) 16:24:52 INFO - Device ID: 0x0a2e 16:24:52 INFO - Revision ID: 0x0009 16:24:52 INFO - Displays: 16:24:52 INFO - Display: 16:24:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:24:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:24:52 INFO - Display Serial Number: 1600x1200 60 16:24:52 INFO - Main Display: Yes 16:24:52 INFO - Mirror: Off 16:24:52 INFO - Online: Yes 16:24:52 INFO - Rotation: Supported 16:24:52 INFO - Return code: 0 16:24:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:24:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:24:52 INFO - Python 2.7.3 16:24:52 INFO - Return code: 0 16:24:52 INFO - grabbing minidump binary from tooltool 16:24:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:24:52 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 0x104e84d30>, '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 0x104e38db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104e79be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:24:52 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 16:24:52 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 16:24:52 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 16:24:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:24:52 INFO - Return code: 0 16:24:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:24:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:24:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:24:52 INFO - ENV: RUST_BACKTRACE is now 1 16:24:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:24:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:24:52 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/BjUo9mIvSymQSrVJ2Jynrg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0168', '--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 16:24:52 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/BjUo9mIvSymQSrVJ2Jynrg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0168 --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 16:24:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hDpuVgO1WE/Render', 16:24:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:24:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:24:52 INFO - 'HOME': '/Users/cltbld', 16:24:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:24:52 INFO - 'LOGNAME': 'cltbld', 16:24:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:24:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:24:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:24:52 INFO - 'MOZ_NO_REMOTE': '1', 16:24:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:24:52 INFO - 'NO_EM_RESTART': '1', 16:24:52 INFO - 'PAGER': '/bin/cat', 16:24:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:24:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:24:52 INFO - 'PWD': '/builds/slave/test', 16:24:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:24:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:24:52 INFO - 'RUST_BACKTRACE': '1', 16:24:52 INFO - 'SHELL': '/bin/bash', 16:24:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4IWJ1LFsaJ/Listeners', 16:24:52 INFO - 'TMPDIR': '/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/', 16:24:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:24:52 INFO - 'USER': 'cltbld', 16:24:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:24:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:24:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:24:52 INFO - 'XPC_FLAGS': '0x0', 16:24:52 INFO - 'XPC_SERVICE_NAME': '0', 16:24:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:24:52 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/BjUo9mIvSymQSrVJ2Jynrg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0168', '--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 16:24:52 INFO - mozversion application_buildid: 20170519142244 16:24:52 INFO - mozversion application_changeset: 5a8f2dcbeac0677b5aae0f9fd43d6a33309b4a6b 16:24:52 INFO - mozversion application_display_name: Nightly 16:24:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:24:52 INFO - mozversion application_name: Firefox 16:24:52 INFO - mozversion application_remotingname: firefox 16:24:52 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:24:52 INFO - mozversion application_vendor: Mozilla 16:24:52 INFO - mozversion application_version: 55.0a1 16:24:52 INFO - mozversion platform_buildid: 20170519142244 16:24:52 INFO - mozversion platform_changeset: 5a8f2dcbeac0677b5aae0f9fd43d6a33309b4a6b 16:24:52 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:24:52 INFO - mozversion platform_version: 55.0a1 16:24:52 INFO - using testdate: 1495236292 16:24:52 INFO - actual date: 1495236292 16:24:52 INFO - starting webserver on 'localhost:49225' 16:24:52 INFO - SUITE-START | Running 2 tests 16:24:52 INFO - TEST-START | dromaeo_css 16:24:52 INFO - operating with platform_type : mac_ 16:24:52 INFO - Initialising browser for dromaeo_css test... 16:24:52 INFO - TEST-INFO | started process 1628 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmpxPm9no/profile http://localhost:49225/getInfo.html) 16:24:53 INFO - PID 1628 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2963: String.localeCompare is deprecated; use String.prototype.localeCompare instead 16:24:55 INFO - PID 1628 | 2017-05-19 16:24:55.314 plugin-container[1630:7941] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b3f, name = 'com.apple.tsm.portname' 16:24:55 INFO - PID 1628 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:24:55 INFO - PID 1628 | 2017-05-19 16:24:55.317 plugin-container[1630:7941] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa30b, name = 'com.apple.CFPasteboardClient' 16:24:55 INFO - PID 1628 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:24:55 INFO - PID 1628 | 2017-05-19 16:24:55.317 plugin-container[1630:7941] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:24:55 INFO - PID 1628 | __metrics Screen width/height:1600/1200 16:24:55 INFO - PID 1628 | colorDepth:24 16:24:55 INFO - PID 1628 | Browser inner width/height: 1024/691 16:24:55 INFO - PID 1628 | __metrics 16:24:55 INFO - PID 1628 | 16:24:55 INFO - PID 1628 | ###!!! [Child][MessageChannel] Error: (msgtype=0x7C0029,name=PHal::Msg_EnableWakeLockNotifications) Channel closing: too late to send/recv, messages will be lost 16:24:55 INFO - PID 1628 | 16:24:55 INFO - PID 1628 | 2017-05-19 16:24:55.975 plugin-container[1631:7997] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 16:24:55 INFO - PID 1628 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:24:55 INFO - PID 1628 | 2017-05-19 16:24:55.976 plugin-container[1631:7997] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x981b, name = 'com.apple.CFPasteboardClient' 16:24:55 INFO - PID 1628 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:24:55 INFO - PID 1628 | 2017-05-19 16:24:55.976 plugin-container[1631:7997] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:24:56 INFO - TEST-INFO | 1628: exit 0 16:24:56 INFO - Browser initialized. 16:24:56 INFO - Running cycle 1/1 for dromaeo_css test... 16:24:56 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmpxPm9no/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:24:58 INFO - PID 1632 | 2017-05-19 16:24:58.665 plugin-container[1633:8088] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993f, name = 'com.apple.tsm.portname' 16:24:58 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:24:58 INFO - PID 1632 | 2017-05-19 16:24:58.669 plugin-container[1633:8088] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 16:24:58 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:24:58 INFO - PID 1632 | 2017-05-19 16:24:58.669 plugin-container[1633:8088] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:24:59 INFO - PID 1632 | 2017-05-19 16:24:59.329 plugin-container[1634:8135] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 16:24:59 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:24:59 INFO - PID 1632 | 2017-05-19 16:24:59.330 plugin-container[1634:8135] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x491b, name = 'com.apple.CFPasteboardClient' 16:24:59 INFO - PID 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:24:59 INFO - PID 1632 | 2017-05-19 16:24:59.330 plugin-container[1634:8135] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:25:08 INFO - PID 1632 | RSS: Main: 241233920 16:25:08 INFO - PID 1632 | 16:28:11 INFO - PID 1632 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49225/tests/dromaeo/cssquery-ext.html) 16:28:13 INFO - PID 1632 | RSS: Main: 265093120 16:28:13 INFO - PID 1632 | 16:31:15 INFO - PID 1632 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-ext.html (next: http://localhost:49225/tests/dromaeo/cssquery-jquery.html) 16:31:15 INFO - PID 1632 | RSS: Main: 271888384 16:31:15 INFO - PID 1632 | 16:34:17 INFO - PID 1632 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49225/tests/dromaeo/cssquery-mootools.html) 16:34:18 INFO - PID 1632 | RSS: Main: 276807680 16:34:18 INFO - PID 1632 | 16:37:19 INFO - PID 1632 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49225/tests/dromaeo/cssquery-prototype.html) 16:37:20 INFO - PID 1632 | RSS: Main: 274681856 16:37:20 INFO - PID 1632 | 16:40:23 INFO - PID 1632 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49225/tests/dromaeo/cssquery-yui.html) 16:40:24 INFO - PID 1632 | RSS: Main: 276119552 16:40:24 INFO - PID 1632 | 16:43:25 INFO - PID 1632 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-yui.html (next: http://localhost:49225/tests/dromaeo/cssquery-dojo.html) 16:43:26 INFO - PID 1632 | RSS: Main: 277458944 16:43:26 INFO - PID 1632 | 16:43:26 INFO - PID 1632 | __start_tp_report 16:43:26 INFO - PID 1632 | _x_x_mozilla_page_load 16:43:26 INFO - PID 1632 | _x_x_mozilla_page_load_details 16:43:26 INFO - PID 1632 | |i|pagename|runs| 16:43:26 INFO - PID 1632 | |0;dojo.html;3002.6997300269977;3532.947005794912;3549.2901419716063;3581.247937933034;3590.8204589770503;5042.82350117746;6108.938910610893;6235.812925612227;6241.594296370738;6258.812235632927;5091.592672586194;6176.938230617692;6180.443760061594;6184.938150618493;6194.566380353377;5401;5468.945310546894;5513.2005859150395;5523.978064058142;5535.723213839312;5361.651492652984;5512.173174023888;5543.445655434444;5553.555715542747;5568.49883510484;4157.459530261061;5480.534154596824;5533.114701647737;5586.888262234753;5588.748506317205;5359.196120581905;5399.730013499309;5453.945460545384;5488.945110548923;5556.611037227396;5375.489328513824;5394.622376433651;5420.051490989101;5442.265294185274;5447.727613619343;5439.564834813205;5441.863953401157;6202.658853763086;6259.874802503924;6270.87458250837;6444.2589102252905;6666.100076489661;6674.232463266738;6675.933240667579;6697.531172817905;6625.801225963229;6741.39327460526;6852;6859.5541289816;6869.210040845317;5633.943660563342;6094.329623741385;6339.49284057279;6340.904886426707;6350.714217860207;6841.555298905555;6926.272741362084;6944.652767361612;6963.9303606964295;6984;6378.330275321025;6469.967650161719;6538.869222615616;6575.4410875076155;6575.572587781875;6710.798676039629;6794.218664853605;6799.082123913307;6809.080774095532;6822.692978815965;6419.550631455847;6525.412712855866;6692.230393504712;6693.196816381968;6717.630530320879;6607.240167380813;6683.933160668428;6689.933100668931;6694.430973367221;6728.899066514006;5358.410574836765;5362.329708786402;5366.865828354323;5384.757685904144;5413.648112872729;4816.54242846929;4816.759162041884;4832.903341933142;4833.347498087784;4853.053654537332;5321;6180.072989051679;6270.749170033238;6295.276043254993;6347.333529979378;6790.253072162058;6856.382925536725;6864.622445765431;6884.65576721172;6892.551984121016;5891.764329426861;6270.561060725706;6287.59130656515;6365.586236894587;6384.042393640991;5786.132080187921;5874.03078492044;5947.37552556984;5970.955082860482;6013.819585412444;6982.825429364307;6983.406410455066;7005.159380874327;7008.194057683228;7042;6753.4354259333;6822.590644561342;6823.805833979133;6838.794836154923;6849.589024658537;6742.325767423415;6806.353396427331;6863.691133899087;6954.826129346807;7004.3696067354185;5355.705436201047;6555.868882622225;6638.435732962752;6641.50188735837;6689.09697190873;6817.250102488623;6819.079424277659;6835.726570937107;6844.555103918229;6873.0034145049685;6374.235091789015;6445.742170313135;6447.516436267392;6504.544681872224;6659.633720145439;6511.869762604816;6583.407493325623;6598.472122230114;6616.239132499824;6637.734490620321;6395.072714456471;6445.16212892321;6495.123158373559;6500.349965003462;6519.967400162969;5352.705601191972;6479.449246813885;6554.672266386567;6593.307702691341;6625.503087268377;6419.743210271537;6442.452391546771;6472.611643301417;6499.610023398611;6504.479641628753 16:43:26 INFO - PID 1632 | |1;ext.html;756.451572609858;766.8542976834401;830.800607854115;1207.1730864357914;1281.5066199513185;39555.40666889995;40064.799676001654;40104.79685609429;40250.78620675869;40262.59737402625;39664.80167599165;39838.80483585497;40244.39633405501;40295.59704402955;40312.39531407031;7800.726974555878;7977.321927636129;8278.58607069647;8462.703805366813;8483.66065357385;8459.703910363138;8480.024797148315;8574.314054875626;8575;8576.271016963534;8478.872816907751;8519.190676885717;8553.95723021384;8597.484150950932;8616.439931404439;7971.880421793678;8520.360972927056;8548.529830859301;8574.142585741438;8593.613287402082;7984.880226796603;8307.127751586057;8319.168083191693;8320.167983201693;8337.666493340259;37941.05147371338;38002.80998595017;38020.80989595034;38154.046148846224;38192.80903595492;3508.6666766657127;3528.2767032758225;3541.220931395089;3543.8050907200095;3550.840212190432;25137.49725005508;30641;30657.540136897966;30709.692903070907;31046;3106.673799251069;3129.4992801151934;3132.6084239470065;3134.639516455614;3162.0829959311773;301537;301912;309514;312808;313488;47327.526724733;47493.575192744276;47532.57402277868;47564.28653570199;47712.80717982009;49100.75449622801;49336.0132797342;49485.257721134214;49628;49639.75180124076;14041.71916561663;14125.152490850582;14169.433222670978;14191.645208869655;14199.85800142006;11373.94313028446;11470.082393408507;11627.476763545661;11629.360385178896;11639.76720465586;13073.934630326787;13180.736385272241;13260;13266.668333291553;13382.862456691095;5404.297441332602;5450;5873.58884878063;5894.646321220741;5908.202392676933;980.1815484070738;1010.1312870930993;1014.1177175856905;1043.7338478688039;1051.3901936876593;23573.1749388774;24548.631770523458;24763.25710228696;24800.131995380438;24919;993.8459538771502;1069.4813015687384;1138.1577632551853;1139.9544018239178;1152.5850693750244;12346.259224446561;12525.874741252654;12565.308908009963;12644.557440489538;12699.428525716365;297981;298632;299925.00074999407;300847;301056;53857.46142538603;54226.18660720092;54446;54606.726966365706;54959.45040549623;1333.999500374719;1497.6480527076346;1516.2873449478948;1536.523677659929;1562.4921900382421;20237.79762202418;23585.52828943446;23870.522589547763;24039.879800600884;24127.758722412553;37542;37546.06134846542;37697.62302377051;37713.43429848609;37813.81093094517;243711.78144109165;243909;244947;245458;246134;33593.32813343768;34858.825705871306;36614;36717.44923826198;36845.63154368529;43786.68639940807;44527.77736111299;45982.310265345375;46222.7688861567;46335.536644633125;30254;30674.233144171576;31177.37645247128;31203.531947021267;31791.205219868763;22075;30704.53943190809;31463.52804707977;31575.052748417584;31605.52591711172;37888.62111378851;38051.61948380481;38179.618203817605;38216.42675359816;38221.853344399715;26538.60192097159;28893.855530722212;29473.705262947955;31409.057728268188;31637;21281.89359053195;21527.138914443247;24847.751522484545;25034.37414064663;25137.74862251354 16:43:26 INFO - PID 1632 | |2;jquery.html;438.95829896159853;441.55182489772875;442.89718600684307;454.79306915353504;467.6235630317595;19384.515387115312;21205.681914771292;21596.352109436742;21755.021024053916;21755.564888702218;21090;21744.541124610805;22651.546969060608;23291.650625240592;23503.76496235037;5830.329512106109;6124.816255512342;6348.143000694893;6350.714217860184;6351.650659213741;4997.50024997501;5008.398992120932;6237.688115594225;6284.214473190851;6301.842453938665;6107.3587273336325;6119.173911521954;6184.783532576361;6257.4368306852375;6258.624482531062;6087.299960504554;6186.010238361885;6190.473809726133;6198.78304259351;6210.378962103799;4640.883977900546;5492.5605951523785;5512.062949298589;5540.445955404468;5552.91670624941;26806.329841753915;26831.463370732672;26949.865250673818;27069;27212.727872721218;1839.3010655950786;2174.010825074603;2178.36827320077;2181.683655869906;2185.726784151981;17790.288388464447;17911.552211194696;18207.45377638673;18355.908220458947;18672.439826805217;2077.522169900916;2102.621528124937;2130.3608917324896;2139.764625891151;2143.271287762153;113815;148237;148325;149213;149229;10901.890981090246;12650.430730617145;12663.5436924753;12689.302088385228;13108.279044652445;16444;16792.664146716997;16796.16019199035;16828.9805223374;16982.32070717182;8660.8700869487;9090.500022498694;9092;9191.63233470667;9290.396124251902;8739.825203496022;8747.825043499095;8767.342449316327;8878.644854205759;8931.910680893108;8110.4857494176995;8241.464304820287;8279.668813247523;8311.501309921425;8332.541710205876;1961.5586493038952;2239.261043855524;2285.737140228895;2287.165184707603;2447.192426499251;792.2671528835804;827.3960009193322;928.8188803183315;977.9755506112405;999.8900120986685;20070.896100714603;20644.48388790262;20659.793402066087;20702.68945965812;20811.68782468264;929.1730359979623;997.870276864003;1001.9949900250452;1003.1423133221136;1005.2561104782554;8463.449875758057;10526.947365263279;10607.93920607933;10826.67519974402;10859.619913302997;552591;556973;564166;567932;570148;102294.7705229486;132628;133815.33092334672;137683.31158344145;139472;1182.0897908610323;1186.7033241689578;1201.777671130826;1213.8725433829497;1214.7388311513066;18483;18502.722459163393;18536.814631853507;18583;18669.253229870654;44082;54531.72734136304;54794.726026369615;55196;55229.723851380484;76699.2621033067;80060;80512;80642.79035814585;81649;7497.962510187414;8448.535330556879;8788.692395766118;8849.24781393563;9072.773180670536;13304.534341298007;13865.930670346585;13871.79192312096;14218.786718199028;14236.857631423967;11733.00269477104;11755.412229388325;11992.460339284928;12024.819627705767;12106.636800895634;10971.725706857393;11085;11305.378204198849;11401.600943966921;11621.941890290833;11637.359945203096;11811.940940295244;12111.273323600612;12134.968527675246;12160.331181785086;7511.474196806171;8308.169183081885;8834.29325653959;8855.335849811161;9134.634614615607;9529;11773.882261177621;12037.45831437605;12069.336186509476;12110.939445302716 16:43:26 INFO - PID 1632 | |3;mootools.html;1361.8774310312072;1414.747948065962;1425.4013314407948;1432.8352239492463;1437.9352929118181;11126.165537584673;11241.55033798648;11495.19533632646;11827.40862956852;12178.147529672926;11721.53113875446;11962.760744785099;11999.64001079969;12010.339431331273;12059.698507537294;2514.7610976957208;2552.5788244939654;2561.3596600849787;2571.9742802571923;2572.1383336582267;2498.350428888493;2541.834780739246;2543.8092143089248;2544.860032698201;2547.5541780188487;2481.466484705788;2512.723600403954;2544.1477105169793;2545.4018305698146;2545.8090643201836;2469.5678256305164;2485.875706214691;2507.949841003179;2523.116909081825;2533;2343.121329501437;2433.4646377796857;2503.7120731115974;2518.508890766301;2521.407469244726;11484.885151148548;11518.769624607545;11529.077673786078;11545.422728863607;11570.074394048455;6375.968120159369;6610.4050635443045;6624.205095388536;6634.237062737799;6643.900341494881;3936.8425262989444;4213.726107802983;4240.406343111967;4252.468441444819;4331.415258940035;1017.8167929772638;1226.558438961982;1247.6756043428684;1273.2360583649736;1277.3294020639146;13886.166829990132;15972.600684982852;15972.840271597368;15980.680386392207;16132.516024519166;5300.761465734051;5389.514943655012;5405.5135037846785;5551.2228288039705;5570.41510641385;11046.944765276123;11069.612563560398;11075;11087.667369978913;11091.944540277247;5260.473952604709;6273.874522509615;6359.904601430982;6364.363563643598;6366.140571022853;5849.64902105875;5989.131575921554;6012.218411606463;6110.633361998294;6202.914489964238;4212.831486740496;4322.935155972663;4352.869413917588;4391.143726973273;4399.450068741407;1227.8096894981293;1249.7562975219744;1270.6315168601002;1272.8981681465461;1275.3623188405797;766.6511737159691;799.4403917257827;820.9425340226246;821.9589020548949;824.5135370131651;6086.02623580225;6089.238845144357;6243.7814676486105;6254.3425992112125;6267.373262673696;492.31813937696546;603.8218725475995;609.969501524922;618.3229363846581;618.6071844378762;3996.9200615988098;4368.885934086851;4500.729956202638;4554.749488778149;4558;14476.34856431463;14571.635709107357;14753;14763.92618036903;14764;13906.652333691536;14026.789598156036;14234.501792437422;14284;14296.213708246147;7996.320312773363;8235.423520353517;8411.579421028924;8442.915570844336;8495.36284778644;5814.94185058144;6749.5612785168805;6978.371946524837;7123.216446190811;7143.214246432992;11802.114841386758;12908.354582271037;13047.608571742863;13114.409851556893;13278.80081798793;13039;13211.801822972855;13225.338733063501;13335.799963000369;13668;9151.588178531849;9177.862332064891;9212;9248.306377021616;9417.811643766949;8694.478331300143;10550.841737373792;10584.89415105839;10589.417582033062;10618.309809862334;8833.602487887933;8918.821623567363;9023.819523609622;9183.816323673356;9267.397619154734;6778.593284402755;8746.606402712021;8798;8862.822743545223;9054.954725226331;8095.2714255717265;8101.149379315087;8225.300849427866;8229.876551851607;8233.876491852507;6020.066889632114;6072.848178795565;6116.602420842631;6142;6186.319504854552;6035.1249068885645;6176.104464852661;6191.318954915046;6237.126802247598;6257.530685198537 16:43:26 INFO - PID 1632 | |4;prototype.html;375.8402678861484;399.3271337795814;400.1157442053066;405.7220803749432;411.2638377304624;6054.727537260823;6090.3605121462215;6098.298695650002;6209.9068513972325;6234.501239900808;5138.922916156261;5830.970845145768;5964.165016897627;6070.2412198475195;6087.595399817081;4130.855420060306;4324.82700691972;4480.305552639346;4485.461744590653;4502.842400515982;3687.299413111514;4373.956260437387;4464.218761716703;4476.2390393632995;4482.394876691654;3458.4120699481023;4455.153520831048;4455.2871540553515;4457.990856172087;4479.350494178342;3628.6915612172734;4351.260285751446;4440.866773996785;4451.688381813275;4484.8206071757095;3131.3267647455677;3182.7931184472936;3757.9624203757885;3790.5830358660523;3807.162424266657;5614.578906581982;5946.643201407929;5954.791582294599;5980.372060933626;5996.16053752475;5227.921581176285;5305.851130292199;5359.3032905722;5365.624406291522;5385.21914322425;6589.80230593083;6595.670216489157;6595.97762346837;6598.337922453036;6624.768133115318;3632.854685812564;4274.978625106885;4354.782260886943;4357.694961352707;4376.627986621141;6693.598384096969;6871.725130994754;6892.965535172392;6928.61892595907;6937.167539895245;4332.285172946428;4407.272799987966;4412.0955204183065;4417.403650507141;4428.623566996841;4129.256733787887;4471.821127154943;4475.6419486441655;4484.304932735431;4495.145922274757;4387.846425375096;4497.415336006298;4764.190087685102;5150.381954165449;5312.1766126250495;5729.942700572941;6077.483413909778;6628.767993120217;6636.104125943032;6649.867002659919;1501.8948673592963;1509.8490150984815;1514.66677330987;1521.9619509512129;1522.824875139351;988.4909271724923;991.7917237380086;992.5409647817696;1005.5073014222978;1012.2661070723666;5375.521731523862;5555.8888822223325;5741.995150848585;6117.990531562243;6172.074188871705;6541.803745887631;6793.93206067933;6820.897686534706;6845.212800527904;6870.793876183622;6066.393360663987;6099.3900609938655;6099.5361113333365;6134.325224225332;6146.4775494082605;3518.9794959461983;3563.376409128392;3569.375359312162;3578.1233597768714;3601.6218297078954;6818.522703410813;6874.346937040973;7011.1586609607175;7056.153261608537;7057.4120822815485;7034.557915627386;7052.647367631701;7070.328318809705;7074.575525468488;7125.465590080765;6751.392374686204;6900.102986611809;6977.895331569929;7010.334018268359;7016.333448322402;5646.802361917313;6104.779044191268;6459.8372292987715;6810.62541560219;6840.452763778985;6563.442107420923;6634.303398143318;6653.767118150841;6686.398224159849;6841.17905851301;5367.463253674601;5638.633488823213;5729.541636668973;6675.7997260082275;6924.307569243036;3930.918997275772;4324.221640104685;4390.05613793036;4403.493598236244;4419.270820314612;6704.66476676174;6855.348741869514;6901.654917254217;6914.412274956484;6945.409640180641;6594.142761440982;6648.404382947961;6683.298253683488;6696.430803381767;6706.429953454011;6439.3238709934785;6582.670866456754;6639.7012134455035;6643.867122657616;6690.330966903271;5541.473560011793;6510.502584405518;6534.705938232886;6566.409023187936;6634.502412318999;6056.51817654941;6326.493880489457;6363.809085727436;6478.838029049311;6585.670716464253;6150.80059388415;6461.19235095613;6465.482761378984;6499.63779881609;6501.414872661483 16:43:26 INFO - PID 1632 | |5;yui.html;747.762453140053;807.9111297757242;812.4231795425247;837.7696133563269;848.7496188624356;16211.675766484663;16586.25361858716;16713.247903844327;18735.15691793869;19389.515262118453;17812;18180.818191818078;18287.359942402018;18693.906530467364;18762.530936726555;977.3207620703614;991.0733464210967;997.561073127825;1002.2583288366629;1002.5638847101534;1009.7980403919246;1012.5645972231937;1019.469875664654;1020.5458570935937;1024.3956065921104;893.1738142218422;1041.6874937518753;1053.210092430677;1059.7191744187758;1061.347271428074;1024.2164743970868;1031.381171297223;1052.4895425718482;1053.3837704942541;1053.5575058475458;751.3125490176482;769.634423648768;775.275117764886;777.786108820079;778.4006315137312;16917.238724257437;17287.74068388975;17464.214110365065;17479.737803932952;17489.825101748946;16488.587785305343;16919.91540042304;17492;17522;17534.29862805486;14157.362918668685;14244.287785610679;14249.430022799075;14355.928220358936;14411.855881441155;3542.982285088584;3562.928741425157;3605.4952306677087;3609.061643972586;3612.205314830759;113056.43471782589;119977.40011299888;121820;121856.1721574177;121962;13260.867391325963;13478.123921945238;13502.86497135016;13575.864241357658;13597;23910;25516.21386502964;25552.872235638704;25683;25962.610560841604;7663.693452261959;7738.071431428265;7758.806029849186;7772.572508512086;7782.883256751154;3669;3794.8425730152567;3877.8836634901;3886.6113388661474;3887.183691424776;3410.079278594815;3527.7883327000463;3531.4173161428075;3539.079839241764;3540.7875527468436;911.5260064766285;944.5938246554048;945.5083356654501;947.625687853294;949.0604301741364;630.3601844128214;642.8007317731518;646.0761111610442;663.0882536512296;673.9318180683671;10182.879883212841;11042.558297668164;11623.88376116228;11818.586349477899;12048.622993260109;625.6058683029753;631.6399652198203;643.2474005413584;666.886629273015;673.7507122364757;4965.32968049316;5442.455754424526;5504.091824848935;5521.779128834882;5530.253415788898;68070;74642.25357746353;88252.6762098569;90640;92365.53817231006;81922.59038704887;82150.76773848398;83103.58448207841;84136;86609;61670.0749488758;73019;79371.60314198508;80052;80774.59612701899;11959.342236176746;12317.876821232032;12322.630321090382;12340.629781106582;12403.93798031004;28202.43595128127;33776.15559611029;35646.64353356433;38038.2392352157;38279.42580861234;33849.98450046503;47868.803279918284;49250.753746232476;53848.73075634597;71282.28717712963;16437;16617.83382166163;16631.66736665284;16921.83078169252;17002;17437.215325310644;18444.815551844848;18490.260389584804;18595.07024648735;18655.720164197275;16199.352025918832;16884.746728799324;17155;17271;17537.912310438365;13029.47882084706;15638.218089095728;15881.841181588035;17351.30594776195;17369.218385172444;15784.13187274711;16328.265228064529;16438.095904725356;16688.332466701682;16988.320467181173;11285.830712539155;11648.534058637559;11897.940510297392;12154.392280385768;12234.938825305817;12233.204841685261;13083.41124649412;13120;13213.207207567577;13287.800682989957 16:43:26 INFO - PID 1632 | __end_tp_report 16:43:26 INFO - PID 1632 | __start_cc_report 16:43:26 INFO - PID 1632 | _x_x_mozilla_cycle_collect,333 16:43:26 INFO - PID 1632 | __end_cc_report 16:43:26 INFO - PID 1632 | __startTimestamp1495237406042__endTimestamp 16:43:26 INFO - PID 1632 | 16:43:26 INFO - PID 1632 | ------- Summary: start ------- 16:43:26 INFO - PID 1632 | Number of tests: 6 16:43:26 INFO - PID 1632 | 16:43:26 INFO - PID 1632 | [#0] dojo.html Cycles:165 Average:6154.38 Median:6442.45 stddev:776.42 (12.1%) stddev-sans-first:738.39 16:43:26 INFO - PID 1632 | Values: 3002.7 3532.9 3549.3 3581.2 3590.8 5042.8 6108.9 6235.8 6241.6 6258.8 5091.6 6176.9 6180.4 6184.9 6194.6 5401.0 5468.9 5513.2 5524.0 5535.7 5361.7 5512.2 5543.4 5553.6 5568.5 4157.5 5480.5 5533.1 5586.9 5588.7 5359.2 5399.7 5453.9 5488.9 5556.6 5375.5 5394.6 5420.1 5442.3 5447.7 5439.6 5441.9 6202.7 6259.9 6270.9 6444.3 6666.1 6674.2 6675.9 6697.5 6625.8 6741.4 6852.0 6859.6 6869.2 5633.9 6094.3 6339.5 6340.9 6350.7 6841.6 6926.3 6944.7 6963.9 6984.0 6378.3 6470.0 6538.9 6575.4 6575.6 6710.8 6794.2 6799.1 6809.1 6822.7 6419.6 6525.4 6692.2 6693.2 6717.6 6607.2 6683.9 6689.9 6694.4 6728.9 5358.4 5362.3 5366.9 5384.8 5413.6 4816.5 4816.8 4832.9 4833.3 4853.1 5321.0 6180.1 6270.7 6295.3 6347.3 6790.3 6856.4 6864.6 6884.7 6892.6 5891.8 6270.6 6287.6 6365.6 6384.0 5786.1 5874.0 5947.4 5971.0 6013.8 6982.8 6983.4 7005.2 7008.2 7042.0 6753.4 6822.6 6823.8 6838.8 6849.6 6742.3 6806.4 6863.7 6954.8 7004.4 5355.7 6555.9 6638.4 6641.5 6689.1 6817.3 6819.1 6835.7 6844.6 6873.0 6374.2 6445.7 6447.5 6504.5 6659.6 6511.9 6583.4 6598.5 6616.2 6637.7 6395.1 6445.2 6495.1 6500.3 6520.0 5352.7 6479.4 6554.7 6593.3 6625.5 6419.7 6442.5 6472.6 6499.6 6504.5 16:43:26 INFO - PID 1632 | 16:43:26 INFO - PID 1632 | [#1] ext.html Cycles:180 Average:43894.88 Median:34226.08 stddev:74764.53 (218.4%) stddev-sans-first:74904.10 16:43:26 INFO - PID 1632 | Values: 756.5 766.9 830.8 1207.2 1281.5 39555.4 40064.8 40104.8 40250.8 40262.6 39664.8 39838.8 40244.4 40295.6 40312.4 7800.7 7977.3 8278.6 8462.7 8483.7 8459.7 8480.0 8574.3 8575.0 8576.3 8478.9 8519.2 8554.0 8597.5 8616.4 7971.9 8520.4 8548.5 8574.1 8593.6 7984.9 8307.1 8319.2 8320.2 8337.7 37941.1 38002.8 38020.8 38154.0 38192.8 3508.7 3528.3 3541.2 3543.8 3550.8 25137.5 30641.0 30657.5 30709.7 31046.0 3106.7 3129.5 3132.6 3134.6 3162.1 301537.0 301912.0 309514.0 312808.0 313488.0 47327.5 47493.6 47532.6 47564.3 47712.8 49100.8 49336.0 49485.3 49628.0 49639.8 14041.7 14125.2 14169.4 14191.6 14199.9 11373.9 11470.1 11627.5 11629.4 11639.8 13073.9 13180.7 13260.0 13266.7 13382.9 5404.3 5450.0 5873.6 5894.6 5908.2 980.2 1010.1 1014.1 1043.7 1051.4 23573.2 24548.6 24763.3 24800.1 24919.0 993.8 1069.5 1138.2 1140.0 1152.6 12346.3 12525.9 12565.3 12644.6 12699.4 297981.0 298632.0 299925.0 300847.0 301056.0 53857.5 54226.2 54446.0 54606.7 54959.5 1334.0 1497.6 1516.3 1536.5 1562.5 20237.8 23585.5 23870.5 24039.9 24127.8 37542.0 37546.1 37697.6 37713.4 37813.8 243711.8 243909.0 244947.0 245458.0 246134.0 33593.3 34858.8 36614.0 36717.4 36845.6 43786.7 44527.8 45982.3 46222.8 46335.5 30254.0 30674.2 31177.4 31203.5 31791.2 22075.0 30704.5 31463.5 31575.1 31605.5 37888.6 38051.6 38179.6 38216.4 38221.9 26538.6 28893.9 29473.7 31409.1 31637.0 21281.9 21527.1 24847.8 25034.4 25137.7 16:43:26 INFO - PID 1632 | 16:43:26 INFO - PID 1632 | [#2] jquery.html Cycles:180 Average:35465.60 Median:12468.64 stddev:94839.10 (760.6%) stddev-sans-first:95068.68 16:43:26 INFO - PID 1632 | Values: 439.0 441.6 442.9 454.8 467.6 19384.5 21205.7 21596.4 21755.0 21755.6 21090.0 21744.5 22651.5 23291.7 23503.8 5830.3 6124.8 6348.1 6350.7 6351.7 4997.5 5008.4 6237.7 6284.2 6301.8 6107.4 6119.2 6184.8 6257.4 6258.6 6087.3 6186.0 6190.5 6198.8 6210.4 4640.9 5492.6 5512.1 5540.4 5552.9 26806.3 26831.5 26949.9 27069.0 27212.7 1839.3 2174.0 2178.4 2181.7 2185.7 17790.3 17911.6 18207.5 18355.9 18672.4 2077.5 2102.6 2130.4 2139.8 2143.3 113815.0 148237.0 148325.0 149213.0 149229.0 10901.9 12650.4 12663.5 12689.3 13108.3 16444.0 16792.7 16796.2 16829.0 16982.3 8660.9 9090.5 9092.0 9191.6 9290.4 8739.8 8747.8 8767.3 8878.6 8931.9 8110.5 8241.5 8279.7 8311.5 8332.5 1961.6 2239.3 2285.7 2287.2 2447.2 792.3 827.4 928.8 978.0 999.9 20070.9 20644.5 20659.8 20702.7 20811.7 929.2 997.9 1002.0 1003.1 1005.3 8463.4 10526.9 10607.9 10826.7 10859.6 552591.0 556973.0 564166.0 567932.0 570148.0 102294.8 132628.0 133815.3 137683.3 139472.0 1182.1 1186.7 1201.8 1213.9 1214.7 18483.0 18502.7 18536.8 18583.0 18669.3 44082.0 54531.7 54794.7 55196.0 55229.7 76699.3 80060.0 80512.0 80642.8 81649.0 7498.0 8448.5 8788.7 8849.2 9072.8 13304.5 13865.9 13871.8 14218.8 14236.9 11733.0 11755.4 11992.5 12024.8 12106.6 10971.7 11085.0 11305.4 11401.6 11621.9 11637.4 11811.9 12111.3 12135.0 12160.3 7511.5 8308.2 8834.3 8855.3 9134.6 9529.0 11773.9 12037.5 12069.3 12110.9 16:43:26 INFO - PID 1632 | 16:43:26 INFO - PID 1632 | [#3] mootools.html Cycles:180 Average:6952.76 Median:4104.88 stddev:4337.48 (105.7%) stddev-sans-first:4329.30 16:43:26 INFO - PID 1632 | Values: 1361.9 1414.7 1425.4 1432.8 1437.9 11126.2 11241.6 11495.2 11827.4 12178.1 11721.5 11962.8 11999.6 12010.3 12059.7 2514.8 2552.6 2561.4 2572.0 2572.1 2498.4 2541.8 2543.8 2544.9 2547.6 2481.5 2512.7 2544.1 2545.4 2545.8 2469.6 2485.9 2507.9 2523.1 2533.0 2343.1 2433.5 2503.7 2518.5 2521.4 11484.9 11518.8 11529.1 11545.4 11570.1 6376.0 6610.4 6624.2 6634.2 6643.9 3936.8 4213.7 4240.4 4252.5 4331.4 1017.8 1226.6 1247.7 1273.2 1277.3 13886.2 15972.6 15972.8 15980.7 16132.5 5300.8 5389.5 5405.5 5551.2 5570.4 11046.9 11069.6 11075.0 11087.7 11091.9 5260.5 6273.9 6359.9 6364.4 6366.1 5849.6 5989.1 6012.2 6110.6 6202.9 4212.8 4322.9 4352.9 4391.1 4399.5 1227.8 1249.8 1270.6 1272.9 1275.4 766.7 799.4 820.9 822.0 824.5 6086.0 6089.2 6243.8 6254.3 6267.4 492.3 603.8 610.0 618.3 618.6 3996.9 4368.9 4500.7 4554.7 4558.0 14476.3 14571.6 14753.0 14763.9 14764.0 13906.7 14026.8 14234.5 14284.0 14296.2 7996.3 8235.4 8411.6 8442.9 8495.4 5814.9 6749.6 6978.4 7123.2 7143.2 11802.1 12908.4 13047.6 13114.4 13278.8 13039.0 13211.8 13225.3 13335.8 13668.0 9151.6 9177.9 9212.0 9248.3 9417.8 8694.5 10550.8 10584.9 10589.4 10618.3 8833.6 8918.8 9023.8 9183.8 9267.4 6778.6 8746.6 8798.0 8862.8 9055.0 8095.3 8101.1 8225.3 8229.9 8233.9 6020.1 6072.8 6116.6 6142.0 6186.3 6035.1 6176.1 6191.3 6237.1 6257.5 16:43:26 INFO - PID 1632 | 16:43:26 INFO - PID 1632 | [#4] prototype.html Cycles:180 Average:5288.00 Median:6061.46 stddev:1707.20 (28.2%) stddev-sans-first:1671.71 16:43:26 INFO - PID 1632 | Values: 375.8 399.3 400.1 405.7 411.3 6054.7 6090.4 6098.3 6209.9 6234.5 5138.9 5831.0 5964.2 6070.2 6087.6 4130.9 4324.8 4480.3 4485.5 4502.8 3687.3 4374.0 4464.2 4476.2 4482.4 3458.4 4455.2 4455.3 4458.0 4479.4 3628.7 4351.3 4440.9 4451.7 4484.8 3131.3 3182.8 3758.0 3790.6 3807.2 5614.6 5946.6 5954.8 5980.4 5996.2 5227.9 5305.9 5359.3 5365.6 5385.2 6589.8 6595.7 6596.0 6598.3 6624.8 3632.9 4275.0 4354.8 4357.7 4376.6 6693.6 6871.7 6893.0 6928.6 6937.2 4332.3 4407.3 4412.1 4417.4 4428.6 4129.3 4471.8 4475.6 4484.3 4495.1 4387.8 4497.4 4764.2 5150.4 5312.2 5729.9 6077.5 6628.8 6636.1 6649.9 1501.9 1509.8 1514.7 1522.0 1522.8 988.5 991.8 992.5 1005.5 1012.3 5375.5 5555.9 5742.0 6118.0 6172.1 6541.8 6793.9 6820.9 6845.2 6870.8 6066.4 6099.4 6099.5 6134.3 6146.5 3519.0 3563.4 3569.4 3578.1 3601.6 6818.5 6874.3 7011.2 7056.2 7057.4 7034.6 7052.6 7070.3 7074.6 7125.5 6751.4 6900.1 6977.9 7010.3 7016.3 5646.8 6104.8 6459.8 6810.6 6840.5 6563.4 6634.3 6653.8 6686.4 6841.2 5367.5 5638.6 5729.5 6675.8 6924.3 3930.9 4324.2 4390.1 4403.5 4419.3 6704.7 6855.3 6901.7 6914.4 6945.4 6594.1 6648.4 6683.3 6696.4 6706.4 6439.3 6582.7 6639.7 6643.9 6690.3 5541.5 6510.5 6534.7 6566.4 6634.5 6056.5 6326.5 6363.8 6478.8 6585.7 6150.8 6461.2 6465.5 6499.6 6501.4 16:43:26 INFO - PID 1632 | 16:43:26 INFO - PID 1632 | [#5] yui.html Cycles:180 Average:20426.01 Median:18625.40 stddev:27642.69 (148.4%) stddev-sans-first:27680.74 16:43:26 INFO - PID 1632 | Values: 747.8 807.9 812.4 837.8 848.7 16211.7 16586.3 16713.2 18735.2 19389.5 17812.0 18180.8 18287.4 18693.9 18762.5 977.3 991.1 997.6 1002.3 1002.6 1009.8 1012.6 1019.5 1020.5 1024.4 893.2 1041.7 1053.2 1059.7 1061.3 1024.2 1031.4 1052.5 1053.4 1053.6 751.3 769.6 775.3 777.8 778.4 16917.2 17287.7 17464.2 17479.7 17489.8 16488.6 16919.9 17492.0 17522.0 17534.3 14157.4 14244.3 14249.4 14355.9 14411.9 3543.0 3562.9 3605.5 3609.1 3612.2 113056.4 119977.4 121820.0 121856.2 121962.0 13260.9 13478.1 13502.9 13575.9 13597.0 23910.0 25516.2 25552.9 25683.0 25962.6 7663.7 7738.1 7758.8 7772.6 7782.9 3669.0 3794.8 3877.9 3886.6 3887.2 3410.1 3527.8 3531.4 3539.1 3540.8 911.5 944.6 945.5 947.6 949.1 630.4 642.8 646.1 663.1 673.9 10182.9 11042.6 11623.9 11818.6 12048.6 625.6 631.6 643.2 666.9 673.8 4965.3 5442.5 5504.1 5521.8 5530.3 68070.0 74642.3 88252.7 90640.0 92365.5 81922.6 82150.8 83103.6 84136.0 86609.0 61670.1 73019.0 79371.6 80052.0 80774.6 11959.3 12317.9 12322.6 12340.6 12403.9 28202.4 33776.2 35646.6 38038.2 38279.4 33850.0 47868.8 49250.8 53848.7 71282.3 16437.0 16617.8 16631.7 16921.8 17002.0 17437.2 18444.8 18490.3 18595.1 18655.7 16199.4 16884.7 17155.0 17271.0 17537.9 13029.5 15638.2 15881.8 17351.3 17369.2 15784.1 16328.3 16438.1 16688.3 16988.3 11285.8 11648.5 11897.9 12154.4 12234.9 12233.2 13083.4 13120.0 13213.2 13287.8 16:43:26 INFO - PID 1632 | -------- Summary: end -------- 16:43:26 INFO - PID 1632 | 16:43:26 INFO - TEST-INFO | 1632: exit 0 16:43:26 INFO - TEST-OK | dromaeo_css | took 1114065ms 16:43:26 INFO - TEST-START | kraken 16:43:26 INFO - operating with platform_type : mac_ 16:43:26 INFO - Initialising browser for kraken test... 16:43:26 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmpjhzVKt/profile http://localhost:49225/getInfo.html) 16:43:27 INFO - PID 1646 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2963: String.localeCompare is deprecated; use String.prototype.localeCompare instead 16:43:29 INFO - PID 1646 | 2017-05-19 16:43:29.310 plugin-container[1647:10494] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a43, name = 'com.apple.tsm.portname' 16:43:29 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:43:29 INFO - PID 1646 | 2017-05-19 16:43:29.317 plugin-container[1647:10494] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f17, name = 'com.apple.CFPasteboardClient' 16:43:29 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:43:29 INFO - PID 1646 | 2017-05-19 16:43:29.317 plugin-container[1647:10494] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:43:29 INFO - PID 1646 | __metrics Screen width/height:1600/1200 16:43:29 INFO - PID 1646 | colorDepth:24 16:43:29 INFO - PID 1646 | Browser inner width/height: 1024/691 16:43:29 INFO - PID 1646 | __metrics 16:43:29 INFO - PID 1646 | 2017-05-19 16:43:29.847 plugin-container[1648:10547] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 16:43:29 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:43:29 INFO - PID 1646 | 2017-05-19 16:43:29.848 plugin-container[1648:10547] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a17, name = 'com.apple.CFPasteboardClient' 16:43:29 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:43:29 INFO - PID 1646 | 2017-05-19 16:43:29.848 plugin-container[1648:10547] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:43:30 INFO - TEST-INFO | 1646: exit 0 16:43:30 INFO - Browser initialized. 16:43:30 INFO - Running cycle 1/1 for kraken test... 16:43:30 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/T/tmpjhzVKt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:43:32 INFO - PID 1649 | 2017-05-19 16:43:32.341 plugin-container[1650:10640] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983f, name = 'com.apple.tsm.portname' 16:43:32 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:43:32 INFO - PID 1649 | 2017-05-19 16:43:32.341 plugin-container[1650:10640] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e03, name = 'com.apple.CFPasteboardClient' 16:43:32 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:43:32 INFO - PID 1649 | 2017-05-19 16:43:32.341 plugin-container[1650:10640] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:43:33 INFO - PID 1649 | 2017-05-19 16:43:33.115 plugin-container[1651:10687] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 16:43:33 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:43:33 INFO - PID 1649 | 2017-05-19 16:43:33.115 plugin-container[1651:10687] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 16:43:33 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:43:33 INFO - PID 1649 | 2017-05-19 16:43:33.115 plugin-container[1651:10687] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:43:42 INFO - PID 1649 | RSS: Main: 243073024 16:43:42 INFO - PID 1649 | 16:43:45 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection) 16:43:46 INFO - PID 1649 | RSS: Main: 254357504 16:43:46 INFO - PID 1649 | 16:43:48 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-dft) 16:43:49 INFO - PID 1649 | RSS: Main: 263217152 16:43:49 INFO - PID 1649 | 16:43:52 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-fft) 16:43:53 INFO - PID 1649 | RSS: Main: 264847360 16:43:53 INFO - PID 1649 | 16:43:55 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator) 16:43:56 INFO - PID 1649 | RSS: Main: 267149312 16:43:56 INFO - PID 1649 | 16:43:58 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:43:58 INFO - PID 1649 | RSS: Main: 267915264 16:43:58 INFO - PID 1649 | 16:44:01 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom) 16:44:02 INFO - PID 1649 | RSS: Main: 277155840 16:44:02 INFO - PID 1649 | 16:44:05 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate) 16:44:06 INFO - PID 1649 | RSS: Main: 276434944 16:44:06 INFO - PID 1649 | 16:44:09 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial) 16:44:10 INFO - PID 1649 | RSS: Main: 276221952 16:44:10 INFO - PID 1649 | 16:44:11 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:44:12 INFO - PID 1649 | RSS: Main: 275652608 16:44:12 INFO - PID 1649 | 16:44:13 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:44:14 INFO - PID 1649 | RSS: Main: 275652608 16:44:14 INFO - PID 1649 | 16:44:16 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:44:17 INFO - PID 1649 | RSS: Main: 275337216 16:44:17 INFO - PID 1649 | 16:44:20 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:44:20 INFO - PID 1649 | RSS: Main: 275533824 16:44:20 INFO - PID 1649 | 16:44:23 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:44:24 INFO - PID 1649 | RSS: Main: 275591168 16:44:24 INFO - PID 1649 | 16:44:26 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49225/tests/kraken/driver.html?testName=ai-astar) 16:44:26 INFO - PID 1649 | RSS: Main: 277225472 16:44:26 INFO - PID 1649 | 16:44:26 INFO - PID 1649 | __start_tp_report 16:44:26 INFO - PID 1649 | _x_x_mozilla_page_load 16:44:26 INFO - PID 1649 | _x_x_mozilla_page_load_details 16:44:26 INFO - PID 1649 | |i|pagename|runs| 16:44:26 INFO - PID 1649 | |0;ai-astar;99;114;115;114;120;116;113;115;117;114 16:44:26 INFO - PID 1649 | |1;audio-beat-detection;139;108;127;184;92;133;96;144;164;152 16:44:26 INFO - PID 1649 | |2;audio-dft;204;200;199;201;213;270;257;281;198;198 16:44:26 INFO - PID 1649 | |3;audio-fft;78;63;55;92;53;61;69;86;88;87 16:44:26 INFO - PID 1649 | |4;audio-oscillator;86;104;119;103;70;69;86;71;70;89 16:44:26 INFO - PID 1649 | |5;imaging-gaussian-blur;90;92;90;104;102;89;90;106;135;90 16:44:26 INFO - PID 1649 | |6;imaging-darkroom;122;134;152;98;99;108;154;114;99;150 16:44:26 INFO - PID 1649 | |7;imaging-desaturate;91;94;90;103;93;90;90;90;93;90 16:44:26 INFO - PID 1649 | |8;json-parse-financial;51;49;49;50;51;52;51;53;53;56 16:44:26 INFO - PID 1649 | |9;json-stringify-tinderbox;55;59;59;58;60;58;56;60;59;58 16:44:26 INFO - PID 1649 | |10;stanford-crypto-aes;86;83;86;83;80;86;85;83;85;84 16:44:26 INFO - PID 1649 | |11;stanford-crypto-ccm;139;142;149;139;120;149;162;162;184;138 16:44:26 INFO - PID 1649 | |12;stanford-crypto-pbkdf2;188;221;207;181;184;184;185;182;184;205 16:44:26 INFO - PID 1649 | |13;stanford-crypto-sha256-iterative;71;69;75;96;100;69;71;68;70;72 16:44:26 INFO - PID 1649 | __end_tp_report 16:44:26 INFO - PID 1649 | __start_cc_report 16:44:26 INFO - PID 1649 | _x_x_mozilla_cycle_collect,917 16:44:26 INFO - PID 1649 | __end_cc_report 16:44:26 INFO - PID 1649 | __startTimestamp1495237466621__endTimestamp 16:44:26 INFO - PID 1649 | 16:44:26 INFO - PID 1649 | ------- Summary: start ------- 16:44:26 INFO - PID 1649 | Number of tests: 14 16:44:26 INFO - PID 1649 | 16:44:26 INFO - PID 1649 | [#0] ai-astar Cycles:10 Average:113.70 Median:115.50 stddev:5.54 (4.8%) stddev-sans-first:2.12 16:44:26 INFO - PID 1649 | Values: 99.0 114.0 115.0 114.0 120.0 116.0 113.0 115.0 117.0 114.0 16:44:26 INFO - PID 1649 | 16:44:26 INFO - PID 1649 | [#1] audio-beat-detection Cycles:10 Average:133.90 Median:158.00 stddev:29.41 (18.6%) stddev-sans-first:31.13 16:44:26 INFO - PID 1649 | Values: 139.0 108.0 127.0 184.0 92.0 133.0 96.0 144.0 164.0 152.0 16:44:26 INFO - PID 1649 | 16:44:26 INFO - PID 1649 | [#2] audio-dft Cycles:10 Average:222.10 Median:208.50 stddev:33.37 (16.0%) stddev-sans-first:34.74 16:44:26 INFO - PID 1649 | Values: 204.0 200.0 199.0 201.0 213.0 270.0 257.0 281.0 198.0 198.0 16:44:26 INFO - PID 1649 | 16:44:26 INFO - PID 1649 | [#3] audio-fft Cycles:10 Average:73.20 Median:82.00 stddev:14.76 (18.0%) stddev-sans-first:15.55 16:44:26 INFO - PID 1649 | Values: 78.0 63.0 55.0 92.0 53.0 61.0 69.0 86.0 88.0 87.0 16:44:26 INFO - PID 1649 | 16:44:26 INFO - PID 1649 | [#4] audio-oscillator Cycles:10 Average:86.70 Median:70.50 stddev:17.42 (24.7%) stddev-sans-first:18.48 16:44:26 INFO - PID 1649 | Values: 86.0 104.0 119.0 103.0 70.0 69.0 86.0 71.0 70.0 89.0 16:44:26 INFO - PID 1649 | 16:44:26 INFO - PID 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:98.80 Median:90.00 stddev:14.34 (15.9%) stddev-sans-first:14.86 16:44:26 INFO - PID 1649 | Values: 90.0 92.0 90.0 104.0 102.0 89.0 90.0 106.0 135.0 90.0 16:44:26 INFO - PID 1649 | 16:44:26 INFO - PID 1649 | [#6] imaging-darkroom Cycles:10 Average:123.00 Median:153.00 stddev:22.94 (15.0%) stddev-sans-first:24.33 16:44:26 INFO - PID 1649 | Values: 122.0 134.0 152.0 98.0 99.0 108.0 154.0 114.0 99.0 150.0 16:44:26 INFO - PID 1649 | 16:44:26 INFO - PID 1649 | [#7] imaging-desaturate Cycles:10 Average:92.40 Median:90.50 stddev:4.03 (4.5%) stddev-sans-first:4.25 16:44:26 INFO - PID 1649 | Values: 91.0 94.0 90.0 103.0 93.0 90.0 90.0 90.0 93.0 90.0 16:44:26 INFO - PID 1649 | 16:44:26 INFO - PID 1649 | [#8] json-parse-financial Cycles:10 Average:51.50 Median:51.50 stddev:2.12 (4.1%) stddev-sans-first:2.24 16:44:26 INFO - PID 1649 | Values: 51.0 49.0 49.0 50.0 51.0 52.0 51.0 53.0 53.0 56.0 16:44:26 INFO - PID 1649 | 16:44:26 INFO - PID 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:58.20 Median:59.00 stddev:1.62 (2.7%) stddev-sans-first:1.24 16:44:26 INFO - PID 1649 | Values: 55.0 59.0 59.0 58.0 60.0 58.0 56.0 60.0 59.0 58.0 16:44:26 INFO - PID 1649 | 16:44:26 INFO - PID 1649 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:85.00 stddev:1.91 (2.2%) stddev-sans-first:1.90 16:44:26 INFO - PID 1649 | Values: 86.0 83.0 86.0 83.0 80.0 86.0 85.0 83.0 85.0 84.0 16:44:26 INFO - PID 1649 | 16:44:26 INFO - PID 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:148.40 Median:149.00 stddev:17.54 (11.8%) stddev-sans-first:18.28 16:44:26 INFO - PID 1649 | Values: 139.0 142.0 149.0 139.0 120.0 149.0 162.0 162.0 184.0 138.0 16:44:26 INFO - PID 1649 | 16:44:26 INFO - PID 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.10 Median:186.50 stddev:13.80 (7.4%) stddev-sans-first:14.55 16:44:26 INFO - PID 1649 | Values: 188.0 221.0 207.0 181.0 184.0 184.0 185.0 182.0 184.0 205.0 16:44:26 INFO - PID 1649 | 16:44:26 INFO - PID 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.10 Median:71.00 stddev:11.74 (16.5%) stddev-sans-first:12.31 16:44:26 INFO - PID 1649 | Values: 71.0 69.0 75.0 96.0 100.0 69.0 71.0 68.0 70.0 72.0 16:44:26 INFO - PID 1649 | -------- Summary: end -------- 16:44:26 INFO - PID 1649 | 16:44:27 INFO - TEST-INFO | 1649: exit 0 16:44:27 INFO - TEST-OK | kraken | took 60768ms 16:44:27 INFO - SUITE-END | took 1174s 16:44:27 INFO - Completed test suite (00:19:35) 16:44:27 INFO - javascript benchmark 16:44:27 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7804.298867125876, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3002.6997300269977, 3532.947005794912, 3549.2901419716063, 3581.247937933034, 3590.8204589770503, 5042.82350117746, 6108.938910610893, 6235.812925612227, 6241.594296370738, 6258.812235632927, 5091.592672586194, 6176.938230617692, 6180.443760061594, 6184.938150618493, 6194.566380353377, 5401.0, 5468.945310546894, 5513.2005859150395, 5523.978064058142, 5535.723213839312, 5361.651492652984, 5512.173174023888, 5543.445655434444, 5553.555715542747, 5568.49883510484, 4157.459530261061, 5480.534154596824, 5533.114701647737, 5586.888262234753, 5588.748506317205, 5359.196120581905, 5399.730013499309, 5453.945460545384, 5488.945110548923, 5556.611037227396, 5375.489328513824, 5394.622376433651, 5420.051490989101, 5442.265294185274, 5447.727613619343, 5439.564834813205, 5441.863953401157, 6202.658853763086, 6259.874802503924, 6270.87458250837, 6444.2589102252905, 6666.100076489661, 6674.232463266738, 6675.933240667579, 6697.531172817905, 6625.801225963229, 6741.39327460526, 6852.0, 6859.5541289816, 6869.210040845317, 5633.943660563342, 6094.329623741385, 6339.49284057279, 6340.904886426707, 6350.714217860207, 6841.555298905555, 6926.272741362084, 6944.652767361612, 6963.9303606964295, 6984.0, 6378.330275321025, 6469.967650161719, 6538.869222615616, 6575.4410875076155, 6575.572587781875, 6710.798676039629, 6794.218664853605, 6799.082123913307, 6809.080774095532, 6822.692978815965, 6419.550631455847, 6525.412712855866, 6692.230393504712, 6693.196816381968, 6717.630530320879, 6607.240167380813, 6683.933160668428, 6689.933100668931, 6694.430973367221, 6728.899066514006, 5358.410574836765, 5362.329708786402, 5366.865828354323, 5384.757685904144, 5413.648112872729, 4816.54242846929, 4816.759162041884, 4832.903341933142, 4833.347498087784, 4853.053654537332, 5321.0, 6180.072989051679, 6270.749170033238, 6295.276043254993, 6347.333529979378, 6790.253072162058, 6856.382925536725, 6864.622445765431, 6884.65576721172, 6892.551984121016, 5891.764329426861, 6270.561060725706, 6287.59130656515, 6365.586236894587, 6384.042393640991, 5786.132080187921, 5874.03078492044, 5947.37552556984, 5970.955082860482, 6013.819585412444, 6982.825429364307, 6983.406410455066, 7005.159380874327, 7008.194057683228, 7042.0, 6753.4354259333, 6822.590644561342, 6823.805833979133, 6838.794836154923, 6849.589024658537, 6742.325767423415, 6806.353396427331, 6863.691133899087, 6954.826129346807, 7004.3696067354185, 5355.705436201047, 6555.868882622225, 6638.435732962752, 6641.50188735837, 6689.09697190873, 6817.250102488623, 6819.079424277659, 6835.726570937107, 6844.555103918229, 6873.0034145049685, 6374.235091789015, 6445.742170313135, 6447.516436267392, 6504.544681872224, 6659.633720145439, 6511.869762604816, 6583.407493325623, 6598.472122230114, 6616.239132499824, 6637.734490620321, 6395.072714456471, 6445.16212892321, 6495.123158373559, 6500.349965003462, 6519.967400162969, 5352.705601191972, 6479.449246813885, 6554.672266386567, 6593.307702691341, 6625.503087268377, 6419.743210271537, 6442.452391546771, 6472.611643301417, 6499.610023398611, 6504.479641628753], "value": 6101.724284110134, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [756.451572609858, 766.8542976834401, 830.800607854115, 1207.1730864357914, 1281.5066199513185, 39555.40666889995, 40064.799676001654, 40104.79685609429, 40250.78620675869, 40262.59737402625, 39664.80167599165, 39838.80483585497, 40244.39633405501, 40295.59704402955, 40312.39531407031, 7800.726974555878, 7977.321927636129, 8278.58607069647, 8462.703805366813, 8483.66065357385, 8459.703910363138, 8480.024797148315, 8574.314054875626, 8575.0, 8576.271016963534, 8478.872816907751, 8519.190676885717, 8553.95723021384, 8597.484150950932, 8616.439931404439, 7971.880421793678, 8520.360972927056, 8548.529830859301, 8574.142585741438, 8593.613287402082, 7984.880226796603, 8307.127751586057, 8319.168083191693, 8320.167983201693, 8337.666493340259, 37941.05147371338, 38002.80998595017, 38020.80989595034, 38154.046148846224, 38192.80903595492, 3508.6666766657127, 3528.2767032758225, 3541.220931395089, 3543.8050907200095, 3550.840212190432, 25137.49725005508, 30641.0, 30657.540136897966, 30709.692903070907, 31046.0, 3106.673799251069, 3129.4992801151934, 3132.6084239470065, 3134.639516455614, 3162.0829959311773, 301537.0, 301912.0, 309514.0, 312808.0, 313488.0, 47327.526724733, 47493.575192744276, 47532.57402277868, 47564.28653570199, 47712.80717982009, 49100.75449622801, 49336.0132797342, 49485.257721134214, 49628.0, 49639.75180124076, 14041.71916561663, 14125.152490850582, 14169.433222670978, 14191.645208869655, 14199.85800142006, 11373.94313028446, 11470.082393408507, 11627.476763545661, 11629.360385178896, 11639.76720465586, 13073.934630326787, 13180.736385272241, 13260.0, 13266.668333291553, 13382.862456691095, 5404.297441332602, 5450.0, 5873.58884878063, 5894.646321220741, 5908.202392676933, 980.1815484070738, 1010.1312870930993, 1014.1177175856905, 1043.7338478688039, 1051.3901936876593, 23573.1749388774, 24548.631770523458, 24763.25710228696, 24800.131995380438, 24919.0, 993.8459538771502, 1069.4813015687384, 1138.1577632551853, 1139.9544018239178, 1152.5850693750244, 12346.259224446561, 12525.874741252654, 12565.308908009963, 12644.557440489538, 12699.428525716365, 297981.0, 298632.0, 299925.00074999407, 300847.0, 301056.0, 53857.46142538603, 54226.18660720092, 54446.0, 54606.726966365706, 54959.45040549623, 1333.999500374719, 1497.6480527076346, 1516.2873449478948, 1536.523677659929, 1562.4921900382421, 20237.79762202418, 23585.52828943446, 23870.522589547763, 24039.879800600884, 24127.758722412553, 37542.0, 37546.06134846542, 37697.62302377051, 37713.43429848609, 37813.81093094517, 243711.78144109165, 243909.0, 244947.0, 245458.0, 246134.0, 33593.32813343768, 34858.825705871306, 36614.0, 36717.44923826198, 36845.63154368529, 43786.68639940807, 44527.77736111299, 45982.310265345375, 46222.7688861567, 46335.536644633125, 30254.0, 30674.233144171576, 31177.37645247128, 31203.531947021267, 31791.205219868763, 22075.0, 30704.53943190809, 31463.52804707977, 31575.052748417584, 31605.52591711172, 37888.62111378851, 38051.61948380481, 38179.618203817605, 38216.42675359816, 38221.853344399715, 26538.60192097159, 28893.855530722212, 29473.705262947955, 31409.057728268188, 31637.0, 21281.89359053195, 21527.138914443247, 24847.751522484545, 25034.37414064663, 25137.74862251354], "value": 17454.85596792897, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [438.95829896159853, 441.55182489772875, 442.89718600684307, 454.79306915353504, 467.6235630317595, 19384.515387115312, 21205.681914771292, 21596.352109436742, 21755.021024053916, 21755.564888702218, 21090.0, 21744.541124610805, 22651.546969060608, 23291.650625240592, 23503.76496235037, 5830.329512106109, 6124.816255512342, 6348.143000694893, 6350.714217860184, 6351.650659213741, 4997.50024997501, 5008.398992120932, 6237.688115594225, 6284.214473190851, 6301.842453938665, 6107.3587273336325, 6119.173911521954, 6184.783532576361, 6257.4368306852375, 6258.624482531062, 6087.299960504554, 6186.010238361885, 6190.473809726133, 6198.78304259351, 6210.378962103799, 4640.883977900546, 5492.5605951523785, 5512.062949298589, 5540.445955404468, 5552.91670624941, 26806.329841753915, 26831.463370732672, 26949.865250673818, 27069.0, 27212.727872721218, 1839.3010655950786, 2174.010825074603, 2178.36827320077, 2181.683655869906, 2185.726784151981, 17790.288388464447, 17911.552211194696, 18207.45377638673, 18355.908220458947, 18672.439826805217, 2077.522169900916, 2102.621528124937, 2130.3608917324896, 2139.764625891151, 2143.271287762153, 113815.0, 148237.0, 148325.0, 149213.0, 149229.0, 10901.890981090246, 12650.430730617145, 12663.5436924753, 12689.302088385228, 13108.279044652445, 16444.0, 16792.664146716997, 16796.16019199035, 16828.9805223374, 16982.32070717182, 8660.8700869487, 9090.500022498694, 9092.0, 9191.63233470667, 9290.396124251902, 8739.825203496022, 8747.825043499095, 8767.342449316327, 8878.644854205759, 8931.910680893108, 8110.4857494176995, 8241.464304820287, 8279.668813247523, 8311.501309921425, 8332.541710205876, 1961.5586493038952, 2239.261043855524, 2285.737140228895, 2287.165184707603, 2447.192426499251, 792.2671528835804, 827.3960009193322, 928.8188803183315, 977.9755506112405, 999.8900120986685, 20070.896100714603, 20644.48388790262, 20659.793402066087, 20702.68945965812, 20811.68782468264, 929.1730359979623, 997.870276864003, 1001.9949900250452, 1003.1423133221136, 1005.2561104782554, 8463.449875758057, 10526.947365263279, 10607.93920607933, 10826.67519974402, 10859.619913302997, 552591.0, 556973.0, 564166.0, 567932.0, 570148.0, 102294.7705229486, 132628.0, 133815.33092334672, 137683.31158344145, 139472.0, 1182.0897908610323, 1186.7033241689578, 1201.777671130826, 1213.8725433829497, 1214.7388311513066, 18483.0, 18502.722459163393, 18536.814631853507, 18583.0, 18669.253229870654, 44082.0, 54531.72734136304, 54794.726026369615, 55196.0, 55229.723851380484, 76699.2621033067, 80060.0, 80512.0, 80642.79035814585, 81649.0, 7497.962510187414, 8448.535330556879, 8788.692395766118, 8849.24781393563, 9072.773180670536, 13304.534341298007, 13865.930670346585, 13871.79192312096, 14218.786718199028, 14236.857631423967, 11733.00269477104, 11755.412229388325, 11992.460339284928, 12024.819627705767, 12106.636800895634, 10971.725706857393, 11085.0, 11305.378204198849, 11401.600943966921, 11621.941890290833, 11637.359945203096, 11811.940940295244, 12111.273323600612, 12134.968527675246, 12160.331181785086, 7511.474196806171, 8308.169183081885, 8834.29325653959, 8855.335849811161, 9134.634614615607, 9529.0, 11773.882261177621, 12037.45831437605, 12069.336186509476, 12110.939445302716], "value": 10363.340660605889, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1361.8774310312072, 1414.747948065962, 1425.4013314407948, 1432.8352239492463, 1437.9352929118181, 11126.165537584673, 11241.55033798648, 11495.19533632646, 11827.40862956852, 12178.147529672926, 11721.53113875446, 11962.760744785099, 11999.64001079969, 12010.339431331273, 12059.698507537294, 2514.7610976957208, 2552.5788244939654, 2561.3596600849787, 2571.9742802571923, 2572.1383336582267, 2498.350428888493, 2541.834780739246, 2543.8092143089248, 2544.860032698201, 2547.5541780188487, 2481.466484705788, 2512.723600403954, 2544.1477105169793, 2545.4018305698146, 2545.8090643201836, 2469.5678256305164, 2485.875706214691, 2507.949841003179, 2523.116909081825, 2533.0, 2343.121329501437, 2433.4646377796857, 2503.7120731115974, 2518.508890766301, 2521.407469244726, 11484.885151148548, 11518.769624607545, 11529.077673786078, 11545.422728863607, 11570.074394048455, 6375.968120159369, 6610.4050635443045, 6624.205095388536, 6634.237062737799, 6643.900341494881, 3936.8425262989444, 4213.726107802983, 4240.406343111967, 4252.468441444819, 4331.415258940035, 1017.8167929772638, 1226.558438961982, 1247.6756043428684, 1273.2360583649736, 1277.3294020639146, 13886.166829990132, 15972.600684982852, 15972.840271597368, 15980.680386392207, 16132.516024519166, 5300.761465734051, 5389.514943655012, 5405.5135037846785, 5551.2228288039705, 5570.41510641385, 11046.944765276123, 11069.612563560398, 11075.0, 11087.667369978913, 11091.944540277247, 5260.473952604709, 6273.874522509615, 6359.904601430982, 6364.363563643598, 6366.140571022853, 5849.64902105875, 5989.131575921554, 6012.218411606463, 6110.633361998294, 6202.914489964238, 4212.831486740496, 4322.935155972663, 4352.869413917588, 4391.143726973273, 4399.450068741407, 1227.8096894981293, 1249.7562975219744, 1270.6315168601002, 1272.8981681465461, 1275.3623188405797, 766.6511737159691, 799.4403917257827, 820.9425340226246, 821.9589020548949, 824.5135370131651, 6086.02623580225, 6089.238845144357, 6243.7814676486105, 6254.3425992112125, 6267.373262673696, 492.31813937696546, 603.8218725475995, 609.969501524922, 618.3229363846581, 618.6071844378762, 3996.9200615988098, 4368.885934086851, 4500.729956202638, 4554.749488778149, 4558.0, 14476.34856431463, 14571.635709107357, 14753.0, 14763.92618036903, 14764.0, 13906.652333691536, 14026.789598156036, 14234.501792437422, 14284.0, 14296.213708246147, 7996.320312773363, 8235.423520353517, 8411.579421028924, 8442.915570844336, 8495.36284778644, 5814.94185058144, 6749.5612785168805, 6978.371946524837, 7123.216446190811, 7143.214246432992, 11802.114841386758, 12908.354582271037, 13047.608571742863, 13114.409851556893, 13278.80081798793, 13039.0, 13211.801822972855, 13225.338733063501, 13335.799963000369, 13668.0, 9151.588178531849, 9177.862332064891, 9212.0, 9248.306377021616, 9417.811643766949, 8694.478331300143, 10550.841737373792, 10584.89415105839, 10589.417582033062, 10618.309809862334, 8833.602487887933, 8918.821623567363, 9023.819523609622, 9183.816323673356, 9267.397619154734, 6778.593284402755, 8746.606402712021, 8798.0, 8862.822743545223, 9054.954725226331, 8095.2714255717265, 8101.149379315087, 8225.300849427866, 8229.876551851607, 8233.876491852507, 6020.066889632114, 6072.848178795565, 6116.602420842631, 6142.0, 6186.319504854552, 6035.1249068885645, 6176.104464852661, 6191.318954915046, 6237.126802247598, 6257.530685198537], "value": 5247.165964590567, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [375.8402678861484, 399.3271337795814, 400.1157442053066, 405.7220803749432, 411.2638377304624, 6054.727537260823, 6090.3605121462215, 6098.298695650002, 6209.9068513972325, 6234.501239900808, 5138.922916156261, 5830.970845145768, 5964.165016897627, 6070.2412198475195, 6087.595399817081, 4130.855420060306, 4324.82700691972, 4480.305552639346, 4485.461744590653, 4502.842400515982, 3687.299413111514, 4373.956260437387, 4464.218761716703, 4476.2390393632995, 4482.394876691654, 3458.4120699481023, 4455.153520831048, 4455.2871540553515, 4457.990856172087, 4479.350494178342, 3628.6915612172734, 4351.260285751446, 4440.866773996785, 4451.688381813275, 4484.8206071757095, 3131.3267647455677, 3182.7931184472936, 3757.9624203757885, 3790.5830358660523, 3807.162424266657, 5614.578906581982, 5946.643201407929, 5954.791582294599, 5980.372060933626, 5996.16053752475, 5227.921581176285, 5305.851130292199, 5359.3032905722, 5365.624406291522, 5385.21914322425, 6589.80230593083, 6595.670216489157, 6595.97762346837, 6598.337922453036, 6624.768133115318, 3632.854685812564, 4274.978625106885, 4354.782260886943, 4357.694961352707, 4376.627986621141, 6693.598384096969, 6871.725130994754, 6892.965535172392, 6928.61892595907, 6937.167539895245, 4332.285172946428, 4407.272799987966, 4412.0955204183065, 4417.403650507141, 4428.623566996841, 4129.256733787887, 4471.821127154943, 4475.6419486441655, 4484.304932735431, 4495.145922274757, 4387.846425375096, 4497.415336006298, 4764.190087685102, 5150.381954165449, 5312.1766126250495, 5729.942700572941, 6077.483413909778, 6628.767993120217, 6636.104125943032, 6649.867002659919, 1501.8948673592963, 1509.8490150984815, 1514.66677330987, 1521.9619509512129, 1522.824875139351, 988.4909271724923, 991.7917237380086, 992.5409647817696, 1005.5073014222978, 1012.2661070723666, 5375.521731523862, 5555.8888822223325, 5741.995150848585, 6117.990531562243, 6172.074188871705, 6541.803745887631, 6793.93206067933, 6820.897686534706, 6845.212800527904, 6870.793876183622, 6066.393360663987, 6099.3900609938655, 6099.5361113333365, 6134.325224225332, 6146.4775494082605, 3518.9794959461983, 3563.376409128392, 3569.375359312162, 3578.1233597768714, 3601.6218297078954, 6818.522703410813, 6874.346937040973, 7011.1586609607175, 7056.153261608537, 7057.4120822815485, 7034.557915627386, 7052.647367631701, 7070.328318809705, 7074.575525468488, 7125.465590080765, 6751.392374686204, 6900.102986611809, 6977.895331569929, 7010.334018268359, 7016.333448322402, 5646.802361917313, 6104.779044191268, 6459.8372292987715, 6810.62541560219, 6840.452763778985, 6563.442107420923, 6634.303398143318, 6653.767118150841, 6686.398224159849, 6841.17905851301, 5367.463253674601, 5638.633488823213, 5729.541636668973, 6675.7997260082275, 6924.307569243036, 3930.918997275772, 4324.221640104685, 4390.05613793036, 4403.493598236244, 4419.270820314612, 6704.66476676174, 6855.348741869514, 6901.654917254217, 6914.412274956484, 6945.409640180641, 6594.142761440982, 6648.404382947961, 6683.298253683488, 6696.430803381767, 6706.429953454011, 6439.3238709934785, 6582.670866456754, 6639.7012134455035, 6643.867122657616, 6690.330966903271, 5541.473560011793, 6510.502584405518, 6534.705938232886, 6566.409023187936, 6634.502412318999, 6056.51817654941, 6326.493880489457, 6363.809085727436, 6478.838029049311, 6585.670716464253, 6150.80059388415, 6461.19235095613, 6465.482761378984, 6499.63779881609, 6501.414872661483], "value": 4753.174004099688, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [747.762453140053, 807.9111297757242, 812.4231795425247, 837.7696133563269, 848.7496188624356, 16211.675766484663, 16586.25361858716, 16713.247903844327, 18735.15691793869, 19389.515262118453, 17812.0, 18180.818191818078, 18287.359942402018, 18693.906530467364, 18762.530936726555, 977.3207620703614, 991.0733464210967, 997.561073127825, 1002.2583288366629, 1002.5638847101534, 1009.7980403919246, 1012.5645972231937, 1019.469875664654, 1020.5458570935937, 1024.3956065921104, 893.1738142218422, 1041.6874937518753, 1053.210092430677, 1059.7191744187758, 1061.347271428074, 1024.2164743970868, 1031.381171297223, 1052.4895425718482, 1053.3837704942541, 1053.5575058475458, 751.3125490176482, 769.634423648768, 775.275117764886, 777.786108820079, 778.4006315137312, 16917.238724257437, 17287.74068388975, 17464.214110365065, 17479.737803932952, 17489.825101748946, 16488.587785305343, 16919.91540042304, 17492.0, 17522.0, 17534.29862805486, 14157.362918668685, 14244.287785610679, 14249.430022799075, 14355.928220358936, 14411.855881441155, 3542.982285088584, 3562.928741425157, 3605.4952306677087, 3609.061643972586, 3612.205314830759, 113056.43471782589, 119977.40011299888, 121820.0, 121856.1721574177, 121962.0, 13260.867391325963, 13478.123921945238, 13502.86497135016, 13575.864241357658, 13597.0, 23910.0, 25516.21386502964, 25552.872235638704, 25683.0, 25962.610560841604, 7663.693452261959, 7738.071431428265, 7758.806029849186, 7772.572508512086, 7782.883256751154, 3669.0, 3794.8425730152567, 3877.8836634901, 3886.6113388661474, 3887.183691424776, 3410.079278594815, 3527.7883327000463, 3531.4173161428075, 3539.079839241764, 3540.7875527468436, 911.5260064766285, 944.5938246554048, 945.5083356654501, 947.625687853294, 949.0604301741364, 630.3601844128214, 642.8007317731518, 646.0761111610442, 663.0882536512296, 673.9318180683671, 10182.879883212841, 11042.558297668164, 11623.88376116228, 11818.586349477899, 12048.622993260109, 625.6058683029753, 631.6399652198203, 643.2474005413584, 666.886629273015, 673.7507122364757, 4965.32968049316, 5442.455754424526, 5504.091824848935, 5521.779128834882, 5530.253415788898, 68070.0, 74642.25357746353, 88252.6762098569, 90640.0, 92365.53817231006, 81922.59038704887, 82150.76773848398, 83103.58448207841, 84136.0, 86609.0, 61670.0749488758, 73019.0, 79371.60314198508, 80052.0, 80774.59612701899, 11959.342236176746, 12317.876821232032, 12322.630321090382, 12340.629781106582, 12403.93798031004, 28202.43595128127, 33776.15559611029, 35646.64353356433, 38038.2392352157, 38279.42580861234, 33849.98450046503, 47868.803279918284, 49250.753746232476, 53848.73075634597, 71282.28717712963, 16437.0, 16617.83382166163, 16631.66736665284, 16921.83078169252, 17002.0, 17437.215325310644, 18444.815551844848, 18490.260389584804, 18595.07024648735, 18655.720164197275, 16199.352025918832, 16884.746728799324, 17155.0, 17271.0, 17537.912310438365, 13029.47882084706, 15638.218089095728, 15881.841181588035, 17351.30594776195, 17369.218385172444, 15784.13187274711, 16328.265228064529, 16438.095904725356, 16688.332466701682, 16988.320467181173, 11285.830712539155, 11648.534058637559, 11897.940510297392, 12154.392280385768, 12234.938825305817, 12233.204841685261, 13083.41124649412, 13120.0, 13213.207207567577, 13287.800682989957], "value": 8207.201517108406, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1554.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 114.0, 115.0, 114.0, 120.0, 116.0, 113.0, 115.0, 117.0, 114.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 108.0, 127.0, 184.0, 92.0, 133.0, 96.0, 144.0, 164.0, 152.0], "value": 133.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [204.0, 200.0, 199.0, 201.0, 213.0, 270.0, 257.0, 281.0, 198.0, 198.0], "value": 222.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 63.0, 55.0, 92.0, 53.0, 61.0, 69.0, 86.0, 88.0, 87.0], "value": 73.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 104.0, 119.0, 103.0, 70.0, 69.0, 86.0, 71.0, 70.0, 89.0], "value": 86.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 90.0, 104.0, 102.0, 89.0, 90.0, 106.0, 135.0, 90.0], "value": 98.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 134.0, 152.0, 98.0, 99.0, 108.0, 154.0, 114.0, 99.0, 150.0], "value": 123.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 90.0, 103.0, 93.0, 90.0, 90.0, 90.0, 93.0, 90.0], "value": 92.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 49.0, 50.0, 51.0, 52.0, 51.0, 53.0, 53.0, 56.0], "value": 51.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 59.0, 59.0, 58.0, 60.0, 58.0, 56.0, 60.0, 59.0, 58.0], "value": 58.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 86.0, 83.0, 80.0, 86.0, 85.0, 83.0, 85.0, 84.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 142.0, 149.0, 139.0, 120.0, 149.0, 162.0, 162.0, 184.0, 138.0], "value": 148.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 221.0, 207.0, 181.0, 184.0, 184.0, 185.0, 182.0, 184.0, 205.0], "value": 192.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 75.0, 96.0, 100.0, 69.0, 71.0, 68.0, 70.0, 72.0], "value": 76.1, "unit": "score"}]}]} 16:44:27 INFO - Return code: 0 16:44:27 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:44:27 INFO - # TBPL SUCCESS # 16:44:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:44:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:44:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:44:27 INFO - 2017-05-19 16:44:27.953 screenresolution[1656:11076] starting screenresolution argv=screenresolution get 16:44:27 INFO - 2017-05-19 16:44:27.956 screenresolution[1656:11076] Display 0: 1600x1200x32@60 16:44:27 INFO - 2017-05-19 16:44:27.962 screenresolution[1657:11079] starting screenresolution argv=screenresolution list 16:44:27 INFO - Available Modes on Display 0 16:44:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:44:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:44:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:44:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:44:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:44:28 INFO - Intel Iris: 16:44:28 INFO - Chipset Model: Intel Iris 16:44:28 INFO - Type: GPU 16:44:28 INFO - Bus: Built-In 16:44:28 INFO - VRAM (Dynamic, Max): 1536 MB 16:44:28 INFO - Vendor: Intel (0x8086) 16:44:28 INFO - Device ID: 0x0a2e 16:44:28 INFO - Revision ID: 0x0009 16:44:28 INFO - Displays: 16:44:28 INFO - Display: 16:44:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:44:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:44:28 INFO - Display Serial Number: 1600x1200 60 16:44:28 INFO - Main Display: Yes 16:44:28 INFO - Mirror: Off 16:44:28 INFO - Online: Yes 16:44:28 INFO - Rotation: Supported 16:44:28 INFO - Return code: 0 16:44:28 INFO - Running post-action listener: _resource_record_post_action 16:44:28 INFO - [mozharness: 2017-05-19 23:44:28.196562Z] Finished run-tests step (success) 16:44:28 INFO - Running post-run listener: _resource_record_post_run 16:44:28 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 40336384; Write bytes: 567665664; Read time: 398; Write time: 1738 16:44:28 INFO - TinderboxPrint: CPU usage
54.2% 16:44:28 INFO - TinderboxPrint: I/O read bytes / time
40,336,384 / 398 16:44:28 INFO - TinderboxPrint: I/O write bytes / time
567,665,664 / 1,738 16:44:28 INFO - TinderboxPrint: CPU idle
2,194.6 (45.8%) 16:44:28 INFO - TinderboxPrint: CPU system
964.0 (20.1%) 16:44:28 INFO - TinderboxPrint: CPU user
1,631.0 (34.1%) 16:44:28 INFO - TinderboxPrint: Swap in / out
285,151,232 / 0 16:44:28 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 232102400; Write bytes: 232216576; Read time: 16761; Write time: 490 16:44:28 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 39357440; Write bytes: 317017088; Read time: 378; Write time: 1207 16:44:28 INFO - Running post-run listener: _upload_blobber_files 16:44:28 INFO - Blob upload gear active. 16:44:28 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:44:28 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 16:44:28 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'] 16:44:28 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 16:44:29 INFO - (blobuploader) - INFO - Open directory for files ... 16:44:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:44:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:44:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:44:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:44:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:44:30 INFO - (blobuploader) - INFO - Done attempting. 16:44:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:44:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:44:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:44:30 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:44:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:44:30 INFO - (blobuploader) - INFO - Done attempting. 16:44:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:44:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:44:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:44:31 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:44:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:44:31 INFO - (blobuploader) - INFO - Done attempting. 16:44:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:44:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:44:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:44:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:44:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:44:34 INFO - (blobuploader) - INFO - Done attempting. 16:44:34 INFO - (blobuploader) - INFO - Iteration through files over. 16:44:34 INFO - Return code: 0 16:44:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:44:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:44:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aad9ff76859045052e0fd167a94ec9512d01e9243a8bd414876df2a815375889a363adc0a54101a6321cf66c6ee5495e6220dcf9b6a5274e94549a656125df53", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90e4393d04c6e0127ca8771dcd2fc08a15be2a028267e65c46dfeff3a2fdd1151fd6b22a056a7fa3b96737cb13b978a9c1b4307f96f34a9946ab100fc12a4af8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60a5684ac421cef860e9c8b418f819415510627c485be3ecb9f7094bd7d7208b254fdf8666d998127a49b8ebbeb2af4541592b20aba4292bef71e4e77ed960b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f38b679a2ac733cbd572b9274a22264af3bd9d531f5a49c55ea55cd8f9aa838bc602aad2f330656f36085296def834486c6298366bc72fcfe587da180168305a"} 16:44:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:44:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:44:34 INFO - Contents: 16:44:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/aad9ff76859045052e0fd167a94ec9512d01e9243a8bd414876df2a815375889a363adc0a54101a6321cf66c6ee5495e6220dcf9b6a5274e94549a656125df53", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90e4393d04c6e0127ca8771dcd2fc08a15be2a028267e65c46dfeff3a2fdd1151fd6b22a056a7fa3b96737cb13b978a9c1b4307f96f34a9946ab100fc12a4af8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60a5684ac421cef860e9c8b418f819415510627c485be3ecb9f7094bd7d7208b254fdf8666d998127a49b8ebbeb2af4541592b20aba4292bef71e4e77ed960b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f38b679a2ac733cbd572b9274a22264af3bd9d531f5a49c55ea55cd8f9aa838bc602aad2f330656f36085296def834486c6298366bc72fcfe587da180168305a"} 16:44:34 INFO - Running post-run listener: copy_logs_to_upload_dir 16:44:34 INFO - Copying logs to upload dir... 16:44:34 INFO - mkdir: /builds/slave/test/build/upload/logs 16:44:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.983425 ========= master_lag: 1.98 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2017-05-19 16:44:36.049270) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-19 16:44:36.053172) ========= 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.hDpuVgO1WE/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.4IWJ1LFsaJ/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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/aad9ff76859045052e0fd167a94ec9512d01e9243a8bd414876df2a815375889a363adc0a54101a6321cf66c6ee5495e6220dcf9b6a5274e94549a656125df53", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90e4393d04c6e0127ca8771dcd2fc08a15be2a028267e65c46dfeff3a2fdd1151fd6b22a056a7fa3b96737cb13b978a9c1b4307f96f34a9946ab100fc12a4af8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60a5684ac421cef860e9c8b418f819415510627c485be3ecb9f7094bd7d7208b254fdf8666d998127a49b8ebbeb2af4541592b20aba4292bef71e4e77ed960b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f38b679a2ac733cbd572b9274a22264af3bd9d531f5a49c55ea55cd8f9aa838bc602aad2f330656f36085296def834486c6298366bc72fcfe587da180168305a"} build_url:https://queue.taskcluster.net/v1/task/BjUo9mIvSymQSrVJ2Jynrg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011513 build_url: 'https://queue.taskcluster.net/v1/task/BjUo9mIvSymQSrVJ2Jynrg/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/aad9ff76859045052e0fd167a94ec9512d01e9243a8bd414876df2a815375889a363adc0a54101a6321cf66c6ee5495e6220dcf9b6a5274e94549a656125df53", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/90e4393d04c6e0127ca8771dcd2fc08a15be2a028267e65c46dfeff3a2fdd1151fd6b22a056a7fa3b96737cb13b978a9c1b4307f96f34a9946ab100fc12a4af8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/60a5684ac421cef860e9c8b418f819415510627c485be3ecb9f7094bd7d7208b254fdf8666d998127a49b8ebbeb2af4541592b20aba4292bef71e4e77ed960b2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f38b679a2ac733cbd572b9274a22264af3bd9d531f5a49c55ea55cd8f9aa838bc602aad2f330656f36085296def834486c6298366bc72fcfe587da180168305a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-19 16:44:36.086159) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 16:44:36.086491) ========= 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.hDpuVgO1WE/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.4IWJ1LFsaJ/Listeners TMPDIR=/var/folders/wq/k1fxgwns1ls22g4fg828fjf400000w/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.004665 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 16:44:36.134722) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-19 16:44:36.135012) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-19 16:44:36.135335) ========= ========= Total master_lag: 5.87 =========