builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0229 starttime: 1497472675.01 results: success (0) buildid: 20170614122518 builduid: 5f31aebdbd604dd9815ba4df06f28641 revision: 9b5584b94beb70d24a194ffad513ea30f1ab9dfd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-14 13:37:55.005482) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-14 13:37:55.005873) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-14 13:37:55.006143) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-14 13:37:55.029195) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 13:37:55.029505) ========= 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.SaVKK6qIYe/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.5zXC6dXmKm/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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-14 13:37:55-- 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% 132M=0s 2017-06-14 13:37:55 (132 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.151232 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 13:37:55.201784) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 13:37:55.202091) ========= 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.SaVKK6qIYe/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.5zXC6dXmKm/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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.023798 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 13:37:55.252059) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-14 13:37:55.252393) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9b5584b94beb70d24a194ffad513ea30f1ab9dfd --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 9b5584b94beb70d24a194ffad513ea30f1ab9dfd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SaVKK6qIYe/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.5zXC6dXmKm/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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-14 13:37:55,304 truncating revision to first 12 chars 2017-06-14 13:37:55,304 Setting DEBUG logging. 2017-06-14 13:37:55,304 attempt 1/10 2017-06-14 13:37:55,304 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/9b5584b94beb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-14 13:37:56,204 unpacking tar archive at: mozilla-beta-9b5584b94beb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.172340 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-14 13:37:56.447057) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-14 13:37:56.447377) ========= script_repo_revision: 9b5584b94beb70d24a194ffad513ea30f1ab9dfd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-14 13:37:56.447764) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-14 13:37:56.448026) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-14 13:37:56.465901) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 14 secs) (at 2017-06-14 13:37:56.466260) ========= /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.SaVKK6qIYe/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.5zXC6dXmKm/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 13:37:56 INFO - MultiFileLogger online at 20170614 13:37:56 in /builds/slave/test 13:37:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 13:37:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:37:56 INFO - {'append_to_log': False, 13:37:56 INFO - 'base_work_dir': '/builds/slave/test', 13:37:56 INFO - 'blob_upload_branch': 'Mozilla-Beta', 13:37:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:37:56 INFO - 'branch': 'Mozilla-Beta', 13:37:56 INFO - 'buildbot_json_path': 'buildprops.json', 13:37:56 INFO - 'config_files': ('talos/mac_config.py',), 13:37:56 INFO - 'default_actions': ('clobber', 13:37:56 INFO - 'read-buildbot-config', 13:37:56 INFO - 'download-and-extract', 13:37:56 INFO - 'populate-webroot', 13:37:56 INFO - 'create-virtualenv', 13:37:56 INFO - 'install', 13:37:56 INFO - 'setup-mitmproxy', 13:37:56 INFO - 'run-tests'), 13:37:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:37:56 INFO - 'download_minidump_stackwalk': True, 13:37:56 INFO - 'download_symbols': 'ondemand', 13:37:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:37:56 INFO - 'tooltool.py': '/tools/tooltool.py', 13:37:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:37:56 INFO - '/tools/misc-python/virtualenv.py')}, 13:37:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:37:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:37:56 INFO - 'gecko_profile': False, 13:37:56 INFO - 'gecko_profile_interval': 0, 13:37:56 INFO - 'installer_path': 'installer.exe', 13:37:56 INFO - 'log_level': 'info', 13:37:56 INFO - 'log_name': 'talos', 13:37:56 INFO - 'log_to_console': True, 13:37:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:37:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:37:56 INFO - 'opt_config_files': (), 13:37:56 INFO - 'pip_index': False, 13:37:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:37:56 INFO - 'cmd': ('bash', 13:37:56 INFO - '-c', 13:37:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:37:56 INFO - 'enabled': True, 13:37:56 INFO - 'halt_on_failure': False, 13:37:56 INFO - 'name': 'check_screen_resolution'},), 13:37:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:37:56 INFO - 'cmd': ('bash', 13:37:56 INFO - '-c', 13:37:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:37:56 INFO - 'enabled': True, 13:37:56 INFO - 'halt_on_failure': False, 13:37:56 INFO - 'name': 'check_screen_resolution'},), 13:37:56 INFO - 'run_cmd_checks_enabled': True, 13:37:56 INFO - 'suite': 'dromaeojs-e10s', 13:37:56 INFO - 'system_bits': '32', 13:37:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:37:56 INFO - 'title': 't-yosemite-r7-0229', 13:37:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:37:56 INFO - 'use_talos_json': True, 13:37:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:37:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:37:56 INFO - 'work_dir': 'build'} 13:37:56 INFO - [mozharness: 2017-06-14 20:37:56.638164Z] Running clobber step. 13:37:56 INFO - Running pre-action listener: _resource_record_pre_action 13:37:56 INFO - Running main action method: clobber 13:37:56 INFO - rmtree: /builds/slave/test/build 13:37:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:37:59 INFO - Running post-action listener: _resource_record_post_action 13:37:59 INFO - [mozharness: 2017-06-14 20:37:59.010194Z] Finished clobber step (success) 13:37:59 INFO - [mozharness: 2017-06-14 20:37:59.010334Z] Running read-buildbot-config step. 13:37:59 INFO - Running pre-action listener: _resource_record_pre_action 13:37:59 INFO - Running main action method: read_buildbot_config 13:37:59 INFO - Using buildbot properties: 13:37:59 INFO - { 13:37:59 INFO - "project": "", 13:37:59 INFO - "product": "firefox", 13:37:59 INFO - "script_repo_revision": "production", 13:37:59 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 13:37:59 INFO - "repository": "", 13:37:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 13:37:59 INFO - "buildid": "20170614122518", 13:37:59 INFO - "pgo_build": "False", 13:37:59 INFO - "basedir": "/builds/slave/test", 13:37:59 INFO - "buildnumber": 2, 13:37:59 INFO - "slavename": "t-yosemite-r7-0229", 13:37:59 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 13:37:59 INFO - "platform": "yosemite_r7_devedition", 13:37:59 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 13:37:59 INFO - "branch": "mozilla-beta", 13:37:59 INFO - "repo_path": "releases/mozilla-beta", 13:37:59 INFO - "revision": "9b5584b94beb70d24a194ffad513ea30f1ab9dfd", 13:37:59 INFO - "stage_platform": "macosx64-devedition", 13:37:59 INFO - "builduid": "5f31aebdbd604dd9815ba4df06f28641", 13:37:59 INFO - "slavebuilddir": "test" 13:37:59 INFO - } 13:37:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/Hl-OmLE2RiGJ7mHRbYqIZQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg. 13:37:59 INFO - Running post-action listener: _resource_record_post_action 13:37:59 INFO - [mozharness: 2017-06-14 20:37:59.014718Z] Finished read-buildbot-config step (success) 13:37:59 INFO - [mozharness: 2017-06-14 20:37:59.014878Z] Running download-and-extract step. 13:37:59 INFO - Running pre-action listener: _resource_record_pre_action 13:37:59 INFO - Running main action method: download_and_extract 13:37:59 INFO - mkdir: /builds/slave/test/build/tests 13:37:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:37:59 INFO - trying https://queue.taskcluster.net/v1/task/Hl-OmLE2RiGJ7mHRbYqIZQ/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 13:37:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Hl-OmLE2RiGJ7mHRbYqIZQ/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 13:37:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hl-OmLE2RiGJ7mHRbYqIZQ/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json'}, attempt #1 13:38:00 INFO - Downloaded 1290 bytes. 13:38:00 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 13:38:00 INFO - Using the following test package requirements: 13:38:00 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 13:38:00 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 13:38:00 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 13:38:00 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 13:38:00 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 13:38:00 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 13:38:00 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 13:38:00 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 13:38:00 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 13:38:00 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 13:38:00 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 13:38:00 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 13:38:00 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 13:38:00 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 13:38:00 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 13:38:00 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 13:38:00 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 13:38:00 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 13:38:00 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 13:38:00 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 13:38:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hl-OmLE2RiGJ7mHRbYqIZQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 13:38:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hl-OmLE2RiGJ7mHRbYqIZQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 13:38:00 INFO - Fetch https://queue.taskcluster.net/v1/task/Hl-OmLE2RiGJ7mHRbYqIZQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 13:38:02 INFO - Content-Length response header: 59545124 13:38:02 INFO - Bytes received: 59545124 13:38:19 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip', u'firefox-55.0.en-US.mac.talos.tests.zip'] for test suite category: talos 13:38:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hl-OmLE2RiGJ7mHRbYqIZQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 13:38:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hl-OmLE2RiGJ7mHRbYqIZQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 13:38:19 INFO - Fetch https://queue.taskcluster.net/v1/task/Hl-OmLE2RiGJ7mHRbYqIZQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 13:38:22 INFO - Content-Length response header: 59545124 13:38:22 INFO - Bytes received: 59545124 13:38:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Hl-OmLE2RiGJ7mHRbYqIZQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 13:38:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Hl-OmLE2RiGJ7mHRbYqIZQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 13:38:39 INFO - Fetch https://queue.taskcluster.net/v1/task/Hl-OmLE2RiGJ7mHRbYqIZQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 13:38:40 INFO - Content-Length response header: 14450018 13:38:40 INFO - Bytes received: 14450018 13:38:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:38:40 INFO - trying https://queue.taskcluster.net/v1/task/Hl-OmLE2RiGJ7mHRbYqIZQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 13:38:40 INFO - Downloading https://queue.taskcluster.net/v1/task/Hl-OmLE2RiGJ7mHRbYqIZQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 13:38:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Hl-OmLE2RiGJ7mHRbYqIZQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg'}, attempt #1 13:38:42 INFO - Downloaded 66879106 bytes. 13:38:42 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Hl-OmLE2RiGJ7mHRbYqIZQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 13:38:42 INFO - mkdir: /builds/slave/test/properties 13:38:42 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:38:42 INFO - Writing to file /builds/slave/test/properties/build_url 13:38:42 INFO - Contents: 13:38:42 INFO - build_url:https://queue.taskcluster.net/v1/task/Hl-OmLE2RiGJ7mHRbYqIZQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 13:38:43 INFO - Running post-action listener: _resource_record_post_action 13:38:43 INFO - Running post-action listener: set_extra_try_arguments 13:38:43 INFO - [mozharness: 2017-06-14 20:38:43.520533Z] Finished download-and-extract step (success) 13:38:43 INFO - [mozharness: 2017-06-14 20:38:43.520670Z] Running populate-webroot step. 13:38:43 INFO - Running pre-action listener: _resource_record_pre_action 13:38:43 INFO - Running main action method: populate_webroot 13:38:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:38:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:38:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:38:43 INFO - '--tppagecycles', 13:38:43 INFO - '7'], 13:38:43 INFO - 'tests': ['tsvgm']}}, 13:38:43 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:38:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:38:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:38:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:38:43 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:38:43 INFO - 'talos_options': ['--disable-e10s'], 13:38:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:38:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:38:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:38:43 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:38:43 INFO - 'talos_options': ['--disable-e10s'], 13:38:43 INFO - 'tests': ['damp', 'tps']}, 13:38:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:38:43 INFO - 'tests': ['damp', 'tps']}, 13:38:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:38:43 INFO - 'tests': ['dromaeo_dom']}, 13:38:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:38:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:38:43 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 13:38:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 13:38:43 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 13:38:43 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 13:38:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:38:43 INFO - 'tests': ['a11yr', 13:38:43 INFO - 'ts_paint', 13:38:43 INFO - 'tpaint', 13:38:43 INFO - 'sessionrestore', 13:38:43 INFO - 'sessionrestore_no_auto_restore', 13:38:43 INFO - 'tabpaint']}, 13:38:43 INFO - 'other-e10s': {'tests': ['a11yr', 13:38:43 INFO - 'ts_paint', 13:38:43 INFO - 'tpaint', 13:38:43 INFO - 'sessionrestore', 13:38:43 INFO - 'sessionrestore_no_auto_restore', 13:38:43 INFO - 'tabpaint']}, 13:38:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:38:43 INFO - 'ts_paint', 13:38:43 INFO - 'tpaint', 13:38:43 INFO - 'sessionrestore', 13:38:43 INFO - 'sessionrestore_no_auto_restore', 13:38:43 INFO - 'tabpaint']}, 13:38:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:38:43 INFO - 'ts_paint', 13:38:43 INFO - 'tpaint', 13:38:43 INFO - 'sessionrestore', 13:38:43 INFO - 'sessionrestore_no_auto_restore', 13:38:43 INFO - 'tabpaint']}, 13:38:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:38:43 INFO - 'tests': ['a11yr', 13:38:43 INFO - 'ts_paint', 13:38:43 INFO - 'tpaint', 13:38:43 INFO - 'sessionrestore', 13:38:43 INFO - 'sessionrestore_no_auto_restore', 13:38:43 INFO - 'tabpaint']}, 13:38:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:38:43 INFO - 'tests': ['a11yr', 13:38:43 INFO - 'ts_paint', 13:38:43 INFO - 'tpaint', 13:38:43 INFO - 'sessionrestore', 13:38:43 INFO - 'sessionrestore_no_auto_restore', 13:38:43 INFO - 'tabpaint']}, 13:38:43 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 13:38:43 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 13:38:43 INFO - 'bloom_basic_ref']}, 13:38:43 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 13:38:43 INFO - 'talos_options': ['--mitmproxy', 13:38:43 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 13:38:43 INFO - '--firstNonBlankPaint'], 13:38:43 INFO - 'tests': ['quantum_pageload_google', 13:38:43 INFO - 'quantum_pageload_youtube', 13:38:43 INFO - 'quantum_pageload_amazon', 13:38:43 INFO - 'quantum_pageload_facebook']}, 13:38:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:38:43 INFO - 'tests': ['tsvgx', 13:38:43 INFO - 'tsvgr_opacity', 13:38:43 INFO - 'tart', 13:38:43 INFO - 'tscrollx', 13:38:43 INFO - 'cart', 13:38:43 INFO - 'tsvg_static']}, 13:38:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:38:43 INFO - 'tsvgr_opacity', 13:38:43 INFO - 'tart', 13:38:43 INFO - 'tscrollx', 13:38:43 INFO - 'cart', 13:38:43 INFO - 'tsvg_static']}, 13:38:43 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:38:43 INFO - 'talos_options': ['--disable-e10s'], 13:38:43 INFO - 'tests': ['tp5o']}, 13:38:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:38:43 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:38:43 INFO - 'talos_options': ['--disable-e10s', 13:38:43 INFO - '--xperf_path', 13:38:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:38:43 INFO - 'tests': ['tp5n']}, 13:38:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:38:43 INFO - 'talos_options': ['--xperf_path', 13:38:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:38:43 INFO - 'tests': ['tp5n']}}, 13:38:43 INFO - 'talos.zip': {'path': '', 13:38:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:38:43 INFO - Running post-action listener: _resource_record_post_action 13:38:43 INFO - [mozharness: 2017-06-14 20:38:43.542027Z] Finished populate-webroot step (success) 13:38:43 INFO - [mozharness: 2017-06-14 20:38:43.542138Z] Running create-virtualenv step. 13:38:43 INFO - Running pre-action listener: _resource_record_pre_action 13:38:43 INFO - Running main action method: create_virtualenv 13:38:43 INFO - Creating virtualenv /builds/slave/test/build/venv 13:38:43 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 13:38:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:38:43 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 13:38:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:38:43 INFO - Using real prefix '/tools/python27' 13:38:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:38:44 INFO - Installing distribute.............................................................................................................................................................................................done. 13:38:47 INFO - Installing pip.................done. 13:38:47 INFO - Return code: 0 13:38:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:38:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:38:47 INFO - Reading from file tmpfile_stdout 13:38:47 INFO - Output received: 13:38:47 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 13:38:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:38:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:38:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:38:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:47 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 0x100fecc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ff0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faeb84a0030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d42d30>, '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 0x100d0ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d33be8>, '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.SaVKK6qIYe/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.5zXC6dXmKm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:38:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:38:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:38:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SaVKK6qIYe/Render', 13:38:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:38:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:38:47 INFO - 'HOME': '/Users/cltbld', 13:38:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:38:47 INFO - 'LOGNAME': 'cltbld', 13:38:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:47 INFO - 'MOZ_NO_REMOTE': '1', 13:38:47 INFO - 'NO_EM_RESTART': '1', 13:38:47 INFO - 'PAGER': '/bin/cat', 13:38:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:38:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:47 INFO - 'PWD': '/builds/slave/test', 13:38:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:38:47 INFO - 'SHELL': '/bin/bash', 13:38:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5zXC6dXmKm/Listeners', 13:38:47 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 13:38:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:38:47 INFO - 'USER': 'cltbld', 13:38:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:38:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:38:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:47 INFO - 'XPC_FLAGS': '0x0', 13:38:47 INFO - 'XPC_SERVICE_NAME': '0', 13:38:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:38:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:47 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 13:38:47 INFO - Cleaning up... 13:38:47 INFO - Return code: 0 13:38:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:38:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:38:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:38:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:47 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 0x100fecc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ff0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faeb84a0030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d42d30>, '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 0x100d0ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d33be8>, '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.SaVKK6qIYe/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.5zXC6dXmKm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:38:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 13:38:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 13:38:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SaVKK6qIYe/Render', 13:38:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:38:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:38:47 INFO - 'HOME': '/Users/cltbld', 13:38:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:38:47 INFO - 'LOGNAME': 'cltbld', 13:38:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:47 INFO - 'MOZ_NO_REMOTE': '1', 13:38:47 INFO - 'NO_EM_RESTART': '1', 13:38:47 INFO - 'PAGER': '/bin/cat', 13:38:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:38:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:47 INFO - 'PWD': '/builds/slave/test', 13:38:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:38:47 INFO - 'SHELL': '/bin/bash', 13:38:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5zXC6dXmKm/Listeners', 13:38:47 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 13:38:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:38:47 INFO - 'USER': 'cltbld', 13:38:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:38:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:38:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:47 INFO - 'XPC_FLAGS': '0x0', 13:38:47 INFO - 'XPC_SERVICE_NAME': '0', 13:38:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:38:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:48 INFO - Downloading/unpacking psutil>=3.1.1 13:38:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:38:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:38:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:38:49 INFO - Installing collected packages: psutil 13:38:49 INFO - Running setup.py install for psutil 13:38:49 INFO - building 'psutil._psutil_osx' extension 13:38:49 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 13:38:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 13:38:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 13:38:50 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 13:38:50 INFO - building 'psutil._psutil_posix' extension 13:38:50 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 13:38:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:38:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:38:50 INFO - ^ 13:38:50 INFO - 1 warning generated. 13:38:50 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 13:38:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:38:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:38:50 INFO - Successfully installed psutil 13:38:50 INFO - Cleaning up... 13:38:50 INFO - Return code: 0 13:38:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:38:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:38:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:50 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 0x100fecc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ff0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faeb84a0030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d42d30>, '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 0x100d0ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d33be8>, '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.SaVKK6qIYe/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.5zXC6dXmKm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:38:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 13:38:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 13:38:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SaVKK6qIYe/Render', 13:38:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:38:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:38:50 INFO - 'HOME': '/Users/cltbld', 13:38:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:38:50 INFO - 'LOGNAME': 'cltbld', 13:38:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:50 INFO - 'MOZ_NO_REMOTE': '1', 13:38:50 INFO - 'NO_EM_RESTART': '1', 13:38:50 INFO - 'PAGER': '/bin/cat', 13:38:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:38:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:50 INFO - 'PWD': '/builds/slave/test', 13:38:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:38:50 INFO - 'SHELL': '/bin/bash', 13:38:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5zXC6dXmKm/Listeners', 13:38:50 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 13:38:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:38:50 INFO - 'USER': 'cltbld', 13:38:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:38:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:38:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:50 INFO - 'XPC_FLAGS': '0x0', 13:38:50 INFO - 'XPC_SERVICE_NAME': '0', 13:38:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:38:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:38:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:38:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:38:52 INFO - Installing collected packages: mozsystemmonitor 13:38:52 INFO - Running setup.py install for mozsystemmonitor 13:38:52 INFO - Successfully installed mozsystemmonitor 13:38:52 INFO - Cleaning up... 13:38:52 INFO - Return code: 0 13:38:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:38:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:38:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:38:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:53 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 0x100fecc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ff0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faeb84a0030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d42d30>, '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 0x100d0ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d33be8>, '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.SaVKK6qIYe/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.5zXC6dXmKm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:38:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 13:38:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 13:38:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SaVKK6qIYe/Render', 13:38:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:38:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:38:53 INFO - 'HOME': '/Users/cltbld', 13:38:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:38:53 INFO - 'LOGNAME': 'cltbld', 13:38:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:53 INFO - 'MOZ_NO_REMOTE': '1', 13:38:53 INFO - 'NO_EM_RESTART': '1', 13:38:53 INFO - 'PAGER': '/bin/cat', 13:38:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:38:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:53 INFO - 'PWD': '/builds/slave/test', 13:38:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:38:53 INFO - 'SHELL': '/bin/bash', 13:38:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5zXC6dXmKm/Listeners', 13:38:53 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 13:38:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:38:53 INFO - 'USER': 'cltbld', 13:38:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:38:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:38:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:53 INFO - 'XPC_FLAGS': '0x0', 13:38:53 INFO - 'XPC_SERVICE_NAME': '0', 13:38:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:38:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:53 INFO - Downloading/unpacking jsonschema==2.5.1 13:38:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:38:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:38:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:55 INFO - Downloading functools32-3.2.3-2.tar.gz 13:38:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:38:55 INFO - warning: no files found matching '*.txt' 13:38:55 INFO - no previously-included directories found matching 'build' 13:38:55 INFO - no previously-included directories found matching 'dist' 13:38:55 INFO - no previously-included directories found matching '.git*' 13:38:55 INFO - Installing collected packages: jsonschema, functools32 13:38:55 INFO - Running setup.py install for functools32 13:38:55 INFO - warning: no files found matching '*.txt' 13:38:55 INFO - no previously-included directories found matching 'build' 13:38:55 INFO - no previously-included directories found matching 'dist' 13:38:55 INFO - no previously-included directories found matching '.git*' 13:38:55 INFO - Successfully installed jsonschema functools32 13:38:55 INFO - Cleaning up... 13:38:55 INFO - Return code: 0 13:38:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:38: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')]} 13:38:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38: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')]} 13:38:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:55 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 0x100fecc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ff0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faeb84a0030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d42d30>, '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 0x100d0ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d33be8>, '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.SaVKK6qIYe/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.5zXC6dXmKm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:38:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 13:38:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 13:38:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SaVKK6qIYe/Render', 13:38:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:38:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:38:55 INFO - 'HOME': '/Users/cltbld', 13:38:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:38:55 INFO - 'LOGNAME': 'cltbld', 13:38:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:55 INFO - 'MOZ_NO_REMOTE': '1', 13:38:55 INFO - 'NO_EM_RESTART': '1', 13:38:55 INFO - 'PAGER': '/bin/cat', 13:38:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:38:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:55 INFO - 'PWD': '/builds/slave/test', 13:38:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:38:55 INFO - 'SHELL': '/bin/bash', 13:38:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5zXC6dXmKm/Listeners', 13:38:55 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 13:38:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:38:55 INFO - 'USER': 'cltbld', 13:38:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:38:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:38:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:55 INFO - 'XPC_FLAGS': '0x0', 13:38:55 INFO - 'XPC_SERVICE_NAME': '0', 13:38:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:38:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:38:55 INFO - Cleaning up... 13:38:55 INFO - Return code: 0 13:38:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:38: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')]} 13:38:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38: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')]} 13:38:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:55 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 0x100fecc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ff0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faeb84a0030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d42d30>, '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 0x100d0ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d33be8>, '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.SaVKK6qIYe/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.5zXC6dXmKm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:38:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:38:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:38:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SaVKK6qIYe/Render', 13:38:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:38:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:38:55 INFO - 'HOME': '/Users/cltbld', 13:38:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:38:55 INFO - 'LOGNAME': 'cltbld', 13:38:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:55 INFO - 'MOZ_NO_REMOTE': '1', 13:38:55 INFO - 'NO_EM_RESTART': '1', 13:38:55 INFO - 'PAGER': '/bin/cat', 13:38:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:38:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:55 INFO - 'PWD': '/builds/slave/test', 13:38:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:38:55 INFO - 'SHELL': '/bin/bash', 13:38:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5zXC6dXmKm/Listeners', 13:38:55 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 13:38:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:38:55 INFO - 'USER': 'cltbld', 13:38:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:38:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:38:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:55 INFO - 'XPC_FLAGS': '0x0', 13:38:55 INFO - 'XPC_SERVICE_NAME': '0', 13:38:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:38:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:56 INFO - Downloading/unpacking blobuploader==1.2.4 13:38:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:57 INFO - Downloading blobuploader-1.2.4.tar.gz 13:38:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:38:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:38:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:38:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:38:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:38:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:38:58 INFO - Downloading docopt-0.6.1.tar.gz 13:38:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:38:58 INFO - Installing collected packages: blobuploader, requests, docopt 13:38:58 INFO - Running setup.py install for blobuploader 13:38:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:38:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:38:58 INFO - Running setup.py install for requests 13:38:59 INFO - Running setup.py install for docopt 13:38:59 INFO - Successfully installed blobuploader requests docopt 13:38:59 INFO - Cleaning up... 13:38:59 INFO - Return code: 0 13:38:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:38:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:38:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:38:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:38:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:38:59 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 0x100fecc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ff0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faeb84a0030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d42d30>, '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 0x100d0ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d33be8>, '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.SaVKK6qIYe/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.5zXC6dXmKm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:38:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:38:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:38:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SaVKK6qIYe/Render', 13:38:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:38:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:38:59 INFO - 'HOME': '/Users/cltbld', 13:38:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:38:59 INFO - 'LOGNAME': 'cltbld', 13:38:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:38:59 INFO - 'MOZ_NO_REMOTE': '1', 13:38:59 INFO - 'NO_EM_RESTART': '1', 13:38:59 INFO - 'PAGER': '/bin/cat', 13:38:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:38:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:38:59 INFO - 'PWD': '/builds/slave/test', 13:38:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:38:59 INFO - 'SHELL': '/bin/bash', 13:38:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5zXC6dXmKm/Listeners', 13:38:59 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 13:38:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:38:59 INFO - 'USER': 'cltbld', 13:38:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:38:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:38:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:38:59 INFO - 'XPC_FLAGS': '0x0', 13:38:59 INFO - 'XPC_SERVICE_NAME': '0', 13:38:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:38:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:38:59 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-cESCJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:38:59 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-zwTSJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:38:59 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-V59Tzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:38:59 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-XiF7iA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:38:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:38:59 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-RtLKQx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:39:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:39:00 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-bxCHcR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:39:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:39:00 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-KRullc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:39:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:39:00 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-5m7UeT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:39:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:39:00 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-ghlmSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:39:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:39:00 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-0aQJ1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:39:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:39:00 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-SfrrtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:39:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:39:00 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-XzHC8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:39:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:39:00 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-qBp3MH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:39:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:39:00 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-cDuekp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:39:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:39:01 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-GeofJs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:39:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:39:01 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-4zn0Tm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:39:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:39:01 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-rXPRQ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:39:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:39:01 INFO - Running setup.py install for manifestparser 13:39:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:39:01 INFO - Running setup.py install for mozcrash 13:39:01 INFO - Running setup.py install for mozdebug 13:39:01 INFO - Running setup.py install for mozdevice 13:39:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:39:01 INFO - Running setup.py install for mozfile 13:39:02 INFO - Running setup.py install for mozhttpd 13:39:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:39:02 INFO - Running setup.py install for mozinfo 13:39:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:39:02 INFO - Running setup.py install for mozInstall 13:39:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:39:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:39:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:39:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:39:02 INFO - Running setup.py install for mozleak 13:39:02 INFO - Running setup.py install for mozlog 13:39:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:39:02 INFO - Running setup.py install for moznetwork 13:39:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:39:02 INFO - Running setup.py install for mozprocess 13:39:03 INFO - Running setup.py install for mozprofile 13:39:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:39:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:39:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:39:03 INFO - Running setup.py install for mozrunner 13:39:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:39:03 INFO - Running setup.py install for mozscreenshot 13:39:03 INFO - Running setup.py install for moztest 13:39:03 INFO - Running setup.py install for mozversion 13:39:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:39:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:39:03 INFO - Cleaning up... 13:39:03 INFO - Return code: 0 13:39:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:39:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:39:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:39:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:39:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:39:03 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 0x100fecc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ff0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faeb84a0030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d42d30>, '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 0x100d0ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d33be8>, '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.SaVKK6qIYe/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.5zXC6dXmKm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:39:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:39:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:39:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SaVKK6qIYe/Render', 13:39:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:39:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:39:03 INFO - 'HOME': '/Users/cltbld', 13:39:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:39:03 INFO - 'LOGNAME': 'cltbld', 13:39:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:39:03 INFO - 'MOZ_NO_REMOTE': '1', 13:39:03 INFO - 'NO_EM_RESTART': '1', 13:39:03 INFO - 'PAGER': '/bin/cat', 13:39:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:39:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:39:03 INFO - 'PWD': '/builds/slave/test', 13:39:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:39:03 INFO - 'SHELL': '/bin/bash', 13:39:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5zXC6dXmKm/Listeners', 13:39:03 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 13:39:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:39:03 INFO - 'USER': 'cltbld', 13:39:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:39:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:39:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:39:03 INFO - 'XPC_FLAGS': '0x0', 13:39:03 INFO - 'XPC_SERVICE_NAME': '0', 13:39:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:39:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:39:04 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-uDaSsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:39:04 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 13:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:39:04 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-XKtMRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:39:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:39:04 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-opBZJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:39:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:39:04 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-aheZTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:39:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:39:04 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-X66amg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:39:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 13:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:39:04 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-SRnvqT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:39:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 13:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:39:04 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-0cdYTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:39:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 13:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:39:04 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-CLqKHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:39:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 13:39:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:39:04 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-quWX23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:39:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 13:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:39:05 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-4JjgOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:39:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:39:05 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-VfqlAH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:39:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 13:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:39:05 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-p23s3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:39:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 13:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:39:05 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-GFVx1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:39:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 13:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:39:05 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-Lvkspp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:39:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 13:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:39:05 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-uoTDnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:39:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 13:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:39:05 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-E_s_yj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:39:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 13:39:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:39:05 INFO - Running setup.py (path:/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/pip-pEKluY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:39:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 13:39:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:39:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:39:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:39:05 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:39:05 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:39:05 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 13:39:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:39:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:39:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:39:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:39:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:39:07 INFO - Downloading blessings-1.6.tar.gz 13:39:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:39:07 INFO - Installing collected packages: blessings 13:39:07 INFO - Running setup.py install for blessings 13:39:07 INFO - Successfully installed blessings 13:39:07 INFO - Cleaning up... 13:39:07 INFO - Return code: 0 13:39:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:39:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:39:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:39:07 INFO - Reading from file tmpfile_stdout 13:39:07 INFO - Current package versions: 13:39:07 INFO - blessings == 1.6 13:39:07 INFO - blobuploader == 1.2.4 13:39:07 INFO - docopt == 0.6.1 13:39:07 INFO - functools32 == 3.2.3-2 13:39:07 INFO - jsonschema == 2.5.1 13:39:07 INFO - manifestparser == 1.1 13:39:07 INFO - mozInstall == 1.13 13:39:07 INFO - mozcrash == 1.0 13:39:07 INFO - mozdebug == 0.1 13:39:07 INFO - mozdevice == 0.51 13:39:07 INFO - mozfile == 1.2 13:39:07 INFO - mozhttpd == 0.7 13:39:07 INFO - mozinfo == 0.10 13:39:07 INFO - mozleak == 0.1 13:39:07 INFO - mozlog == 3.4 13:39:07 INFO - moznetwork == 0.27 13:39:07 INFO - mozprocess == 0.25 13:39:07 INFO - mozprofile == 0.28 13:39:07 INFO - mozrunner == 6.13 13:39:07 INFO - mozscreenshot == 0.1 13:39:07 INFO - mozsystemmonitor == 0.3 13:39:07 INFO - moztest == 0.8 13:39:07 INFO - mozversion == 1.4 13:39:07 INFO - psutil == 3.1.1 13:39:07 INFO - requests == 1.2.3 13:39:07 INFO - wsgiref == 0.1.2 13:39:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:39:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:39:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:39:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:39:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:39:07 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 0x100fecc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ff0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faeb84a0030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d42d30>, '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 0x100d0ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d33be8>, '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.SaVKK6qIYe/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.5zXC6dXmKm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:39:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 13:39:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:39:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SaVKK6qIYe/Render', 13:39:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:39:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:39:07 INFO - 'HOME': '/Users/cltbld', 13:39:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:39:07 INFO - 'LOGNAME': 'cltbld', 13:39:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:39:07 INFO - 'MOZ_NO_REMOTE': '1', 13:39:07 INFO - 'NO_EM_RESTART': '1', 13:39:07 INFO - 'PAGER': '/bin/cat', 13:39:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:39:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:39:07 INFO - 'PWD': '/builds/slave/test', 13:39:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:39:07 INFO - 'SHELL': '/bin/bash', 13:39:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5zXC6dXmKm/Listeners', 13:39:07 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 13:39:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:39:07 INFO - 'USER': 'cltbld', 13:39:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:39:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:39:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:39:07 INFO - 'XPC_FLAGS': '0x0', 13:39:07 INFO - 'XPC_SERVICE_NAME': '0', 13:39:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:39:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:39:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:39:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 13:39:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 13:39:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:39:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 13:39:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 13:39:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 13:39:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 13:39:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 13:39:08 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 13:39:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:39:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:39:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:39:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:39:09 INFO - Downloading marionette_driver-1.4.0.tar.gz 13:39:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 13:39:09 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:39:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:39:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 13:39:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 13:39:09 INFO - Installing collected packages: marionette-driver 13:39:09 INFO - Running setup.py install for marionette-driver 13:39:09 INFO - Successfully installed marionette-driver 13:39:09 INFO - Cleaning up... 13:39:09 INFO - Return code: 0 13:39:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:39:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:39:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:39:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:39:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:39:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100fecc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ff0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faeb84a0030>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d42d30>, '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 0x100d0ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d33be8>, '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.SaVKK6qIYe/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.5zXC6dXmKm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:39:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:39:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:39:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SaVKK6qIYe/Render', 13:39:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:39:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:39:09 INFO - 'HOME': '/Users/cltbld', 13:39:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:39:09 INFO - 'LOGNAME': 'cltbld', 13:39:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:39:09 INFO - 'MOZ_NO_REMOTE': '1', 13:39:09 INFO - 'NO_EM_RESTART': '1', 13:39:09 INFO - 'PAGER': '/bin/cat', 13:39:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:39:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:39:09 INFO - 'PWD': '/builds/slave/test', 13:39:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:39:09 INFO - 'SHELL': '/bin/bash', 13:39:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5zXC6dXmKm/Listeners', 13:39:09 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 13:39:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:39:09 INFO - 'USER': 'cltbld', 13:39:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:39:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:39:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:39:09 INFO - 'XPC_FLAGS': '0x0', 13:39:09 INFO - 'XPC_SERVICE_NAME': '0', 13:39:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:39:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:39:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:39:10 INFO - Cleaning up... 13:39:10 INFO - Return code: 0 13:39:10 INFO - Running post-action listener: _resource_record_post_action 13:39:10 INFO - Running post-action listener: _start_resource_monitoring 13:39:10 INFO - Starting resource monitoring. 13:39:10 INFO - [mozharness: 2017-06-14 20:39:10.249778Z] Finished create-virtualenv step (success) 13:39:10 INFO - [mozharness: 2017-06-14 20:39:10.250930Z] Running install step. 13:39:10 INFO - Running pre-action listener: _resource_record_pre_action 13:39:10 INFO - Running main action method: install 13:39:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:39:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:39:10 INFO - Reading from file tmpfile_stdout 13:39:10 INFO - Detecting whether we're running mozinstall >=1.0... 13:39:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:39:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:39:10 INFO - Reading from file tmpfile_stdout 13:39:10 INFO - Output received: 13:39:10 INFO - Usage: mozinstall [options] installer 13:39:10 INFO - Options: 13:39:10 INFO - -h, --help show this help message and exit 13:39:10 INFO - -d DEST, --destination=DEST 13:39:10 INFO - Directory to install application into. [default: 13:39:10 INFO - "/builds/slave/test"] 13:39:10 INFO - --app=APP Application being installed. [default: firefox] 13:39:10 INFO - mkdir: /builds/slave/test/build/application 13:39:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:39:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0.en-US.mac.dmg --destination /builds/slave/test/build/application 13:39:31 INFO - Reading from file tmpfile_stdout 13:39:31 INFO - Output received: 13:39:31 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 13:39:31 INFO - Running post-action listener: _resource_record_post_action 13:39:31 INFO - [mozharness: 2017-06-14 20:39:31.971455Z] Finished install step (success) 13:39:31 INFO - [mozharness: 2017-06-14 20:39:31.971615Z] Running setup-mitmproxy step. 13:39:31 INFO - Running pre-action listener: _resource_record_pre_action 13:39:31 INFO - Running main action method: setup_mitmproxy 13:39:31 INFO - Skipping: mitmproxy is not required 13:39:31 INFO - Running post-action listener: _resource_record_post_action 13:39:31 INFO - [mozharness: 2017-06-14 20:39:31.972327Z] Finished setup-mitmproxy step (success) 13:39:31 INFO - [mozharness: 2017-06-14 20:39:31.972499Z] Running run-tests step. 13:39:31 INFO - Running pre-action listener: _resource_record_pre_action 13:39:31 INFO - Running main action method: run_tests 13:39:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:39:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:39:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:39:31 INFO - 2017-06-14 13:39:31.987 screenresolution[2059:18951] starting screenresolution argv=screenresolution get 13:39:31 INFO - 2017-06-14 13:39:31.989 screenresolution[2059:18951] Display 0: 1600x1200x32@60 13:39:31 INFO - 2017-06-14 13:39:31.995 screenresolution[2060:18955] starting screenresolution argv=screenresolution list 13:39:31 INFO - Available Modes on Display 0 13:39:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:39:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:39:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:39:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:39:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:39:32 INFO - Intel Iris: 13:39:32 INFO - Chipset Model: Intel Iris 13:39:32 INFO - Type: GPU 13:39:32 INFO - Bus: Built-In 13:39:32 INFO - VRAM (Dynamic, Max): 1536 MB 13:39:32 INFO - Vendor: Intel (0x8086) 13:39:32 INFO - Device ID: 0x0a2e 13:39:32 INFO - Revision ID: 0x0009 13:39:32 INFO - Displays: 13:39:32 INFO - Display: 13:39:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:39:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:39:32 INFO - Display Serial Number: 1600x1200 60 13:39:32 INFO - Main Display: Yes 13:39:32 INFO - Mirror: Off 13:39:32 INFO - Online: Yes 13:39:32 INFO - Rotation: Supported 13:39:32 INFO - Return code: 0 13:39:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:39:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:39:32 INFO - Python 2.7.3 13:39:32 INFO - Return code: 0 13:39:32 INFO - grabbing minidump binary from tooltool 13:39:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:39:32 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 0x100d42d30>, '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 0x100d0ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d33be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:39:32 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 13:39:32 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 13:39:32 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 13:39:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:39:32 INFO - Return code: 0 13:39:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:39:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:39:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:39:32 INFO - ENV: RUST_BACKTRACE is now 1 13:39:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:39:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:39:32 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/Hl-OmLE2RiGJ7mHRbYqIZQ/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0229', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 13:39:32 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/Hl-OmLE2RiGJ7mHRbYqIZQ/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0229 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 13:39:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SaVKK6qIYe/Render', 13:39:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:39:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:39:32 INFO - 'HOME': '/Users/cltbld', 13:39:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:39:32 INFO - 'LOGNAME': 'cltbld', 13:39:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:39:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:39:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:39:32 INFO - 'MOZ_NO_REMOTE': '1', 13:39:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:39:32 INFO - 'NO_EM_RESTART': '1', 13:39:32 INFO - 'PAGER': '/bin/cat', 13:39:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:39:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:39:32 INFO - 'PWD': '/builds/slave/test', 13:39:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:39:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:39:32 INFO - 'RUST_BACKTRACE': '1', 13:39:32 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 13:39:32 INFO - 'SHELL': '/bin/bash', 13:39:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5zXC6dXmKm/Listeners', 13:39:32 INFO - 'TMPDIR': '/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/', 13:39:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:39:32 INFO - 'USER': 'cltbld', 13:39:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:39:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:39:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:39:32 INFO - 'XPC_FLAGS': '0x0', 13:39:32 INFO - 'XPC_SERVICE_NAME': '0', 13:39:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:39:32 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/Hl-OmLE2RiGJ7mHRbYqIZQ/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0229', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 13:39:32 INFO - mozversion application_buildid: 20170614122518 13:39:32 INFO - mozversion application_changeset: 9b5584b94beb70d24a194ffad513ea30f1ab9dfd 13:39:32 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:39:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:39:32 INFO - mozversion application_name: Firefox 13:39:32 INFO - mozversion application_remotingname: firefox-dev 13:39:32 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 13:39:32 INFO - mozversion application_vendor: Mozilla 13:39:32 INFO - mozversion application_version: 55.0 13:39:32 INFO - mozversion platform_buildid: 20170614122518 13:39:32 INFO - mozversion platform_changeset: 9b5584b94beb70d24a194ffad513ea30f1ab9dfd 13:39:32 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 13:39:32 INFO - mozversion platform_version: 55.0 13:39:32 INFO - using testdate: 1497472772 13:39:32 INFO - actual date: 1497472772 13:39:32 INFO - starting webserver on 'localhost:49606' 13:39:32 INFO - SUITE-START | Running 2 tests 13:39:32 INFO - TEST-START | dromaeo_css 13:39:32 INFO - operating with platform_type : mac_ 13:39:32 INFO - Initialising browser for dromaeo_css test... 13:39:32 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49606/getInfo.html -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmpxTrDd7/profile 13:39:32 INFO - TEST-INFO | started process 2070 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49606/getInfo.html -foreground) 13:40:06 INFO - TEST-INFO | 2070: killed by SIGTERM 13:40:06 INFO - Browser initialized. 13:40:06 INFO - Running cycle 1/1 for dromaeo_css test... 13:40:06 INFO - TEST-INFO | started process 2077 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmpxTrDd7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:40:07 INFO - PID 2077 | 2017-06-14 13:40:07.751 plugin-container[2078:19428] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 13:40:07 INFO - PID 2077 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:40:07 INFO - PID 2077 | 2017-06-14 13:40:07.752 plugin-container[2078:19428] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient' 13:40:07 INFO - PID 2077 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:40:07 INFO - PID 2077 | 2017-06-14 13:40:07.752 plugin-container[2078:19428] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:40:08 INFO - PID 2077 | 2017-06-14 13:40:08.494 plugin-container[2079:19473] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x923f, name = 'com.apple.tsm.portname' 13:40:08 INFO - PID 2077 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:40:08 INFO - PID 2077 | 2017-06-14 13:40:08.494 plugin-container[2079:19473] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 13:40:08 INFO - PID 2077 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:40:08 INFO - PID 2077 | 2017-06-14 13:40:08.494 plugin-container[2079:19473] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:40:17 INFO - PID 2077 | RSS: Main: 214761472 13:40:17 INFO - PID 2077 | 13:43:20 INFO - PID 2077 | Cycle 1(1): loaded http://localhost:49606/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49606/tests/dromaeo/cssquery-ext.html) 13:43:22 INFO - PID 2077 | RSS: Main: 239128576 13:43:22 INFO - PID 2077 | 13:46:23 INFO - PID 2077 | Cycle 1(1): loaded http://localhost:49606/tests/dromaeo/cssquery-ext.html (next: http://localhost:49606/tests/dromaeo/cssquery-jquery.html) 13:46:24 INFO - PID 2077 | RSS: Main: 246956032 13:46:24 INFO - PID 2077 | 13:49:26 INFO - PID 2077 | Cycle 1(1): loaded http://localhost:49606/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49606/tests/dromaeo/cssquery-mootools.html) 13:49:26 INFO - PID 2077 | RSS: Main: 249307136 13:49:26 INFO - PID 2077 | 13:52:28 INFO - PID 2077 | Cycle 1(1): loaded http://localhost:49606/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49606/tests/dromaeo/cssquery-prototype.html) 13:52:28 INFO - PID 2077 | RSS: Main: 251973632 13:52:28 INFO - PID 2077 | 13:55:31 INFO - PID 2077 | Cycle 1(1): loaded http://localhost:49606/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49606/tests/dromaeo/cssquery-yui.html) 13:55:32 INFO - PID 2077 | RSS: Main: 252461056 13:55:32 INFO - PID 2077 | 13:58:34 INFO - PID 2077 | Cycle 1(1): loaded http://localhost:49606/tests/dromaeo/cssquery-yui.html (next: http://localhost:49606/tests/dromaeo/cssquery-dojo.html) 13:58:34 INFO - PID 2077 | RSS: Main: 250777600 13:58:34 INFO - PID 2077 | 13:58:34 INFO - PID 2077 | __start_tp_report 13:58:34 INFO - PID 2077 | _x_x_mozilla_page_load 13:58:34 INFO - PID 2077 | _x_x_mozilla_page_load_details 13:58:34 INFO - PID 2077 | |i|pagename|runs| 13:58:34 INFO - PID 2077 | |0;dojo.html;2989.2975150839547;3460.480927860819;3492.982535087325;3569.1077230692313;3629.909252268693;6215.8446038849;6265.404786545277;6266.968665156679;6279.497640188785;6349.301576826557;5262.394824595172;6304.275008374038;6337.461315788152;6347.17486726726;6366.331535188808;5478.424765399635;5479.369872464667;5488;5569.192467092269;5653.095504719246;5513.2005859150395;5525.613207075507;5562.888742225153;5650.0959846424585;5656.264685590867;5364.570834333244;5394.460553944614;5406.837794866121;5412.972935135338;5588.105903055492;5504.146857237135;5515.4760297772045;5544.77820887164;5580.665160090367;5589.189567512729;5471.452854714536;5496.587755918322;5540.806071787468;5542;5548.8624831909765;6199.225096862892;6256.468200202943;6271.686415679198;6290.528210384239;6307.621542707407;6603.834904127387;6698.49761267902;6833;6845.315468453165;6869.725210991554;6768.830779230559;6801.965990170067;6814.795556133324;6847.726090956356;6945.2707465716385;6363.077353783676;6402.135711678957;6427.485801135898;6482.319356467598;6510.08858759774;7090.787276381717;7096.7516136934955;7107.324804143598;7114.50198486101;7146.106736657918;6451.258105317855;6562.67186640666;6577.3422657733845;6719.563228390139;6742.764003259862;6727.966360168168;6746.83132921671;6793.864122717518;6860.451163906875;6904.723811047602;6723.5629684071355;6741.898871516931;6823.215330236987;6831.316868313228;6833.350831670983;6837.760678376332;6842.110525631636;6857.485688573377;6860.588364698134;6862.416694580917;4613.284940834176;5442.482964118402;5443.265159203457;5465.508104270635;5510.559155267649;4181.268278051329;4684.461286951976;4765.547273009059;4855.5387238212315;4864.173090574611;4663.836765713184;4830.951690483121;6327.430531252209;6383.0744542042075;6397.96801015992;5494.2582751328855;6875.931240687629;6917;6917.239103698589;6919.4464442844455;5400.1899715043055;5869.796691678196;5991.101334799728;6368.853606350901;6421.5825971311715;6059.121427393092;6076.361981991916;6104.816855494342;6141.692915354214;6152.692365381713;6844.555103918229;7032.8945065824055;7054.611996340251;7056.7530136446;7080.858382832417;5375;6758.831029224309;6781.42357899584;6803.149606299213;6901.689423976033;5792.768289268382;6963.7214511421;7005.439564834699;7010.859782804213;7038.8944165838575;5694.829155125353;6731.427828634621;6731.764388246388;6778.559393639398;6786.016027676058;6883.862322753617;6893.241743408121;6902.482313826584;6919.896201557081;6921.1002569667635;6496.350364963466;6571.605703657605;6672.266050734318;6685.364890335411;6710.966445167743;6464.159659244457;6541.280459149585;6560.606363618198;6643.867122657616;6644.4020038196795;5305.840824775263;5910.615809972338;6545.607263564201;6557.409833115043;6603.20761508622;6673.766418175341;6695.799126026227;6706.765263215764;6715.4627629790475;6721;6333.619982801047;6381.074744162163;6410.711517981609;6422.678866056585;6531.3468653134305 13:58:34 INFO - PID 2077 | |1;ext.html;763.7059732003179;803.8914746509223;829.601791140253;857.1856736100706;869.5608717597612;36831.26337473249;37513.624863751356;37527;37560.436593451086;37677.24645507088;37072.814635926785;37249;37346.626533734656;37452.81273593635;37497.437538436876;7350.264973502647;8021.438499305051;8165.714200003001;8219.260266576008;8253.917460825405;8157.061937877131;8259.132791056943;8271.710490132846;8272.627731752056;8295.419320647557;8251.876221856677;8295.792605184888;8297.875531867026;8311;8315.625796839156;8244.29923456507;8273.627686754111;8274.627641756135;8279.586020698971;8286.709965151222;8155.592220388957;8162;8204.138565450661;8208.05607355156;8217.876731849026;35822.10444738876;35863;35919.82040089809;35950.28099438023;35967.92096237117;3520.806355650464;3559.2169722661233;3565.1978304881195;3567.5362202913716;3568.107973006748;29502.819887204485;29744.95892643769;29929;29955.700442995727;29964.951226706962;3079.9846000770076;3150.921226969321;3155.7317628001647;3162.5414314924437;3194.4888817789274;262764.6861765698;266148.6692566544;267166.32084197854;334053;338454;46458.07083858304;46808.29787553189;46866.59400217999;47027.76486117616;47064.823379415786;40453.595464045575;43760;44413;49002;49167.26249106266;14943.626409339637;15068.020578662352;15079.170645614377;15084.170370629721;15102.471413500474;12482.001439884787;12489.313087780078;12489.437975290952;12513.061520385825;12567.18313309632;13334.266615336055;13381.933090334485;13388.397522111529;13418.060735748599;13508.121972071784;5875.6474611523445;5897.351291357947;5924.703764811742;5940.940590594038;5961.450022994053;1000.4497526360473;1001.529281237817;1004.6483730694199;1054.6308791922766;1056.8414737789394;22636.773632263797;22721.431964201027;22955.770442295696;23128.76871231266;23941.880290598434;894.1460904835866;1102.6636875752972;1153.8269259611125;1161.1233518693332;1162.1400163878668;10280.640177593748;10541.73499180088;11756.412179391167;11871.525138994517;11891.108166887518;349141;350457;351627;351783;352289.71565426537;52181.7390913043;52389.214161787604;52442.475575243756;52547.47452525502;52562.737186313825;1495.102938237049;1505.2172870107484;1527.6791873706645;1529.1360381384482;1540.0528674865102;17606.647867042844;21686.67469987983;22222.555548889253;22835;23615.40961475977;36036;36171.45742813807;36601.45097823482;36604.450933236556;36680;297527;298002;299225;299878;300287;25848;30883;31506;33353;33378.83310583432;41201;42783;42882.78558607312;42944;43027.139457211306;25518.106866259594;27509.31226719348;27780.16659500218;27996.020139295026;30190.698093019666;29358.266043349086;29600.851995739882;29939.700602993693;30152;30257.69742302549;33347.49978750369;34251.65748342584;35436.822815886786;35938;36131.45802812908;28873;29057;29188.854055729586;29828.70171298259;30818.84590577033;20280.492987675425;23776.643350350103;24155.275341739776;24197.63703544413;24206.515869682156 13:58:34 INFO - PID 2077 | |2;jquery.html;500.3945226276206;515.4175781367055;524.0985504931518;528.6114705691316;545.7325910303947;21456;21636.3509094727;21859.344219673396;22315.44211394716;22756;21105.57788844222;25723.485530289385;25952.35119122023;25960;25972.961081556714;6278.529110316733;6412.454941329969;6414.134091897604;6420.06908998195;6423.839404014913;6283.088952101943;6334.809955701337;6335.778247761329;6346.762381335641;6356.364363563653;5667.886642267152;6047.304559975593;6271.996480563111;6310.495160387181;6324.715387807537;6239.65681887496;6242.406971337716;6256.937430625681;6278.152449419315;6290.842728931768;5732.340780810218;5754.482096611325;5755.280589926259;5770.16332631766;5778.42215778423;29948.550771738443;29966.70033299661;30646.54030189549;31224.8438757807;31374.52938205929;2296.8621882687094;2335.8248131390214;2340.508493216427;2357.6817129687443;2362.7873491385685;19035.048247587703;19549.511262218417;19635.607287854305;19651.705224421647;19699.21203151872;2162.4701948022835;2193.309107631107;2234.240358278177;2235.184157782414;2235.3294011796397;163826;206970.9999999985;208261;208508;208522;12721.618351449471;15322.463713770188;15332.846671533141;15513.146776927151;15585.688286234212;21074.894625526773;21162;21221.045052972655;21272.36182914515;21316.720996740245;10322.277440579088;10429.9478502607;10442.68671939843;10488.31825931312;10514.106300964351;9772.51137443125;9822.066903644143;9853.507324633883;9854.950725246328;9902.257330700082;9135.862962055575;9399.389039712534;9438.858417123749;9471.526423678926;9475.952620236854;1889.6976483762533;2164.8809315487483;2229.9373079901025;2526.3810366459966;2587.063682720126;966.6085235479642;979.4155069190234;992.2310209587483;1001.6644573569995;1026.4867566216892;16922.746158807382;21215.68176477354;21442.463938401666;21482.78517214839;21987.560248794933;912.3613470570627;994.5452488576519;996.138340335619;1004.9949750251202;1048.6015314180563;11027.44862756859;11330;11350.205485615928;11621.01221396174;11667.941660291644;547742.261288691;556567;563499;565660;566397;150506;153941.23029385007;154334.22832885763;155943;157396.21301893418;1200.1239095460362;1204.6265657646159;1233.7224124572078;1244.0669497876593;1245.8691837357128;19412.902935485232;23277;23555.29334119979;24590.508189836462;24655.753442465346;73107.63446182734;75271.1182220427;76527;76753;76893.61553192198;99006.51490227797;100168.49915750374;100416;101889;103105;12122.454489548169;12153.756924861276;12241.265524068584;12250.571230006906;12295.446704898122;18322.541936451697;18630.906845465684;18657.53356166107;18715.90642046781;18783.342583010075;14298.928505357406;15060.94573379853;15075.396984120951;15094.698106037598;15131;11281.605143820256;13135.934320328337;13320.800187996994;13354.599362019155;13793;15406.768898466307;15571;15598.766018509505;15639.983401078893;15682.607934801721;11950.163488556065;11956.043516518832;11985.101117416054;11988.220765650205;12004.819927700917;14036.087654302437;14408.991370603957;14831;14856.257187140816;14912.701745965236 13:58:34 INFO - PID 2077 | |3;mootools.html;1420.6235347632876;1455.4978532406328;1476.9778453323195;1486.866182043616;1499.467688970415;13600.727985440297;14160.787588186186;14210.92894535526;14246.358913848875;14249.857501424982;13441.66395840102;14216.289185540707;14270.215138167398;14324.641883952907;14399.280035998185;2806.1581525542356;2843.445528122017;2872.7989040767156;2874.5257032589598;2877.180003698946;2780.4856101621267;2832.4618322518654;2836.305105249217;2837.829730216183;2841.403305305878;2326.8836558172106;2418.056957786465;2778.749912507884;2815.338950019488;2829.8160619559767;2350.6591544226076;2633.73662633737;2739.8904043838224;2768.9030883919168;2843.7867159963084;2797.4125433658955;2803.8317700937805;2811.353388720606;2824.096289187461;2841.1476557032806;13550.322483875865;13748.312584370842;13809.309534523334;13834.654133646638;13871.861281387159;7631.961840190819;7927.405444591679;7942.285194332538;7958.204179582053;8005.919940800576;4736.692115012514;4880.755962201875;4905.8282960096585;4911.607071434277;4987.426445958688;1280.8142819291152;1307.1372893890082;1332.1540821578271;1366.6788304748377;1385.4942945824803;20850.687239691415;20979.5804083919;21051.68422473664;21060.789392106035;21063.789362106338;5710.029295019856;5786.652800831879;6746;6799.082123913307;6853.897191542131;11983.760324793455;14462.566123016326;14495.565133046026;14501.637459063397;14522.346494407566;7482.551046937201;7515.135759387631;7545.962270188614;7639.274268944441;7661.080670319598;6928.965355173192;6971.54684945487;6977.197622273504;7084.787456376317;7140.143182818095;5143.794248230029;5298.788048478018;5312.07038768214;5323.787048518016;5341.332333458317;1276.6999755057554;1528.938842446312;1530.9387624494896;1549.5738671865101;1550.046721266413;824.6454024769407;824.884516167737;847.7287268074157;851.3274513134678;854.619694236059;6855.5886646801355;7189.676464559107;7275.89086163708;7332.8900066499045;7502.887456688045;679.8538314262457;904.8598765555447;934.2666007184464;974.5322245322143;992.5880759484733;4790.760461976957;4885.169521181408;4964.6204627054785;5017.598592112622;5321.388040375329;18042.368517102102;18811.435656930313;18933.621327573648;18967.146478408504;18970.810291896905;18093.63812723738;18430.539236519195;18511.444656660322;18769.34307299238;18776.71834922477;9117.954410227907;9242.815143697222;9706.514674266258;9758.170555502862;9794.510274486247;7526.849463010819;7675.194104618935;7749.527589757928;8181.959090204511;8213.54825484604;16288.67422651564;16643.50069497917;17086.40197593078;17233.913830430767;17314.91342543279;17174.31302747876;17863.838850474676;17886;17977.280908763503;18006.459806205836;10914.563417463212;10973.3964631946;10990.17573681961;11068.889311106786;11146.554137834395;12613.810792838298;12689.111762176928;12723.80914286305;12750.61748147557;12773.10588258813;9864.950675246864;10057.09486146221;10171.135453486537;10351.327163734333;10519;9703.5148242589;9756.46339451337;9823.214142868695;9910.950445247727;10294.176465882561;9376.48429336393;9477.052294770469;9478.431294122374;9493.335466517554;9518.286128540525;6766.594004359754;6807.285235050248;6908;6951.513394062367;7220.133583969747;6708.731650733916;6880.621565813928;7119.216886142417;7119.82200444993;7210.387117095106 13:58:34 INFO - PID 2077 | |4;prototype.html;439.6988063176722;459.2812248830581;464.533144190089;477.5988169937252;481.2708746249431;5683;6550.901736473951;6585.604863708175;6589.901151482732;6614.8677026459445;4864.464908860023;6109.938900611004;6460.224773027243;6502.902456463145;6529.412352888239;5064.1390963536105;5080.187170052793;5090.9236361454605;5098.464661210575;5114.744262786846;5082.898342033157;5086.465921078289;5100.566451851597;5129.358830146231;5140.614453915952;4709.5055019223;4746.121967436035;5048.71257829253;5057.342545469084;5060.721660308682;4196.664266858675;4384.736915785031;4442.822651997223;4822;5074.467180946003;4149.734331029065;4389.495208051051;4399.714018588814;4620.907581848378;4625.421822272216;5175.482451754832;6404.455621272197;6411.589450320922;6440.355964403522;6442.323556026597;5688.60179787415;6070.57505974582;6089.695515224221;6124.571280010401;6167.290761562388;5781.335146458127;6999.12510936133;7013.684384202723;7031.437485001239;7036;5036.8415264489;5064.898702025976;5322.840314790563;5395.29861118056;5398.946010539884;7353.558786472829;7380.409567234608;7414.295641914064;7429.4056475482375;7466.328030477283;5498.560115190775;5499.312585926759;5517.834464966058;5534.976029434568;5553.416891226444;5136.717480538531;5611.242482264842;5612.438756124437;5617.747201375948;5617.859553511195;4782.995570930144;5070.771815268322;5588.944110558842;6124.846878828065;6132;6072.392760723892;6224.533160013018;6893.4494237132585;7003.649817509104;7045.295470452914;1819.2995696656883;1891.9810801892081;1898.867079304463;1899.0979284839866;1902.4292712186289;1052.752603138262;1216.367488905764;1345.0987838148465;1380.5858242527202;1386.473140206715;4686.671932964659;5415.265291028006;5917.993941030035;6422.2614399344675;6483.98996311145;7001.964990175016;7021.403180729695;7108.898120791182;7164.564048223629;7203.279672032755;5662.867426514714;6387.520935929824;6446.387593178641;6454.290028096905;6459.321771214049;3270.547089682738;3388.593368795711;3696.9075773105887;3705.6109108543756;3706.3884459064493;6136.386361363917;6266.655333956585;6662.833429164213;7163.495665910217;7336.486445948733;7022.6488675566015;7372.083258352867;7403.22266162056;7405.333519983227;7410.296021877913;7300.890486642598;7325.304096110861;7340.853182936311;7354.669039893324;7395.741149059757;6820.590764554143;7068.823279418056;7103.680334385069;7131;7136.250693677297;5763.94236057634;5827.766889324549;6124.663143526971;6948.583085014915;7061.611611361219;6331.715072821642;6500.869982600416;6967.79096627102;7320.084989376328;7336;4126.607972242632;4456.197884380843;4645.488996210482;4893.363862697826;4920.8769780755765;7083.964580177066;7169.247229041084;7176.928230717626;7209;7231.720843783962;6027.909581356195;6193.411625895533;6715.462762978852;6784.830379240559;6929.688164032731;6484.740610375721;6619.139511863427;6899.310068993061;6935.895961560682;6943.826404339729;5350.411454740053;6212.968935155295;6712.798616041331;6742.460603151834;6792.490563207681;5699.430056994267;6017.518598512021;6868.793936181923;6932;6946.548474349152;6143.938560614337;6400.607677930704;6707.832304192434;6740.966295168493;6771.45828333742 13:58:34 INFO - PID 2077 | |5;yui.html;725.3109545931367;823.1850468036644;824.9587520623967;845.7209120990074;869.3740506835079;16911.830881691196;17097.31610735572;17270.82729172708;17324.220410081543;17350.219240134193;14073.296335183251;14081.225532595729;14110.153390796559;17468.21393037313;17522;994.0208894239169;1017.7913527726836;1019.9643012494564;1022.821006323894;1025.676911772793;1017.5980487707351;1028.341861208827;1036.29014125324;1038.3250886923486;1039.0336986602456;1077.854489643896;1096.4627332607006;1099.351382684216;1101.5869049106584;1102.1127991966464;928.3640706116298;1051.4059556350637;1054.3515737821185;1062.1768129699467;1086.7283179205199;765.6937225109945;776.9028871391076;781.7928249014016;781.847539729753;781.9726309579138;17567.824321756747;17578.208980595904;17704.468865934043;17750.11249437523;17785.288588456446;17416.651666966718;17936.730949035777;18328.358507452176;18365;18384.06967268431;14382.640433989234;15102.622434439118;15155.939084264106;15199.696006079817;15253.61865953349;3621.692156166729;3649.799261040668;3653.6163702811355;3653.9634603654154;3654.671079602822;133359.33320333433;155748.2125893701;159504;161100;164637.53043704355;13257.93371033158;14124.788128178085;14313.78529322061;14480.85519144795;14677.192754398611;26639.200823975312;27231.319217019336;27588.862055689595;27693.584596231074;27856.303592409946;8261.215184557457;8263.462874913112;8280.875786863202;8329.750107496784;8372.623231954576;4049.5545489996666;4066.597024026706;4095.160492099172;4106.7946602669745;4112.876613701534;4666.696664716783;4739.2891066339635;4753.31076993834;4754.072955773591;4762.9523704763205;994.8209322321907;1056.1181413519644;1056.7146870344964;1062.8671416072991;1064.164630764846;540.8350453111839;542.6445678080863;559.1389260538806;560.9298837645294;564.4355644355644;11605;12009.819852702216;12041.157119001586;12385.62843114708;12642.936785316015;650.9772157974448;660.6102399584172;663.6018388966581;673.6968364236113;685.0033201691526;5679.772809087673;5712.514436272881;5733.88532229353;5784.161296612052;5838.299404071538;129323.35338323248;152335;157596;160341;160902.1954890218;93517;110896;118844.40577797056;125968.74031259754;127122.45755084847;81054;105414.94585054052;107454.46272768742;109223.45388273007;112404.43797781122;9194.724158275263;11926.880731192578;12175.330356830105;12219.450124744586;12698.30159341245;44078.55921440745;46628.7668561655;46848;48641.5135848637;48648.75675621599;58840.411595883495;60761.69619151876;63964.68017660068;64767;65039.34960650333;16807.243674034944;16981.15094245258;17686.78915135608;17815.376461824293;17979.910100449415;18103.456896293133;18476.630467390845;18928.242870285587;19331.4200573983;19362.90318548398;17127.400540981005;18134.5466363342;18474.538136546693;18482.260709571467;18756.87458752479;14885.479008234659;17116.914415427844;18091.457256282334;18411;18425.815741842947;17102.48692539226;17614;18155.27378904829;18156.45530634083;18347.174377152794;11971.461284242405;12086.697832554257;12106.51573937068;12294.016478681506;12326.81509777372;11321.825914600848;12132.878671213175;13230.140040897311;13402.463901443833;13460.663483412993 13:58:34 INFO - PID 2077 | __end_tp_report 13:58:34 INFO - PID 2077 | __start_cc_report 13:58:34 INFO - PID 2077 | _x_x_mozilla_cycle_collect,396 13:58:34 INFO - PID 2077 | __end_cc_report 13:58:34 INFO - PID 2077 | __startTimestamp1497473914393__endTimestamp 13:58:34 INFO - PID 2077 | 13:58:34 INFO - PID 2077 | ------- Summary: start ------- 13:58:34 INFO - PID 2077 | Number of tests: 6 13:58:34 INFO - PID 2077 | 13:58:34 INFO - PID 2077 | [#0] dojo.html Cycles:165 Average:6200.00 Median:6427.49 stddev:808.83 (12.6%) stddev-sans-first:771.10 13:58:34 INFO - PID 2077 | Values: 2989.3 3460.5 3493.0 3569.1 3629.9 6215.8 6265.4 6267.0 6279.5 6349.3 5262.4 6304.3 6337.5 6347.2 6366.3 5478.4 5479.4 5488.0 5569.2 5653.1 5513.2 5525.6 5562.9 5650.1 5656.3 5364.6 5394.5 5406.8 5413.0 5588.1 5504.1 5515.5 5544.8 5580.7 5589.2 5471.5 5496.6 5540.8 5542.0 5548.9 6199.2 6256.5 6271.7 6290.5 6307.6 6603.8 6698.5 6833.0 6845.3 6869.7 6768.8 6802.0 6814.8 6847.7 6945.3 6363.1 6402.1 6427.5 6482.3 6510.1 7090.8 7096.8 7107.3 7114.5 7146.1 6451.3 6562.7 6577.3 6719.6 6742.8 6728.0 6746.8 6793.9 6860.5 6904.7 6723.6 6741.9 6823.2 6831.3 6833.4 6837.8 6842.1 6857.5 6860.6 6862.4 4613.3 5442.5 5443.3 5465.5 5510.6 4181.3 4684.5 4765.5 4855.5 4864.2 4663.8 4831.0 6327.4 6383.1 6398.0 5494.3 6875.9 6917.0 6917.2 6919.4 5400.2 5869.8 5991.1 6368.9 6421.6 6059.1 6076.4 6104.8 6141.7 6152.7 6844.6 7032.9 7054.6 7056.8 7080.9 5375.0 6758.8 6781.4 6803.1 6901.7 5792.8 6963.7 7005.4 7010.9 7038.9 5694.8 6731.4 6731.8 6778.6 6786.0 6883.9 6893.2 6902.5 6919.9 6921.1 6496.4 6571.6 6672.3 6685.4 6711.0 6464.2 6541.3 6560.6 6643.9 6644.4 5305.8 5910.6 6545.6 6557.4 6603.2 6673.8 6695.8 6706.8 6715.5 6721.0 6333.6 6381.1 6410.7 6422.7 6531.3 13:58:34 INFO - PID 2077 | 13:58:34 INFO - PID 2077 | [#1] ext.html Cycles:180 Average:45538.96 Median:191696.33 stddev:83223.83 (43.4%) stddev-sans-first:83389.39 13:58:34 INFO - PID 2077 | Values: 763.7 803.9 829.6 857.2 869.6 36831.3 37513.6 37527.0 37560.4 37677.2 37072.8 37249.0 37346.6 37452.8 37497.4 7350.3 8021.4 8165.7 8219.3 8253.9 8157.1 8259.1 8271.7 8272.6 8295.4 8251.9 8295.8 8297.9 8311.0 8315.6 8244.3 8273.6 8274.6 8279.6 8286.7 8155.6 8162.0 8204.1 8208.1 8217.9 35822.1 35863.0 35919.8 35950.3 35967.9 3520.8 3559.2 3565.2 3567.5 3568.1 29502.8 29745.0 29929.0 29955.7 29965.0 3080.0 3150.9 3155.7 3162.5 3194.5 262764.7 266148.7 267166.3 334053.0 338454.0 46458.1 46808.3 46866.6 47027.8 47064.8 40453.6 43760.0 44413.0 49002.0 49167.3 14943.6 15068.0 15079.2 15084.2 15102.5 12482.0 12489.3 12489.4 12513.1 12567.2 13334.3 13381.9 13388.4 13418.1 13508.1 5875.6 5897.4 5924.7 5940.9 5961.5 1000.4 1001.5 1004.6 1054.6 1056.8 22636.8 22721.4 22955.8 23128.8 23941.9 894.1 1102.7 1153.8 1161.1 1162.1 10280.6 10541.7 11756.4 11871.5 11891.1 349141.0 350457.0 351627.0 351783.0 352289.7 52181.7 52389.2 52442.5 52547.5 52562.7 1495.1 1505.2 1527.7 1529.1 1540.1 17606.6 21686.7 22222.6 22835.0 23615.4 36036.0 36171.5 36601.5 36604.5 36680.0 297527.0 298002.0 299225.0 299878.0 300287.0 25848.0 30883.0 31506.0 33353.0 33378.8 41201.0 42783.0 42882.8 42944.0 43027.1 25518.1 27509.3 27780.2 27996.0 30190.7 29358.3 29600.9 29939.7 30152.0 30257.7 33347.5 34251.7 35436.8 35938.0 36131.5 28873.0 29057.0 29188.9 29828.7 30818.8 20280.5 23776.6 24155.3 24197.6 24206.5 13:58:34 INFO - PID 2077 | 13:58:34 INFO - PID 2077 | [#2] jquery.html Cycles:180 Average:40293.12 Median:11822.63 stddev:97616.27 (825.7%) stddev-sans-first:97844.39 13:58:34 INFO - PID 2077 | Values: 500.4 515.4 524.1 528.6 545.7 21456.0 21636.4 21859.3 22315.4 22756.0 21105.6 25723.5 25952.4 25960.0 25973.0 6278.5 6412.5 6414.1 6420.1 6423.8 6283.1 6334.8 6335.8 6346.8 6356.4 5667.9 6047.3 6272.0 6310.5 6324.7 6239.7 6242.4 6256.9 6278.2 6290.8 5732.3 5754.5 5755.3 5770.2 5778.4 29948.6 29966.7 30646.5 31224.8 31374.5 2296.9 2335.8 2340.5 2357.7 2362.8 19035.0 19549.5 19635.6 19651.7 19699.2 2162.5 2193.3 2234.2 2235.2 2235.3 163826.0 206971.0 208261.0 208508.0 208522.0 12721.6 15322.5 15332.8 15513.1 15585.7 21074.9 21162.0 21221.0 21272.4 21316.7 10322.3 10429.9 10442.7 10488.3 10514.1 9772.5 9822.1 9853.5 9855.0 9902.3 9135.9 9399.4 9438.9 9471.5 9476.0 1889.7 2164.9 2229.9 2526.4 2587.1 966.6 979.4 992.2 1001.7 1026.5 16922.7 21215.7 21442.5 21482.8 21987.6 912.4 994.5 996.1 1005.0 1048.6 11027.4 11330.0 11350.2 11621.0 11667.9 547742.3 556567.0 563499.0 565660.0 566397.0 150506.0 153941.2 154334.2 155943.0 157396.2 1200.1 1204.6 1233.7 1244.1 1245.9 19412.9 23277.0 23555.3 24590.5 24655.8 73107.6 75271.1 76527.0 76753.0 76893.6 99006.5 100168.5 100416.0 101889.0 103105.0 12122.5 12153.8 12241.3 12250.6 12295.4 18322.5 18630.9 18657.5 18715.9 18783.3 14298.9 15060.9 15075.4 15094.7 15131.0 11281.6 13135.9 13320.8 13354.6 13793.0 15406.8 15571.0 15598.8 15640.0 15682.6 11950.2 11956.0 11985.1 11988.2 12004.8 14036.1 14409.0 14831.0 14856.3 14912.7 13:58:34 INFO - PID 2077 | 13:58:34 INFO - PID 2077 | [#3] mootools.html Cycles:180 Average:8462.22 Median:2834.38 stddev:5678.11 (200.3%) stddev-sans-first:5669.38 13:58:34 INFO - PID 2077 | Values: 1420.6 1455.5 1477.0 1486.9 1499.5 13600.7 14160.8 14210.9 14246.4 14249.9 13441.7 14216.3 14270.2 14324.6 14399.3 2806.2 2843.4 2872.8 2874.5 2877.2 2780.5 2832.5 2836.3 2837.8 2841.4 2326.9 2418.1 2778.7 2815.3 2829.8 2350.7 2633.7 2739.9 2768.9 2843.8 2797.4 2803.8 2811.4 2824.1 2841.1 13550.3 13748.3 13809.3 13834.7 13871.9 7632.0 7927.4 7942.3 7958.2 8005.9 4736.7 4880.8 4905.8 4911.6 4987.4 1280.8 1307.1 1332.2 1366.7 1385.5 20850.7 20979.6 21051.7 21060.8 21063.8 5710.0 5786.7 6746.0 6799.1 6853.9 11983.8 14462.6 14495.6 14501.6 14522.3 7482.6 7515.1 7546.0 7639.3 7661.1 6929.0 6971.5 6977.2 7084.8 7140.1 5143.8 5298.8 5312.1 5323.8 5341.3 1276.7 1528.9 1530.9 1549.6 1550.0 824.6 824.9 847.7 851.3 854.6 6855.6 7189.7 7275.9 7332.9 7502.9 679.9 904.9 934.3 974.5 992.6 4790.8 4885.2 4964.6 5017.6 5321.4 18042.4 18811.4 18933.6 18967.1 18970.8 18093.6 18430.5 18511.4 18769.3 18776.7 9118.0 9242.8 9706.5 9758.2 9794.5 7526.8 7675.2 7749.5 8182.0 8213.5 16288.7 16643.5 17086.4 17233.9 17314.9 17174.3 17863.8 17886.0 17977.3 18006.5 10914.6 10973.4 10990.2 11068.9 11146.6 12613.8 12689.1 12723.8 12750.6 12773.1 9865.0 10057.1 10171.1 10351.3 10519.0 9703.5 9756.5 9823.2 9911.0 10294.2 9376.5 9477.1 9478.4 9493.3 9518.3 6766.6 6807.3 6908.0 6951.5 7220.1 6708.7 6880.6 7119.2 7119.8 7210.4 13:58:34 INFO - PID 2077 | 13:58:34 INFO - PID 2077 | [#4] prototype.html Cycles:180 Average:5666.86 Median:6134.19 stddev:1670.14 (27.2%) stddev-sans-first:1628.10 13:58:34 INFO - PID 2077 | Values: 439.7 459.3 464.5 477.6 481.3 5683.0 6550.9 6585.6 6589.9 6614.9 4864.5 6109.9 6460.2 6502.9 6529.4 5064.1 5080.2 5090.9 5098.5 5114.7 5082.9 5086.5 5100.6 5129.4 5140.6 4709.5 4746.1 5048.7 5057.3 5060.7 4196.7 4384.7 4442.8 4822.0 5074.5 4149.7 4389.5 4399.7 4620.9 4625.4 5175.5 6404.5 6411.6 6440.4 6442.3 5688.6 6070.6 6089.7 6124.6 6167.3 5781.3 6999.1 7013.7 7031.4 7036.0 5036.8 5064.9 5322.8 5395.3 5398.9 7353.6 7380.4 7414.3 7429.4 7466.3 5498.6 5499.3 5517.8 5535.0 5553.4 5136.7 5611.2 5612.4 5617.7 5617.9 4783.0 5070.8 5588.9 6124.8 6132.0 6072.4 6224.5 6893.4 7003.6 7045.3 1819.3 1892.0 1898.9 1899.1 1902.4 1052.8 1216.4 1345.1 1380.6 1386.5 4686.7 5415.3 5918.0 6422.3 6484.0 7002.0 7021.4 7108.9 7164.6 7203.3 5662.9 6387.5 6446.4 6454.3 6459.3 3270.5 3388.6 3696.9 3705.6 3706.4 6136.4 6266.7 6662.8 7163.5 7336.5 7022.6 7372.1 7403.2 7405.3 7410.3 7300.9 7325.3 7340.9 7354.7 7395.7 6820.6 7068.8 7103.7 7131.0 7136.3 5763.9 5827.8 6124.7 6948.6 7061.6 6331.7 6500.9 6967.8 7320.1 7336.0 4126.6 4456.2 4645.5 4893.4 4920.9 7084.0 7169.2 7176.9 7209.0 7231.7 6027.9 6193.4 6715.5 6784.8 6929.7 6484.7 6619.1 6899.3 6935.9 6943.8 5350.4 6213.0 6712.8 6742.5 6792.5 5699.4 6017.5 6868.8 6932.0 6946.5 6143.9 6400.6 6707.8 6741.0 6771.5 13:58:34 INFO - PID 2077 | 13:58:34 INFO - PID 2077 | [#5] yui.html Cycles:180 Average:25915.27 Median:17727.29 stddev:40344.57 (227.6%) stddev-sans-first:40413.41 13:58:34 INFO - PID 2077 | Values: 725.3 823.2 825.0 845.7 869.4 16911.8 17097.3 17270.8 17324.2 17350.2 14073.3 14081.2 14110.2 17468.2 17522.0 994.0 1017.8 1020.0 1022.8 1025.7 1017.6 1028.3 1036.3 1038.3 1039.0 1077.9 1096.5 1099.4 1101.6 1102.1 928.4 1051.4 1054.4 1062.2 1086.7 765.7 776.9 781.8 781.8 782.0 17567.8 17578.2 17704.5 17750.1 17785.3 17416.7 17936.7 18328.4 18365.0 18384.1 14382.6 15102.6 15155.9 15199.7 15253.6 3621.7 3649.8 3653.6 3654.0 3654.7 133359.3 155748.2 159504.0 161100.0 164637.5 13257.9 14124.8 14313.8 14480.9 14677.2 26639.2 27231.3 27588.9 27693.6 27856.3 8261.2 8263.5 8280.9 8329.8 8372.6 4049.6 4066.6 4095.2 4106.8 4112.9 4666.7 4739.3 4753.3 4754.1 4763.0 994.8 1056.1 1056.7 1062.9 1064.2 540.8 542.6 559.1 560.9 564.4 11605.0 12009.8 12041.2 12385.6 12642.9 651.0 660.6 663.6 673.7 685.0 5679.8 5712.5 5733.9 5784.2 5838.3 129323.4 152335.0 157596.0 160341.0 160902.2 93517.0 110896.0 118844.4 125968.7 127122.5 81054.0 105414.9 107454.5 109223.5 112404.4 9194.7 11926.9 12175.3 12219.5 12698.3 44078.6 46628.8 46848.0 48641.5 48648.8 58840.4 60761.7 63964.7 64767.0 65039.3 16807.2 16981.2 17686.8 17815.4 17979.9 18103.5 18476.6 18928.2 19331.4 19362.9 17127.4 18134.5 18474.5 18482.3 18756.9 14885.5 17116.9 18091.5 18411.0 18425.8 17102.5 17614.0 18155.3 18156.5 18347.2 11971.5 12086.7 12106.5 12294.0 12326.8 11321.8 12132.9 13230.1 13402.5 13460.7 13:58:34 INFO - PID 2077 | -------- Summary: end -------- 13:58:34 INFO - PID 2077 | 13:58:34 INFO - TEST-INFO | 2077: exit 0 13:58:34 INFO - TEST-OK | dromaeo_css | took 1142362ms 13:58:34 INFO - TEST-START | kraken 13:58:34 INFO - operating with platform_type : mac_ 13:58:34 INFO - Initialising browser for kraken test... 13:58:34 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49606/getInfo.html -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmpBN44cl/profile 13:58:34 INFO - TEST-INFO | started process 2086 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49606/getInfo.html -foreground) 13:59:08 INFO - TEST-INFO | 2086: killed by SIGTERM 13:59:08 INFO - Browser initialized. 13:59:08 INFO - Running cycle 1/1 for kraken test... 13:59:08 INFO - TEST-INFO | started process 2090 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/T/tmpBN44cl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:59:09 INFO - PID 2090 | 2017-06-14 13:59:09.834 plugin-container[2091:21867] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 13:59:09 INFO - PID 2090 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:59:09 INFO - PID 2090 | 2017-06-14 13:59:09.835 plugin-container[2091:21867] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.CFPasteboardClient' 13:59:09 INFO - PID 2090 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:59:09 INFO - PID 2090 | 2017-06-14 13:59:09.835 plugin-container[2091:21867] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:59:10 INFO - PID 2090 | 2017-06-14 13:59:10.617 plugin-container[2092:21911] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 13:59:10 INFO - PID 2090 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:59:10 INFO - PID 2090 | 2017-06-14 13:59:10.618 plugin-container[2092:21911] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a03, name = 'com.apple.CFPasteboardClient' 13:59:10 INFO - PID 2090 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:59:10 INFO - PID 2090 | 2017-06-14 13:59:10.618 plugin-container[2092:21911] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:59:19 INFO - PID 2090 | RSS: Main: 214171648 13:59:19 INFO - PID 2090 | 13:59:22 INFO - PID 2090 | Cycle 1(1): loaded http://localhost:49606/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49606/tests/kraken/driver.html?testName=audio-beat-detection) 13:59:23 INFO - PID 2090 | RSS: Main: 228265984 13:59:23 INFO - PID 2090 | 13:59:26 INFO - PID 2090 | Cycle 1(1): loaded http://localhost:49606/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49606/tests/kraken/driver.html?testName=audio-dft) 13:59:26 INFO - PID 2090 | RSS: Main: 237473792 13:59:26 INFO - PID 2090 | 13:59:30 INFO - PID 2090 | Cycle 1(1): loaded http://localhost:49606/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49606/tests/kraken/driver.html?testName=audio-fft) 13:59:31 INFO - PID 2090 | RSS: Main: 241676288 13:59:31 INFO - PID 2090 | 13:59:32 INFO - PID 2090 | Cycle 1(1): loaded http://localhost:49606/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49606/tests/kraken/driver.html?testName=audio-oscillator) 13:59:33 INFO - PID 2090 | RSS: Main: 243240960 13:59:33 INFO - PID 2090 | 13:59:35 INFO - PID 2090 | Cycle 1(1): loaded http://localhost:49606/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49606/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:59:36 INFO - PID 2090 | RSS: Main: 243400704 13:59:36 INFO - PID 2090 | 13:59:39 INFO - PID 2090 | Cycle 1(1): loaded http://localhost:49606/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49606/tests/kraken/driver.html?testName=imaging-darkroom) 13:59:39 INFO - PID 2090 | RSS: Main: 249810944 13:59:39 INFO - PID 2090 | 13:59:42 INFO - PID 2090 | Cycle 1(1): loaded http://localhost:49606/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49606/tests/kraken/driver.html?testName=imaging-desaturate) 13:59:43 INFO - PID 2090 | RSS: Main: 249942016 13:59:43 INFO - PID 2090 | 13:59:46 INFO - PID 2090 | Cycle 1(1): loaded http://localhost:49606/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49606/tests/kraken/driver.html?testName=json-parse-financial) 13:59:46 INFO - PID 2090 | RSS: Main: 249696256 13:59:46 INFO - PID 2090 | 13:59:48 INFO - PID 2090 | Cycle 1(1): loaded http://localhost:49606/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49606/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:59:48 INFO - PID 2090 | RSS: Main: 249532416 13:59:48 INFO - PID 2090 | 13:59:50 INFO - PID 2090 | Cycle 1(1): loaded http://localhost:49606/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49606/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:59:51 INFO - PID 2090 | RSS: Main: 249466880 13:59:51 INFO - PID 2090 | 13:59:53 INFO - PID 2090 | Cycle 1(1): loaded http://localhost:49606/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49606/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:59:53 INFO - PID 2090 | RSS: Main: 249409536 13:59:53 INFO - PID 2090 | 13:59:56 INFO - PID 2090 | Cycle 1(1): loaded http://localhost:49606/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49606/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:59:57 INFO - PID 2090 | RSS: Main: 249470976 13:59:57 INFO - PID 2090 | 14:00:00 INFO - PID 2090 | Cycle 1(1): loaded http://localhost:49606/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49606/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:00:01 INFO - PID 2090 | RSS: Main: 249700352 14:00:01 INFO - PID 2090 | 14:00:02 INFO - PID 2090 | Cycle 1(1): loaded http://localhost:49606/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49606/tests/kraken/driver.html?testName=ai-astar) 14:00:03 INFO - PID 2090 | RSS: Main: 251318272 14:00:03 INFO - PID 2090 | 14:00:03 INFO - PID 2090 | __start_tp_report 14:00:03 INFO - PID 2090 | _x_x_mozilla_page_load 14:00:03 INFO - PID 2090 | _x_x_mozilla_page_load_details 14:00:03 INFO - PID 2090 | |i|pagename|runs| 14:00:03 INFO - PID 2090 | |0;ai-astar;111;116;106;123;117;114;122;96;166;99 14:00:03 INFO - PID 2090 | |1;audio-beat-detection;180;149;167;153;112;132;112;153;172;147 14:00:03 INFO - PID 2090 | |2;audio-dft;198;198;193;202;173;270;281;227;196;195 14:00:03 INFO - PID 2090 | |3;audio-fft;70;83;81;147;62;58;58;62;126;57 14:00:03 INFO - PID 2090 | |4;audio-oscillator;70;71;122;68;69;71;134;101;103;145 14:00:03 INFO - PID 2090 | |5;imaging-gaussian-blur;92;93;92;93;93;92;93;93;92;89 14:00:03 INFO - PID 2090 | |6;imaging-darkroom;96;96;97;101;97;97;97;98;98;97 14:00:03 INFO - PID 2090 | |7;imaging-desaturate;91;94;93;90;116;93;93;92;91;91 14:00:03 INFO - PID 2090 | |8;json-parse-financial;48;48;52;53;51;53;54;53;53;53 14:00:03 INFO - PID 2090 | |9;json-stringify-tinderbox;54;54;55;56;58;60;61;57;57;56 14:00:03 INFO - PID 2090 | |10;stanford-crypto-aes;102;80;81;80;82;93;71;78;91;72 14:00:03 INFO - PID 2090 | |11;stanford-crypto-ccm;135;135;132;144;143;148;152;134;141;161 14:00:03 INFO - PID 2090 | |12;stanford-crypto-pbkdf2;179;224;181;175;178;185;181;216;169;213 14:00:03 INFO - PID 2090 | |13;stanford-crypto-sha256-iterative;65;72;88;92;95;89;65;66;63;67 14:00:03 INFO - PID 2090 | __end_tp_report 14:00:03 INFO - PID 2090 | __start_cc_report 14:00:03 INFO - PID 2090 | _x_x_mozilla_cycle_collect,1020 14:00:03 INFO - PID 2090 | __end_cc_report 14:00:03 INFO - PID 2090 | __startTimestamp1497474003105__endTimestamp 14:00:03 INFO - PID 2090 | 14:00:03 INFO - PID 2090 | ------- Summary: start ------- 14:00:03 INFO - PID 2090 | Number of tests: 14 14:00:03 INFO - PID 2090 | 14:00:03 INFO - PID 2090 | [#0] ai-astar Cycles:10 Average:117.00 Median:122.50 stddev:19.42 (15.9%) stddev-sans-first:20.48 14:00:03 INFO - PID 2090 | Values: 111.0 116.0 106.0 123.0 117.0 114.0 122.0 96.0 166.0 99.0 14:00:03 INFO - PID 2090 | 14:00:03 INFO - PID 2090 | [#1] audio-beat-detection Cycles:10 Average:147.70 Median:153.00 stddev:23.24 (15.2%) stddev-sans-first:21.51 14:00:03 INFO - PID 2090 | Values: 180.0 149.0 167.0 153.0 112.0 132.0 112.0 153.0 172.0 147.0 14:00:03 INFO - PID 2090 | 14:00:03 INFO - PID 2090 | [#2] audio-dft Cycles:10 Average:213.30 Median:200.00 stddev:35.36 (17.7%) stddev-sans-first:37.07 14:00:03 INFO - PID 2090 | Values: 198.0 198.0 193.0 202.0 173.0 270.0 281.0 227.0 196.0 195.0 14:00:03 INFO - PID 2090 | 14:00:03 INFO - PID 2090 | [#3] audio-fft Cycles:10 Average:80.40 Median:62.00 stddev:31.37 (50.6%) stddev-sans-first:33.05 14:00:03 INFO - PID 2090 | Values: 70.0 83.0 81.0 147.0 62.0 58.0 58.0 62.0 126.0 57.0 14:00:03 INFO - PID 2090 | 14:00:03 INFO - PID 2090 | [#4] audio-oscillator Cycles:10 Average:95.40 Median:68.50 stddev:29.87 (43.6%) stddev-sans-first:30.24 14:00:03 INFO - PID 2090 | Values: 70.0 71.0 122.0 68.0 69.0 71.0 134.0 101.0 103.0 145.0 14:00:03 INFO - PID 2090 | 14:00:03 INFO - PID 2090 | [#5] imaging-gaussian-blur Cycles:10 Average:92.20 Median:93.00 stddev:1.23 (1.3%) stddev-sans-first:1.30 14:00:03 INFO - PID 2090 | Values: 92.0 93.0 92.0 93.0 93.0 92.0 93.0 93.0 92.0 89.0 14:00:03 INFO - PID 2090 | 14:00:03 INFO - PID 2090 | [#6] imaging-darkroom Cycles:10 Average:97.40 Median:97.00 stddev:1.43 (1.5%) stddev-sans-first:1.42 14:00:03 INFO - PID 2090 | Values: 96.0 96.0 97.0 101.0 97.0 97.0 97.0 98.0 98.0 97.0 14:00:03 INFO - PID 2090 | 14:00:03 INFO - PID 2090 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:92.50 stddev:7.69 (8.3%) stddev-sans-first:8.06 14:00:03 INFO - PID 2090 | Values: 91.0 94.0 93.0 90.0 116.0 93.0 93.0 92.0 91.0 91.0 14:00:03 INFO - PID 2090 | 14:00:03 INFO - PID 2090 | [#8] json-parse-financial Cycles:10 Average:51.80 Median:53.00 stddev:2.15 (4.1%) stddev-sans-first:1.79 14:00:03 INFO - PID 2090 | Values: 48.0 48.0 52.0 53.0 51.0 53.0 54.0 53.0 53.0 53.0 14:00:03 INFO - PID 2090 | 14:00:03 INFO - PID 2090 | [#9] json-stringify-tinderbox Cycles:10 Average:56.80 Median:57.00 stddev:2.35 (4.1%) stddev-sans-first:2.26 14:00:03 INFO - PID 2090 | Values: 54.0 54.0 55.0 56.0 58.0 60.0 61.0 57.0 57.0 56.0 14:00:03 INFO - PID 2090 | 14:00:03 INFO - PID 2090 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:80.50 stddev:9.65 (12.0%) stddev-sans-first:7.39 14:00:03 INFO - PID 2090 | Values: 102.0 80.0 81.0 80.0 82.0 93.0 71.0 78.0 91.0 72.0 14:00:03 INFO - PID 2090 | 14:00:03 INFO - PID 2090 | [#11] stanford-crypto-ccm Cycles:10 Average:142.50 Median:143.50 stddev:9.20 (6.4%) stddev-sans-first:9.35 14:00:03 INFO - PID 2090 | Values: 135.0 135.0 132.0 144.0 143.0 148.0 152.0 134.0 141.0 161.0 14:00:03 INFO - PID 2090 | 14:00:03 INFO - PID 2090 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.10 Median:183.00 stddev:19.66 (10.7%) stddev-sans-first:20.44 14:00:03 INFO - PID 2090 | Values: 179.0 224.0 181.0 175.0 178.0 185.0 181.0 216.0 169.0 213.0 14:00:03 INFO - PID 2090 | 14:00:03 INFO - PID 2090 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.20 Median:80.00 stddev:13.07 (16.3%) stddev-sans-first:13.22 14:00:03 INFO - PID 2090 | Values: 65.0 72.0 88.0 92.0 95.0 89.0 65.0 66.0 63.0 67.0 14:00:03 INFO - PID 2090 | -------- Summary: end -------- 14:00:03 INFO - PID 2090 | 14:00:03 INFO - TEST-INFO | 2090: exit 0 14:00:03 INFO - TEST-OK | kraken | took 88951ms 14:00:03 INFO - SUITE-END | took 1231s 14:00:04 INFO - Completed test suite (00:20:31) 14:00:04 INFO - javascript benchmark 14:00:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8432.448795158118, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2989.2975150839547, 3460.480927860819, 3492.982535087325, 3569.1077230692313, 3629.909252268693, 6215.8446038849, 6265.404786545277, 6266.968665156679, 6279.497640188785, 6349.301576826557, 5262.394824595172, 6304.275008374038, 6337.461315788152, 6347.17486726726, 6366.331535188808, 5478.424765399635, 5479.369872464667, 5488.0, 5569.192467092269, 5653.095504719246, 5513.2005859150395, 5525.613207075507, 5562.888742225153, 5650.0959846424585, 5656.264685590867, 5364.570834333244, 5394.460553944614, 5406.837794866121, 5412.972935135338, 5588.105903055492, 5504.146857237135, 5515.4760297772045, 5544.77820887164, 5580.665160090367, 5589.189567512729, 5471.452854714536, 5496.587755918322, 5540.806071787468, 5542.0, 5548.8624831909765, 6199.225096862892, 6256.468200202943, 6271.686415679198, 6290.528210384239, 6307.621542707407, 6603.834904127387, 6698.49761267902, 6833.0, 6845.315468453165, 6869.725210991554, 6768.830779230559, 6801.965990170067, 6814.795556133324, 6847.726090956356, 6945.2707465716385, 6363.077353783676, 6402.135711678957, 6427.485801135898, 6482.319356467598, 6510.08858759774, 7090.787276381717, 7096.7516136934955, 7107.324804143598, 7114.50198486101, 7146.106736657918, 6451.258105317855, 6562.67186640666, 6577.3422657733845, 6719.563228390139, 6742.764003259862, 6727.966360168168, 6746.83132921671, 6793.864122717518, 6860.451163906875, 6904.723811047602, 6723.5629684071355, 6741.898871516931, 6823.215330236987, 6831.316868313228, 6833.350831670983, 6837.760678376332, 6842.110525631636, 6857.485688573377, 6860.588364698134, 6862.416694580917, 4613.284940834176, 5442.482964118402, 5443.265159203457, 5465.508104270635, 5510.559155267649, 4181.268278051329, 4684.461286951976, 4765.547273009059, 4855.5387238212315, 4864.173090574611, 4663.836765713184, 4830.951690483121, 6327.430531252209, 6383.0744542042075, 6397.96801015992, 5494.2582751328855, 6875.931240687629, 6917.0, 6917.239103698589, 6919.4464442844455, 5400.1899715043055, 5869.796691678196, 5991.101334799728, 6368.853606350901, 6421.5825971311715, 6059.121427393092, 6076.361981991916, 6104.816855494342, 6141.692915354214, 6152.692365381713, 6844.555103918229, 7032.8945065824055, 7054.611996340251, 7056.7530136446, 7080.858382832417, 5375.0, 6758.831029224309, 6781.42357899584, 6803.149606299213, 6901.689423976033, 5792.768289268382, 6963.7214511421, 7005.439564834699, 7010.859782804213, 7038.8944165838575, 5694.829155125353, 6731.427828634621, 6731.764388246388, 6778.559393639398, 6786.016027676058, 6883.862322753617, 6893.241743408121, 6902.482313826584, 6919.896201557081, 6921.1002569667635, 6496.350364963466, 6571.605703657605, 6672.266050734318, 6685.364890335411, 6710.966445167743, 6464.159659244457, 6541.280459149585, 6560.606363618198, 6643.867122657616, 6644.4020038196795, 5305.840824775263, 5910.615809972338, 6545.607263564201, 6557.409833115043, 6603.20761508622, 6673.766418175341, 6695.799126026227, 6706.765263215764, 6715.4627629790475, 6721.0, 6333.619982801047, 6381.074744162163, 6410.711517981609, 6422.678866056585, 6531.3468653134305], "value": 6144.600607263778, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [763.7059732003179, 803.8914746509223, 829.601791140253, 857.1856736100706, 869.5608717597612, 36831.26337473249, 37513.624863751356, 37527.0, 37560.436593451086, 37677.24645507088, 37072.814635926785, 37249.0, 37346.626533734656, 37452.81273593635, 37497.437538436876, 7350.264973502647, 8021.438499305051, 8165.714200003001, 8219.260266576008, 8253.917460825405, 8157.061937877131, 8259.132791056943, 8271.710490132846, 8272.627731752056, 8295.419320647557, 8251.876221856677, 8295.792605184888, 8297.875531867026, 8311.0, 8315.625796839156, 8244.29923456507, 8273.627686754111, 8274.627641756135, 8279.586020698971, 8286.709965151222, 8155.592220388957, 8162.0, 8204.138565450661, 8208.05607355156, 8217.876731849026, 35822.10444738876, 35863.0, 35919.82040089809, 35950.28099438023, 35967.92096237117, 3520.806355650464, 3559.2169722661233, 3565.1978304881195, 3567.5362202913716, 3568.107973006748, 29502.819887204485, 29744.95892643769, 29929.0, 29955.700442995727, 29964.951226706962, 3079.9846000770076, 3150.921226969321, 3155.7317628001647, 3162.5414314924437, 3194.4888817789274, 262764.6861765698, 266148.6692566544, 267166.32084197854, 334053.0, 338454.0, 46458.07083858304, 46808.29787553189, 46866.59400217999, 47027.76486117616, 47064.823379415786, 40453.595464045575, 43760.0, 44413.0, 49002.0, 49167.26249106266, 14943.626409339637, 15068.020578662352, 15079.170645614377, 15084.170370629721, 15102.471413500474, 12482.001439884787, 12489.313087780078, 12489.437975290952, 12513.061520385825, 12567.18313309632, 13334.266615336055, 13381.933090334485, 13388.397522111529, 13418.060735748599, 13508.121972071784, 5875.6474611523445, 5897.351291357947, 5924.703764811742, 5940.940590594038, 5961.450022994053, 1000.4497526360473, 1001.529281237817, 1004.6483730694199, 1054.6308791922766, 1056.8414737789394, 22636.773632263797, 22721.431964201027, 22955.770442295696, 23128.76871231266, 23941.880290598434, 894.1460904835866, 1102.6636875752972, 1153.8269259611125, 1161.1233518693332, 1162.1400163878668, 10280.640177593748, 10541.73499180088, 11756.412179391167, 11871.525138994517, 11891.108166887518, 349141.0, 350457.0, 351627.0, 351783.0, 352289.71565426537, 52181.7390913043, 52389.214161787604, 52442.475575243756, 52547.47452525502, 52562.737186313825, 1495.102938237049, 1505.2172870107484, 1527.6791873706645, 1529.1360381384482, 1540.0528674865102, 17606.647867042844, 21686.67469987983, 22222.555548889253, 22835.0, 23615.40961475977, 36036.0, 36171.45742813807, 36601.45097823482, 36604.450933236556, 36680.0, 297527.0, 298002.0, 299225.0, 299878.0, 300287.0, 25848.0, 30883.0, 31506.0, 33353.0, 33378.83310583432, 41201.0, 42783.0, 42882.78558607312, 42944.0, 43027.139457211306, 25518.106866259594, 27509.31226719348, 27780.16659500218, 27996.020139295026, 30190.698093019666, 29358.266043349086, 29600.851995739882, 29939.700602993693, 30152.0, 30257.69742302549, 33347.49978750369, 34251.65748342584, 35436.822815886786, 35938.0, 36131.45802812908, 28873.0, 29057.0, 29188.854055729586, 29828.70171298259, 30818.84590577033, 20280.492987675425, 23776.643350350103, 24155.275341739776, 24197.63703544413, 24206.515869682156], "value": 17097.593993790648, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.3945226276206, 515.4175781367055, 524.0985504931518, 528.6114705691316, 545.7325910303947, 21456.0, 21636.3509094727, 21859.344219673396, 22315.44211394716, 22756.0, 21105.57788844222, 25723.485530289385, 25952.35119122023, 25960.0, 25972.961081556714, 6278.529110316733, 6412.454941329969, 6414.134091897604, 6420.06908998195, 6423.839404014913, 6283.088952101943, 6334.809955701337, 6335.778247761329, 6346.762381335641, 6356.364363563653, 5667.886642267152, 6047.304559975593, 6271.996480563111, 6310.495160387181, 6324.715387807537, 6239.65681887496, 6242.406971337716, 6256.937430625681, 6278.152449419315, 6290.842728931768, 5732.340780810218, 5754.482096611325, 5755.280589926259, 5770.16332631766, 5778.42215778423, 29948.550771738443, 29966.70033299661, 30646.54030189549, 31224.8438757807, 31374.52938205929, 2296.8621882687094, 2335.8248131390214, 2340.508493216427, 2357.6817129687443, 2362.7873491385685, 19035.048247587703, 19549.511262218417, 19635.607287854305, 19651.705224421647, 19699.21203151872, 2162.4701948022835, 2193.309107631107, 2234.240358278177, 2235.184157782414, 2235.3294011796397, 163826.0, 206970.9999999985, 208261.0, 208508.0, 208522.0, 12721.618351449471, 15322.463713770188, 15332.846671533141, 15513.146776927151, 15585.688286234212, 21074.894625526773, 21162.0, 21221.045052972655, 21272.36182914515, 21316.720996740245, 10322.277440579088, 10429.9478502607, 10442.68671939843, 10488.31825931312, 10514.106300964351, 9772.51137443125, 9822.066903644143, 9853.507324633883, 9854.950725246328, 9902.257330700082, 9135.862962055575, 9399.389039712534, 9438.858417123749, 9471.526423678926, 9475.952620236854, 1889.6976483762533, 2164.8809315487483, 2229.9373079901025, 2526.3810366459966, 2587.063682720126, 966.6085235479642, 979.4155069190234, 992.2310209587483, 1001.6644573569995, 1026.4867566216892, 16922.746158807382, 21215.68176477354, 21442.463938401666, 21482.78517214839, 21987.560248794933, 912.3613470570627, 994.5452488576519, 996.138340335619, 1004.9949750251202, 1048.6015314180563, 11027.44862756859, 11330.0, 11350.205485615928, 11621.01221396174, 11667.941660291644, 547742.261288691, 556567.0, 563499.0, 565660.0, 566397.0, 150506.0, 153941.23029385007, 154334.22832885763, 155943.0, 157396.21301893418, 1200.1239095460362, 1204.6265657646159, 1233.7224124572078, 1244.0669497876593, 1245.8691837357128, 19412.902935485232, 23277.0, 23555.29334119979, 24590.508189836462, 24655.753442465346, 73107.63446182734, 75271.1182220427, 76527.0, 76753.0, 76893.61553192198, 99006.51490227797, 100168.49915750374, 100416.0, 101889.0, 103105.0, 12122.454489548169, 12153.756924861276, 12241.265524068584, 12250.571230006906, 12295.446704898122, 18322.541936451697, 18630.906845465684, 18657.53356166107, 18715.90642046781, 18783.342583010075, 14298.928505357406, 15060.94573379853, 15075.396984120951, 15094.698106037598, 15131.0, 11281.605143820256, 13135.934320328337, 13320.800187996994, 13354.599362019155, 13793.0, 15406.768898466307, 15571.0, 15598.766018509505, 15639.983401078893, 15682.607934801721, 11950.163488556065, 11956.043516518832, 11985.101117416054, 11988.220765650205, 12004.819927700917, 14036.087654302437, 14408.991370603957, 14831.0, 14856.257187140816, 14912.701745965236], "value": 11929.329003221226, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1420.6235347632876, 1455.4978532406328, 1476.9778453323195, 1486.866182043616, 1499.467688970415, 13600.727985440297, 14160.787588186186, 14210.92894535526, 14246.358913848875, 14249.857501424982, 13441.66395840102, 14216.289185540707, 14270.215138167398, 14324.641883952907, 14399.280035998185, 2806.1581525542356, 2843.445528122017, 2872.7989040767156, 2874.5257032589598, 2877.180003698946, 2780.4856101621267, 2832.4618322518654, 2836.305105249217, 2837.829730216183, 2841.403305305878, 2326.8836558172106, 2418.056957786465, 2778.749912507884, 2815.338950019488, 2829.8160619559767, 2350.6591544226076, 2633.73662633737, 2739.8904043838224, 2768.9030883919168, 2843.7867159963084, 2797.4125433658955, 2803.8317700937805, 2811.353388720606, 2824.096289187461, 2841.1476557032806, 13550.322483875865, 13748.312584370842, 13809.309534523334, 13834.654133646638, 13871.861281387159, 7631.961840190819, 7927.405444591679, 7942.285194332538, 7958.204179582053, 8005.919940800576, 4736.692115012514, 4880.755962201875, 4905.8282960096585, 4911.607071434277, 4987.426445958688, 1280.8142819291152, 1307.1372893890082, 1332.1540821578271, 1366.6788304748377, 1385.4942945824803, 20850.687239691415, 20979.5804083919, 21051.68422473664, 21060.789392106035, 21063.789362106338, 5710.029295019856, 5786.652800831879, 6746.0, 6799.082123913307, 6853.897191542131, 11983.760324793455, 14462.566123016326, 14495.565133046026, 14501.637459063397, 14522.346494407566, 7482.551046937201, 7515.135759387631, 7545.962270188614, 7639.274268944441, 7661.080670319598, 6928.965355173192, 6971.54684945487, 6977.197622273504, 7084.787456376317, 7140.143182818095, 5143.794248230029, 5298.788048478018, 5312.07038768214, 5323.787048518016, 5341.332333458317, 1276.6999755057554, 1528.938842446312, 1530.9387624494896, 1549.5738671865101, 1550.046721266413, 824.6454024769407, 824.884516167737, 847.7287268074157, 851.3274513134678, 854.619694236059, 6855.5886646801355, 7189.676464559107, 7275.89086163708, 7332.8900066499045, 7502.887456688045, 679.8538314262457, 904.8598765555447, 934.2666007184464, 974.5322245322143, 992.5880759484733, 4790.760461976957, 4885.169521181408, 4964.6204627054785, 5017.598592112622, 5321.388040375329, 18042.368517102102, 18811.435656930313, 18933.621327573648, 18967.146478408504, 18970.810291896905, 18093.63812723738, 18430.539236519195, 18511.444656660322, 18769.34307299238, 18776.71834922477, 9117.954410227907, 9242.815143697222, 9706.514674266258, 9758.170555502862, 9794.510274486247, 7526.849463010819, 7675.194104618935, 7749.527589757928, 8181.959090204511, 8213.54825484604, 16288.67422651564, 16643.50069497917, 17086.40197593078, 17233.913830430767, 17314.91342543279, 17174.31302747876, 17863.838850474676, 17886.0, 17977.280908763503, 18006.459806205836, 10914.563417463212, 10973.3964631946, 10990.17573681961, 11068.889311106786, 11146.554137834395, 12613.810792838298, 12689.111762176928, 12723.80914286305, 12750.61748147557, 12773.10588258813, 9864.950675246864, 10057.09486146221, 10171.135453486537, 10351.327163734333, 10519.0, 9703.5148242589, 9756.46339451337, 9823.214142868695, 9910.950445247727, 10294.176465882561, 9376.48429336393, 9477.052294770469, 9478.431294122374, 9493.335466517554, 9518.286128540525, 6766.594004359754, 6807.285235050248, 6908.0, 6951.513394062367, 7220.133583969747, 6708.731650733916, 6880.621565813928, 7119.216886142417, 7119.82200444993, 7210.387117095106], "value": 6211.657792227171, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [439.6988063176722, 459.2812248830581, 464.533144190089, 477.5988169937252, 481.2708746249431, 5683.0, 6550.901736473951, 6585.604863708175, 6589.901151482732, 6614.8677026459445, 4864.464908860023, 6109.938900611004, 6460.224773027243, 6502.902456463145, 6529.412352888239, 5064.1390963536105, 5080.187170052793, 5090.9236361454605, 5098.464661210575, 5114.744262786846, 5082.898342033157, 5086.465921078289, 5100.566451851597, 5129.358830146231, 5140.614453915952, 4709.5055019223, 4746.121967436035, 5048.71257829253, 5057.342545469084, 5060.721660308682, 4196.664266858675, 4384.736915785031, 4442.822651997223, 4822.0, 5074.467180946003, 4149.734331029065, 4389.495208051051, 4399.714018588814, 4620.907581848378, 4625.421822272216, 5175.482451754832, 6404.455621272197, 6411.589450320922, 6440.355964403522, 6442.323556026597, 5688.60179787415, 6070.57505974582, 6089.695515224221, 6124.571280010401, 6167.290761562388, 5781.335146458127, 6999.12510936133, 7013.684384202723, 7031.437485001239, 7036.0, 5036.8415264489, 5064.898702025976, 5322.840314790563, 5395.29861118056, 5398.946010539884, 7353.558786472829, 7380.409567234608, 7414.295641914064, 7429.4056475482375, 7466.328030477283, 5498.560115190775, 5499.312585926759, 5517.834464966058, 5534.976029434568, 5553.416891226444, 5136.717480538531, 5611.242482264842, 5612.438756124437, 5617.747201375948, 5617.859553511195, 4782.995570930144, 5070.771815268322, 5588.944110558842, 6124.846878828065, 6132.0, 6072.392760723892, 6224.533160013018, 6893.4494237132585, 7003.649817509104, 7045.295470452914, 1819.2995696656883, 1891.9810801892081, 1898.867079304463, 1899.0979284839866, 1902.4292712186289, 1052.752603138262, 1216.367488905764, 1345.0987838148465, 1380.5858242527202, 1386.473140206715, 4686.671932964659, 5415.265291028006, 5917.993941030035, 6422.2614399344675, 6483.98996311145, 7001.964990175016, 7021.403180729695, 7108.898120791182, 7164.564048223629, 7203.279672032755, 5662.867426514714, 6387.520935929824, 6446.387593178641, 6454.290028096905, 6459.321771214049, 3270.547089682738, 3388.593368795711, 3696.9075773105887, 3705.6109108543756, 3706.3884459064493, 6136.386361363917, 6266.655333956585, 6662.833429164213, 7163.495665910217, 7336.486445948733, 7022.6488675566015, 7372.083258352867, 7403.22266162056, 7405.333519983227, 7410.296021877913, 7300.890486642598, 7325.304096110861, 7340.853182936311, 7354.669039893324, 7395.741149059757, 6820.590764554143, 7068.823279418056, 7103.680334385069, 7131.0, 7136.250693677297, 5763.94236057634, 5827.766889324549, 6124.663143526971, 6948.583085014915, 7061.611611361219, 6331.715072821642, 6500.869982600416, 6967.79096627102, 7320.084989376328, 7336.0, 4126.607972242632, 4456.197884380843, 4645.488996210482, 4893.363862697826, 4920.8769780755765, 7083.964580177066, 7169.247229041084, 7176.928230717626, 7209.0, 7231.720843783962, 6027.909581356195, 6193.411625895533, 6715.462762978852, 6784.830379240559, 6929.688164032731, 6484.740610375721, 6619.139511863427, 6899.310068993061, 6935.895961560682, 6943.826404339729, 5350.411454740053, 6212.968935155295, 6712.798616041331, 6742.460603151834, 6792.490563207681, 5699.430056994267, 6017.518598512021, 6868.793936181923, 6932.0, 6946.548474349152, 6143.938560614337, 6400.607677930704, 6707.832304192434, 6740.966295168493, 6771.45828333742], "value": 5175.809941482047, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [725.3109545931367, 823.1850468036644, 824.9587520623967, 845.7209120990074, 869.3740506835079, 16911.830881691196, 17097.31610735572, 17270.82729172708, 17324.220410081543, 17350.219240134193, 14073.296335183251, 14081.225532595729, 14110.153390796559, 17468.21393037313, 17522.0, 994.0208894239169, 1017.7913527726836, 1019.9643012494564, 1022.821006323894, 1025.676911772793, 1017.5980487707351, 1028.341861208827, 1036.29014125324, 1038.3250886923486, 1039.0336986602456, 1077.854489643896, 1096.4627332607006, 1099.351382684216, 1101.5869049106584, 1102.1127991966464, 928.3640706116298, 1051.4059556350637, 1054.3515737821185, 1062.1768129699467, 1086.7283179205199, 765.6937225109945, 776.9028871391076, 781.7928249014016, 781.847539729753, 781.9726309579138, 17567.824321756747, 17578.208980595904, 17704.468865934043, 17750.11249437523, 17785.288588456446, 17416.651666966718, 17936.730949035777, 18328.358507452176, 18365.0, 18384.06967268431, 14382.640433989234, 15102.622434439118, 15155.939084264106, 15199.696006079817, 15253.61865953349, 3621.692156166729, 3649.799261040668, 3653.6163702811355, 3653.9634603654154, 3654.671079602822, 133359.33320333433, 155748.2125893701, 159504.0, 161100.0, 164637.53043704355, 13257.93371033158, 14124.788128178085, 14313.78529322061, 14480.85519144795, 14677.192754398611, 26639.200823975312, 27231.319217019336, 27588.862055689595, 27693.584596231074, 27856.303592409946, 8261.215184557457, 8263.462874913112, 8280.875786863202, 8329.750107496784, 8372.623231954576, 4049.5545489996666, 4066.597024026706, 4095.160492099172, 4106.7946602669745, 4112.876613701534, 4666.696664716783, 4739.2891066339635, 4753.31076993834, 4754.072955773591, 4762.9523704763205, 994.8209322321907, 1056.1181413519644, 1056.7146870344964, 1062.8671416072991, 1064.164630764846, 540.8350453111839, 542.6445678080863, 559.1389260538806, 560.9298837645294, 564.4355644355644, 11605.0, 12009.819852702216, 12041.157119001586, 12385.62843114708, 12642.936785316015, 650.9772157974448, 660.6102399584172, 663.6018388966581, 673.6968364236113, 685.0033201691526, 5679.772809087673, 5712.514436272881, 5733.88532229353, 5784.161296612052, 5838.299404071538, 129323.35338323248, 152335.0, 157596.0, 160341.0, 160902.1954890218, 93517.0, 110896.0, 118844.40577797056, 125968.74031259754, 127122.45755084847, 81054.0, 105414.94585054052, 107454.46272768742, 109223.45388273007, 112404.43797781122, 9194.724158275263, 11926.880731192578, 12175.330356830105, 12219.450124744586, 12698.30159341245, 44078.55921440745, 46628.7668561655, 46848.0, 48641.5135848637, 48648.75675621599, 58840.411595883495, 60761.69619151876, 63964.68017660068, 64767.0, 65039.34960650333, 16807.243674034944, 16981.15094245258, 17686.78915135608, 17815.376461824293, 17979.910100449415, 18103.456896293133, 18476.630467390845, 18928.242870285587, 19331.4200573983, 19362.90318548398, 17127.400540981005, 18134.5466363342, 18474.538136546693, 18482.260709571467, 18756.87458752479, 14885.479008234659, 17116.914415427844, 18091.457256282334, 18411.0, 18425.815741842947, 17102.48692539226, 17614.0, 18155.27378904829, 18156.45530634083, 18347.174377152794, 11971.461284242405, 12086.697832554257, 12106.51573937068, 12294.016478681506, 12326.81509777372, 11321.825914600848, 12132.878671213175, 13230.140040897311, 13402.463901443833, 13460.663483412993], "value": 8922.073452318866, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1538.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 116.0, 106.0, 123.0, 117.0, 114.0, 122.0, 96.0, 166.0, 99.0], "value": 117.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 149.0, 167.0, 153.0, 112.0, 132.0, 112.0, 153.0, 172.0, 147.0], "value": 147.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 198.0, 193.0, 202.0, 173.0, 270.0, 281.0, 227.0, 196.0, 195.0], "value": 213.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 83.0, 81.0, 147.0, 62.0, 58.0, 58.0, 62.0, 126.0, 57.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 122.0, 68.0, 69.0, 71.0, 134.0, 101.0, 103.0, 145.0], "value": 95.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 92.0, 93.0, 93.0, 92.0, 93.0, 93.0, 92.0, 89.0], "value": 92.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 97.0, 101.0, 97.0, 97.0, 97.0, 98.0, 98.0, 97.0], "value": 97.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 93.0, 90.0, 116.0, 93.0, 93.0, 92.0, 91.0, 91.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 48.0, 52.0, 53.0, 51.0, 53.0, 54.0, 53.0, 53.0, 53.0], "value": 51.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 55.0, 56.0, 58.0, 60.0, 61.0, 57.0, 57.0, 56.0], "value": 56.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 80.0, 81.0, 80.0, 82.0, 93.0, 71.0, 78.0, 91.0, 72.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 135.0, 132.0, 144.0, 143.0, 148.0, 152.0, 134.0, 141.0, 161.0], "value": 142.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 224.0, 181.0, 175.0, 178.0, 185.0, 181.0, 216.0, 169.0, 213.0], "value": 190.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 88.0, 92.0, 95.0, 89.0, 65.0, 66.0, 63.0, 67.0], "value": 76.2, "unit": "score"}]}]} 14:00:04 INFO - Return code: 0 14:00:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:00:04 INFO - # TBPL SUCCESS # 14:00:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:00:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:00:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:00:04 INFO - 2017-06-14 14:00:04.505 screenresolution[2098:22288] starting screenresolution argv=screenresolution get 14:00:04 INFO - 2017-06-14 14:00:04.507 screenresolution[2098:22288] Display 0: 1600x1200x32@60 14:00:04 INFO - 2017-06-14 14:00:04.513 screenresolution[2099:22291] starting screenresolution argv=screenresolution list 14:00:04 INFO - Available Modes on Display 0 14:00:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:00:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:00:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:00:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:00:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:00:04 INFO - Intel Iris: 14:00:04 INFO - Chipset Model: Intel Iris 14:00:04 INFO - Type: GPU 14:00:04 INFO - Bus: Built-In 14:00:04 INFO - VRAM (Dynamic, Max): 1536 MB 14:00:04 INFO - Vendor: Intel (0x8086) 14:00:04 INFO - Device ID: 0x0a2e 14:00:04 INFO - Revision ID: 0x0009 14:00:04 INFO - Displays: 14:00:04 INFO - Display: 14:00:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:00:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:00:04 INFO - Display Serial Number: 1600x1200 60 14:00:04 INFO - Main Display: Yes 14:00:04 INFO - Mirror: Off 14:00:04 INFO - Online: Yes 14:00:04 INFO - Rotation: Supported 14:00:04 INFO - Return code: 0 14:00:04 INFO - Running post-action listener: _resource_record_post_action 14:00:04 INFO - [mozharness: 2017-06-14 21:00:04.748879Z] Finished run-tests step (success) 14:00:04 INFO - Running post-run listener: _resource_record_post_run 14:00:04 INFO - Total resource usage - Wall time: 1254s; CPU: 53.0%; Read bytes: 1658880; Write bytes: 560424960; Read time: 11; Write time: 1737 14:00:04 INFO - TinderboxPrint: CPU usage
53.1% 14:00:04 INFO - TinderboxPrint: I/O read bytes / time
1,658,880 / 11 14:00:04 INFO - TinderboxPrint: I/O write bytes / time
560,424,960 / 1,737 14:00:04 INFO - TinderboxPrint: CPU idle
2,353.5 (46.9%) 14:00:04 INFO - TinderboxPrint: CPU system
1,016.8 (20.3%) 14:00:04 INFO - TinderboxPrint: CPU user
1,647.4 (32.8%) 14:00:04 INFO - TinderboxPrint: Swap in / out
243,863,552 / 0 14:00:04 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 227590656; Write bytes: 225333248; Read time: 17018; Write time: 487 14:00:04 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 14:00:04 INFO - run-tests - Wall time: 1233s; CPU: 53.0%; Read bytes: 1658880; Write bytes: 316647424; Read time: 11; Write time: 1197 14:00:05 INFO - Running post-run listener: _upload_blobber_files 14:00:05 INFO - Blob upload gear active. 14:00:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:00:05 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:00:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:00:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:00:05 INFO - (blobuploader) - INFO - Open directory for files ... 14:00:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:00:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:00:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:00:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:00:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:00:07 INFO - (blobuploader) - INFO - Done attempting. 14:00:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:00:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:00:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:00:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:00:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:00:08 INFO - (blobuploader) - INFO - Done attempting. 14:00:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:00:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:00:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:00:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:00:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:00:09 INFO - (blobuploader) - INFO - Done attempting. 14:00:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:00:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:00:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:00:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:00:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:00:11 INFO - (blobuploader) - INFO - Done attempting. 14:00:11 INFO - (blobuploader) - INFO - Iteration through files over. 14:00:11 INFO - Return code: 0 14:00:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:00:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:00:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/58e82da73472035d440d1bfc1e2f277a2c0ac2acb59d74944582b2379b86fc0275acd884f5e2f8e373b709d46dc4163d2340ac17790df32e7b6d37ee54db72fa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1b78e71f3681f2ecca0289ccd66bbb32b926bf4ac471e41c2842b9036c0aa8a6734d484e8cac9c8f3af27179cc27dd1fa8d46be334efd0ca677d9258ce9c4609", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/43b6b9c294e904be7f9ef2ebeca4aff41b4f2eec64db0903f4fc327e624451ca920d7979911a8f626bfcc25881f8ea156d50f03b8bfbddff940a929213bd5c2b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/282a66e361395ed3e967a8caf5e87d675b089f0e44f272514397f05c07e896cd6fdb28b55d67ba2ced6fb905da9d8082fa3ce120f0bf03dadfd601038a6191c6"} 14:00:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:00:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:00:11 INFO - Contents: 14:00:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/58e82da73472035d440d1bfc1e2f277a2c0ac2acb59d74944582b2379b86fc0275acd884f5e2f8e373b709d46dc4163d2340ac17790df32e7b6d37ee54db72fa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1b78e71f3681f2ecca0289ccd66bbb32b926bf4ac471e41c2842b9036c0aa8a6734d484e8cac9c8f3af27179cc27dd1fa8d46be334efd0ca677d9258ce9c4609", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/43b6b9c294e904be7f9ef2ebeca4aff41b4f2eec64db0903f4fc327e624451ca920d7979911a8f626bfcc25881f8ea156d50f03b8bfbddff940a929213bd5c2b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/282a66e361395ed3e967a8caf5e87d675b089f0e44f272514397f05c07e896cd6fdb28b55d67ba2ced6fb905da9d8082fa3ce120f0bf03dadfd601038a6191c6"} 14:00:11 INFO - Running post-run listener: copy_logs_to_upload_dir 14:00:11 INFO - Copying logs to upload dir... 14:00:11 INFO - mkdir: /builds/slave/test/build/upload/logs 14:00:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1334.806695 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 14 secs) (at 2017-06-14 14:00:11.345278) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-14 14:00:11.353932) ========= 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.SaVKK6qIYe/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.5zXC6dXmKm/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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/58e82da73472035d440d1bfc1e2f277a2c0ac2acb59d74944582b2379b86fc0275acd884f5e2f8e373b709d46dc4163d2340ac17790df32e7b6d37ee54db72fa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1b78e71f3681f2ecca0289ccd66bbb32b926bf4ac471e41c2842b9036c0aa8a6734d484e8cac9c8f3af27179cc27dd1fa8d46be334efd0ca677d9258ce9c4609", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/43b6b9c294e904be7f9ef2ebeca4aff41b4f2eec64db0903f4fc327e624451ca920d7979911a8f626bfcc25881f8ea156d50f03b8bfbddff940a929213bd5c2b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/282a66e361395ed3e967a8caf5e87d675b089f0e44f272514397f05c07e896cd6fdb28b55d67ba2ced6fb905da9d8082fa3ce120f0bf03dadfd601038a6191c6"} build_url:https://queue.taskcluster.net/v1/task/Hl-OmLE2RiGJ7mHRbYqIZQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010649 build_url: 'https://queue.taskcluster.net/v1/task/Hl-OmLE2RiGJ7mHRbYqIZQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/58e82da73472035d440d1bfc1e2f277a2c0ac2acb59d74944582b2379b86fc0275acd884f5e2f8e373b709d46dc4163d2340ac17790df32e7b6d37ee54db72fa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1b78e71f3681f2ecca0289ccd66bbb32b926bf4ac471e41c2842b9036c0aa8a6734d484e8cac9c8f3af27179cc27dd1fa8d46be334efd0ca677d9258ce9c4609", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/43b6b9c294e904be7f9ef2ebeca4aff41b4f2eec64db0903f4fc327e624451ca920d7979911a8f626bfcc25881f8ea156d50f03b8bfbddff940a929213bd5c2b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/282a66e361395ed3e967a8caf5e87d675b089f0e44f272514397f05c07e896cd6fdb28b55d67ba2ced6fb905da9d8082fa3ce120f0bf03dadfd601038a6191c6"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-14 14:00:11.394625) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 14:00:11.394997) ========= 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.SaVKK6qIYe/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.5zXC6dXmKm/Listeners TMPDIR=/var/folders/4z/g2dtsn9d40n5qvzk37q47mnc00000w/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.005374 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 14:00:11.447194) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-14 14:00:11.450494) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-14 14:00:11.450872) ========= ========= Total master_lag: 0.22 =========