builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0338 starttime: 1496691501.84 results: success (0) buildid: 20170605113726 builduid: 87b04f5ac17a4a7288aa69a3b1b2ce3d revision: 6f0be3ad8512b63a13cb4ec87ae4efae31411ecf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 12:38:21.837381) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 12:38:21.838137) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 12:38:21.840716) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 12:38:21.977610) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 12:38:21.977976) ========= 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.lDs5ADwmkP/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.f4LgI4DZwi/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-05 12:38:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.3M=0.001s 2017-06-05 12:38:22 (18.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.139932 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 12:38:22.168884) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 12:38:22.169553) ========= 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.lDs5ADwmkP/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.f4LgI4DZwi/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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.026706 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 12:38:22.277170) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-05 12:38:22.277804) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6f0be3ad8512b63a13cb4ec87ae4efae31411ecf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6f0be3ad8512b63a13cb4ec87ae4efae31411ecf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lDs5ADwmkP/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.f4LgI4DZwi/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-05 12:38:22,357 truncating revision to first 12 chars 2017-06-05 12:38:22,357 Setting DEBUG logging. 2017-06-05 12:38:22,357 attempt 1/10 2017-06-05 12:38:22,357 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6f0be3ad8512?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-05 12:38:23,532 unpacking tar archive at: mozilla-beta-6f0be3ad8512/testing/mozharness/ program finished with exit code 0 elapsedTime=1.445756 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-05 12:38:23.763325) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 12:38:23.763716) ========= script_repo_revision: 6f0be3ad8512b63a13cb4ec87ae4efae31411ecf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 12:38:23.764792) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 12:38:23.765187) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 12:38:23.784251) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-06-05 12:38:23.784556) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lDs5ADwmkP/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.f4LgI4DZwi/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 12:38:23 INFO - MultiFileLogger online at 20170605 12:38:23 in /builds/slave/test 12:38:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 12:38:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:38:23 INFO - {'append_to_log': False, 12:38:23 INFO - 'base_work_dir': '/builds/slave/test', 12:38:23 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:38:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:38:23 INFO - 'branch': 'Mozilla-Beta', 12:38:23 INFO - 'buildbot_json_path': 'buildprops.json', 12:38:23 INFO - 'config_files': ('talos/mac_config.py',), 12:38:23 INFO - 'default_actions': ('clobber', 12:38:23 INFO - 'read-buildbot-config', 12:38:23 INFO - 'download-and-extract', 12:38:23 INFO - 'populate-webroot', 12:38:23 INFO - 'create-virtualenv', 12:38:23 INFO - 'install', 12:38:23 INFO - 'run-tests'), 12:38:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:38:23 INFO - 'download_minidump_stackwalk': True, 12:38:23 INFO - 'download_symbols': 'ondemand', 12:38:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:38:23 INFO - 'tooltool.py': '/tools/tooltool.py', 12:38:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:38:23 INFO - '/tools/misc-python/virtualenv.py')}, 12:38:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:38:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:38:23 INFO - 'gecko_profile': False, 12:38:23 INFO - 'gecko_profile_interval': 0, 12:38:23 INFO - 'installer_path': 'installer.exe', 12:38:23 INFO - 'log_level': 'info', 12:38:23 INFO - 'log_name': 'talos', 12:38:23 INFO - 'log_to_console': True, 12:38:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:38:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:38:23 INFO - 'opt_config_files': (), 12:38:23 INFO - 'pip_index': False, 12:38:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:38:23 INFO - 'cmd': ('bash', 12:38:23 INFO - '-c', 12:38:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:38:23 INFO - 'enabled': True, 12:38:23 INFO - 'halt_on_failure': False, 12:38:23 INFO - 'name': 'check_screen_resolution'},), 12:38:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:38:23 INFO - 'cmd': ('bash', 12:38:23 INFO - '-c', 12:38:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:38:23 INFO - 'enabled': True, 12:38:23 INFO - 'halt_on_failure': False, 12:38:23 INFO - 'name': 'check_screen_resolution'},), 12:38:23 INFO - 'run_cmd_checks_enabled': True, 12:38:23 INFO - 'suite': 'dromaeojs-e10s', 12:38:23 INFO - 'system_bits': '32', 12:38:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:38:23 INFO - 'title': 't-yosemite-r7-0338', 12:38:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:38:23 INFO - 'use_talos_json': True, 12:38:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:38:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:38:23 INFO - 'work_dir': 'build'} 12:38:23 INFO - [mozharness: 2017-06-05 19:38:23.955938Z] Running clobber step. 12:38:23 INFO - Running pre-action listener: _resource_record_pre_action 12:38:23 INFO - Running main action method: clobber 12:38:23 INFO - rmtree: /builds/slave/test/build 12:38:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:38:28 INFO - Running post-action listener: _resource_record_post_action 12:38:28 INFO - [mozharness: 2017-06-05 19:38:28.776869Z] Finished clobber step (success) 12:38:28 INFO - [mozharness: 2017-06-05 19:38:28.777125Z] Running read-buildbot-config step. 12:38:28 INFO - Running pre-action listener: _resource_record_pre_action 12:38:28 INFO - Running main action method: read_buildbot_config 12:38:28 INFO - Using buildbot properties: 12:38:28 INFO - { 12:38:28 INFO - "project": "", 12:38:28 INFO - "product": "firefox", 12:38:28 INFO - "script_repo_revision": "production", 12:38:28 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 12:38:28 INFO - "repository": "", 12:38:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 12:38:28 INFO - "buildid": "20170605113726", 12:38:28 INFO - "pgo_build": "False", 12:38:28 INFO - "basedir": "/builds/slave/test", 12:38:28 INFO - "buildnumber": 3, 12:38:28 INFO - "slavename": "t-yosemite-r7-0338", 12:38:28 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 12:38:28 INFO - "platform": "yosemite_r7_devedition", 12:38:28 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 12:38:28 INFO - "branch": "mozilla-beta", 12:38:28 INFO - "repo_path": "releases/mozilla-beta", 12:38:28 INFO - "revision": "6f0be3ad8512b63a13cb4ec87ae4efae31411ecf", 12:38:28 INFO - "stage_platform": "macosx64-devedition", 12:38:28 INFO - "builduid": "87b04f5ac17a4a7288aa69a3b1b2ce3d", 12:38:28 INFO - "slavebuilddir": "test" 12:38:28 INFO - } 12:38:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q9Y9fgvWSD-t9pZ3qL1t3g/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 12:38:28 INFO - Running post-action listener: _resource_record_post_action 12:38:28 INFO - [mozharness: 2017-06-05 19:38:28.782200Z] Finished read-buildbot-config step (success) 12:38:28 INFO - [mozharness: 2017-06-05 19:38:28.782314Z] Running download-and-extract step. 12:38:28 INFO - Running pre-action listener: _resource_record_pre_action 12:38:28 INFO - Running main action method: download_and_extract 12:38:28 INFO - mkdir: /builds/slave/test/build/tests 12:38:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:38:28 INFO - trying https://queue.taskcluster.net/v1/task/Q9Y9fgvWSD-t9pZ3qL1t3g/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 12:38:28 INFO - Downloading https://queue.taskcluster.net/v1/task/Q9Y9fgvWSD-t9pZ3qL1t3g/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 12:38:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q9Y9fgvWSD-t9pZ3qL1t3g/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 12:38:30 INFO - Downloaded 1287 bytes. 12:38:30 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 12:38:30 INFO - Using the following test package requirements: 12:38:30 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:38:30 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 12:38:30 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:38:30 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:38:30 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 12:38:30 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:38:30 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 12:38:30 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 12:38:30 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:38:30 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 12:38:30 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:38:30 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:38:30 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 12:38:30 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:38:30 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 12:38:30 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:38:30 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 12:38:30 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:38:30 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 12:38:30 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 12:38:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q9Y9fgvWSD-t9pZ3qL1t3g/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:38:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q9Y9fgvWSD-t9pZ3qL1t3g/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:38:30 INFO - Fetch https://queue.taskcluster.net/v1/task/Q9Y9fgvWSD-t9pZ3qL1t3g/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:38:31 INFO - Expected file size: 51966687 12:38:31 INFO - Obtained file size: 51966687 12:38:47 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 12:38:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q9Y9fgvWSD-t9pZ3qL1t3g/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:38:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q9Y9fgvWSD-t9pZ3qL1t3g/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:38:47 INFO - Fetch https://queue.taskcluster.net/v1/task/Q9Y9fgvWSD-t9pZ3qL1t3g/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:38:49 INFO - Expected file size: 51966687 12:38:49 INFO - Obtained file size: 51966687 12:39:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q9Y9fgvWSD-t9pZ3qL1t3g/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 12:39:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q9Y9fgvWSD-t9pZ3qL1t3g/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 12:39:04 INFO - Fetch https://queue.taskcluster.net/v1/task/Q9Y9fgvWSD-t9pZ3qL1t3g/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 12:39:05 INFO - Expected file size: 14404461 12:39:05 INFO - Obtained file size: 14404461 12:39:05 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')]} 12:39:05 INFO - trying https://queue.taskcluster.net/v1/task/Q9Y9fgvWSD-t9pZ3qL1t3g/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:39:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Q9Y9fgvWSD-t9pZ3qL1t3g/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 12:39:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q9Y9fgvWSD-t9pZ3qL1t3g/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 12:39:08 INFO - Downloaded 74895586 bytes. 12:39:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q9Y9fgvWSD-t9pZ3qL1t3g/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:39:08 INFO - mkdir: /builds/slave/test/properties 12:39:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:39:08 INFO - Writing to file /builds/slave/test/properties/build_url 12:39:08 INFO - Contents: 12:39:08 INFO - build_url:https://queue.taskcluster.net/v1/task/Q9Y9fgvWSD-t9pZ3qL1t3g/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:39:08 INFO - Running post-action listener: _resource_record_post_action 12:39:08 INFO - Running post-action listener: set_extra_try_arguments 12:39:08 INFO - [mozharness: 2017-06-05 19:39:08.796747Z] Finished download-and-extract step (success) 12:39:08 INFO - [mozharness: 2017-06-05 19:39:08.796920Z] Running populate-webroot step. 12:39:08 INFO - Running pre-action listener: _resource_record_pre_action 12:39:08 INFO - Running main action method: populate_webroot 12:39:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:39:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:39:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:39:08 INFO - '--tppagecycles', 12:39:08 INFO - '7'], 12:39:08 INFO - 'tests': ['tsvgm']}}, 12:39:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:39:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:39:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:39:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:39:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:39:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:39:08 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:39:08 INFO - 'talos_options': ['--disable-e10s'], 12:39:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:39:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:39:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:39:08 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:39:08 INFO - 'talos_options': ['--disable-e10s'], 12:39:08 INFO - 'tests': ['damp', 'tps']}, 12:39:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:39:08 INFO - 'tests': ['damp', 'tps']}, 12:39:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:39:08 INFO - 'tests': ['dromaeo_dom']}, 12:39:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:39:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:39:08 INFO - 'tests': ['basic_compositor_video']}, 12:39:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:39:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:39:08 INFO - 'tests': ['a11yr', 12:39:08 INFO - 'ts_paint', 12:39:08 INFO - 'tpaint', 12:39:08 INFO - 'sessionrestore', 12:39:08 INFO - 'sessionrestore_no_auto_restore', 12:39:08 INFO - 'tabpaint']}, 12:39:08 INFO - 'other-e10s': {'tests': ['a11yr', 12:39:08 INFO - 'ts_paint', 12:39:08 INFO - 'tpaint', 12:39:08 INFO - 'sessionrestore', 12:39:08 INFO - 'sessionrestore_no_auto_restore', 12:39:08 INFO - 'tabpaint']}, 12:39:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:39:08 INFO - 'ts_paint', 12:39:08 INFO - 'tpaint', 12:39:08 INFO - 'sessionrestore', 12:39:08 INFO - 'sessionrestore_no_auto_restore', 12:39:08 INFO - 'tabpaint']}, 12:39:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:39:08 INFO - 'ts_paint', 12:39:08 INFO - 'tpaint', 12:39:08 INFO - 'sessionrestore', 12:39:08 INFO - 'sessionrestore_no_auto_restore', 12:39:08 INFO - 'tabpaint']}, 12:39:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:39:08 INFO - 'tests': ['a11yr', 12:39:08 INFO - 'ts_paint', 12:39:08 INFO - 'tpaint', 12:39:08 INFO - 'sessionrestore', 12:39:08 INFO - 'sessionrestore_no_auto_restore', 12:39:08 INFO - 'tabpaint']}, 12:39:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:39:08 INFO - 'tests': ['a11yr', 12:39:08 INFO - 'ts_paint', 12:39:08 INFO - 'tpaint', 12:39:08 INFO - 'sessionrestore', 12:39:08 INFO - 'sessionrestore_no_auto_restore', 12:39:08 INFO - 'tabpaint']}, 12:39:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:39:08 INFO - 'tests': ['tsvgx', 12:39:08 INFO - 'tsvgr_opacity', 12:39:08 INFO - 'tart', 12:39:08 INFO - 'tscrollx', 12:39:08 INFO - 'cart', 12:39:08 INFO - 'tsvg_static']}, 12:39:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:39:08 INFO - 'tsvgr_opacity', 12:39:08 INFO - 'tart', 12:39:08 INFO - 'tscrollx', 12:39:08 INFO - 'cart', 12:39:08 INFO - 'tsvg_static']}, 12:39:08 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:39:08 INFO - 'talos_options': ['--disable-e10s'], 12:39:08 INFO - 'tests': ['tp5o']}, 12:39:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:39:08 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:39:08 INFO - 'talos_options': ['--disable-e10s', 12:39:08 INFO - '--xperf_path', 12:39:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:39:08 INFO - 'tests': ['tp5n']}, 12:39:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:39:08 INFO - 'talos_options': ['--xperf_path', 12:39:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:39:08 INFO - 'tests': ['tp5n']}}, 12:39:08 INFO - 'talos.zip': {'path': '', 12:39:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:39:08 INFO - Running post-action listener: _resource_record_post_action 12:39:08 INFO - [mozharness: 2017-06-05 19:39:08.815866Z] Finished populate-webroot step (success) 12:39:08 INFO - [mozharness: 2017-06-05 19:39:08.815981Z] Running create-virtualenv step. 12:39:08 INFO - Running pre-action listener: _resource_record_pre_action 12:39:08 INFO - Running main action method: create_virtualenv 12:39:08 INFO - Creating virtualenv /builds/slave/test/build/venv 12:39:08 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 12:39:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:39:08 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:39:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:39:08 INFO - Using real prefix '/tools/python27' 12:39:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:39:09 INFO - Installing distribute.............................................................................................................................................................................................done. 12:39:12 INFO - Installing pip.................done. 12:39:12 INFO - Return code: 0 12:39:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:39:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:39:12 INFO - Reading from file tmpfile_stdout 12:39:12 INFO - Output received: 12:39:12 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) 12:39:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:39:12 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')]} 12:39:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39:12 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')]} 12:39:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:12 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 0x104b71b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b74768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faae8da7a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10494fd30>, '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 0x104876db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104944be8>, '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.lDs5ADwmkP/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.f4LgI4DZwi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 12:39:12 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 12:39:12 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 12:39:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lDs5ADwmkP/Render', 12:39:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:39:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:39:12 INFO - 'HOME': '/Users/cltbld', 12:39:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:39:12 INFO - 'LOGNAME': 'cltbld', 12:39:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:12 INFO - 'MOZ_NO_REMOTE': '1', 12:39:12 INFO - 'NO_EM_RESTART': '1', 12:39:12 INFO - 'PAGER': '/bin/cat', 12:39:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:39:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:12 INFO - 'PWD': '/builds/slave/test', 12:39:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:39:12 INFO - 'SHELL': '/bin/bash', 12:39:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f4LgI4DZwi/Listeners', 12:39:12 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 12:39:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:39:12 INFO - 'USER': 'cltbld', 12:39:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:39:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:39:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:12 INFO - 'XPC_FLAGS': '0x0', 12:39:12 INFO - 'XPC_SERVICE_NAME': '0', 12:39:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:39:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:13 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:39:13 INFO - Cleaning up... 12:39:13 INFO - Return code: 0 12:39:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:39: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')]} 12:39:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39: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')]} 12:39:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104b71b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b74768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faae8da7a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10494fd30>, '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 0x104876db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104944be8>, '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.lDs5ADwmkP/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.f4LgI4DZwi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 12:39:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:39:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:39:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lDs5ADwmkP/Render', 12:39:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:39:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:39:13 INFO - 'HOME': '/Users/cltbld', 12:39:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:39:13 INFO - 'LOGNAME': 'cltbld', 12:39:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:13 INFO - 'MOZ_NO_REMOTE': '1', 12:39:13 INFO - 'NO_EM_RESTART': '1', 12:39:13 INFO - 'PAGER': '/bin/cat', 12:39:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:39:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:13 INFO - 'PWD': '/builds/slave/test', 12:39:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:39:13 INFO - 'SHELL': '/bin/bash', 12:39:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f4LgI4DZwi/Listeners', 12:39:13 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 12:39:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:39:13 INFO - 'USER': 'cltbld', 12:39:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:39:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:39:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:13 INFO - 'XPC_FLAGS': '0x0', 12:39:13 INFO - 'XPC_SERVICE_NAME': '0', 12:39:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:39:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:13 INFO - Downloading/unpacking psutil>=3.1.1 12:39: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 12:39: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 12:39: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 12:39: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 12:39:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:39:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:39:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:39:15 INFO - Installing collected packages: psutil 12:39:15 INFO - Running setup.py install for psutil 12:39:15 INFO - building 'psutil._psutil_osx' extension 12:39:15 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 12:39:15 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 12:39:15 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 12:39:16 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:39:16 INFO - building 'psutil._psutil_posix' extension 12:39:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:39:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:39:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:39:16 INFO - ^ 12:39:16 INFO - 1 warning generated. 12:39:16 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:39:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:39:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:39:16 INFO - Successfully installed psutil 12:39:16 INFO - Cleaning up... 12:39:16 INFO - Return code: 0 12:39:16 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:39:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104b71b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b74768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faae8da7a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10494fd30>, '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 0x104876db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104944be8>, '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.lDs5ADwmkP/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.f4LgI4DZwi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 12:39: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:39: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 mozsystemmonitor==0.3 12:39:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lDs5ADwmkP/Render', 12:39:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:39:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:39:17 INFO - 'HOME': '/Users/cltbld', 12:39:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:39:17 INFO - 'LOGNAME': 'cltbld', 12:39:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:17 INFO - 'MOZ_NO_REMOTE': '1', 12:39:17 INFO - 'NO_EM_RESTART': '1', 12:39:17 INFO - 'PAGER': '/bin/cat', 12:39:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:39:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:17 INFO - 'PWD': '/builds/slave/test', 12:39:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:39:17 INFO - 'SHELL': '/bin/bash', 12:39:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f4LgI4DZwi/Listeners', 12:39:17 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 12:39:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:39:17 INFO - 'USER': 'cltbld', 12:39:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:39:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:39:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:17 INFO - 'XPC_FLAGS': '0x0', 12:39:17 INFO - 'XPC_SERVICE_NAME': '0', 12:39:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:39:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:39: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 12:39: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 12:39: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 12:39: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 12:39:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:39:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:39:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:39:19 INFO - Installing collected packages: mozsystemmonitor 12:39:19 INFO - Running setup.py install for mozsystemmonitor 12:39:19 INFO - Successfully installed mozsystemmonitor 12:39:19 INFO - Cleaning up... 12:39:19 INFO - Return code: 0 12:39:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:39:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:39:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:39:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:19 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 0x104b71b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b74768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faae8da7a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10494fd30>, '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 0x104876db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104944be8>, '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.lDs5ADwmkP/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.f4LgI4DZwi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 12:39:19 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 12:39:19 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 12:39:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lDs5ADwmkP/Render', 12:39:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:39:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:39:19 INFO - 'HOME': '/Users/cltbld', 12:39:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:39:19 INFO - 'LOGNAME': 'cltbld', 12:39:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:19 INFO - 'MOZ_NO_REMOTE': '1', 12:39:19 INFO - 'NO_EM_RESTART': '1', 12:39:19 INFO - 'PAGER': '/bin/cat', 12:39:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:39:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:19 INFO - 'PWD': '/builds/slave/test', 12:39:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:39:19 INFO - 'SHELL': '/bin/bash', 12:39:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f4LgI4DZwi/Listeners', 12:39:19 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 12:39:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:39:19 INFO - 'USER': 'cltbld', 12:39:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:39:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:39:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:19 INFO - 'XPC_FLAGS': '0x0', 12:39:19 INFO - 'XPC_SERVICE_NAME': '0', 12:39:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:39:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:19 INFO - Downloading/unpacking jsonschema==2.5.1 12:39: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 12:39: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 12:39: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 12:39: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 12:39:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:39:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:39:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:39:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:39:21 INFO - Downloading functools32-3.2.3-2.tar.gz 12:39:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:39:21 INFO - warning: no files found matching '*.txt' 12:39:21 INFO - no previously-included directories found matching 'build' 12:39:21 INFO - no previously-included directories found matching 'dist' 12:39:21 INFO - no previously-included directories found matching '.git*' 12:39:21 INFO - Installing collected packages: jsonschema, functools32 12:39:21 INFO - Running setup.py install for functools32 12:39:21 INFO - warning: no files found matching '*.txt' 12:39:21 INFO - no previously-included directories found matching 'build' 12:39:21 INFO - no previously-included directories found matching 'dist' 12:39:21 INFO - no previously-included directories found matching '.git*' 12:39:21 INFO - Successfully installed jsonschema functools32 12:39:21 INFO - Cleaning up... 12:39:21 INFO - Return code: 0 12:39:21 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:39: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')]} 12:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39: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')]} 12:39:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104b71b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b74768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faae8da7a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10494fd30>, '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 0x104876db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104944be8>, '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.lDs5ADwmkP/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.f4LgI4DZwi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 12:39:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:39:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:39:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lDs5ADwmkP/Render', 12:39:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:39:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:39:21 INFO - 'HOME': '/Users/cltbld', 12:39:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:39:21 INFO - 'LOGNAME': 'cltbld', 12:39:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:21 INFO - 'MOZ_NO_REMOTE': '1', 12:39:21 INFO - 'NO_EM_RESTART': '1', 12:39:21 INFO - 'PAGER': '/bin/cat', 12:39:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:39:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:21 INFO - 'PWD': '/builds/slave/test', 12:39:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:39:21 INFO - 'SHELL': '/bin/bash', 12:39:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f4LgI4DZwi/Listeners', 12:39:21 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 12:39:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:39:21 INFO - 'USER': 'cltbld', 12:39:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:39:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:39:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:21 INFO - 'XPC_FLAGS': '0x0', 12:39:21 INFO - 'XPC_SERVICE_NAME': '0', 12:39:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:39:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:39:21 INFO - Cleaning up... 12:39:21 INFO - Return code: 0 12:39:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:39: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')]} 12:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39: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')]} 12:39:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104b71b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b74768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faae8da7a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10494fd30>, '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 0x104876db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104944be8>, '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.lDs5ADwmkP/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.f4LgI4DZwi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 12:39:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:39:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:39:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lDs5ADwmkP/Render', 12:39:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:39:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:39:21 INFO - 'HOME': '/Users/cltbld', 12:39:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:39:21 INFO - 'LOGNAME': 'cltbld', 12:39:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:21 INFO - 'MOZ_NO_REMOTE': '1', 12:39:21 INFO - 'NO_EM_RESTART': '1', 12:39:21 INFO - 'PAGER': '/bin/cat', 12:39:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:39:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:21 INFO - 'PWD': '/builds/slave/test', 12:39:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:39:21 INFO - 'SHELL': '/bin/bash', 12:39:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f4LgI4DZwi/Listeners', 12:39:21 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 12:39:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:39:21 INFO - 'USER': 'cltbld', 12:39:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:39:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:39:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:21 INFO - 'XPC_FLAGS': '0x0', 12:39:21 INFO - 'XPC_SERVICE_NAME': '0', 12:39:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:39:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:22 INFO - Downloading/unpacking blobuploader==1.2.4 12:39:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:39:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:39:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:39:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:39:23 INFO - Downloading blobuploader-1.2.4.tar.gz 12:39:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:39:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:39:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:39:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:39:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:39:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:39:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:39:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:39:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:39:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:39:24 INFO - Downloading docopt-0.6.1.tar.gz 12:39:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:39:24 INFO - Installing collected packages: blobuploader, requests, docopt 12:39:24 INFO - Running setup.py install for blobuploader 12:39:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:39:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:39:24 INFO - Running setup.py install for requests 12:39:25 INFO - Running setup.py install for docopt 12:39:25 INFO - Successfully installed blobuploader requests docopt 12:39:25 INFO - Cleaning up... 12:39:25 INFO - Return code: 0 12:39:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:39: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')]} 12:39:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39: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')]} 12:39:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104b71b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b74768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faae8da7a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10494fd30>, '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 0x104876db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104944be8>, '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.lDs5ADwmkP/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.f4LgI4DZwi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 12:39:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:39:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:39:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lDs5ADwmkP/Render', 12:39:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:39:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:39:25 INFO - 'HOME': '/Users/cltbld', 12:39:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:39:25 INFO - 'LOGNAME': 'cltbld', 12:39:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:25 INFO - 'MOZ_NO_REMOTE': '1', 12:39:25 INFO - 'NO_EM_RESTART': '1', 12:39:25 INFO - 'PAGER': '/bin/cat', 12:39:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:39:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:25 INFO - 'PWD': '/builds/slave/test', 12:39:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:39:25 INFO - 'SHELL': '/bin/bash', 12:39:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f4LgI4DZwi/Listeners', 12:39:25 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 12:39:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:39:25 INFO - 'USER': 'cltbld', 12:39:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:39:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:39:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:25 INFO - 'XPC_FLAGS': '0x0', 12:39:25 INFO - 'XPC_SERVICE_NAME': '0', 12:39:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:39:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:39:25 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-QxK1GT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:39:25 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-tUeSgc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:39:25 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-saP4NY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:39:25 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-7m9y3j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:39:26 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-2QbBH8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:39:26 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-iAfDzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:39:26 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-B3mVwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:39:26 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-go9xnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:39:26 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-JUPKZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:39:26 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-mM2kaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:39:26 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-QrJCC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:39:26 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-afNnH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:39:26 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-6Du4Ex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:39:27 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-5qcsLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:39:27 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-3GpxAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:39:27 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-2oLasN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:39:27 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-z8FRUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:39:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:39:27 INFO - Running setup.py install for manifestparser 12:39:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:39:27 INFO - Running setup.py install for mozcrash 12:39:27 INFO - Running setup.py install for mozdebug 12:39:27 INFO - Running setup.py install for mozdevice 12:39:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:39:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:39:28 INFO - Running setup.py install for mozfile 12:39:28 INFO - Running setup.py install for mozhttpd 12:39:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:39:28 INFO - Running setup.py install for mozinfo 12:39:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:39:28 INFO - Running setup.py install for mozInstall 12:39:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:39:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:39:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:39:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:39:28 INFO - Running setup.py install for mozleak 12:39:28 INFO - Running setup.py install for mozlog 12:39:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:39:28 INFO - Running setup.py install for moznetwork 12:39:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:39:29 INFO - Running setup.py install for mozprocess 12:39:29 INFO - Running setup.py install for mozprofile 12:39:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:39:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:39:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:39:29 INFO - Running setup.py install for mozrunner 12:39:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:39:29 INFO - Running setup.py install for mozscreenshot 12:39:29 INFO - Running setup.py install for moztest 12:39:29 INFO - Running setup.py install for mozversion 12:39:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:39:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:39:30 INFO - Cleaning up... 12:39:30 INFO - Return code: 0 12:39:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:39:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:39:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:39:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104b71b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b74768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faae8da7a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10494fd30>, '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 0x104876db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104944be8>, '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.lDs5ADwmkP/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.f4LgI4DZwi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 12:39:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:39:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:39:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lDs5ADwmkP/Render', 12:39:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:39:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:39:30 INFO - 'HOME': '/Users/cltbld', 12:39:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:39:30 INFO - 'LOGNAME': 'cltbld', 12:39:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:30 INFO - 'MOZ_NO_REMOTE': '1', 12:39:30 INFO - 'NO_EM_RESTART': '1', 12:39:30 INFO - 'PAGER': '/bin/cat', 12:39:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:39:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:30 INFO - 'PWD': '/builds/slave/test', 12:39:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:39:30 INFO - 'SHELL': '/bin/bash', 12:39:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f4LgI4DZwi/Listeners', 12:39:30 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 12:39:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:39:30 INFO - 'USER': 'cltbld', 12:39:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:39:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:39:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:30 INFO - 'XPC_FLAGS': '0x0', 12:39:30 INFO - 'XPC_SERVICE_NAME': '0', 12:39:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:39:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:39:30 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-IKh3fV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:39:30 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)) 12:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:39:30 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-FsPtur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:39:30 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)) 12:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:39:30 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-F6mkBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:39:30 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)) 12:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:39:30 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-v4M24U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:39:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:39:30 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-TDueXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:39:30 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)) 12:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:39:30 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-CZUU1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:39:30 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)) 12:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:39:30 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-e_qFvQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:39:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:39:31 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-XAIuj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:39:31 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)) 12:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:39:31 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-ATJM__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:39:31 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)) 12:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:39:31 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-0gFGE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:39:31 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)) 12:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:39:31 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-bp_HfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:39:31 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)) 12:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:39:31 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-yCAYi1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:39:31 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)) 12:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:39:31 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-vQaBuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:39:31 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)) 12:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:39:31 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-D2HmD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:39:31 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)) 12:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:39:31 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-LGeQuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:39:31 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)) 12:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:39:31 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-R36T2k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:39:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:39:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:39:32 INFO - Running setup.py (path:/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/pip-yGr6BC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:39:32 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)) 12:39:32 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)) 12:39:32 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)) 12:39:32 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)) 12:39:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:39:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:39:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:39:32 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 12:39:32 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 12:39:32 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 12:39:32 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 12:39:33 INFO - Downloading blessings-1.6.tar.gz 12:39:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:39:33 INFO - Installing collected packages: blessings 12:39:33 INFO - Running setup.py install for blessings 12:39:33 INFO - Successfully installed blessings 12:39:33 INFO - Cleaning up... 12:39:33 INFO - Return code: 0 12:39:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:39:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:39:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:39:34 INFO - Reading from file tmpfile_stdout 12:39:34 INFO - Current package versions: 12:39:34 INFO - blessings == 1.6 12:39:34 INFO - blobuploader == 1.2.4 12:39:34 INFO - docopt == 0.6.1 12:39:34 INFO - functools32 == 3.2.3-2 12:39:34 INFO - jsonschema == 2.5.1 12:39:34 INFO - manifestparser == 1.1 12:39:34 INFO - mozInstall == 1.12 12:39:34 INFO - mozcrash == 1.0 12:39:34 INFO - mozdebug == 0.1 12:39:34 INFO - mozdevice == 0.49 12:39:34 INFO - mozfile == 1.2 12:39:34 INFO - mozhttpd == 0.7 12:39:34 INFO - mozinfo == 0.9 12:39:34 INFO - mozleak == 0.1 12:39:34 INFO - mozlog == 3.4 12:39:34 INFO - moznetwork == 0.27 12:39:34 INFO - mozprocess == 0.25 12:39:34 INFO - mozprofile == 0.28 12:39:34 INFO - mozrunner == 6.13 12:39:34 INFO - mozscreenshot == 0.1 12:39:34 INFO - mozsystemmonitor == 0.3 12:39:34 INFO - moztest == 0.8 12:39:34 INFO - mozversion == 1.4 12:39:34 INFO - psutil == 3.1.1 12:39:34 INFO - requests == 1.2.3 12:39:34 INFO - wsgiref == 0.1.2 12:39:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:39:34 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')]} 12:39:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39:34 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')]} 12:39:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:34 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 0x104b71b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b74768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faae8da7a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10494fd30>, '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 0x104876db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104944be8>, '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.lDs5ADwmkP/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.f4LgI4DZwi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 12:39:34 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 12:39:34 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 12:39:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lDs5ADwmkP/Render', 12:39:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:39:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:39:34 INFO - 'HOME': '/Users/cltbld', 12:39:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:39:34 INFO - 'LOGNAME': 'cltbld', 12:39:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:34 INFO - 'MOZ_NO_REMOTE': '1', 12:39:34 INFO - 'NO_EM_RESTART': '1', 12:39:34 INFO - 'PAGER': '/bin/cat', 12:39:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:39:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:34 INFO - 'PWD': '/builds/slave/test', 12:39:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:39:34 INFO - 'SHELL': '/bin/bash', 12:39:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f4LgI4DZwi/Listeners', 12:39:34 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 12:39:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:39:34 INFO - 'USER': 'cltbld', 12:39:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:39:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:39:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:34 INFO - 'XPC_FLAGS': '0x0', 12:39:34 INFO - 'XPC_SERVICE_NAME': '0', 12:39:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:39:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:34 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)) 12:39:34 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)) 12:39:34 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)) 12:39:34 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)) 12:39:34 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)) 12:39:34 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)) 12:39:34 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)) 12:39:34 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)) 12:39:34 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)) 12:39:34 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)) 12:39:34 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)) 12:39:34 INFO - Cleaning up... 12:39:34 INFO - Return code: 0 12:39:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:39:34 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')]} 12:39:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39:34 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')]} 12:39:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:34 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 0x104b71b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104b74768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faae8da7a00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10494fd30>, '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 0x104876db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104944be8>, '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.lDs5ADwmkP/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.f4LgI4DZwi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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 12:39:34 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 12:39:34 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 12:39:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lDs5ADwmkP/Render', 12:39:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:39:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:39:34 INFO - 'HOME': '/Users/cltbld', 12:39:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:39:34 INFO - 'LOGNAME': 'cltbld', 12:39:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:34 INFO - 'MOZ_NO_REMOTE': '1', 12:39:34 INFO - 'NO_EM_RESTART': '1', 12:39:34 INFO - 'PAGER': '/bin/cat', 12:39:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:39:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:34 INFO - 'PWD': '/builds/slave/test', 12:39:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:39:34 INFO - 'SHELL': '/bin/bash', 12:39:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f4LgI4DZwi/Listeners', 12:39:34 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 12:39:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:39:34 INFO - 'USER': 'cltbld', 12:39:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:39:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:39:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:34 INFO - 'XPC_FLAGS': '0x0', 12:39:34 INFO - 'XPC_SERVICE_NAME': '0', 12:39:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:39:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:39:34 INFO - Cleaning up... 12:39:34 INFO - Return code: 0 12:39:34 INFO - Running post-action listener: _resource_record_post_action 12:39:34 INFO - Running post-action listener: _start_resource_monitoring 12:39:34 INFO - Starting resource monitoring. 12:39:34 INFO - [mozharness: 2017-06-05 19:39:34.676456Z] Finished create-virtualenv step (success) 12:39:34 INFO - [mozharness: 2017-06-05 19:39:34.677467Z] Running install step. 12:39:34 INFO - Running pre-action listener: _resource_record_pre_action 12:39:34 INFO - Running main action method: install 12:39:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:39:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:39:34 INFO - Reading from file tmpfile_stdout 12:39:34 INFO - Detecting whether we're running mozinstall >=1.0... 12:39:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:39:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:39:35 INFO - Reading from file tmpfile_stdout 12:39:35 INFO - Output received: 12:39:35 INFO - Usage: mozinstall [options] installer 12:39:35 INFO - Options: 12:39:35 INFO - -h, --help show this help message and exit 12:39:35 INFO - -d DEST, --destination=DEST 12:39:35 INFO - Directory to install application into. [default: 12:39:35 INFO - "/builds/slave/test"] 12:39:35 INFO - --app=APP Application being installed. [default: firefox] 12:39:35 INFO - mkdir: /builds/slave/test/build/application 12:39:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:39:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 12:39:55 INFO - Reading from file tmpfile_stdout 12:39:55 INFO - Output received: 12:39:55 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:39:55 INFO - Running post-action listener: _resource_record_post_action 12:39:55 INFO - [mozharness: 2017-06-05 19:39:55.096837Z] Finished install step (success) 12:39:55 INFO - [mozharness: 2017-06-05 19:39:55.096987Z] Running run-tests step. 12:39:55 INFO - Running pre-action listener: _resource_record_pre_action 12:39:55 INFO - Running main action method: run_tests 12:39:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:39:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:39:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:39:55 INFO - 2017-06-05 12:39:55.111 screenresolution[1624:7400] starting screenresolution argv=screenresolution get 12:39:55 INFO - 2017-06-05 12:39:55.114 screenresolution[1624:7400] Display 0: 1600x1200x32@60 12:39:55 INFO - 2017-06-05 12:39:55.119 screenresolution[1625:7403] starting screenresolution argv=screenresolution list 12:39:55 INFO - Available Modes on Display 0 12:39:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:39:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:39:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:39:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:39:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:39:55 INFO - Intel Iris: 12:39:55 INFO - Chipset Model: Intel Iris 12:39:55 INFO - Type: GPU 12:39:55 INFO - Bus: Built-In 12:39:55 INFO - VRAM (Dynamic, Max): 1536 MB 12:39:55 INFO - Vendor: Intel (0x8086) 12:39:55 INFO - Device ID: 0x0a2e 12:39:55 INFO - Revision ID: 0x0009 12:39:55 INFO - Displays: 12:39:55 INFO - Display: 12:39:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:39:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:39:55 INFO - Display Serial Number: 1600x1200 60 12:39:55 INFO - Main Display: Yes 12:39:55 INFO - Mirror: Off 12:39:55 INFO - Online: Yes 12:39:55 INFO - Rotation: Supported 12:39:55 INFO - Return code: 0 12:39:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:39:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:39:55 INFO - Python 2.7.3 12:39:55 INFO - Return code: 0 12:39:55 INFO - grabbing minidump binary from tooltool 12:39:55 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')]} 12:39:55 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 0x10494fd30>, '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 0x104876db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104944be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:39:55 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 12:39:55 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 12:39:55 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 12:39:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:39:55 INFO - Return code: 0 12:39:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:39:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:39:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:39:55 INFO - ENV: RUST_BACKTRACE is now 1 12:39:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:39:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:39:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q9Y9fgvWSD-t9pZ3qL1t3g/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0338', '--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 12:39:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Q9Y9fgvWSD-t9pZ3qL1t3g/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0338 --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 12:39:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lDs5ADwmkP/Render', 12:39:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:39:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:39:55 INFO - 'HOME': '/Users/cltbld', 12:39:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:39:55 INFO - 'LOGNAME': 'cltbld', 12:39:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:39:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:39:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:55 INFO - 'MOZ_NO_REMOTE': '1', 12:39:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:39:55 INFO - 'NO_EM_RESTART': '1', 12:39:55 INFO - 'PAGER': '/bin/cat', 12:39:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:39:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:55 INFO - 'PWD': '/builds/slave/test', 12:39:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:39:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:39:55 INFO - 'RUST_BACKTRACE': '1', 12:39:55 INFO - 'SHELL': '/bin/bash', 12:39:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f4LgI4DZwi/Listeners', 12:39:55 INFO - 'TMPDIR': '/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/', 12:39:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:39:55 INFO - 'USER': 'cltbld', 12:39:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:39:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:39:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:55 INFO - 'XPC_FLAGS': '0x0', 12:39:55 INFO - 'XPC_SERVICE_NAME': '0', 12:39:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:39:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Q9Y9fgvWSD-t9pZ3qL1t3g/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0338', '--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 12:39:55 INFO - mozversion application_buildid: 20170605113726 12:39:55 INFO - mozversion application_changeset: 6f0be3ad8512b63a13cb4ec87ae4efae31411ecf 12:39:55 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:39:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:39:55 INFO - mozversion application_name: Firefox 12:39:55 INFO - mozversion application_remotingname: firefox-dev 12:39:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:39:55 INFO - mozversion application_vendor: Mozilla 12:39:55 INFO - mozversion application_version: 54.0 12:39:55 INFO - mozversion platform_buildid: 20170605113726 12:39:55 INFO - mozversion platform_changeset: 6f0be3ad8512b63a13cb4ec87ae4efae31411ecf 12:39:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:39:55 INFO - mozversion platform_version: 54.0 12:39:55 INFO - using testdate: 1496691595 12:39:55 INFO - actual date: 1496691595 12:39:55 INFO - starting webserver on 'localhost:49224' 12:39:55 INFO - SUITE-START | Running 2 tests 12:39:55 INFO - TEST-START | dromaeo_css 12:39:55 INFO - operating with platform_type : mac_ 12:39:55 INFO - Initialising browser for dromaeo_css test... 12:39:55 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpuFbedT/profile http://localhost:49224/getInfo.html) 12:39:58 INFO - PROCESS | 1635 | 2017-06-05 12:39:58.560 plugin-container[1639:7598] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 12:39:58 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:39:58 INFO - PROCESS | 1635 | 2017-06-05 12:39:58.561 plugin-container[1639:7598] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 12:39:58 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:39:58 INFO - PROCESS | 1635 | 2017-06-05 12:39:58.561 plugin-container[1639:7598] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:39:58 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 12:39:58 INFO - PROCESS | 1635 | colorDepth:24 12:39:58 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 12:39:58 INFO - PROCESS | 1635 | __metrics 12:39:58 ERROR - PROCESS | 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3381: TypeError: this.tabs is undefined 12:39:59 INFO - TEST-INFO | 1635: exit 0 12:39:59 INFO - Browser initialized. 12:39:59 INFO - Running cycle 1/1 for dromaeo_css test... 12:39:59 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpuFbedT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:40:01 INFO - PROCESS | 1640 | 2017-06-05 12:40:01.753 plugin-container[1641:7735] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 12:40:01 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:40:01 INFO - PROCESS | 1640 | 2017-06-05 12:40:01.754 plugin-container[1641:7735] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 12:40:01 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:40:01 INFO - PROCESS | 1640 | 2017-06-05 12:40:01.754 plugin-container[1641:7735] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:40:11 INFO - PROCESS | 1640 | RSS: Main: 202940416 12:40:11 INFO - PROCESS | 1640 | 12:43:14 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49224/tests/dromaeo/cssquery-ext.html) 12:43:16 INFO - PROCESS | 1640 | RSS: Main: 216182784 12:43:16 INFO - PROCESS | 1640 | 12:46:18 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-ext.html (next: http://localhost:49224/tests/dromaeo/cssquery-jquery.html) 12:46:19 INFO - PROCESS | 1640 | RSS: Main: 230354944 12:46:19 INFO - PROCESS | 1640 | 12:49:21 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49224/tests/dromaeo/cssquery-mootools.html) 12:49:22 INFO - PROCESS | 1640 | RSS: Main: 232730624 12:49:22 INFO - PROCESS | 1640 | 12:52:24 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49224/tests/dromaeo/cssquery-prototype.html) 12:52:24 INFO - PROCESS | 1640 | RSS: Main: 236351488 12:52:24 INFO - PROCESS | 1640 | 12:55:27 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49224/tests/dromaeo/cssquery-yui.html) 12:55:28 INFO - PROCESS | 1640 | RSS: Main: 238366720 12:55:28 INFO - PROCESS | 1640 | 12:58:30 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-yui.html (next: http://localhost:49224/tests/dromaeo/cssquery-dojo.html) 12:58:30 INFO - PROCESS | 1640 | RSS: Main: 236261376 12:58:30 INFO - PROCESS | 1640 | 12:58:30 INFO - PROCESS | 1640 | __start_tp_report 12:58:30 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 12:58:30 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 12:58:30 INFO - PROCESS | 1640 | |i|pagename|runs| 12:58:30 INFO - PROCESS | 1640 | |0;dojo.html;3509.2981403719255;3538.1331573764433;3545.8227088645567;3550.875719349822;3551.129973156577;5935.990881550136;5994.670293133876;5998.340182579913;6014.097885317205;6032.668203248825;5937.554683398741;6002.669853158074;6010.308814486335;6023.728932198051;6032.728527216274;5365.168398898177;5408.729563521828;5415.431379705134;5424.864378390533;5544.0575102232515;5457.2359869618285;5476.288082549283;5491.752871120809;5497.038018346773;5506.504414602685;5273.551748101416;5293.761780719858;5410.648307859996;5449.5640348772;5451.536619387357;4564.885877853047;5397.514223719864;5416.458354164592;5436.83689489314;5447.836564903019;5320.14877619579;5395.838124856261;5433.375161856358;5440.156775699773;5458.754356053987;5709.857253568653;5982.162497250389;6062.363451837582;6090.66501342426;6093.268807743055;6293.30773614902;6306.085617585471;6312.274088479791;6333.841653958688;6334.714937827809;5109.540141387293;6511.706973186218;6516.804495865132;6527.445167160797;6537.673116344164;5029.19532874742;6119.2350956130485;6190.969045154746;6208.441240288395;6218.4714299284615;6364.522660800459;6390.329015453311;6534.205758605763;6549.083128362033;6597.637129957802;5340.492653198017;6323.27282362525;6341.936580634227;6342.682865856688;6366.936330636727;6428.582142160838;6492.577982431127;6506.902396464056;6509.8698026040165;6511;6211.844703882349;6234.2830574484515;6316.273628532777;6418.293987661354;6437.356264373525;6249.0938813871735;6265.906011409833;6320.968395158086;6338.176037115146;6348.301686814447;4849.2726091086615;4895.31465594817;4909.484504127086;4934.629902757308;4967.677100988424;4428.180786554498;4434.246178149722;4439.866803995886;4455.353973673864;4463.843765468193;5850.414958504116;5853.795117170878;5892.558058145571;5983.730732116978;5990.071538911388;6410.102585638015;6424.453921416732;6464.935350646528;6473.093766872642;6489.870202595922;5729.742161602655;5930.169776231331;5953.226080609493;5967.6419414835245;5973.133895585116;5442.265294185314;5477.28795256622;5552.056150454432;5559.8888022239325;5562.221289019541;6619.172603424572;6628.801135965832;6632.635205063768;6633.071370008106;6653.939187973587;6396.456301214449;6424.710888009958;6450.580712253689;6472.708728107153;6473.126127972663;6256.843578910381;6405;6449.4840412765925;6497.220333560003;6629.237637671729;6316.526260530387;6380.330065343072;6385.8722825544155;6409.2949775525585;6410.455111315404;6405.487560995016;6422.807315780534;6446.355364463516;6451.3226111257645;6455.160829092188;6147.924113280247;6206.317305096345;6209.068639704081;6239.78160764371;6315.55791094619;6214.036824292061;6222.066689996536;6277.843053923506;6285.402886725937;6289.874202515833;5678.574106942078;5925.844460330367;6182.103594978613;6240.188775459162;6244.2819075806865;5074.340335756328;5791.899539087728;6108.372425856702;6137.652792202512;6233.3107488741925;6224.941759900918;6262.029385445263;6272.121902933684;6278.215223097113;6415.262244841903 12:58:30 INFO - PROCESS | 1640 | |1;ext.html;766.7814672818248;815.0341844913776;843.118940706961;966.8356379415494;979.2753362511744;38856.805715971415;39779.60220397795;41652.58347416532;41699;41785.37321940169;40220.597794022055;40322.395164072565;40403.59596404035;40478;40591.39112913309;8046.879296810538;8189.22202390772;10807.621733239299;11119.888801111967;11306.660800176009;10979.231453798237;10988;11087.445627718622;11096;11100.167487438475;11081.113510919147;11115.555377784878;11126.443677816118;11151.442427878614;11155.330680159217;9491.762705932339;10842.891571084267;11095.667129986114;11107.944460277648;11112.277596972483;10755.97818207277;10778.353298802096;10781;10847.67456976292;10855.294405863593;35112;35220.82389588061;35421.291574168376;35512;35567.28865422703;3832.1952389997855;3862.8841134766003;3898.5516665583536;3911.9217615647813;3959.3467077932105;25348.493030139292;25518.74481255201;25666.87166564174;27155.864220678966;27307.08850380474;3083.6762139975203;3152.889648862302;3163.6994485523837;3176.6664500227375;3285.8357082146035;333215.3339233312;363872;367673;367821;368519;45232.3215151773;45382;45655.543444565796;45663.63009109732;45830.770846145555;44250;46392.536074639494;46442.838929026366;46463.535364646596;46607.53392466032;13734.65663358424;13743.518976835763;13863.306834658226;13900.791488127687;13913;12162.148649594621;12187.695307617381;12188;12210.75578488443;12212.57255996036;11789.410529473493;11846.703832404086;11994.340311282964;11997.160198786001;12062.819057714141;5831.825045248649;5900.1149827526215;5909.881802364014;5943.613665111754;5951.256092988377;847.7626264645909;886.4016788667624;897.8967418746928;899.321012635456;922.806210695748;27609.585856212172;27651.86174069117;27751.224817649283;28006.85996570045;28039.719602804118;976.6142373762324;989.9901000990042;995.9502024898726;996.7109538233831;1013.1793247469574;13961.232132232824;14092.154470731584;14257.928710356382;14336.713265734626;14360.497382591559;452733;454986;457458;458026;458308;56105;56160.719196403756;56319.71840140773;56655;56722.43277567254;1452.244832686997;1456.410153887677;1494.3126161965617;1494.7085318362815;1499.2503748125937;25711;27429.45141097127;27444.17667469979;27478.0382686605;27498.312542186606;41758.791206043774;43076.56923430726;47140;47236;47715.522844771105;471712;474907;475372;475385.6230718824;475467;29553;30348.089557313317;30403.391932161674;30606.08181754551;31283.687163128077;31292.687073129888;33532.32935341328;39718.00704982283;39894.60105399025;40786.79606601948;24001.559906405673;27931.022414215404;28742.281442964093;29007.129786106452;29849.612031037897;26743;27100.1869943902;28673.856630716713;28685.139445816658;29603.259918502208;32933.34133317368;33139;33440.832795835864;33732.66267337295;34108.488372673935;24092.759072409754;26230;27047.594286086118;29191.270218244714;30303;22165.55668886581;22747.431314217276;23789.286321410385;24060.639090413308;24158.39604009914 12:58:30 INFO - PROCESS | 1640 | |2;jquery.html;408.53427093113874;408.54243247562715;419.205605377809;419.6789456066109;451.690591944518;19929.60140797185;21129.15483380665;22478;22546.21088261911;22601.321960341196;18071.27714891403;21288.893555532202;22133.004014819333;22237.666435003488;22655;6800.319968003209;7061.576305421666;7065.964670176668;7068.505204635678;7108.075950126502;5991.5206783457215;6601.14185155929;6871.03805467235;6871.415929645986;6950.652467376611;6711;6916.965415172917;6934.375906168444;6936.410405115571;6953;6855.245922948472;6880.8967865482055;6913.10129683143;6919.481038922051;6925.826854328657;6487.740490380379;6733.629650369227;6741.696623651947;6788.59268443895;6799.694013769392;16993.745093823603;25738.74261257382;26141.607875881877;26457.73542264572;26821.73178268212;2977.3747513022076;2996.1460983619563;3020.622422197225;3067.4478593853096;3076.292452735861;15623.843761562353;16183.271752771036;16251.431199907947;16398.508044758677;16505.257263423176;2083.2187929526426;2134.466383404149;2138.005827290302;2142.8500004999655;2148.904058929941;129994;134853;162779.1861040687;177847;178391;21025.369238922856;22322.88838555797;22360.44098897508;22522.66216006761;22522.986465609087;33127;33239.67041318369;33250.667493324756;33315.66684333126;33471.497927531105;8384.706535271358;10516.1061309788;10707.304025238334;10756.56973721058;10930.56277748897;8818.735437936872;8918.687845925491;8949.149830766068;9031.232345250728;9095.953965293944;8792.692255771017;9844.310898237054;9938.850917236246;9959.850602240973;9984.80030399388;3917.980410097988;4181.452862440856;4187.413762073312;4210.221109094828;4219.683523735732;1043.9164866810636;1080.2276372393776;1099.9010089092021;1106.3417266726285;1148.4544841200363;18788.060596970096;21481.78518214829;21823.345299641038;21863.562728745335;24460.87769561141;943.4763706143019;966.6809952715587;1015.8577799108131;1091.1270983213396;1147.5524545427122;9209.263258939269;10793.29843560166;10922.017018468376;11433.656990290305;11436.970672639502;711253;773712;783086.695518632;792188;796436;130948;159278;162370.99999999764;163797;166048;1215.1554669504785;1265.8291130697237;1306.0531114941782;1309.8755618216255;1319.6568892088126;19763.20947162155;20173.6973945388;20383.388498345073;20581.176752930314;20628;62133.689331553054;68783.65608171927;68860.6556967212;69418.30581694118;69938.65030674814;94051;94988.52505737427;96347.55478667686;96833.0316696824;97232.02767972229;7657.961710191414;8862.468251904906;9989.850152247867;10017.69946901594;11598.47806848677;13278.668033299246;13327.66680832987;13414.664633384244;13692.794608081085;13788.379522921292;11402.828957565478;11558.768824623628;11593.014593759624;11788.29270243788;11833.940830295793;9221.400608960395;10703.571857125939;10774.622888198875;11042.668719938094;11064.17018723615;11572;11709.590164344207;11784.823227651763;11887.108466864847;12039.337836419081;10177;10913.399763012792;11639.59261425846;11716.355600442057;11754.764904701688;9618;11238.719032024264;11385.715357116138;11453.59912403062;11509.96410323075 12:58:30 INFO - PROCESS | 1640 | |3;mootools.html;1481.8295895971962;1555.1446704312625;1613.6046668566196;1617.2318148879292;1625.6098536351271;10788.352698838075;11120;11445.542178312879;11447.484863181156;11469.853014698527;11103.05624021957;11249.100071994242;11301.660950171508;11314.603988860392;11315.717107072307;2438.8414753041084;2461.1509029384833;2473.5423946569854;2478.8016958643357;2482.689663792026;2389.581823180945;2406.795422389099;2416.0818888822223;2436.4030812450974;2444.535538247736;2366.526694661066;2366.715994080704;2382.2257766226044;2466.371075375777;2470.4194514289215;2297.517521320525;2310.330004298751;2409.072507084776;2421.903123875043;2423.51529694061;2154.0952799824113;2157.8057974782187;2160.4274867160216;2181.214762685432;2181.9345419637434;10941;11219.102471802236;11226.101911847032;11235.382053987027;11237.494312755945;5900.734466948998;6084.361142080063;6085.208922840046;6092.238470191226;6099.45104940553;3596.7841929484316;3659.6157403472785;3708.517892673935;3716.888493345177;3732.1602639405914;1201.255221762504;1205.3129716061849;1215.2769102384068;1259.615817175761;1261.6215135459327;13040.347982600832;13626.863731362659;13663.863361366359;15488.845111548966;15661.530154095395;4733.337332773414;4776.498467660915;4846.248831431134;4877.853664390074;4917.188663870421;11651;11791.233569818107;11795.05639548834;11846.052315814713;11980;5028.698278103326;5938.732757025944;5948.762049518057;5956.642601443928;5966.433188847052;4364.869053928387;5051.6716413433005;5314.7608357624;5339.839804805862;5361.195820626937;3917.314469967744;3943.7830919299713;3958.129211573449;4003.4795476587856;4020.537638171589;1047.9371237725761;1060.925735198544;1070.5664205996584;1084.8806631270554;1086.5436516663021;966.3477152921749;991.1624677147798;1002.1081237698454;1003.2275148135894;1025.2720568396521;6648.4681225501845;6768.357006084414;6770.119884415093;6832.624205668637;6875.209350924608;875.8817559629496;1002.6390499420203;1023.2632504596827;1026.9691909242736;1029.281100562063;3740.4576336431064;3767.5102236709054;4344.761038142932;4348.760818155032;4350.630196433275;13356.599302020955;13926.651833704238;13934.930325348309;13963.720725585432;13989.580312590639;12828.551000715117;13122.34388280582;13699.835513981225;13917.791233131511;13928;10133.493325333851;10265.332753371156;10377;10407.115395191493;10410.479476026168;6144.969275153596;6736.090627765189;6824.863502730017;6871.347222013901;7049.471289653194;11883.227590206609;12061.75876482483;12102.818457723317;12150.635480935585;12217.022638188746;11996.460159293027;12039.09706771978;12050.09624278165;12207.816882746944;12257.938710306391;9267.258619310574;9305.205387172053;9435.962044175272;9483.810323793623;9498.00270971538;10290.588376464857;10336.638217662347;10383.273170878269;10393.688189354332;10414.739631509274;8719.12808719123;8933.597988090421;8934;8955.373123881265;8958.328125390492;8734.432261902955;8740.694075707319;8760;8844.159804818531;8892.59983300766;8018.759437216893;8173.67305307781;8179.263866252066;8185.345172386075;8193.46742461738;5929.584929054925;5977.491913187254;6008.699565021644;6108.114323423168;6208.068789681584;6041.456268935817;6117.204763380732;6143.631382117087;6244.781432649836;6245.219347581552 12:58:30 INFO - PROCESS | 1640 | |4;prototype.html;366.9980952001038;377.2285675793003;381.30031392394955;391.6572998626202;395.6933376633109;4789.832355867546;5152;5227.817026404076;5256.214206470567;5310.522053015228;4412.690482138396;4580.679352445331;5052.949470505294;5123.591911801447;5160.200168973815;3566.928661426776;3681.8711345102924;3983.275637279153;4074.7555146691143;4084.65280451162;3723.013401448605;4039.97980010101;4056.797160141996;4075.510938687347;4078.877633670995;3870.8838734838;4023.255697695921;4065.1869626074904;4065.4918135233097;4091.672666186698;3584.8773910003442;3780.7731536107644;3963;3983.701222408331;4076.612721791425;3532.44572388151;3550.5029295898594;3566.144125409909;3573.3032058748554;3583.079789971191;4227.879611902848;4289.549597292302;4748.6438517111355;4968.01279488204;4976.87557811054;4015.927815221358;4041.4544036554985;4064.918701625951;4336.436263285784;4610.746408947506;4471.8658440246845;5271.947280527223;5307.575393968474;5594.2447769551;5600.351708082323;3783.867564635252;4017.337139372;4037.858674946392;4054.979725101385;4055.574164712692;5317.46825317469;5450.5283573435345;5644.915326270109;5882.970585147047;5957.255343082114;4994.875128121826;5031.77357018935;5035.486142673766;5132;5163.554204822655;5102.316235642229;5257.526822585985;5278.736063196825;5283.199904050859;5283.392409872914;5258.790478190038;5289.523942845162;5327.920081198785;5423.640716288427;5474.644148130438;5719.482518251246;5730.484256416942;5750.699720111988;5764.884702305931;5799.82600521985;1554.875609951201;1786.6873297172942;1800.4958611588777;1811.2573844723863;1821.954451138732;1203.7351782607811;1336.953206637763;1355.437493440231;1418.0569921002625;1480.726065677853;4438.1359828871955;4476.597106260452;4745.003549254695;5066.670666406671;5085.660596125749;5559.304412154251;5875.265591801025;5878.882422351529;5917.6745279010065;5926.259217597801;4493.59557639814;4557.171975540496;4729.4797572267025;4754.857354279377;4860.8298709545;2597.545429549821;2707.713835927958;2937.030779842647;3026.32039218236;3073.8329477718157;5363.222332761728;5767.423257674199;5968.46283834457;6008.669523176267;6061.363556826558;5367.919481207785;5503.981763373788;5577.498025177753;5657.066584013592;5996.550258730613;5113.463086375951;5560.9712203241725;5691.499727667538;6031.517478601701;6106.328303886569;4800.543948324904;5067.974660126823;5877.441643043904;5920.644761314335;5978.940210597838;4538.636909047203;4947.554720075211;5394.406615272395;5555.166724991283;5919.348871624204;5667.688277144796;5915.977135547869;5921.105246321136;6160.936086411102;6173;4207.664746065962;4672.719636821802;4712.410948631421;4735.360726302043;4760.642951778561;5346.652467589594;5550.335838797319;5697.943020569741;5822.70886455684;5824.679642619697;5357.330333708286;5698.743556539883;5709.914351284651;5742.692980264801;5754.76272601376;5183.611229157904;5221.37343518765;5630.930123276565;5709.003697411746;5794.942050579441;5229.424763276113;5285.524302812765;5425.914817036687;5462.984706240831;5533.723313834373;5328.4671532846405;5559.694216818114;5694.800681976111;5724.914126288026;5739.655620662774;4862.854114376574;4997.650164488451;5394.514493695587;5555.69436185469;5557.583181261502 12:58:30 INFO - PROCESS | 1640 | |5;yui.html;733.915599706034;741.3068780690052;770.279788397848;783.1659282863753;873.9038705742367;15639.139847308408;16264.512064638051;16864.831351686495;17048.659026819456;17153.656926861455;15190.772138417906;15338.156401397919;15610.84389156108;15661.530154095366;15768.369265229405;973.5618971462835;992.7270000749779;996.7308826616809;997.4862945582994;998.3061772068415;834.7787836223406;986.8865080515725;1012.9442880641561;1017;1019.9745006374826;1038.8389799581078;1053.3627155570884;1055.4617145255936;1057.4871187474107;1057.7778666480049;1036.8237399642042;1051.05405135378;1051.7055224537103;1053.1416895230363;1053.2574534952917;785.1363500149847;787.2835719495289;788.0503992688796;789.2462698123278;789.3487872505207;14702.926485367612;16491;16820.66358672832;16897.66204675912;17995.640087198313;14266.429342826275;14273.64315892111;14316.355763990645;14353.282335883165;14480.275986200753;11612.593559225472;11779.88220117805;11851.762964740743;11856.940715296454;12424.937875310656;3757.1734218471893;3768.8680896168776;3793.6585707286476;3813.504244448204;3927.725059245854;118506.40746796297;124639;127585;128650;128982.35508822397;10824.512896919658;10927.508262128224;11135.554577816796;11153.609623663133;11550.94224528872;23009.964551595218;23197.652035219486;23775.286741397787;23819.404514887265;23920.760792391855;7159.534630249017;7815.57014364204;8051.396145289127;8133.186681331819;8228.876566851503;4408.294672852392;4415.116976604692;4488.079943611552;4513.1650644630845;4513.368128462018;3436.7766095204233;3554.306910152495;3637.727170462173;3663.432168013962;3672.6510981456718;627.9968600157061;639.9712012959428;642.2132887213127;649;654.7511945460694;682.3619915379137;699.3251512290664;707.8301207710216;717.9856402871914;736.2527035059419;8575.099614540384;9763.365381250196;10388.688339349832;10585.682429527127;10659.84010239847;577.826652004397;591.6775357430125;592.7806711516766;606.2361424604941;713.3971793834202;4474.373587697725;5020.673656212334;5038.823641172541;5090.796368145307;5125.692458452505;59265.407345926855;93239.53380233055;93878.53060734653;99140.00859991452;100411.49382759264;54500.90998180014;70893.64553177201;80912.19087809164;86612;89478;75073;77011.45977080427;77450.6127469359;78028;80497.59751201207;10738.892611074101;10893.891061089287;11227.943860280646;11294.830577541508;11388.487518061542;47166.52833471759;48352.274715878586;48896;49259.507404926924;49751.75124124356;61131.77736445335;62041.68979155075;64620;65200.67399662971;65668.74650028082;12243.653198148375;12813.487460501476;12883.48466061347;13116.08187426871;13174.011949103664;13208.405621746851;14616;14894.329755161261;14939.402423902922;15339.846601534287;13316.933415332862;13589.32053397346;13895.374708137955;13943.721125577635;13951;13310.600681979557;13591;13753.106048106842;13795.724085518434;13811.930940345233;11712.70718232051;12822.358882056047;13135.080544361803;13418.993575481683;13728.382222800197;9446.247605435105;9499.477528735986;9685.854712179464;9758.556831738544;9833;9538.807649043869;9900.356476828983;11181.496832642388;11183.944080279547;11203.71990700239 12:58:30 INFO - PROCESS | 1640 | __end_tp_report 12:58:30 INFO - PROCESS | 1640 | __start_cc_report 12:58:30 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,379 12:58:30 INFO - PROCESS | 1640 | __end_cc_report 12:58:30 INFO - PROCESS | 1640 | __startTimestamp1496692710766__endTimestamp 12:58:30 INFO - PROCESS | 1640 | 12:58:30 INFO - PROCESS | 1640 | ------- Summary: start ------- 12:58:30 INFO - PROCESS | 1640 | Number of tests: 6 12:58:30 INFO - PROCESS | 1640 | 12:58:30 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:5906.83 Median:6190.97 stddev:677.65 (10.9%) stddev-sans-first:653.11 12:58:30 INFO - PROCESS | 1640 | Values: 3509.3 3538.1 3545.8 3550.9 3551.1 5936.0 5994.7 5998.3 6014.1 6032.7 5937.6 6002.7 6010.3 6023.7 6032.7 5365.2 5408.7 5415.4 5424.9 5544.1 5457.2 5476.3 5491.8 5497.0 5506.5 5273.6 5293.8 5410.6 5449.6 5451.5 4564.9 5397.5 5416.5 5436.8 5447.8 5320.1 5395.8 5433.4 5440.2 5458.8 5709.9 5982.2 6062.4 6090.7 6093.3 6293.3 6306.1 6312.3 6333.8 6334.7 5109.5 6511.7 6516.8 6527.4 6537.7 5029.2 6119.2 6191.0 6208.4 6218.5 6364.5 6390.3 6534.2 6549.1 6597.6 5340.5 6323.3 6341.9 6342.7 6366.9 6428.6 6492.6 6506.9 6509.9 6511.0 6211.8 6234.3 6316.3 6418.3 6437.4 6249.1 6265.9 6321.0 6338.2 6348.3 4849.3 4895.3 4909.5 4934.6 4967.7 4428.2 4434.2 4439.9 4455.4 4463.8 5850.4 5853.8 5892.6 5983.7 5990.1 6410.1 6424.5 6464.9 6473.1 6489.9 5729.7 5930.2 5953.2 5967.6 5973.1 5442.3 5477.3 5552.1 5559.9 5562.2 6619.2 6628.8 6632.6 6633.1 6653.9 6396.5 6424.7 6450.6 6472.7 6473.1 6256.8 6405.0 6449.5 6497.2 6629.2 6316.5 6380.3 6385.9 6409.3 6410.5 6405.5 6422.8 6446.4 6451.3 6455.2 6147.9 6206.3 6209.1 6239.8 6315.6 6214.0 6222.1 6277.8 6285.4 6289.9 5678.6 5925.8 6182.1 6240.2 6244.3 5074.3 5791.9 6108.4 6137.7 6233.3 6224.9 6262.0 6272.1 6278.2 6415.3 12:58:30 INFO - PROCESS | 1640 | 12:58:30 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:55912.75 Median:3158.29 stddev:115145.77 (3645.8%) stddev-sans-first:115394.34 12:58:30 INFO - PROCESS | 1640 | Values: 766.8 815.0 843.1 966.8 979.3 38856.8 39779.6 41652.6 41699.0 41785.4 40220.6 40322.4 40403.6 40478.0 40591.4 8046.9 8189.2 10807.6 11119.9 11306.7 10979.2 10988.0 11087.4 11096.0 11100.2 11081.1 11115.6 11126.4 11151.4 11155.3 9491.8 10842.9 11095.7 11107.9 11112.3 10756.0 10778.4 10781.0 10847.7 10855.3 35112.0 35220.8 35421.3 35512.0 35567.3 3832.2 3862.9 3898.6 3911.9 3959.3 25348.5 25518.7 25666.9 27155.9 27307.1 3083.7 3152.9 3163.7 3176.7 3285.8 333215.3 363872.0 367673.0 367821.0 368519.0 45232.3 45382.0 45655.5 45663.6 45830.8 44250.0 46392.5 46442.8 46463.5 46607.5 13734.7 13743.5 13863.3 13900.8 13913.0 12162.1 12187.7 12188.0 12210.8 12212.6 11789.4 11846.7 11994.3 11997.2 12062.8 5831.8 5900.1 5909.9 5943.6 5951.3 847.8 886.4 897.9 899.3 922.8 27609.6 27651.9 27751.2 28006.9 28039.7 976.6 990.0 996.0 996.7 1013.2 13961.2 14092.2 14257.9 14336.7 14360.5 452733.0 454986.0 457458.0 458026.0 458308.0 56105.0 56160.7 56319.7 56655.0 56722.4 1452.2 1456.4 1494.3 1494.7 1499.3 25711.0 27429.5 27444.2 27478.0 27498.3 41758.8 43076.6 47140.0 47236.0 47715.5 471712.0 474907.0 475372.0 475385.6 475467.0 29553.0 30348.1 30403.4 30606.1 31283.7 31292.7 33532.3 39718.0 39894.6 40786.8 24001.6 27931.0 28742.3 29007.1 29849.6 26743.0 27100.2 28673.9 28685.1 29603.3 32933.3 33139.0 33440.8 33732.7 34108.5 24092.8 26230.0 27047.6 29191.3 30303.0 22165.6 22747.4 23789.3 24060.6 24158.4 12:58:30 INFO - PROCESS | 1640 | 12:58:30 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:44326.97 Median:22500.33 stddev:128866.96 (572.7%) stddev-sans-first:129186.27 12:58:30 INFO - PROCESS | 1640 | Values: 408.5 408.5 419.2 419.7 451.7 19929.6 21129.2 22478.0 22546.2 22601.3 18071.3 21288.9 22133.0 22237.7 22655.0 6800.3 7061.6 7066.0 7068.5 7108.1 5991.5 6601.1 6871.0 6871.4 6950.7 6711.0 6917.0 6934.4 6936.4 6953.0 6855.2 6880.9 6913.1 6919.5 6925.8 6487.7 6733.6 6741.7 6788.6 6799.7 16993.7 25738.7 26141.6 26457.7 26821.7 2977.4 2996.1 3020.6 3067.4 3076.3 15623.8 16183.3 16251.4 16398.5 16505.3 2083.2 2134.5 2138.0 2142.9 2148.9 129994.0 134853.0 162779.2 177847.0 178391.0 21025.4 22322.9 22360.4 22522.7 22523.0 33127.0 33239.7 33250.7 33315.7 33471.5 8384.7 10516.1 10707.3 10756.6 10930.6 8818.7 8918.7 8949.1 9031.2 9096.0 8792.7 9844.3 9938.9 9959.9 9984.8 3918.0 4181.5 4187.4 4210.2 4219.7 1043.9 1080.2 1099.9 1106.3 1148.5 18788.1 21481.8 21823.3 21863.6 24460.9 943.5 966.7 1015.9 1091.1 1147.6 9209.3 10793.3 10922.0 11433.7 11437.0 711253.0 773712.0 783086.7 792188.0 796436.0 130948.0 159278.0 162371.0 163797.0 166048.0 1215.2 1265.8 1306.1 1309.9 1319.7 19763.2 20173.7 20383.4 20581.2 20628.0 62133.7 68783.7 68860.7 69418.3 69938.7 94051.0 94988.5 96347.6 96833.0 97232.0 7658.0 8862.5 9989.9 10017.7 11598.5 13278.7 13327.7 13414.7 13692.8 13788.4 11402.8 11558.8 11593.0 11788.3 11833.9 9221.4 10703.6 10774.6 11042.7 11064.2 11572.0 11709.6 11784.8 11887.1 12039.3 10177.0 10913.4 11639.6 11716.4 11754.8 9618.0 11238.7 11385.7 11453.6 11510.0 12:58:30 INFO - PROCESS | 1640 | 12:58:30 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:6782.06 Median:2418.99 stddev:4181.46 (172.9%) stddev-sans-first:4174.22 12:58:30 INFO - PROCESS | 1640 | Values: 1481.8 1555.1 1613.6 1617.2 1625.6 10788.4 11120.0 11445.5 11447.5 11469.9 11103.1 11249.1 11301.7 11314.6 11315.7 2438.8 2461.2 2473.5 2478.8 2482.7 2389.6 2406.8 2416.1 2436.4 2444.5 2366.5 2366.7 2382.2 2466.4 2470.4 2297.5 2310.3 2409.1 2421.9 2423.5 2154.1 2157.8 2160.4 2181.2 2181.9 10941.0 11219.1 11226.1 11235.4 11237.5 5900.7 6084.4 6085.2 6092.2 6099.5 3596.8 3659.6 3708.5 3716.9 3732.2 1201.3 1205.3 1215.3 1259.6 1261.6 13040.3 13626.9 13663.9 15488.8 15661.5 4733.3 4776.5 4846.2 4877.9 4917.2 11651.0 11791.2 11795.1 11846.1 11980.0 5028.7 5938.7 5948.8 5956.6 5966.4 4364.9 5051.7 5314.8 5339.8 5361.2 3917.3 3943.8 3958.1 4003.5 4020.5 1047.9 1060.9 1070.6 1084.9 1086.5 966.3 991.2 1002.1 1003.2 1025.3 6648.5 6768.4 6770.1 6832.6 6875.2 875.9 1002.6 1023.3 1027.0 1029.3 3740.5 3767.5 4344.8 4348.8 4350.6 13356.6 13926.7 13934.9 13963.7 13989.6 12828.6 13122.3 13699.8 13917.8 13928.0 10133.5 10265.3 10377.0 10407.1 10410.5 6145.0 6736.1 6824.9 6871.3 7049.5 11883.2 12061.8 12102.8 12150.6 12217.0 11996.5 12039.1 12050.1 12207.8 12257.9 9267.3 9305.2 9436.0 9483.8 9498.0 10290.6 10336.6 10383.3 10393.7 10414.7 8719.1 8933.6 8934.0 8955.4 8958.3 8734.4 8740.7 8760.0 8844.2 8892.6 8018.8 8173.7 8179.3 8185.3 8193.5 5929.6 5977.5 6008.7 6108.1 6208.1 6041.5 6117.2 6143.6 6244.8 6245.2 12:58:30 INFO - PROCESS | 1640 | 12:58:30 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4660.38 Median:5118.53 stddev:1321.31 (25.8%) stddev-sans-first:1285.12 12:58:30 INFO - PROCESS | 1640 | Values: 367.0 377.2 381.3 391.7 395.7 4789.8 5152.0 5227.8 5256.2 5310.5 4412.7 4580.7 5052.9 5123.6 5160.2 3566.9 3681.9 3983.3 4074.8 4084.7 3723.0 4040.0 4056.8 4075.5 4078.9 3870.9 4023.3 4065.2 4065.5 4091.7 3584.9 3780.8 3963.0 3983.7 4076.6 3532.4 3550.5 3566.1 3573.3 3583.1 4227.9 4289.5 4748.6 4968.0 4976.9 4015.9 4041.5 4064.9 4336.4 4610.7 4471.9 5271.9 5307.6 5594.2 5600.4 3783.9 4017.3 4037.9 4055.0 4055.6 5317.5 5450.5 5644.9 5883.0 5957.3 4994.9 5031.8 5035.5 5132.0 5163.6 5102.3 5257.5 5278.7 5283.2 5283.4 5258.8 5289.5 5327.9 5423.6 5474.6 5719.5 5730.5 5750.7 5764.9 5799.8 1554.9 1786.7 1800.5 1811.3 1822.0 1203.7 1337.0 1355.4 1418.1 1480.7 4438.1 4476.6 4745.0 5066.7 5085.7 5559.3 5875.3 5878.9 5917.7 5926.3 4493.6 4557.2 4729.5 4754.9 4860.8 2597.5 2707.7 2937.0 3026.3 3073.8 5363.2 5767.4 5968.5 6008.7 6061.4 5367.9 5504.0 5577.5 5657.1 5996.6 5113.5 5561.0 5691.5 6031.5 6106.3 4800.5 5068.0 5877.4 5920.6 5978.9 4538.6 4947.6 5394.4 5555.2 5919.3 5667.7 5916.0 5921.1 6160.9 6173.0 4207.7 4672.7 4712.4 4735.4 4760.6 5346.7 5550.3 5697.9 5822.7 5824.7 5357.3 5698.7 5709.9 5742.7 5754.8 5183.6 5221.4 5630.9 5709.0 5794.9 5229.4 5285.5 5425.9 5463.0 5533.7 5328.5 5559.7 5694.8 5724.9 5739.7 4862.9 4997.7 5394.5 5555.7 5557.6 12:58:30 INFO - PROCESS | 1640 | 12:58:30 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:20345.00 Median:3495.54 stddev:29368.72 (840.2%) stddev-sans-first:29414.19 12:58:30 INFO - PROCESS | 1640 | Values: 733.9 741.3 770.3 783.2 873.9 15639.1 16264.5 16864.8 17048.7 17153.7 15190.8 15338.2 15610.8 15661.5 15768.4 973.6 992.7 996.7 997.5 998.3 834.8 986.9 1012.9 1017.0 1020.0 1038.8 1053.4 1055.5 1057.5 1057.8 1036.8 1051.1 1051.7 1053.1 1053.3 785.1 787.3 788.1 789.2 789.3 14702.9 16491.0 16820.7 16897.7 17995.6 14266.4 14273.6 14316.4 14353.3 14480.3 11612.6 11779.9 11851.8 11856.9 12424.9 3757.2 3768.9 3793.7 3813.5 3927.7 118506.4 124639.0 127585.0 128650.0 128982.4 10824.5 10927.5 11135.6 11153.6 11550.9 23010.0 23197.7 23775.3 23819.4 23920.8 7159.5 7815.6 8051.4 8133.2 8228.9 4408.3 4415.1 4488.1 4513.2 4513.4 3436.8 3554.3 3637.7 3663.4 3672.7 628.0 640.0 642.2 649.0 654.8 682.4 699.3 707.8 718.0 736.3 8575.1 9763.4 10388.7 10585.7 10659.8 577.8 591.7 592.8 606.2 713.4 4474.4 5020.7 5038.8 5090.8 5125.7 59265.4 93239.5 93878.5 99140.0 100411.5 54500.9 70893.6 80912.2 86612.0 89478.0 75073.0 77011.5 77450.6 78028.0 80497.6 10738.9 10893.9 11227.9 11294.8 11388.5 47166.5 48352.3 48896.0 49259.5 49751.8 61131.8 62041.7 64620.0 65200.7 65668.7 12243.7 12813.5 12883.5 13116.1 13174.0 13208.4 14616.0 14894.3 14939.4 15339.8 13316.9 13589.3 13895.4 13943.7 13951.0 13310.6 13591.0 13753.1 13795.7 13811.9 11712.7 12822.4 13135.1 13419.0 13728.4 9446.2 9499.5 9685.9 9758.6 9833.0 9538.8 9900.4 11181.5 11183.9 11203.7 12:58:30 INFO - PROCESS | 1640 | -------- Summary: end -------- 12:58:30 INFO - PROCESS | 1640 | 12:58:31 INFO - TEST-INFO | 1640: exit 0 12:58:31 INFO - TEST-OK | dromaeo_css | took 1115529ms 12:58:31 INFO - TEST-START | kraken 12:58:31 INFO - operating with platform_type : mac_ 12:58:31 INFO - Initialising browser for kraken test... 12:58:31 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpOv45g1/profile http://localhost:49224/getInfo.html) 12:58:33 INFO - PROCESS | 1652 | 2017-06-05 12:58:33.878 plugin-container[1654:10170] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4247, name = 'com.apple.tsm.portname' 12:58:33 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:58:33 INFO - PROCESS | 1652 | 2017-06-05 12:58:33.879 plugin-container[1654:10170] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 12:58:33 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:58:33 INFO - PROCESS | 1652 | 2017-06-05 12:58:33.879 plugin-container[1654:10170] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:58:33 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 12:58:33 INFO - PROCESS | 1652 | colorDepth:24 12:58:33 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 12:58:33 INFO - PROCESS | 1652 | __metrics 12:58:34 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3381: TypeError: this.tabs is undefined 12:58:34 INFO - TEST-INFO | 1652: exit 0 12:58:34 INFO - Browser initialized. 12:58:34 INFO - Running cycle 1/1 for kraken test... 12:58:34 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/tmpOv45g1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:58:36 INFO - PROCESS | 1655 | 2017-06-05 12:58:36.953 plugin-container[1656:10294] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 12:58:36 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:58:36 INFO - PROCESS | 1655 | 2017-06-05 12:58:36.954 plugin-container[1656:10294] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x870b, name = 'com.apple.CFPasteboardClient' 12:58:36 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:58:36 INFO - PROCESS | 1655 | 2017-06-05 12:58:36.954 plugin-container[1656:10294] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:58:46 INFO - PROCESS | 1655 | RSS: Main: 203042816 12:58:46 INFO - PROCESS | 1655 | 12:58:49 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection) 12:58:50 INFO - PROCESS | 1655 | RSS: Main: 216469504 12:58:50 INFO - PROCESS | 1655 | 12:58:53 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-dft) 12:58:53 INFO - PROCESS | 1655 | RSS: Main: 224366592 12:58:53 INFO - PROCESS | 1655 | 12:58:57 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-fft) 12:58:57 INFO - PROCESS | 1655 | RSS: Main: 227983360 12:58:57 INFO - PROCESS | 1655 | 12:58:59 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator) 12:59:00 INFO - PROCESS | 1655 | RSS: Main: 229376000 12:59:00 INFO - PROCESS | 1655 | 12:59:02 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:59:02 INFO - PROCESS | 1655 | RSS: Main: 229416960 12:59:02 INFO - PROCESS | 1655 | 12:59:05 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom) 12:59:06 INFO - PROCESS | 1655 | RSS: Main: 233680896 12:59:06 INFO - PROCESS | 1655 | 12:59:09 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate) 12:59:10 INFO - PROCESS | 1655 | RSS: Main: 233345024 12:59:10 INFO - PROCESS | 1655 | 12:59:13 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial) 12:59:13 INFO - PROCESS | 1655 | RSS: Main: 233668608 12:59:13 INFO - PROCESS | 1655 | 12:59:15 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:59:16 INFO - PROCESS | 1655 | RSS: Main: 233005056 12:59:16 INFO - PROCESS | 1655 | 12:59:18 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:59:18 INFO - PROCESS | 1655 | RSS: Main: 233029632 12:59:18 INFO - PROCESS | 1655 | 12:59:20 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:59:21 INFO - PROCESS | 1655 | RSS: Main: 233009152 12:59:21 INFO - PROCESS | 1655 | 12:59:24 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:59:25 INFO - PROCESS | 1655 | RSS: Main: 233410560 12:59:25 INFO - PROCESS | 1655 | 12:59:28 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:59:28 INFO - PROCESS | 1655 | RSS: Main: 233472000 12:59:28 INFO - PROCESS | 1655 | 12:59:30 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49224/tests/kraken/driver.html?testName=ai-astar) 12:59:30 INFO - PROCESS | 1655 | RSS: Main: 235507712 12:59:30 INFO - PROCESS | 1655 | 12:59:30 INFO - PROCESS | 1655 | __start_tp_report 12:59:30 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 12:59:30 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 12:59:30 INFO - PROCESS | 1655 | |i|pagename|runs| 12:59:30 INFO - PROCESS | 1655 | |0;ai-astar;110;119;119;121;120;118;120;120;98;168 12:59:30 INFO - PROCESS | 1655 | |1;audio-beat-detection;142;123;112;163;139;139;137;111;132;111 12:59:30 INFO - PROCESS | 1655 | |2;audio-dft;188;196;195;201;196;194;199;196;197;194 12:59:30 INFO - PROCESS | 1655 | |3;audio-fft;82;63;59;89;63;62;63;66;94;82 12:59:30 INFO - PROCESS | 1655 | |4;audio-oscillator;89;69;86;72;71;70;84;100;100;111 12:59:30 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;89;90;109;88;91;86;140;134;100;91 12:59:30 INFO - PROCESS | 1655 | |6;imaging-darkroom;97;100;97;97;97;97;98;97;97;125 12:59:30 INFO - PROCESS | 1655 | |7;imaging-desaturate;90;90;89;107;90;90;90;100;89;91 12:59:30 INFO - PROCESS | 1655 | |8;json-parse-financial;66;65;66;66;70;69;69;75;99;103 12:59:30 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;61;85;49;63;88;90;83;83;59;58 12:59:30 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;86;86;87;88;87;85;96;84;84;102 12:59:30 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;139;150;149;142;147;133;148;137;137;138 12:59:30 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;167;178;148;220;223;170;170;168;165;167 12:59:30 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;66;68;65;66;66;75;77;67;65;65 12:59:30 INFO - PROCESS | 1655 | __end_tp_report 12:59:30 INFO - PROCESS | 1655 | __start_cc_report 12:59:30 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1005 12:59:30 INFO - PROCESS | 1655 | __end_cc_report 12:59:30 INFO - PROCESS | 1655 | __startTimestamp1496692770643__endTimestamp 12:59:30 INFO - PROCESS | 1655 | 12:59:30 INFO - PROCESS | 1655 | ------- Summary: start ------- 12:59:30 INFO - PROCESS | 1655 | Number of tests: 14 12:59:30 INFO - PROCESS | 1655 | 12:59:30 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:121.30 Median:120.00 stddev:17.88 (14.9%) stddev-sans-first:18.49 12:59:30 INFO - PROCESS | 1655 | Values: 110.0 119.0 119.0 121.0 120.0 118.0 120.0 120.0 98.0 168.0 12:59:30 INFO - PROCESS | 1655 | 12:59:30 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:130.90 Median:138.00 stddev:16.78 (12.2%) stddev-sans-first:17.31 12:59:30 INFO - PROCESS | 1655 | Values: 142.0 123.0 112.0 163.0 139.0 139.0 137.0 111.0 132.0 111.0 12:59:30 INFO - PROCESS | 1655 | 12:59:30 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:195.60 Median:196.00 stddev:3.44 (1.8%) stddev-sans-first:2.30 12:59:30 INFO - PROCESS | 1655 | Values: 188.0 196.0 195.0 201.0 196.0 194.0 199.0 196.0 197.0 194.0 12:59:30 INFO - PROCESS | 1655 | 12:59:30 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:72.30 Median:74.00 stddev:13.00 (17.6%) stddev-sans-first:13.30 12:59:30 INFO - PROCESS | 1655 | Values: 82.0 63.0 59.0 89.0 63.0 62.0 63.0 66.0 94.0 82.0 12:59:30 INFO - PROCESS | 1655 | 12:59:30 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:85.20 Median:71.50 stddev:14.87 (20.8%) stddev-sans-first:15.71 12:59:30 INFO - PROCESS | 1655 | Values: 89.0 69.0 86.0 72.0 71.0 70.0 84.0 100.0 100.0 111.0 12:59:30 INFO - PROCESS | 1655 | 12:59:30 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:101.80 Median:88.50 stddev:19.80 (22.4%) stddev-sans-first:20.45 12:59:30 INFO - PROCESS | 1655 | Values: 89.0 90.0 109.0 88.0 91.0 86.0 140.0 134.0 100.0 91.0 12:59:30 INFO - PROCESS | 1655 | 12:59:30 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:100.20 Median:97.00 stddev:8.77 (9.0%) stddev-sans-first:9.22 12:59:30 INFO - PROCESS | 1655 | Values: 97.0 100.0 97.0 97.0 97.0 97.0 98.0 97.0 97.0 125.0 12:59:30 INFO - PROCESS | 1655 | 12:59:30 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:92.60 Median:90.00 stddev:6.00 (6.7%) stddev-sans-first:6.29 12:59:30 INFO - PROCESS | 1655 | Values: 90.0 90.0 89.0 107.0 90.0 90.0 90.0 100.0 89.0 91.0 12:59:30 INFO - PROCESS | 1655 | 12:59:30 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:74.80 Median:69.00 stddev:14.14 (20.5%) stddev-sans-first:14.64 12:59:30 INFO - PROCESS | 1655 | Values: 66.0 65.0 66.0 66.0 70.0 69.0 69.0 75.0 99.0 103.0 12:59:30 INFO - PROCESS | 1655 | 12:59:30 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:71.90 Median:83.00 stddev:15.23 (18.3%) stddev-sans-first:15.63 12:59:30 INFO - PROCESS | 1655 | Values: 61.0 85.0 49.0 63.0 88.0 90.0 83.0 83.0 59.0 58.0 12:59:30 INFO - PROCESS | 1655 | 12:59:30 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:88.50 Median:86.50 stddev:5.85 (6.8%) stddev-sans-first:6.14 12:59:30 INFO - PROCESS | 1655 | Values: 86.0 86.0 87.0 88.0 87.0 85.0 96.0 84.0 84.0 102.0 12:59:30 INFO - PROCESS | 1655 | 12:59:30 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:142.00 Median:144.50 stddev:6.06 (4.2%) stddev-sans-first:6.32 12:59:30 INFO - PROCESS | 1655 | Values: 139.0 150.0 149.0 142.0 147.0 133.0 148.0 137.0 137.0 138.0 12:59:30 INFO - PROCESS | 1655 | 12:59:30 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.60 Median:170.00 stddev:24.33 (14.3%) stddev-sans-first:25.50 12:59:30 INFO - PROCESS | 1655 | Values: 167.0 178.0 148.0 220.0 223.0 170.0 170.0 168.0 165.0 167.0 12:59:30 INFO - PROCESS | 1655 | 12:59:30 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:66.50 stddev:4.35 (6.5%) stddev-sans-first:4.55 12:59:30 INFO - PROCESS | 1655 | Values: 66.0 68.0 65.0 66.0 66.0 75.0 77.0 67.0 65.0 65.0 12:59:30 INFO - PROCESS | 1655 | -------- Summary: end -------- 12:59:30 INFO - PROCESS | 1655 | 12:59:31 INFO - TEST-INFO | 1655: exit 0 12:59:31 INFO - TEST-OK | kraken | took 60055ms 12:59:31 INFO - SUITE-END | took 1175s 12:59:31 INFO - Completed test suite (00:19:35) 12:59:31 INFO - javascript benchmark 12:59:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7761.134177459474, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3509.2981403719255, 3538.1331573764433, 3545.8227088645567, 3550.875719349822, 3551.129973156577, 5935.990881550136, 5994.670293133876, 5998.340182579913, 6014.097885317205, 6032.668203248825, 5937.554683398741, 6002.669853158074, 6010.308814486335, 6023.728932198051, 6032.728527216274, 5365.168398898177, 5408.729563521828, 5415.431379705134, 5424.864378390533, 5544.0575102232515, 5457.2359869618285, 5476.288082549283, 5491.752871120809, 5497.038018346773, 5506.504414602685, 5273.551748101416, 5293.761780719858, 5410.648307859996, 5449.5640348772, 5451.536619387357, 4564.885877853047, 5397.514223719864, 5416.458354164592, 5436.83689489314, 5447.836564903019, 5320.14877619579, 5395.838124856261, 5433.375161856358, 5440.156775699773, 5458.754356053987, 5709.857253568653, 5982.162497250389, 6062.363451837582, 6090.66501342426, 6093.268807743055, 6293.30773614902, 6306.085617585471, 6312.274088479791, 6333.841653958688, 6334.714937827809, 5109.540141387293, 6511.706973186218, 6516.804495865132, 6527.445167160797, 6537.673116344164, 5029.19532874742, 6119.2350956130485, 6190.969045154746, 6208.441240288395, 6218.4714299284615, 6364.522660800459, 6390.329015453311, 6534.205758605763, 6549.083128362033, 6597.637129957802, 5340.492653198017, 6323.27282362525, 6341.936580634227, 6342.682865856688, 6366.936330636727, 6428.582142160838, 6492.577982431127, 6506.902396464056, 6509.8698026040165, 6511.0, 6211.844703882349, 6234.2830574484515, 6316.273628532777, 6418.293987661354, 6437.356264373525, 6249.0938813871735, 6265.906011409833, 6320.968395158086, 6338.176037115146, 6348.301686814447, 4849.2726091086615, 4895.31465594817, 4909.484504127086, 4934.629902757308, 4967.677100988424, 4428.180786554498, 4434.246178149722, 4439.866803995886, 4455.353973673864, 4463.843765468193, 5850.414958504116, 5853.795117170878, 5892.558058145571, 5983.730732116978, 5990.071538911388, 6410.102585638015, 6424.453921416732, 6464.935350646528, 6473.093766872642, 6489.870202595922, 5729.742161602655, 5930.169776231331, 5953.226080609493, 5967.6419414835245, 5973.133895585116, 5442.265294185314, 5477.28795256622, 5552.056150454432, 5559.8888022239325, 5562.221289019541, 6619.172603424572, 6628.801135965832, 6632.635205063768, 6633.071370008106, 6653.939187973587, 6396.456301214449, 6424.710888009958, 6450.580712253689, 6472.708728107153, 6473.126127972663, 6256.843578910381, 6405.0, 6449.4840412765925, 6497.220333560003, 6629.237637671729, 6316.526260530387, 6380.330065343072, 6385.8722825544155, 6409.2949775525585, 6410.455111315404, 6405.487560995016, 6422.807315780534, 6446.355364463516, 6451.3226111257645, 6455.160829092188, 6147.924113280247, 6206.317305096345, 6209.068639704081, 6239.78160764371, 6315.55791094619, 6214.036824292061, 6222.066689996536, 6277.843053923506, 6285.402886725937, 6289.874202515833, 5678.574106942078, 5925.844460330367, 6182.103594978613, 6240.188775459162, 6244.2819075806865, 5074.340335756328, 5791.899539087728, 6108.372425856702, 6137.652792202512, 6233.3107488741925, 6224.941759900918, 6262.029385445263, 6272.121902933684, 6278.215223097113, 6415.262244841903], "value": 5864.54670957169, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [766.7814672818248, 815.0341844913776, 843.118940706961, 966.8356379415494, 979.2753362511744, 38856.805715971415, 39779.60220397795, 41652.58347416532, 41699.0, 41785.37321940169, 40220.597794022055, 40322.395164072565, 40403.59596404035, 40478.0, 40591.39112913309, 8046.879296810538, 8189.22202390772, 10807.621733239299, 11119.888801111967, 11306.660800176009, 10979.231453798237, 10988.0, 11087.445627718622, 11096.0, 11100.167487438475, 11081.113510919147, 11115.555377784878, 11126.443677816118, 11151.442427878614, 11155.330680159217, 9491.762705932339, 10842.891571084267, 11095.667129986114, 11107.944460277648, 11112.277596972483, 10755.97818207277, 10778.353298802096, 10781.0, 10847.67456976292, 10855.294405863593, 35112.0, 35220.82389588061, 35421.291574168376, 35512.0, 35567.28865422703, 3832.1952389997855, 3862.8841134766003, 3898.5516665583536, 3911.9217615647813, 3959.3467077932105, 25348.493030139292, 25518.74481255201, 25666.87166564174, 27155.864220678966, 27307.08850380474, 3083.6762139975203, 3152.889648862302, 3163.6994485523837, 3176.6664500227375, 3285.8357082146035, 333215.3339233312, 363872.0, 367673.0, 367821.0, 368519.0, 45232.3215151773, 45382.0, 45655.543444565796, 45663.63009109732, 45830.770846145555, 44250.0, 46392.536074639494, 46442.838929026366, 46463.535364646596, 46607.53392466032, 13734.65663358424, 13743.518976835763, 13863.306834658226, 13900.791488127687, 13913.0, 12162.148649594621, 12187.695307617381, 12188.0, 12210.75578488443, 12212.57255996036, 11789.410529473493, 11846.703832404086, 11994.340311282964, 11997.160198786001, 12062.819057714141, 5831.825045248649, 5900.1149827526215, 5909.881802364014, 5943.613665111754, 5951.256092988377, 847.7626264645909, 886.4016788667624, 897.8967418746928, 899.321012635456, 922.806210695748, 27609.585856212172, 27651.86174069117, 27751.224817649283, 28006.85996570045, 28039.719602804118, 976.6142373762324, 989.9901000990042, 995.9502024898726, 996.7109538233831, 1013.1793247469574, 13961.232132232824, 14092.154470731584, 14257.928710356382, 14336.713265734626, 14360.497382591559, 452733.0, 454986.0, 457458.0, 458026.0, 458308.0, 56105.0, 56160.719196403756, 56319.71840140773, 56655.0, 56722.43277567254, 1452.244832686997, 1456.410153887677, 1494.3126161965617, 1494.7085318362815, 1499.2503748125937, 25711.0, 27429.45141097127, 27444.17667469979, 27478.0382686605, 27498.312542186606, 41758.791206043774, 43076.56923430726, 47140.0, 47236.0, 47715.522844771105, 471712.0, 474907.0, 475372.0, 475385.6230718824, 475467.0, 29553.0, 30348.089557313317, 30403.391932161674, 30606.08181754551, 31283.687163128077, 31292.687073129888, 33532.32935341328, 39718.00704982283, 39894.60105399025, 40786.79606601948, 24001.559906405673, 27931.022414215404, 28742.281442964093, 29007.129786106452, 29849.612031037897, 26743.0, 27100.1869943902, 28673.856630716713, 28685.139445816658, 29603.259918502208, 32933.34133317368, 33139.0, 33440.832795835864, 33732.66267337295, 34108.488372673935, 24092.759072409754, 26230.0, 27047.594286086118, 29191.270218244714, 30303.0, 22165.55668886581, 22747.431314217276, 23789.286321410385, 24060.639090413308, 24158.39604009914], "value": 18349.549301414532, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.53427093113874, 408.54243247562715, 419.205605377809, 419.6789456066109, 451.690591944518, 19929.60140797185, 21129.15483380665, 22478.0, 22546.21088261911, 22601.321960341196, 18071.27714891403, 21288.893555532202, 22133.004014819333, 22237.666435003488, 22655.0, 6800.319968003209, 7061.576305421666, 7065.964670176668, 7068.505204635678, 7108.075950126502, 5991.5206783457215, 6601.14185155929, 6871.03805467235, 6871.415929645986, 6950.652467376611, 6711.0, 6916.965415172917, 6934.375906168444, 6936.410405115571, 6953.0, 6855.245922948472, 6880.8967865482055, 6913.10129683143, 6919.481038922051, 6925.826854328657, 6487.740490380379, 6733.629650369227, 6741.696623651947, 6788.59268443895, 6799.694013769392, 16993.745093823603, 25738.74261257382, 26141.607875881877, 26457.73542264572, 26821.73178268212, 2977.3747513022076, 2996.1460983619563, 3020.622422197225, 3067.4478593853096, 3076.292452735861, 15623.843761562353, 16183.271752771036, 16251.431199907947, 16398.508044758677, 16505.257263423176, 2083.2187929526426, 2134.466383404149, 2138.005827290302, 2142.8500004999655, 2148.904058929941, 129994.0, 134853.0, 162779.1861040687, 177847.0, 178391.0, 21025.369238922856, 22322.88838555797, 22360.44098897508, 22522.66216006761, 22522.986465609087, 33127.0, 33239.67041318369, 33250.667493324756, 33315.66684333126, 33471.497927531105, 8384.706535271358, 10516.1061309788, 10707.304025238334, 10756.56973721058, 10930.56277748897, 8818.735437936872, 8918.687845925491, 8949.149830766068, 9031.232345250728, 9095.953965293944, 8792.692255771017, 9844.310898237054, 9938.850917236246, 9959.850602240973, 9984.80030399388, 3917.980410097988, 4181.452862440856, 4187.413762073312, 4210.221109094828, 4219.683523735732, 1043.9164866810636, 1080.2276372393776, 1099.9010089092021, 1106.3417266726285, 1148.4544841200363, 18788.060596970096, 21481.78518214829, 21823.345299641038, 21863.562728745335, 24460.87769561141, 943.4763706143019, 966.6809952715587, 1015.8577799108131, 1091.1270983213396, 1147.5524545427122, 9209.263258939269, 10793.29843560166, 10922.017018468376, 11433.656990290305, 11436.970672639502, 711253.0, 773712.0, 783086.695518632, 792188.0, 796436.0, 130948.0, 159278.0, 162370.99999999764, 163797.0, 166048.0, 1215.1554669504785, 1265.8291130697237, 1306.0531114941782, 1309.8755618216255, 1319.6568892088126, 19763.20947162155, 20173.6973945388, 20383.388498345073, 20581.176752930314, 20628.0, 62133.689331553054, 68783.65608171927, 68860.6556967212, 69418.30581694118, 69938.65030674814, 94051.0, 94988.52505737427, 96347.55478667686, 96833.0316696824, 97232.02767972229, 7657.961710191414, 8862.468251904906, 9989.850152247867, 10017.69946901594, 11598.47806848677, 13278.668033299246, 13327.66680832987, 13414.664633384244, 13692.794608081085, 13788.379522921292, 11402.828957565478, 11558.768824623628, 11593.014593759624, 11788.29270243788, 11833.940830295793, 9221.400608960395, 10703.571857125939, 10774.622888198875, 11042.668719938094, 11064.17018723615, 11572.0, 11709.590164344207, 11784.823227651763, 11887.108466864847, 12039.337836419081, 10177.0, 10913.399763012792, 11639.59261425846, 11716.355600442057, 11754.764904701688, 9618.0, 11238.719032024264, 11385.715357116138, 11453.59912403062, 11509.96410323075], "value": 11812.571799590183, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1481.8295895971962, 1555.1446704312625, 1613.6046668566196, 1617.2318148879292, 1625.6098536351271, 10788.352698838075, 11120.0, 11445.542178312879, 11447.484863181156, 11469.853014698527, 11103.05624021957, 11249.100071994242, 11301.660950171508, 11314.603988860392, 11315.717107072307, 2438.8414753041084, 2461.1509029384833, 2473.5423946569854, 2478.8016958643357, 2482.689663792026, 2389.581823180945, 2406.795422389099, 2416.0818888822223, 2436.4030812450974, 2444.535538247736, 2366.526694661066, 2366.715994080704, 2382.2257766226044, 2466.371075375777, 2470.4194514289215, 2297.517521320525, 2310.330004298751, 2409.072507084776, 2421.903123875043, 2423.51529694061, 2154.0952799824113, 2157.8057974782187, 2160.4274867160216, 2181.214762685432, 2181.9345419637434, 10941.0, 11219.102471802236, 11226.101911847032, 11235.382053987027, 11237.494312755945, 5900.734466948998, 6084.361142080063, 6085.208922840046, 6092.238470191226, 6099.45104940553, 3596.7841929484316, 3659.6157403472785, 3708.517892673935, 3716.888493345177, 3732.1602639405914, 1201.255221762504, 1205.3129716061849, 1215.2769102384068, 1259.615817175761, 1261.6215135459327, 13040.347982600832, 13626.863731362659, 13663.863361366359, 15488.845111548966, 15661.530154095395, 4733.337332773414, 4776.498467660915, 4846.248831431134, 4877.853664390074, 4917.188663870421, 11651.0, 11791.233569818107, 11795.05639548834, 11846.052315814713, 11980.0, 5028.698278103326, 5938.732757025944, 5948.762049518057, 5956.642601443928, 5966.433188847052, 4364.869053928387, 5051.6716413433005, 5314.7608357624, 5339.839804805862, 5361.195820626937, 3917.314469967744, 3943.7830919299713, 3958.129211573449, 4003.4795476587856, 4020.537638171589, 1047.9371237725761, 1060.925735198544, 1070.5664205996584, 1084.8806631270554, 1086.5436516663021, 966.3477152921749, 991.1624677147798, 1002.1081237698454, 1003.2275148135894, 1025.2720568396521, 6648.4681225501845, 6768.357006084414, 6770.119884415093, 6832.624205668637, 6875.209350924608, 875.8817559629496, 1002.6390499420203, 1023.2632504596827, 1026.9691909242736, 1029.281100562063, 3740.4576336431064, 3767.5102236709054, 4344.761038142932, 4348.760818155032, 4350.630196433275, 13356.599302020955, 13926.651833704238, 13934.930325348309, 13963.720725585432, 13989.580312590639, 12828.551000715117, 13122.34388280582, 13699.835513981225, 13917.791233131511, 13928.0, 10133.493325333851, 10265.332753371156, 10377.0, 10407.115395191493, 10410.479476026168, 6144.969275153596, 6736.090627765189, 6824.863502730017, 6871.347222013901, 7049.471289653194, 11883.227590206609, 12061.75876482483, 12102.818457723317, 12150.635480935585, 12217.022638188746, 11996.460159293027, 12039.09706771978, 12050.09624278165, 12207.816882746944, 12257.938710306391, 9267.258619310574, 9305.205387172053, 9435.962044175272, 9483.810323793623, 9498.00270971538, 10290.588376464857, 10336.638217662347, 10383.273170878269, 10393.688189354332, 10414.739631509274, 8719.12808719123, 8933.597988090421, 8934.0, 8955.373123881265, 8958.328125390492, 8734.432261902955, 8740.694075707319, 8760.0, 8844.159804818531, 8892.59983300766, 8018.759437216893, 8173.67305307781, 8179.263866252066, 8185.345172386075, 8193.46742461738, 5929.584929054925, 5977.491913187254, 6008.699565021644, 6108.114323423168, 6208.068789681584, 6041.456268935817, 6117.204763380732, 6143.631382117087, 6244.781432649836, 6245.219347581552], "value": 5190.186089185747, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.9980952001038, 377.2285675793003, 381.30031392394955, 391.6572998626202, 395.6933376633109, 4789.832355867546, 5152.0, 5227.817026404076, 5256.214206470567, 5310.522053015228, 4412.690482138396, 4580.679352445331, 5052.949470505294, 5123.591911801447, 5160.200168973815, 3566.928661426776, 3681.8711345102924, 3983.275637279153, 4074.7555146691143, 4084.65280451162, 3723.013401448605, 4039.97980010101, 4056.797160141996, 4075.510938687347, 4078.877633670995, 3870.8838734838, 4023.255697695921, 4065.1869626074904, 4065.4918135233097, 4091.672666186698, 3584.8773910003442, 3780.7731536107644, 3963.0, 3983.701222408331, 4076.612721791425, 3532.44572388151, 3550.5029295898594, 3566.144125409909, 3573.3032058748554, 3583.079789971191, 4227.879611902848, 4289.549597292302, 4748.6438517111355, 4968.01279488204, 4976.87557811054, 4015.927815221358, 4041.4544036554985, 4064.918701625951, 4336.436263285784, 4610.746408947506, 4471.8658440246845, 5271.947280527223, 5307.575393968474, 5594.2447769551, 5600.351708082323, 3783.867564635252, 4017.337139372, 4037.858674946392, 4054.979725101385, 4055.574164712692, 5317.46825317469, 5450.5283573435345, 5644.915326270109, 5882.970585147047, 5957.255343082114, 4994.875128121826, 5031.77357018935, 5035.486142673766, 5132.0, 5163.554204822655, 5102.316235642229, 5257.526822585985, 5278.736063196825, 5283.199904050859, 5283.392409872914, 5258.790478190038, 5289.523942845162, 5327.920081198785, 5423.640716288427, 5474.644148130438, 5719.482518251246, 5730.484256416942, 5750.699720111988, 5764.884702305931, 5799.82600521985, 1554.875609951201, 1786.6873297172942, 1800.4958611588777, 1811.2573844723863, 1821.954451138732, 1203.7351782607811, 1336.953206637763, 1355.437493440231, 1418.0569921002625, 1480.726065677853, 4438.1359828871955, 4476.597106260452, 4745.003549254695, 5066.670666406671, 5085.660596125749, 5559.304412154251, 5875.265591801025, 5878.882422351529, 5917.6745279010065, 5926.259217597801, 4493.59557639814, 4557.171975540496, 4729.4797572267025, 4754.857354279377, 4860.8298709545, 2597.545429549821, 2707.713835927958, 2937.030779842647, 3026.32039218236, 3073.8329477718157, 5363.222332761728, 5767.423257674199, 5968.46283834457, 6008.669523176267, 6061.363556826558, 5367.919481207785, 5503.981763373788, 5577.498025177753, 5657.066584013592, 5996.550258730613, 5113.463086375951, 5560.9712203241725, 5691.499727667538, 6031.517478601701, 6106.328303886569, 4800.543948324904, 5067.974660126823, 5877.441643043904, 5920.644761314335, 5978.940210597838, 4538.636909047203, 4947.554720075211, 5394.406615272395, 5555.166724991283, 5919.348871624204, 5667.688277144796, 5915.977135547869, 5921.105246321136, 6160.936086411102, 6173.0, 4207.664746065962, 4672.719636821802, 4712.410948631421, 4735.360726302043, 4760.642951778561, 5346.652467589594, 5550.335838797319, 5697.943020569741, 5822.70886455684, 5824.679642619697, 5357.330333708286, 5698.743556539883, 5709.914351284651, 5742.692980264801, 5754.76272601376, 5183.611229157904, 5221.37343518765, 5630.930123276565, 5709.003697411746, 5794.942050579441, 5229.424763276113, 5285.524302812765, 5425.914817036687, 5462.984706240831, 5533.723313834373, 5328.4671532846405, 5559.694216818114, 5694.800681976111, 5724.914126288026, 5739.655620662774, 4862.854114376574, 4997.650164488451, 5394.514493695587, 5555.69436185469, 5557.583181261502], "value": 4289.913110259466, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [733.915599706034, 741.3068780690052, 770.279788397848, 783.1659282863753, 873.9038705742367, 15639.139847308408, 16264.512064638051, 16864.831351686495, 17048.659026819456, 17153.656926861455, 15190.772138417906, 15338.156401397919, 15610.84389156108, 15661.530154095366, 15768.369265229405, 973.5618971462835, 992.7270000749779, 996.7308826616809, 997.4862945582994, 998.3061772068415, 834.7787836223406, 986.8865080515725, 1012.9442880641561, 1017.0, 1019.9745006374826, 1038.8389799581078, 1053.3627155570884, 1055.4617145255936, 1057.4871187474107, 1057.7778666480049, 1036.8237399642042, 1051.05405135378, 1051.7055224537103, 1053.1416895230363, 1053.2574534952917, 785.1363500149847, 787.2835719495289, 788.0503992688796, 789.2462698123278, 789.3487872505207, 14702.926485367612, 16491.0, 16820.66358672832, 16897.66204675912, 17995.640087198313, 14266.429342826275, 14273.64315892111, 14316.355763990645, 14353.282335883165, 14480.275986200753, 11612.593559225472, 11779.88220117805, 11851.762964740743, 11856.940715296454, 12424.937875310656, 3757.1734218471893, 3768.8680896168776, 3793.6585707286476, 3813.504244448204, 3927.725059245854, 118506.40746796297, 124639.0, 127585.0, 128650.0, 128982.35508822397, 10824.512896919658, 10927.508262128224, 11135.554577816796, 11153.609623663133, 11550.94224528872, 23009.964551595218, 23197.652035219486, 23775.286741397787, 23819.404514887265, 23920.760792391855, 7159.534630249017, 7815.57014364204, 8051.396145289127, 8133.186681331819, 8228.876566851503, 4408.294672852392, 4415.116976604692, 4488.079943611552, 4513.1650644630845, 4513.368128462018, 3436.7766095204233, 3554.306910152495, 3637.727170462173, 3663.432168013962, 3672.6510981456718, 627.9968600157061, 639.9712012959428, 642.2132887213127, 649.0, 654.7511945460694, 682.3619915379137, 699.3251512290664, 707.8301207710216, 717.9856402871914, 736.2527035059419, 8575.099614540384, 9763.365381250196, 10388.688339349832, 10585.682429527127, 10659.84010239847, 577.826652004397, 591.6775357430125, 592.7806711516766, 606.2361424604941, 713.3971793834202, 4474.373587697725, 5020.673656212334, 5038.823641172541, 5090.796368145307, 5125.692458452505, 59265.407345926855, 93239.53380233055, 93878.53060734653, 99140.00859991452, 100411.49382759264, 54500.90998180014, 70893.64553177201, 80912.19087809164, 86612.0, 89478.0, 75073.0, 77011.45977080427, 77450.6127469359, 78028.0, 80497.59751201207, 10738.892611074101, 10893.891061089287, 11227.943860280646, 11294.830577541508, 11388.487518061542, 47166.52833471759, 48352.274715878586, 48896.0, 49259.507404926924, 49751.75124124356, 61131.77736445335, 62041.68979155075, 64620.0, 65200.67399662971, 65668.74650028082, 12243.653198148375, 12813.487460501476, 12883.48466061347, 13116.08187426871, 13174.011949103664, 13208.405621746851, 14616.0, 14894.329755161261, 14939.402423902922, 15339.846601534287, 13316.933415332862, 13589.32053397346, 13895.374708137955, 13943.721125577635, 13951.0, 13310.600681979557, 13591.0, 13753.106048106842, 13795.724085518434, 13811.930940345233, 11712.70718232051, 12822.358882056047, 13135.080544361803, 13418.993575481683, 13728.382222800197, 9446.247605435105, 9499.477528735986, 9685.854712179464, 9758.556831738544, 9833.0, 9538.807649043869, 9900.356476828983, 11181.496832642388, 11183.944080279547, 11203.71990700239], "value": 7721.034991268386, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1522.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 119.0, 119.0, 121.0, 120.0, 118.0, 120.0, 120.0, 98.0, 168.0], "value": 121.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 123.0, 112.0, 163.0, 139.0, 139.0, 137.0, 111.0, 132.0, 111.0], "value": 130.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 196.0, 195.0, 201.0, 196.0, 194.0, 199.0, 196.0, 197.0, 194.0], "value": 195.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 63.0, 59.0, 89.0, 63.0, 62.0, 63.0, 66.0, 94.0, 82.0], "value": 72.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 69.0, 86.0, 72.0, 71.0, 70.0, 84.0, 100.0, 100.0, 111.0], "value": 85.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 109.0, 88.0, 91.0, 86.0, 140.0, 134.0, 100.0, 91.0], "value": 101.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 100.0, 97.0, 97.0, 97.0, 97.0, 98.0, 97.0, 97.0, 125.0], "value": 100.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 89.0, 107.0, 90.0, 90.0, 90.0, 100.0, 89.0, 91.0], "value": 92.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 66.0, 70.0, 69.0, 69.0, 75.0, 99.0, 103.0], "value": 74.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 85.0, 49.0, 63.0, 88.0, 90.0, 83.0, 83.0, 59.0, 58.0], "value": 71.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 88.0, 87.0, 85.0, 96.0, 84.0, 84.0, 102.0], "value": 88.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 150.0, 149.0, 142.0, 147.0, 133.0, 148.0, 137.0, 137.0, 138.0], "value": 142.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 178.0, 148.0, 220.0, 223.0, 170.0, 170.0, 168.0, 165.0, 167.0], "value": 177.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 65.0, 66.0, 66.0, 75.0, 77.0, 67.0, 65.0, 65.0], "value": 68.0, "unit": "score"}]}]} 12:59:31 INFO - Return code: 0 12:59:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:59:31 ERROR - # TBPL SUCCESS # 12:59:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:59:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:59:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:59:31 INFO - 2017-06-05 12:59:31.458 screenresolution[1660:10677] starting screenresolution argv=screenresolution get 12:59:31 INFO - 2017-06-05 12:59:31.460 screenresolution[1660:10677] Display 0: 1600x1200x32@60 12:59:31 INFO - 2017-06-05 12:59:31.466 screenresolution[1661:10680] starting screenresolution argv=screenresolution list 12:59:31 INFO - Available Modes on Display 0 12:59:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:59:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:59:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:59:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:59:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:59:31 INFO - Intel Iris: 12:59:31 INFO - Chipset Model: Intel Iris 12:59:31 INFO - Type: GPU 12:59:31 INFO - Bus: Built-In 12:59:31 INFO - VRAM (Dynamic, Max): 1536 MB 12:59:31 INFO - Vendor: Intel (0x8086) 12:59:31 INFO - Device ID: 0x0a2e 12:59:31 INFO - Revision ID: 0x0009 12:59:31 INFO - Displays: 12:59:31 INFO - Display: 12:59:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:59:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:59:31 INFO - Display Serial Number: 1600x1200 60 12:59:31 INFO - Main Display: Yes 12:59:31 INFO - Mirror: Off 12:59:31 INFO - Online: Yes 12:59:31 INFO - Rotation: Supported 12:59:31 INFO - Return code: 0 12:59:31 INFO - Running post-action listener: _resource_record_post_action 12:59:31 INFO - [mozharness: 2017-06-05 19:59:31.710687Z] Finished run-tests step (success) 12:59:31 INFO - Running post-run listener: _resource_record_post_run 12:59:31 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 40253440; Write bytes: 510506496; Read time: 256; Write time: 1593 12:59:31 INFO - TinderboxPrint: CPU usage
54.3% 12:59:31 INFO - TinderboxPrint: I/O read bytes / time
40,253,440 / 256 12:59:31 INFO - TinderboxPrint: I/O write bytes / time
510,506,496 / 1,593 12:59:31 INFO - TinderboxPrint: CPU idle
2,184.8 (45.7%) 12:59:31 INFO - TinderboxPrint: CPU system
952.5 (19.9%) 12:59:31 INFO - TinderboxPrint: CPU user
1,648.4 (34.4%) 12:59:31 INFO - TinderboxPrint: Swap in / out
249,114,624 / 0 12:59:31 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 204245504; Write bytes: 200882176; Read time: 15590; Write time: 424 12:59:31 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 38180864; Write bytes: 298692096; Read time: 237; Write time: 1132 12:59:32 INFO - Running post-run listener: _upload_blobber_files 12:59:32 INFO - Blob upload gear active. 12:59:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:59:32 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:59:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:59:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:59:32 INFO - (blobuploader) - INFO - Open directory for files ... 12:59:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:59:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:59:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:59:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:59:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:59:33 INFO - (blobuploader) - INFO - Done attempting. 12:59:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:59:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:59:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:59:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:59:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:59:34 INFO - (blobuploader) - INFO - Done attempting. 12:59:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:59:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:59:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:59:35 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:59:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:59:35 INFO - (blobuploader) - INFO - Done attempting. 12:59:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:59:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:59:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:59:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:59:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:59:37 INFO - (blobuploader) - INFO - Done attempting. 12:59:37 INFO - (blobuploader) - INFO - Iteration through files over. 12:59:37 INFO - Return code: 0 12:59:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:59:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:59:37 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0dc8f52ed4c18e98dbfe6f90ad280199a27733b0285fa1b647f36cb611334de050a251a7445c73ec83c4b4119501e099daf1332a8f4ba4e09568b18f8a49b63f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dd16c882c0a62330f863ae9a3fd2435f07c1f556a71741325ea935194eab131de98e6f722d326fd87f71fd4f0bb1127fd91e58f63eaf5f03cc47fc645b09e926", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a2e9be45917051813bd3532255aa04f4444acb9a6e6ab8565419b9f230c3463b526ecd13a0d2ce02e9c29ffa47d2bbb0c2b266519a630ce8d51ee2306457f335", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d18cc326df683a00514cbe0889d578916853cc14a7962e353204e3f21bfbc8211a300e176ccc47c090c86650b89701dd63b9bb1b99399bc83c546a9bbd53f3fa"} 12:59:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:59:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:59:37 INFO - Contents: 12:59:37 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0dc8f52ed4c18e98dbfe6f90ad280199a27733b0285fa1b647f36cb611334de050a251a7445c73ec83c4b4119501e099daf1332a8f4ba4e09568b18f8a49b63f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dd16c882c0a62330f863ae9a3fd2435f07c1f556a71741325ea935194eab131de98e6f722d326fd87f71fd4f0bb1127fd91e58f63eaf5f03cc47fc645b09e926", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a2e9be45917051813bd3532255aa04f4444acb9a6e6ab8565419b9f230c3463b526ecd13a0d2ce02e9c29ffa47d2bbb0c2b266519a630ce8d51ee2306457f335", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d18cc326df683a00514cbe0889d578916853cc14a7962e353204e3f21bfbc8211a300e176ccc47c090c86650b89701dd63b9bb1b99399bc83c546a9bbd53f3fa"} 12:59:37 INFO - Running post-run listener: copy_logs_to_upload_dir 12:59:37 INFO - Copying logs to upload dir... 12:59:37 INFO - mkdir: /builds/slave/test/build/upload/logs 12:59:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.601506 ========= master_lag: -0.10 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-06-05 12:59:37.288708) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 12:59:37.291982) ========= 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.lDs5ADwmkP/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.f4LgI4DZwi/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0dc8f52ed4c18e98dbfe6f90ad280199a27733b0285fa1b647f36cb611334de050a251a7445c73ec83c4b4119501e099daf1332a8f4ba4e09568b18f8a49b63f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dd16c882c0a62330f863ae9a3fd2435f07c1f556a71741325ea935194eab131de98e6f722d326fd87f71fd4f0bb1127fd91e58f63eaf5f03cc47fc645b09e926", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a2e9be45917051813bd3532255aa04f4444acb9a6e6ab8565419b9f230c3463b526ecd13a0d2ce02e9c29ffa47d2bbb0c2b266519a630ce8d51ee2306457f335", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d18cc326df683a00514cbe0889d578916853cc14a7962e353204e3f21bfbc8211a300e176ccc47c090c86650b89701dd63b9bb1b99399bc83c546a9bbd53f3fa"} build_url:https://queue.taskcluster.net/v1/task/Q9Y9fgvWSD-t9pZ3qL1t3g/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011112 build_url: 'https://queue.taskcluster.net/v1/task/Q9Y9fgvWSD-t9pZ3qL1t3g/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0dc8f52ed4c18e98dbfe6f90ad280199a27733b0285fa1b647f36cb611334de050a251a7445c73ec83c4b4119501e099daf1332a8f4ba4e09568b18f8a49b63f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dd16c882c0a62330f863ae9a3fd2435f07c1f556a71741325ea935194eab131de98e6f722d326fd87f71fd4f0bb1127fd91e58f63eaf5f03cc47fc645b09e926", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a2e9be45917051813bd3532255aa04f4444acb9a6e6ab8565419b9f230c3463b526ecd13a0d2ce02e9c29ffa47d2bbb0c2b266519a630ce8d51ee2306457f335", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d18cc326df683a00514cbe0889d578916853cc14a7962e353204e3f21bfbc8211a300e176ccc47c090c86650b89701dd63b9bb1b99399bc83c546a9bbd53f3fa"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 12:59:37.326528) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 12:59:37.326968) ========= 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.lDs5ADwmkP/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.f4LgI4DZwi/Listeners TMPDIR=/var/folders/05/tvbsjp3n2dgc0q2v0dhyg30c00000w/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.005066 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 12:59:37.376757) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 12:59:37.377147) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 12:59:37.377524) ========= ========= Total master_lag: 0.14 =========