builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0342 starttime: 1496306122.44 results: success (0) buildid: 20170531234731 builduid: 0b718119936f4abb8d145b0c1465da7b revision: c2332a18922bace569fdbca5cb0f43a82b211ed3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 01:35:22.439136) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 01:35:22.439580) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 01:35:22.439869) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 01:35:22.465169) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 01:35:22.465550) ========= 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.eiTgUi7EgR/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.scD9Rwchgx/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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-01 01:35:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 63.1M=0s 2017-06-01 01:35:22 (63.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.101859 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 01:35:22.591449) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 01:35:22.591772) ========= 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.eiTgUi7EgR/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.scD9Rwchgx/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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.027251 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 01:35:22.640699) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-01 01:35:22.641144) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c2332a18922bace569fdbca5cb0f43a82b211ed3 --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 c2332a18922bace569fdbca5cb0f43a82b211ed3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eiTgUi7EgR/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.scD9Rwchgx/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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-01 01:35:22,722 truncating revision to first 12 chars 2017-06-01 01:35:22,722 Setting DEBUG logging. 2017-06-01 01:35:22,722 attempt 1/10 2017-06-01 01:35:22,722 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/c2332a18922b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-01 01:35:24,484 unpacking tar archive at: mozilla-beta-c2332a18922b/testing/mozharness/ program finished with exit code 0 elapsedTime=2.040564 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-01 01:35:24.705639) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 01:35:24.706131) ========= script_repo_revision: c2332a18922bace569fdbca5cb0f43a82b211ed3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 01:35:24.706703) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 01:35:24.707129) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 01:35:24.727453) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-06-01 01:35:24.728065) ========= /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.eiTgUi7EgR/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.scD9Rwchgx/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 01:35:24 INFO - MultiFileLogger online at 20170601 01:35:24 in /builds/slave/test 01:35:24 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 01:35:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:35:24 INFO - {'append_to_log': False, 01:35:24 INFO - 'base_work_dir': '/builds/slave/test', 01:35:24 INFO - 'blob_upload_branch': 'Mozilla-Beta', 01:35:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:35:24 INFO - 'branch': 'Mozilla-Beta', 01:35:24 INFO - 'buildbot_json_path': 'buildprops.json', 01:35:24 INFO - 'config_files': ('talos/mac_config.py',), 01:35:24 INFO - 'default_actions': ('clobber', 01:35:24 INFO - 'read-buildbot-config', 01:35:24 INFO - 'download-and-extract', 01:35:24 INFO - 'populate-webroot', 01:35:24 INFO - 'create-virtualenv', 01:35:24 INFO - 'install', 01:35:24 INFO - 'run-tests'), 01:35:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:35:24 INFO - 'download_minidump_stackwalk': True, 01:35:24 INFO - 'download_symbols': 'ondemand', 01:35:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:35:24 INFO - 'tooltool.py': '/tools/tooltool.py', 01:35:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:35:24 INFO - '/tools/misc-python/virtualenv.py')}, 01:35:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:35:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:35:24 INFO - 'gecko_profile': False, 01:35:24 INFO - 'gecko_profile_interval': 0, 01:35:24 INFO - 'installer_path': 'installer.exe', 01:35:24 INFO - 'log_level': 'info', 01:35:24 INFO - 'log_name': 'talos', 01:35:24 INFO - 'log_to_console': True, 01:35:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:35:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:35:24 INFO - 'opt_config_files': (), 01:35:24 INFO - 'pip_index': False, 01:35:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:35:24 INFO - 'cmd': ('bash', 01:35:24 INFO - '-c', 01:35:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:35:24 INFO - 'enabled': True, 01:35:24 INFO - 'halt_on_failure': False, 01:35:24 INFO - 'name': 'check_screen_resolution'},), 01:35:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:35:24 INFO - 'cmd': ('bash', 01:35:24 INFO - '-c', 01:35:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:35:24 INFO - 'enabled': True, 01:35:24 INFO - 'halt_on_failure': False, 01:35:24 INFO - 'name': 'check_screen_resolution'},), 01:35:24 INFO - 'run_cmd_checks_enabled': True, 01:35:24 INFO - 'suite': 'dromaeojs-e10s', 01:35:24 INFO - 'system_bits': '32', 01:35:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:35:24 INFO - 'title': 't-yosemite-r7-0342', 01:35:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:35:24 INFO - 'use_talos_json': True, 01:35:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:35:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:35:24 INFO - 'work_dir': 'build'} 01:35:24 INFO - [mozharness: 2017-06-01 08:35:24.919477Z] Running clobber step. 01:35:24 INFO - Running pre-action listener: _resource_record_pre_action 01:35:24 INFO - Running main action method: clobber 01:35:24 INFO - rmtree: /builds/slave/test/build 01:35:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:35:27 INFO - Running post-action listener: _resource_record_post_action 01:35:27 INFO - [mozharness: 2017-06-01 08:35:27.343553Z] Finished clobber step (success) 01:35:27 INFO - [mozharness: 2017-06-01 08:35:27.343707Z] Running read-buildbot-config step. 01:35:27 INFO - Running pre-action listener: _resource_record_pre_action 01:35:27 INFO - Running main action method: read_buildbot_config 01:35:27 INFO - Using buildbot properties: 01:35:27 INFO - { 01:35:27 INFO - "project": "", 01:35:27 INFO - "product": "firefox", 01:35:27 INFO - "script_repo_revision": "production", 01:35:27 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 01:35:27 INFO - "repository": "", 01:35:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 01:35:27 INFO - "buildid": "20170531234731", 01:35:27 INFO - "pgo_build": "False", 01:35:27 INFO - "basedir": "/builds/slave/test", 01:35:27 INFO - "buildnumber": 8, 01:35:27 INFO - "slavename": "t-yosemite-r7-0342", 01:35:27 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 01:35:27 INFO - "platform": "yosemite_r7", 01:35:27 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 01:35:27 INFO - "branch": "mozilla-beta", 01:35:27 INFO - "repo_path": "releases/mozilla-beta", 01:35:27 INFO - "revision": "c2332a18922bace569fdbca5cb0f43a82b211ed3", 01:35:27 INFO - "stage_platform": "macosx64", 01:35:27 INFO - "builduid": "0b718119936f4abb8d145b0c1465da7b", 01:35:27 INFO - "slavebuilddir": "test" 01:35:27 INFO - } 01:35:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/BSTph80pTuydBM8Qh1BuDw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 01:35:27 INFO - Running post-action listener: _resource_record_post_action 01:35:27 INFO - [mozharness: 2017-06-01 08:35:27.346940Z] Finished read-buildbot-config step (success) 01:35:27 INFO - [mozharness: 2017-06-01 08:35:27.347044Z] Running download-and-extract step. 01:35:27 INFO - Running pre-action listener: _resource_record_pre_action 01:35:27 INFO - Running main action method: download_and_extract 01:35:27 INFO - mkdir: /builds/slave/test/build/tests 01:35:27 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')]} 01:35:27 INFO - trying https://queue.taskcluster.net/v1/task/BSTph80pTuydBM8Qh1BuDw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 01:35:27 INFO - Downloading https://queue.taskcluster.net/v1/task/BSTph80pTuydBM8Qh1BuDw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 01:35:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BSTph80pTuydBM8Qh1BuDw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 01:35:28 INFO - Downloaded 1287 bytes. 01:35:28 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 01:35:28 INFO - Using the following test package requirements: 01:35:28 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 01:35:28 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 01:35:28 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 01:35:28 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 01:35:28 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 01:35:28 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 01:35:28 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 01:35:28 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 01:35:28 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 01:35:28 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 01:35:28 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 01:35:28 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 01:35:28 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 01:35:28 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 01:35:28 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 01:35:28 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 01:35:28 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 01:35:28 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 01:35:28 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 01:35:28 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 01:35:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BSTph80pTuydBM8Qh1BuDw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 01:35:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BSTph80pTuydBM8Qh1BuDw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 01:35:28 INFO - Fetch https://queue.taskcluster.net/v1/task/BSTph80pTuydBM8Qh1BuDw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 01:35:30 INFO - Expected file size: 51397779 01:35:30 INFO - Obtained file size: 51397779 01:35:46 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 01:35:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BSTph80pTuydBM8Qh1BuDw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 01:35:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BSTph80pTuydBM8Qh1BuDw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 01:35:46 INFO - Fetch https://queue.taskcluster.net/v1/task/BSTph80pTuydBM8Qh1BuDw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 01:35:47 INFO - Expected file size: 51397779 01:35:47 INFO - Obtained file size: 51397779 01:36:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BSTph80pTuydBM8Qh1BuDw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 01:36:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BSTph80pTuydBM8Qh1BuDw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 01:36:02 INFO - Fetch https://queue.taskcluster.net/v1/task/BSTph80pTuydBM8Qh1BuDw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 01:36:04 INFO - Expected file size: 14404461 01:36:04 INFO - Obtained file size: 14404461 01:36:04 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')]} 01:36:04 INFO - trying https://queue.taskcluster.net/v1/task/BSTph80pTuydBM8Qh1BuDw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 01:36:04 INFO - Downloading https://queue.taskcluster.net/v1/task/BSTph80pTuydBM8Qh1BuDw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 01:36:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BSTph80pTuydBM8Qh1BuDw/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 01:36:06 INFO - Downloaded 59647338 bytes. 01:36:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BSTph80pTuydBM8Qh1BuDw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 01:36:06 INFO - mkdir: /builds/slave/test/properties 01:36:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:36:06 INFO - Writing to file /builds/slave/test/properties/build_url 01:36:06 INFO - Contents: 01:36:06 INFO - build_url:https://queue.taskcluster.net/v1/task/BSTph80pTuydBM8Qh1BuDw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 01:36:06 INFO - Running post-action listener: _resource_record_post_action 01:36:06 INFO - Running post-action listener: set_extra_try_arguments 01:36:06 INFO - [mozharness: 2017-06-01 08:36:06.957005Z] Finished download-and-extract step (success) 01:36:06 INFO - [mozharness: 2017-06-01 08:36:06.957160Z] Running populate-webroot step. 01:36:06 INFO - Running pre-action listener: _resource_record_pre_action 01:36:06 INFO - Running main action method: populate_webroot 01:36:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:36:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:36:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:36:06 INFO - '--tppagecycles', 01:36:06 INFO - '7'], 01:36:06 INFO - 'tests': ['tsvgm']}}, 01:36:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:36:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:36:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:36:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:36:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:36:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:36:06 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:36:06 INFO - 'talos_options': ['--disable-e10s'], 01:36:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:36:06 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:36:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:36:06 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:36:06 INFO - 'talos_options': ['--disable-e10s'], 01:36:06 INFO - 'tests': ['damp', 'tps']}, 01:36:06 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:36:06 INFO - 'tests': ['damp', 'tps']}, 01:36:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:36:06 INFO - 'tests': ['dromaeo_dom']}, 01:36:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:36:06 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:36:06 INFO - 'tests': ['basic_compositor_video']}, 01:36:06 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:36:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:36:06 INFO - 'tests': ['a11yr', 01:36:06 INFO - 'ts_paint', 01:36:06 INFO - 'tpaint', 01:36:06 INFO - 'sessionrestore', 01:36:06 INFO - 'sessionrestore_no_auto_restore', 01:36:06 INFO - 'tabpaint']}, 01:36:06 INFO - 'other-e10s': {'tests': ['a11yr', 01:36:06 INFO - 'ts_paint', 01:36:06 INFO - 'tpaint', 01:36:06 INFO - 'sessionrestore', 01:36:06 INFO - 'sessionrestore_no_auto_restore', 01:36:06 INFO - 'tabpaint']}, 01:36:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:36:06 INFO - 'ts_paint', 01:36:06 INFO - 'tpaint', 01:36:06 INFO - 'sessionrestore', 01:36:06 INFO - 'sessionrestore_no_auto_restore', 01:36:06 INFO - 'tabpaint']}, 01:36:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:36:06 INFO - 'ts_paint', 01:36:06 INFO - 'tpaint', 01:36:06 INFO - 'sessionrestore', 01:36:06 INFO - 'sessionrestore_no_auto_restore', 01:36:06 INFO - 'tabpaint']}, 01:36:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:36:06 INFO - 'tests': ['a11yr', 01:36:06 INFO - 'ts_paint', 01:36:06 INFO - 'tpaint', 01:36:06 INFO - 'sessionrestore', 01:36:06 INFO - 'sessionrestore_no_auto_restore', 01:36:06 INFO - 'tabpaint']}, 01:36:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:36:06 INFO - 'tests': ['a11yr', 01:36:06 INFO - 'ts_paint', 01:36:06 INFO - 'tpaint', 01:36:06 INFO - 'sessionrestore', 01:36:06 INFO - 'sessionrestore_no_auto_restore', 01:36:06 INFO - 'tabpaint']}, 01:36:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:36:06 INFO - 'tests': ['tsvgx', 01:36:06 INFO - 'tsvgr_opacity', 01:36:06 INFO - 'tart', 01:36:06 INFO - 'tscrollx', 01:36:06 INFO - 'cart', 01:36:06 INFO - 'tsvg_static']}, 01:36:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:36:06 INFO - 'tsvgr_opacity', 01:36:06 INFO - 'tart', 01:36:06 INFO - 'tscrollx', 01:36:06 INFO - 'cart', 01:36:06 INFO - 'tsvg_static']}, 01:36:06 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:36:06 INFO - 'talos_options': ['--disable-e10s'], 01:36:06 INFO - 'tests': ['tp5o']}, 01:36:06 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:36:06 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:36:06 INFO - 'talos_options': ['--disable-e10s', 01:36:06 INFO - '--xperf_path', 01:36:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:36:06 INFO - 'tests': ['tp5n']}, 01:36:06 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:36:06 INFO - 'talos_options': ['--xperf_path', 01:36:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:36:06 INFO - 'tests': ['tp5n']}}, 01:36:06 INFO - 'talos.zip': {'path': '', 01:36:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:36:06 INFO - Running post-action listener: _resource_record_post_action 01:36:06 INFO - [mozharness: 2017-06-01 08:36:06.976280Z] Finished populate-webroot step (success) 01:36:06 INFO - [mozharness: 2017-06-01 08:36:06.976413Z] Running create-virtualenv step. 01:36:06 INFO - Running pre-action listener: _resource_record_pre_action 01:36:06 INFO - Running main action method: create_virtualenv 01:36:06 INFO - Creating virtualenv /builds/slave/test/build/venv 01:36:06 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 01:36:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:36:06 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 01:36:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:36:07 INFO - Using real prefix '/tools/python27' 01:36:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:36:07 INFO - Installing distribute.............................................................................................................................................................................................done. 01:36:10 INFO - Installing pip.................done. 01:36:10 INFO - Return code: 0 01:36:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:36:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:36:10 INFO - Reading from file tmpfile_stdout 01:36:10 INFO - Output received: 01:36:10 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) 01:36:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:36:10 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')]} 01:36:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:10 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')]} 01:36:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:10 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 0x1093e1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c79c88af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091bfd30>, '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 0x1090e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091b4be8>, '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.eiTgUi7EgR/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.scD9Rwchgx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 01:36:10 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 01:36:10 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 01:36:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eiTgUi7EgR/Render', 01:36:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:10 INFO - 'HOME': '/Users/cltbld', 01:36:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:10 INFO - 'LOGNAME': 'cltbld', 01:36:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:10 INFO - 'MOZ_NO_REMOTE': '1', 01:36:10 INFO - 'NO_EM_RESTART': '1', 01:36:10 INFO - 'PAGER': '/bin/cat', 01:36:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:10 INFO - 'PWD': '/builds/slave/test', 01:36:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:10 INFO - 'SHELL': '/bin/bash', 01:36:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.scD9Rwchgx/Listeners', 01:36:10 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 01:36:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:10 INFO - 'USER': 'cltbld', 01:36:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:10 INFO - 'XPC_FLAGS': '0x0', 01:36:10 INFO - 'XPC_SERVICE_NAME': '0', 01:36:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:11 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 01:36:11 INFO - Cleaning up... 01:36:11 INFO - Return code: 0 01:36:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:36:11 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')]} 01:36:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:11 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')]} 01:36:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:11 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 0x1093e1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c79c88af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091bfd30>, '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 0x1090e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091b4be8>, '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.eiTgUi7EgR/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.scD9Rwchgx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 01:36:11 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 01:36:11 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 01:36:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eiTgUi7EgR/Render', 01:36:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:11 INFO - 'HOME': '/Users/cltbld', 01:36:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:11 INFO - 'LOGNAME': 'cltbld', 01:36:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:11 INFO - 'MOZ_NO_REMOTE': '1', 01:36:11 INFO - 'NO_EM_RESTART': '1', 01:36:11 INFO - 'PAGER': '/bin/cat', 01:36:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:11 INFO - 'PWD': '/builds/slave/test', 01:36:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:11 INFO - 'SHELL': '/bin/bash', 01:36:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.scD9Rwchgx/Listeners', 01:36:11 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 01:36:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:11 INFO - 'USER': 'cltbld', 01:36:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:11 INFO - 'XPC_FLAGS': '0x0', 01:36:11 INFO - 'XPC_SERVICE_NAME': '0', 01:36:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:11 INFO - Downloading/unpacking psutil>=3.1.1 01:36:11 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 01:36:11 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 01:36:11 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 01:36:11 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 01:36:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:36:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:36:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:36:13 INFO - Installing collected packages: psutil 01:36:13 INFO - Running setup.py install for psutil 01:36:13 INFO - building 'psutil._psutil_osx' extension 01:36:13 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 01:36:13 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 01:36:14 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 01:36:14 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 01:36:14 INFO - building 'psutil._psutil_posix' extension 01:36:14 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 01:36:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:36:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:36:14 INFO - ^ 01:36:14 INFO - 1 warning generated. 01:36:14 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 01:36:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:36:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:36:14 INFO - Successfully installed psutil 01:36:14 INFO - Cleaning up... 01:36:14 INFO - Return code: 0 01:36:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:36:14 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')]} 01:36:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:14 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')]} 01:36:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:14 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 0x1093e1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c79c88af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091bfd30>, '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 0x1090e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091b4be8>, '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.eiTgUi7EgR/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.scD9Rwchgx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 01:36:14 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 01:36:14 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 01:36:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eiTgUi7EgR/Render', 01:36:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:14 INFO - 'HOME': '/Users/cltbld', 01:36:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:14 INFO - 'LOGNAME': 'cltbld', 01:36:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:14 INFO - 'MOZ_NO_REMOTE': '1', 01:36:14 INFO - 'NO_EM_RESTART': '1', 01:36:14 INFO - 'PAGER': '/bin/cat', 01:36:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:14 INFO - 'PWD': '/builds/slave/test', 01:36:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:14 INFO - 'SHELL': '/bin/bash', 01:36:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.scD9Rwchgx/Listeners', 01:36:14 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 01:36:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:14 INFO - 'USER': 'cltbld', 01:36:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:14 INFO - 'XPC_FLAGS': '0x0', 01:36:14 INFO - 'XPC_SERVICE_NAME': '0', 01:36:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:36:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:36:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:36:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:36:16 INFO - Installing collected packages: mozsystemmonitor 01:36:16 INFO - Running setup.py install for mozsystemmonitor 01:36:16 INFO - Successfully installed mozsystemmonitor 01:36:16 INFO - Cleaning up... 01:36:16 INFO - Return code: 0 01:36:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:36:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1093e1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c79c88af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091bfd30>, '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 0x1090e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091b4be8>, '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.eiTgUi7EgR/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.scD9Rwchgx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 01:36:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:36:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 01:36:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eiTgUi7EgR/Render', 01:36:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:16 INFO - 'HOME': '/Users/cltbld', 01:36:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:16 INFO - 'LOGNAME': 'cltbld', 01:36:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:16 INFO - 'MOZ_NO_REMOTE': '1', 01:36:16 INFO - 'NO_EM_RESTART': '1', 01:36:16 INFO - 'PAGER': '/bin/cat', 01:36:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:16 INFO - 'PWD': '/builds/slave/test', 01:36:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:16 INFO - 'SHELL': '/bin/bash', 01:36:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.scD9Rwchgx/Listeners', 01:36:16 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 01:36:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:16 INFO - 'USER': 'cltbld', 01:36:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:16 INFO - 'XPC_FLAGS': '0x0', 01:36:16 INFO - 'XPC_SERVICE_NAME': '0', 01:36:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:16 INFO - Downloading/unpacking jsonschema==2.5.1 01:36:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:36:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:36:18 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 01:36:18 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 01:36:18 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 01:36:18 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 01:36:18 INFO - Downloading functools32-3.2.3-2.tar.gz 01:36:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:36:18 INFO - warning: no files found matching '*.txt' 01:36:18 INFO - no previously-included directories found matching 'build' 01:36:18 INFO - no previously-included directories found matching 'dist' 01:36:18 INFO - no previously-included directories found matching '.git*' 01:36:18 INFO - Installing collected packages: jsonschema, functools32 01:36:18 INFO - Running setup.py install for functools32 01:36:18 INFO - warning: no files found matching '*.txt' 01:36:18 INFO - no previously-included directories found matching 'build' 01:36:18 INFO - no previously-included directories found matching 'dist' 01:36:18 INFO - no previously-included directories found matching '.git*' 01:36:18 INFO - Successfully installed jsonschema functools32 01:36:18 INFO - Cleaning up... 01:36:18 INFO - Return code: 0 01:36:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:36:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1093e1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c79c88af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091bfd30>, '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 0x1090e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091b4be8>, '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.eiTgUi7EgR/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.scD9Rwchgx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 01:36:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:36:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 01:36:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eiTgUi7EgR/Render', 01:36:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:19 INFO - 'HOME': '/Users/cltbld', 01:36:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:19 INFO - 'LOGNAME': 'cltbld', 01:36:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:19 INFO - 'MOZ_NO_REMOTE': '1', 01:36:19 INFO - 'NO_EM_RESTART': '1', 01:36:19 INFO - 'PAGER': '/bin/cat', 01:36:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:19 INFO - 'PWD': '/builds/slave/test', 01:36:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:19 INFO - 'SHELL': '/bin/bash', 01:36:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.scD9Rwchgx/Listeners', 01:36:19 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 01:36:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:19 INFO - 'USER': 'cltbld', 01:36:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:19 INFO - 'XPC_FLAGS': '0x0', 01:36:19 INFO - 'XPC_SERVICE_NAME': '0', 01:36:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:36:19 INFO - Cleaning up... 01:36:19 INFO - Return code: 0 01:36:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:36:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1093e1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c79c88af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091bfd30>, '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 0x1090e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091b4be8>, '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.eiTgUi7EgR/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.scD9Rwchgx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 01:36:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:36:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:36:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eiTgUi7EgR/Render', 01:36:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:20 INFO - 'HOME': '/Users/cltbld', 01:36:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:20 INFO - 'LOGNAME': 'cltbld', 01:36:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:20 INFO - 'MOZ_NO_REMOTE': '1', 01:36:20 INFO - 'NO_EM_RESTART': '1', 01:36:20 INFO - 'PAGER': '/bin/cat', 01:36:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:20 INFO - 'PWD': '/builds/slave/test', 01:36:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:20 INFO - 'SHELL': '/bin/bash', 01:36:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.scD9Rwchgx/Listeners', 01:36:20 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 01:36:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:20 INFO - 'USER': 'cltbld', 01:36:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:20 INFO - 'XPC_FLAGS': '0x0', 01:36:20 INFO - 'XPC_SERVICE_NAME': '0', 01:36:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:20 INFO - Downloading/unpacking blobuploader==1.2.4 01:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:22 INFO - Downloading blobuploader-1.2.4.tar.gz 01:36:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:36:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:36:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:36:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:36:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:36:23 INFO - Downloading docopt-0.6.1.tar.gz 01:36:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:36:23 INFO - Installing collected packages: blobuploader, requests, docopt 01:36:23 INFO - Running setup.py install for blobuploader 01:36:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:36:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:36:23 INFO - Running setup.py install for requests 01:36:24 INFO - Running setup.py install for docopt 01:36:24 INFO - Successfully installed blobuploader requests docopt 01:36:24 INFO - Cleaning up... 01:36:24 INFO - Return code: 0 01:36:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:36:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:24 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 0x1093e1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c79c88af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091bfd30>, '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 0x1090e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091b4be8>, '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.eiTgUi7EgR/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.scD9Rwchgx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 01:36:24 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 01:36:24 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 01:36:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eiTgUi7EgR/Render', 01:36:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:24 INFO - 'HOME': '/Users/cltbld', 01:36:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:24 INFO - 'LOGNAME': 'cltbld', 01:36:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:24 INFO - 'MOZ_NO_REMOTE': '1', 01:36:24 INFO - 'NO_EM_RESTART': '1', 01:36:24 INFO - 'PAGER': '/bin/cat', 01:36:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:24 INFO - 'PWD': '/builds/slave/test', 01:36:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:24 INFO - 'SHELL': '/bin/bash', 01:36:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.scD9Rwchgx/Listeners', 01:36:24 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 01:36:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:24 INFO - 'USER': 'cltbld', 01:36:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:24 INFO - 'XPC_FLAGS': '0x0', 01:36:24 INFO - 'XPC_SERVICE_NAME': '0', 01:36:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:36:24 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-ZHs0Ue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:36:24 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-Vm9WY9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:36:24 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-hGMs4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:36:24 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-2eMxSF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:36:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:36:24 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-kx8LdT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:36:25 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-IO512J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:36:25 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-6yb4W3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:36:25 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-xe3hqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:36:25 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-ES7GZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:36:25 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-uSAAHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:36:25 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-9EmJXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:36:25 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-AcUbTW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:36:25 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-9IG579-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:36:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:36:25 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-iVmDRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:36:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:36:26 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-ATXJ_3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:36:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:36:26 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-wKhd0g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:36:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:36:26 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-zhvoKh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:36:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:36:26 INFO - Running setup.py install for manifestparser 01:36:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:36:26 INFO - Running setup.py install for mozcrash 01:36:26 INFO - Running setup.py install for mozdebug 01:36:26 INFO - Running setup.py install for mozdevice 01:36:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:36:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:36:26 INFO - Running setup.py install for mozfile 01:36:27 INFO - Running setup.py install for mozhttpd 01:36:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:36:27 INFO - Running setup.py install for mozinfo 01:36:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:36:27 INFO - Running setup.py install for mozInstall 01:36:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:36:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:36:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:36:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:36:27 INFO - Running setup.py install for mozleak 01:36:27 INFO - Running setup.py install for mozlog 01:36:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:36:27 INFO - Running setup.py install for moznetwork 01:36:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:36:27 INFO - Running setup.py install for mozprocess 01:36:28 INFO - Running setup.py install for mozprofile 01:36:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:36:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:36:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:36:28 INFO - Running setup.py install for mozrunner 01:36:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:36:28 INFO - Running setup.py install for mozscreenshot 01:36:28 INFO - Running setup.py install for moztest 01:36:28 INFO - Running setup.py install for mozversion 01:36:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:36:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:36:28 INFO - Cleaning up... 01:36:28 INFO - Return code: 0 01:36:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:36:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:28 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 0x1093e1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c79c88af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091bfd30>, '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 0x1090e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091b4be8>, '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.eiTgUi7EgR/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.scD9Rwchgx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 01:36:28 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 01:36:28 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 01:36:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eiTgUi7EgR/Render', 01:36:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:28 INFO - 'HOME': '/Users/cltbld', 01:36:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:28 INFO - 'LOGNAME': 'cltbld', 01:36:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:28 INFO - 'MOZ_NO_REMOTE': '1', 01:36:28 INFO - 'NO_EM_RESTART': '1', 01:36:28 INFO - 'PAGER': '/bin/cat', 01:36:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:28 INFO - 'PWD': '/builds/slave/test', 01:36:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:28 INFO - 'SHELL': '/bin/bash', 01:36:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.scD9Rwchgx/Listeners', 01:36:28 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 01:36:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:28 INFO - 'USER': 'cltbld', 01:36:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:28 INFO - 'XPC_FLAGS': '0x0', 01:36:28 INFO - 'XPC_SERVICE_NAME': '0', 01:36:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:36:29 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-rNPMU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:36:29 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)) 01:36:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:36:29 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-9SHExF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:36:29 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)) 01:36:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:36:29 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-Mw8qhU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:36:29 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)) 01:36:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:36:29 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-sgXW_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:36:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:36:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:36:29 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-VJqage-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:36:29 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)) 01:36:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:36:29 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-tAD0iO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:36:29 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)) 01:36:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:36:29 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-efoTBg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:36:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 01:36:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:36:29 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-xabmwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:36:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 01:36:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:36:29 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-PoSjfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:36:30 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)) 01:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:36:30 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-hIP3PJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:36:30 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)) 01:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:36:30 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-K1iDAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:36:30 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)) 01:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:36:30 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-c2RZO9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:36:30 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)) 01:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:36:30 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-T72ra_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:36:30 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)) 01:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:36:30 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-TMjIjE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:36:30 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)) 01:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:36:30 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-47MWWR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:36:30 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)) 01:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:36:30 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-KhDpm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:36:30 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)) 01:36:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:36:30 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-GMCvI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:36:30 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)) 01:36:30 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)) 01:36:30 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)) 01:36:30 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)) 01:36:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:36:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:36:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:36:30 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 01:36:30 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 01:36:30 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 01:36:30 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 01:36:32 INFO - Downloading blessings-1.6.tar.gz 01:36:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:36:32 INFO - Installing collected packages: blessings 01:36:32 INFO - Running setup.py install for blessings 01:36:32 INFO - Successfully installed blessings 01:36:32 INFO - Cleaning up... 01:36:32 INFO - Return code: 0 01:36:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:36:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:36:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:36:32 INFO - Reading from file tmpfile_stdout 01:36:32 INFO - Current package versions: 01:36:32 INFO - blessings == 1.6 01:36:32 INFO - blobuploader == 1.2.4 01:36:32 INFO - docopt == 0.6.1 01:36:32 INFO - functools32 == 3.2.3-2 01:36:32 INFO - jsonschema == 2.5.1 01:36:32 INFO - manifestparser == 1.1 01:36:32 INFO - mozInstall == 1.12 01:36:32 INFO - mozcrash == 1.0 01:36:32 INFO - mozdebug == 0.1 01:36:32 INFO - mozdevice == 0.49 01:36:32 INFO - mozfile == 1.2 01:36:32 INFO - mozhttpd == 0.7 01:36:32 INFO - mozinfo == 0.9 01:36:32 INFO - mozleak == 0.1 01:36:32 INFO - mozlog == 3.4 01:36:32 INFO - moznetwork == 0.27 01:36:32 INFO - mozprocess == 0.25 01:36:32 INFO - mozprofile == 0.28 01:36:32 INFO - mozrunner == 6.13 01:36:32 INFO - mozscreenshot == 0.1 01:36:32 INFO - mozsystemmonitor == 0.3 01:36:32 INFO - moztest == 0.8 01:36:32 INFO - mozversion == 1.4 01:36:32 INFO - psutil == 3.1.1 01:36:32 INFO - requests == 1.2.3 01:36:32 INFO - wsgiref == 0.1.2 01:36:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:36: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')]} 01:36:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36: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')]} 01:36:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:32 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 0x1093e1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c79c88af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091bfd30>, '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 0x1090e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091b4be8>, '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.eiTgUi7EgR/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.scD9Rwchgx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 01:36:32 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 01:36:32 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 01:36:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eiTgUi7EgR/Render', 01:36:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:32 INFO - 'HOME': '/Users/cltbld', 01:36:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:32 INFO - 'LOGNAME': 'cltbld', 01:36:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:32 INFO - 'MOZ_NO_REMOTE': '1', 01:36:32 INFO - 'NO_EM_RESTART': '1', 01:36:32 INFO - 'PAGER': '/bin/cat', 01:36:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:32 INFO - 'PWD': '/builds/slave/test', 01:36:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:32 INFO - 'SHELL': '/bin/bash', 01:36:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.scD9Rwchgx/Listeners', 01:36:32 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 01:36:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:32 INFO - 'USER': 'cltbld', 01:36:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:32 INFO - 'XPC_FLAGS': '0x0', 01:36:32 INFO - 'XPC_SERVICE_NAME': '0', 01:36:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:33 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)) 01:36:33 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)) 01:36:33 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)) 01:36:33 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)) 01:36:33 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)) 01:36:33 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)) 01:36:33 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)) 01:36:33 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)) 01:36:33 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)) 01:36:33 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)) 01:36:33 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)) 01:36:33 INFO - Cleaning up... 01:36:33 INFO - Return code: 0 01:36:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:36:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:36:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:36:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:36:33 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 0x1093e1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1093e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c79c88af0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091bfd30>, '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 0x1090e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091b4be8>, '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.eiTgUi7EgR/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.scD9Rwchgx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 01:36:33 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 01:36:33 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 01:36:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eiTgUi7EgR/Render', 01:36:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:33 INFO - 'HOME': '/Users/cltbld', 01:36:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:33 INFO - 'LOGNAME': 'cltbld', 01:36:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:33 INFO - 'MOZ_NO_REMOTE': '1', 01:36:33 INFO - 'NO_EM_RESTART': '1', 01:36:33 INFO - 'PAGER': '/bin/cat', 01:36:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:33 INFO - 'PWD': '/builds/slave/test', 01:36:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:33 INFO - 'SHELL': '/bin/bash', 01:36:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.scD9Rwchgx/Listeners', 01:36:33 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 01:36:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:33 INFO - 'USER': 'cltbld', 01:36:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:33 INFO - 'XPC_FLAGS': '0x0', 01:36:33 INFO - 'XPC_SERVICE_NAME': '0', 01:36:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:36:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:36:33 INFO - Cleaning up... 01:36:33 INFO - Return code: 0 01:36:33 INFO - Running post-action listener: _resource_record_post_action 01:36:33 INFO - Running post-action listener: _start_resource_monitoring 01:36:33 INFO - Starting resource monitoring. 01:36:33 INFO - [mozharness: 2017-06-01 08:36:33.525185Z] Finished create-virtualenv step (success) 01:36:33 INFO - [mozharness: 2017-06-01 08:36:33.526127Z] Running install step. 01:36:33 INFO - Running pre-action listener: _resource_record_pre_action 01:36:33 INFO - Running main action method: install 01:36:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:36:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:36:33 INFO - Reading from file tmpfile_stdout 01:36:33 INFO - Detecting whether we're running mozinstall >=1.0... 01:36:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:36:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:36:33 INFO - Reading from file tmpfile_stdout 01:36:33 INFO - Output received: 01:36:33 INFO - Usage: mozinstall [options] installer 01:36:33 INFO - Options: 01:36:33 INFO - -h, --help show this help message and exit 01:36:33 INFO - -d DEST, --destination=DEST 01:36:33 INFO - Directory to install application into. [default: 01:36:33 INFO - "/builds/slave/test"] 01:36:33 INFO - --app=APP Application being installed. [default: firefox] 01:36:33 INFO - mkdir: /builds/slave/test/build/application 01:36:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:36:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 01:36:48 INFO - Reading from file tmpfile_stdout 01:36:48 INFO - Output received: 01:36:48 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 01:36:48 INFO - Running post-action listener: _resource_record_post_action 01:36:48 INFO - [mozharness: 2017-06-01 08:36:48.759227Z] Finished install step (success) 01:36:48 INFO - [mozharness: 2017-06-01 08:36:48.759399Z] Running run-tests step. 01:36:48 INFO - Running pre-action listener: _resource_record_pre_action 01:36:48 INFO - Running main action method: run_tests 01:36:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:36:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:36:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:36:48 INFO - 2017-06-01 01:36:48.773 screenresolution[1622:7363] starting screenresolution argv=screenresolution get 01:36:48 INFO - 2017-06-01 01:36:48.776 screenresolution[1622:7363] Display 0: 1600x1200x32@60 01:36:48 INFO - 2017-06-01 01:36:48.782 screenresolution[1623:7365] starting screenresolution argv=screenresolution list 01:36:48 INFO - Available Modes on Display 0 01:36:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:36:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:36:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:36:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:36:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:36:49 INFO - Intel Iris: 01:36:49 INFO - Chipset Model: Intel Iris 01:36:49 INFO - Type: GPU 01:36:49 INFO - Bus: Built-In 01:36:49 INFO - VRAM (Dynamic, Max): 1536 MB 01:36:49 INFO - Vendor: Intel (0x8086) 01:36:49 INFO - Device ID: 0x0a2e 01:36:49 INFO - Revision ID: 0x0009 01:36:49 INFO - Displays: 01:36:49 INFO - Display: 01:36:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:36:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:36:49 INFO - Display Serial Number: 1600x1200 60 01:36:49 INFO - Main Display: Yes 01:36:49 INFO - Mirror: Off 01:36:49 INFO - Online: Yes 01:36:49 INFO - Rotation: Supported 01:36:49 INFO - Return code: 0 01:36:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:36:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:36:49 INFO - Python 2.7.3 01:36:49 INFO - Return code: 0 01:36:49 INFO - grabbing minidump binary from tooltool 01:36:49 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')]} 01:36:49 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 0x1091bfd30>, '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 0x1090e6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1091b4be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:36:49 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 01:36:49 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 01:36:49 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 01:36:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:36:49 INFO - Return code: 0 01:36:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:36:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:36:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:36:49 INFO - ENV: RUST_BACKTRACE is now 1 01:36:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:36:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:36:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BSTph80pTuydBM8Qh1BuDw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0342', '--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 01:36:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BSTph80pTuydBM8Qh1BuDw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0342 --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 01:36:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eiTgUi7EgR/Render', 01:36:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:36:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:36:49 INFO - 'HOME': '/Users/cltbld', 01:36:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:36:49 INFO - 'LOGNAME': 'cltbld', 01:36:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:36:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:36:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:36:49 INFO - 'MOZ_NO_REMOTE': '1', 01:36:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:36:49 INFO - 'NO_EM_RESTART': '1', 01:36:49 INFO - 'PAGER': '/bin/cat', 01:36:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:36:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:36:49 INFO - 'PWD': '/builds/slave/test', 01:36:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:36:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:36:49 INFO - 'RUST_BACKTRACE': '1', 01:36:49 INFO - 'SHELL': '/bin/bash', 01:36:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.scD9Rwchgx/Listeners', 01:36:49 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 01:36:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:36:49 INFO - 'USER': 'cltbld', 01:36:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:36:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:36:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:36:49 INFO - 'XPC_FLAGS': '0x0', 01:36:49 INFO - 'XPC_SERVICE_NAME': '0', 01:36:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:36:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BSTph80pTuydBM8Qh1BuDw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0342', '--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 01:36:49 INFO - mozversion application_buildid: 20170531234731 01:36:49 INFO - mozversion application_changeset: c2332a18922bace569fdbca5cb0f43a82b211ed3 01:36:49 INFO - mozversion application_display_name: Firefox 01:36:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:36:49 INFO - mozversion application_name: Firefox 01:36:49 INFO - mozversion application_remotingname: firefox 01:36:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 01:36:49 INFO - mozversion application_vendor: Mozilla 01:36:49 INFO - mozversion application_version: 54.0 01:36:49 INFO - mozversion platform_buildid: 20170531234731 01:36:49 INFO - mozversion platform_changeset: c2332a18922bace569fdbca5cb0f43a82b211ed3 01:36:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 01:36:49 INFO - mozversion platform_version: 54.0 01:36:49 INFO - using testdate: 1496306209 01:36:49 INFO - actual date: 1496306209 01:36:49 INFO - starting webserver on 'localhost:49219' 01:36:49 INFO - SUITE-START | Running 2 tests 01:36:49 INFO - TEST-START | dromaeo_css 01:36:49 INFO - operating with platform_type : mac_ 01:36:49 INFO - Initialising browser for dromaeo_css test... 01:36:49 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpUmfhMi/profile http://localhost:49219/getInfo.html) 01:36:52 INFO - PROCESS | 1633 | 2017-06-01 01:36:52.146 plugin-container[1636:7533] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 01:36:52 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:36:52 INFO - PROCESS | 1633 | 2017-06-01 01:36:52.147 plugin-container[1636:7533] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 01:36:52 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:36:52 INFO - PROCESS | 1633 | 2017-06-01 01:36:52.147 plugin-container[1636:7533] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:36:52 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 01:36:52 INFO - PROCESS | 1633 | colorDepth:24 01:36:52 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 01:36:52 INFO - PROCESS | 1633 | __metrics 01:36:52 ERROR - PROCESS | 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 01:36:52 INFO - TEST-INFO | 1633: exit 0 01:36:52 INFO - Browser initialized. 01:36:52 INFO - Running cycle 1/1 for dromaeo_css test... 01:36:53 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpUmfhMi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:36:55 INFO - PROCESS | 1637 | 2017-06-01 01:36:55.184 plugin-container[1638:7669] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x424b, name = 'com.apple.tsm.portname' 01:36:55 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:36:55 INFO - PROCESS | 1637 | 2017-06-01 01:36:55.185 plugin-container[1638:7669] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 01:36:55 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:36:55 INFO - PROCESS | 1637 | 2017-06-01 01:36:55.185 plugin-container[1638:7669] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:37:04 INFO - PROCESS | 1637 | RSS: Main: 174542848 01:37:04 INFO - PROCESS | 1637 | 01:40:08 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49219/tests/dromaeo/cssquery-ext.html) 01:40:09 INFO - PROCESS | 1637 | RSS: Main: 189362176 01:40:09 INFO - PROCESS | 1637 | 01:43:12 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-ext.html (next: http://localhost:49219/tests/dromaeo/cssquery-jquery.html) 01:43:12 INFO - PROCESS | 1637 | RSS: Main: 201777152 01:43:12 INFO - PROCESS | 1637 | 01:46:14 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49219/tests/dromaeo/cssquery-mootools.html) 01:46:15 INFO - PROCESS | 1637 | RSS: Main: 203747328 01:46:15 INFO - PROCESS | 1637 | 01:49:17 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49219/tests/dromaeo/cssquery-prototype.html) 01:49:17 INFO - PROCESS | 1637 | RSS: Main: 206802944 01:49:17 INFO - PROCESS | 1637 | 01:52:20 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49219/tests/dromaeo/cssquery-yui.html) 01:52:21 INFO - PROCESS | 1637 | RSS: Main: 210206720 01:52:21 INFO - PROCESS | 1637 | 01:55:23 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-yui.html (next: http://localhost:49219/tests/dromaeo/cssquery-dojo.html) 01:55:24 INFO - PROCESS | 1637 | RSS: Main: 208338944 01:55:24 INFO - PROCESS | 1637 | 01:55:24 INFO - PROCESS | 1637 | __start_tp_report 01:55:24 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 01:55:24 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 01:55:24 INFO - PROCESS | 1637 | |i|pagename|runs| 01:55:24 INFO - PROCESS | 1637 | |0;dojo.html;3589.1744898673287;3672.1737609037987;3686.944695829562;3720.609336019717;3748.587655357912;6221.626702397848;6227.750889964407;6245.781397651083;6286.497080233582;6292.653904035276;6260.217472815898;6279.780207692732;6294.370562943704;6298.968505157468;6306.558540902139;5405.945940540603;5583.832485025446;5731.828045158652;5762.9135562966585;5810.273715785527;4926.236433352836;5640.294963129609;5784.1902133701315;5803.680797556132;5805.071188609823;5535.861603459905;5580.776768929238;5712.400197979215;5757.654540727549;5768.077107662775;5705.372409035002;5714.9714251428895;5739.741711622987;5760.279965004374;5773.134029895507;4698.224792909165;5632.887342253132;5742.7415766290615;5748.396418376094;5803.88392232153;6301.117843501914;6314.71583778731;6328.082428047907;6343.8414039648915;6357.205349331333;6370;6416.037594360883;6445.291017988017;6447.54867159299;6514;6655.76704815334;6677.833054173636;6687.565308254948;6695.129633147709;6700.497462690318;6290.496760259169;6328.272248691414;6343.46080583151;6348.587341822813;6365.681715914186;6075.875962946869;6383.489320854321;6673.265940746514;6690.2306234782645;6861.2795656455355;5953;6256.499480041586;6555.639439830759;6600.801975940633;6602;6791.762288319885;6810.250872404032;6825.624590647462;6841.760538381132;6856.897146542806;6357.4914006879335;6419.293877673452;6606.207255129415;6701.765438209639;6718.428933540606;5582.385937546866;6377.7767778127545;6558.770443034471;6652.301508341555;6681.098051763047;5309.41596424393;5322.0952438085615;5387.24578559002;5399.973000134974;5410.567154627621;4656.534346565384;4745.857624271277;4827.541383568555;4861.465238823726;4902.460729319772;6235.501159907197;6274.372562743689;6308.337624549448;6330.873382532415;6364.522660800459;5649.293838270216;5695.345035320991;6761;6830.248672646005;6871.14110736158;5377.193420986883;6357.1735674362035;6357.936420635827;6366.076918846741;6374.936250637527;5786.508146807569;5828.708564571754;5971.253593300838;5986.161937328778;6002.069679199731;6944.062551555577;6988.440924726111;6994.965025174842;6995.685194166376;7020.719171233093;6793.5244532882225;6800.795976120724;6846.760363387258;6865.107536020286;6869.450443964571;6699.9665001674675;7042.154941406859;7042.647867606497;7076.32774886385;7076.787696369117;6533.607983521004;6608.603483790988;6624.4700423965005;6728.764493242713;6750.628715420699;6825.1127353443735;6838.700646877078;6842.726290948306;6845.14435695538;6846.246912839683;6573.539852210491;6578.76974305897;6606.669666516559;6652.135222421054;6661.16735408074;5320.5477534408465;5508.338999320107;6264.49884009269;6314.684265786784;6458.903116453351;5715.942260786197;6114.419130182626;6151;6338.683065846782;6406.583572067801;6320.525960553069;6326.810195694136;6520;6607.5044371673275;6607.80176594703;4929;6326.9683651581445;6388.137601423934;6390.265119511129;6400.807975760735 01:55:24 INFO - PROCESS | 1637 | |1;ext.html;771.7723271634867;813.2680587471275;848.5205858689842;987.7234374375178;1007.9193664506836;40378.192436151294;41393.58606413935;42987.78506107473;43038.35442468357;43232.56767432325;41688.58311416885;41880.581194188126;41905.7904710476;41953.58046419535;42020;8342;8482.830343393129;11179.385133817637;11351.43242837859;11580.957713805756;10441.590385298001;10998.230123891331;11367.886321136806;11382.260153090065;11486.770264594703;11415.086793056576;11457.94271028648;11483.540658373697;11488.942555287213;11500.769984600345;9536.809263814685;11318.660440186808;11397.601083962063;11400.543978240861;11415.029722473517;9197;11133.220674552784;11155.944220278847;11169;11183.105351571854;36012.81993590015;36250.63749362499;36345.636543634755;36356.818215909014;36490.81754591237;3960.2871483133026;3975.2447035063246;4017.4777278953547;4023.517177938637;4037.4953130858644;26734.46531069387;26736.465270694476;26949.32626684329;28240.576391354145;28387.716122838712;3210.1653570071717;3275.164832967578;3289.5723555937816;3296.884609038696;3415.743819213544;326160;350165;357907;358776;360424.5936310957;35658.82170589182;39956.40174392992;43785.78107109508;44243.33634995478;44252.451164209095;30764.69235307663;34415.65584344124;35342.11644708851;38365;38531.844044678706;14343.78484322736;14438.844892408582;14483.275836208359;14489.420423183166;14557.78163327551;12547.184433011826;12649.494020239088;12661;12690.238585684889;12723.23660580368;11979.98170155529;12152.392380381123;12161.817572736416;12185.51257949672;12266.386680665932;4864.489228631013;5633.492985631313;5661.235733176051;5705.74324155414;5711.914736200109;1014.2241185493103;1176.9411529423494;1186.6440067979572;1205.108219917255;1251.0742050882282;23500.88249558741;26110.086146985144;26788.866055669987;27200.59199112015;27325;1275.1775105057186;1291.1155858237137;1310.1418570835926;1340.6715354737958;1345.307166809094;11604.824254340772;12484.188527745668;13472.922166226874;13957.720845583031;14227;417229;420072;420631;421511;423174;54370.45629543654;56612.716936415054;56904.71547642235;56994;57346.56633584194;1316.453671726242;1335.00542096139;1415.6743948891813;1534.6163459135216;1537.1929736888;25414;26151.738482615692;26392.472150556496;26574;26652.60021099646;47128;47253;47278;47312.7634361826;47515.28727069028;386211;386279.06860465516;388074;388316;389547;27993.580096298163;28477;31297.530537041508;32006;33208.501872472414;46179.76910115428;46322.53677463182;46979;46993;47862;30441.23896902595;32126.83936580302;32278.35443291168;32297.192570184994;32725.50911736278;28635.42729145447;31583;32065.519017214294;32215.51676724898;32745.18137046593;34597.827010864785;35354.58581656705;38249;38279.42580861234;38731.03172420712;29463.116106516838;29665.555016674334;29756.256093596967;30552.694473055875;30647.54028689527;23105.537889241783;24454.633180502664;24592.508149836543;29002;33787.32425351528 01:55:24 INFO - PROCESS | 1637 | |2;jquery.html;369.9556053273607;397.9661728753056;409.6927304521609;428.11166828830164;471.1895539671763;14430.855691443083;20622.17511299546;20693.068811903464;21701.240456584022;22915.19796807112;23142.30573082806;23184.65223021656;23536.411589710267;23762.287131386085;23887.641685374732;6743.9662801686045;6941.305869413069;6980.476464265175;6985.336393042653;7004.64976751163;6606.6036037837885;6871.622060786654;6922.169339679245;6925.65371731414;6944.097267355237;6634.734610615569;6905.896411553831;6913.377795998359;6928.861422771542;6937.167539895195;6886.552374095668;6978.232394436608;6983.1620205575655;6984.5110842241065;6991.370776630128;6720.899186512206;6738.157730283715;6771.932280677229;6789.287124851919;6815.863682726367;24110.276691699102;26369;27474.725252747416;27657.723422765717;27666;3059.724624783758;3067.9233019174476;3126.1715645353997;3127.9530807037913;3128.765342599314;16297.87550509435;16348.673026539522;16937.407190748265;17016.829831701772;17331.220095095752;2104.2109209046607;2122.5754849030254;2128.4891626009644;2132.9146834126773;2136.5940471310555;113256;134740;140027;141878.29060854734;143736;22209.333719988426;22718.659220111713;23514.412139696302;23585.52828943446;23726;35452.82273588667;35947.741829035855;36214.275714485564;36445.635543644225;36634.63365366312;11290.54837806495;11383.373914434786;11563.017143542726;11572.074234061254;11804.822927656092;9322.580483878242;9324.627014919462;9326.580303886341;9341.953290233505;9515.809683806423;10702.197335199891;10823.350598964087;10871.29336593119;10892.836607450894;10945.616903408501;3888.6305800948867;4151.232022075926;4186.832526698898;4197.664186865043;4198.706090573692;1106.8212353843132;1111.9944400277948;1120.2270433400927;1126.7915435644431;1139.6979800352913;23161;23640;23664;23672.408189795395;23711;1123.3484578944192;1124.4377811094453;1125.5385292030228;1168.8305195746568;1171.4084387384487;11957.282563046245;12003.219790713576;12397.690057748629;12433.37833108341;12504.874951250553;547645;721496;749968;763242;783846;110920.89079109266;117929;137681.62318376687;137940.24119517553;167736;1331.5472739268696;1344.4622151139622;1396.427464739452;1399.0696187035508;1401.1453013661658;18355.35756248525;18782.530436738645;19520.804791952465;20540.200062988395;20628.79371206269;40438.79780601078;55473.72263138658;57292;57476.56308592319;58837;72447.91328129977;75146;75861;77920;79252;11391.772164556829;11554.768904621693;11558.422078895854;11999.880001200225;12022.218555793845;10322.535485903303;12671.30307833078;12687.238765674088;12808.615741527768;12989.935050324688;9369.953150234205;10904.800471947921;10936.289141206114;10989.450527473435;11069;9415.529223538933;10466.68599942003;10479.10927571165;10598.788024239626;10784.730381740206;10661;10757.9462102689;10949.178811589003;11015.504302306255;11071.667849964513;11607.361595112012;11628.069754419796;11671.299722016705;11683.299002059903;11991.040716742466;8827.955860220658;10871.728206794893;10874.782504350027;11162.21404575264;11350.659480215607 01:55:24 INFO - PROCESS | 1637 | |3;mootools.html;1350.8446528649206;1426.6576021754781;1618.8947718398304;1629.2749726371762;1630.144174308488;9925.652602158925;10220.437875916821;10254.230932680051;11770.705732356684;11795.233309834855;9614.423134611929;11150;11695.824562631567;11702.473388697508;11710.297382157076;2501.6747822783013;2538.733432989537;2540.1490500682294;2541.504406640706;2541.9872900635564;2468.259522143359;2526.696796384427;2527.140772137473;2529.949401011979;2530.8101892358054;2479.826412151141;2485.3413845331;2500.712418071918;2507.736687647798;2510.1089113364765;2471.80117642943;2474.5545801755675;2482.950340993188;2486.2292689266296;2498.675172227617;2447.36368544178;2449.5590793657134;2467.506498700267;2470.8641024743633;2474.220620504553;11520.481578328996;11758.294502329802;11763.941180294129;11781.705457363549;11790.882091179064;6027.487663548603;6193.256809182882;6196.481861943807;6210.099535567362;6220.47125994291;3789.450529673182;3816.46569480273;3819.618038196186;3851.634094761021;3877.4377715231135;1256.1960345378964;1261.6530454125098;1272.9490820367175;1309.1883032519804;1326.8739469750358;14579.635509112251;15907.363705451768;16017.19914004295;16028.038317700975;16046.35814567416;4486.394336764495;4801.375821143229;4926.605871530269;4983.152864013126;5010.9477009827615;12160.209586376857;12287.262764233996;12309;12310;12310.876891231152;6078.1186727924205;6149;6151.692415379213;6157.138000679908;6163;4810.831620893252;4898.412190537087;5589.189567512688;5634.4647258511195;5700.40145784695;3383.3740757959363;3987.3819557968563;4090.365993271048;4128.710990230655;4174.874753757392;1094.075506197262;1097.1880808201872;1098.566066403766;1105.3754628634795;1109.5395410904384;771.7954741993376;861.3023451004706;961.4904100826573;994.7565543071161;1052.9631462898915;5900.675462849585;6875.656217189221;6932.6533673316135;6937.7224911004005;7004.579725216503;1030.6238223048683;1043.5564884923876;1066.7013236293851;1112.5160555158532;1117.3072694929197;4234.957650423518;4339.848105316299;4351.586599273064;4415.403920470695;4539.773011349485;13091.869081309256;13300.467981280834;13944.442222310994;14103.647408814657;14401.855981440262;13994.510192143227;14066;14329.713405732036;14355.497557585435;14419.206943617992;10343.120834728981;10649.893501065046;10660.626878059076;10671.733206669895;10726.678199654023;6100.603460775035;6658.500612454182;6997.685104170223;7038.577685338896;7083.858322833411;11136.33182009082;12486.063545233963;12645.93677031609;12839.486420543073;13002.08985371015;10912.399818009768;11260.88739112631;12951.352432378531;13046.41291141882;13046.478140874258;8196.344292456708;8491.320694344315;9174.908250917406;9535.427874327284;9682.031796820262;10151.7462063449;10217.33587316822;10343.224258180517;10348.948255258676;10356.637517686844;8593.484390936565;8615.22462978335;8645.870311945451;8684.696035638723;8773.38586298953;8465.661373545236;8469.872951905849;8473.83052338962;8490.702825401082;8634.395592308729;7764.5729484878875;7855.214478552099;7900.051993760785;7913.723019694283;7915.168907264884;5815.534757219328;5829.970850145722;5855.03391940325;5971.343152253336;6022.518198544193;4624.30635404692;5571.637101363349;5621.831345059655;5635.971820140873;5722.3991480895575 01:55:24 INFO - PROCESS | 1637 | |4;prototype.html;402.8992751812047;405.5154090861421;406.09440946688875;407.3889166250624;412.6863583676407;4911.80352785889;5445;5542.722863856801;5580.302462192226;5585.8603534911645;4630.37489938859;4663.393758811359;4972.30387745715;5435.728213589315;5458.781648734046;3838.309104361214;4219.789010549475;4275.294576394891;4319.265724826779;4328.242557552431;3556.2887422515473;3615.138203825032;3704.2776658551593;3834.3098242316373;4257.956800583861;3769.830357633913;3953.5058117735284;4251.404803327521;4257.914841703164;4284;3752.4183751518694;3897.7527191298796;4153.439285696422;4184.497860256758;4203.831846726127;3734.495843061179;3769.6984241260634;3771.830267637963;3772.1512659651635;3778.678812300958;4837.5109115849855;5150.995555866608;5303.992241474108;5379.273798037293;5436.396263102394;3815.6565909068318;4351.347297905308;4361.80371883266;4573.725576465423;4924.384451943507;5381.2197231401615;5498.835034948918;5648.587853036741;5789.797357092523;5794.884102317972;3491.5286436331025;3702.2965636529243;4044.5550989391145;4171.416001759756;4187.811548480326;5001.474550262169;5628.521575666074;5772.105323674838;5817;5894.322152952465;4024.7383920045104;4474.351219073281;4598.620413875824;4604.677672562889;4627.005193883331;4431.692650668061;5274.7890084396195;5275.60432967529;5292.285541451932;5306.8142615009065;5028.572571331405;5062.620303477181;5260.736963151827;5387.515123638891;5482.5613950883835;5121.539061484485;5402;5558.416366281563;5804.65172089676;5820.650760954356;1591.2759694339047;1703.4208369154298;1784.9553761155814;1810.958698748225;1814.818518148201;1369.274284629148;1426.5149849051372;1431.935562899672;1444.6605047813755;1462.1081140504284;4460.8661740147845;4801.727542201319;4929.630277729185;5431.049566325877;5458.344998600193;4845.781939812647;5040.57155141817;5349.919751203735;5855.745537967828;5905.498032667271;4750.365970032927;5302.750395341583;5332.880095179978;5385.865353366119;5601.943980560224;2709.7832173426077;3098.209956461133;3116.937661246762;3153.952690709641;3159.8578063987175;5126.820561280412;5596.356419011866;5622.791099913538;5658.274226361;6015.4586087252355;5145.639805213674;5318.016167009038;5413.972930135403;5975.223220981332;5984.3417224105315;5214.97392513035;5361.544268737123;5761.567882408836;5766.481016708516;6000.519958403404;4767.33257343979;5021.723805190603;5528.889422211614;5709.344290155808;6041.281423359594;5179.81870634526;5350.411454740053;5696.132983027323;5720.255322126625;5869.828163579503;5330.360356757059;5384.892302154013;5529.009556559555;5950.613210141327;6220.471259942956;4441.423294730324;4660.883477912944;4821.300911367762;5082.364704411949;5137.8201762938115;4680.642613642006;5834.970825146017;5838.649681019152;5947.672877991752;5970.940290597038;5204.609654276021;5381.67709937405;5385.569154467712;5793.246877905845;5808.0252714077415;5239.166291797993;5684.658920464785;5857;5863.677497737665;5873.970630146993;4966.379202599675;5224.843254702365;5267.446918073547;5342.599305052059;5705.605164926862;4957.480189065804;5017.623678224075;5433.592480563894;5437.912417516592;5793.855153621024;4896.853094407174;4974.477679843709;5127.974360128175;5740.282464691913;5768.682722450306 01:55:24 INFO - PROCESS | 1637 | |5;yui.html;763.9006929099216;774.6126936531734;799.7480793550031;814.279362763954;918.7381596245069;15672.216389180538;17534;17660.646787064266;17667.734983975235;17802.376916807916;15639;16112.677746445093;16124.355025798954;16329;16353.427630032951;919.4804935211617;995.4972738766912;1024.0629823711295;1027.712240572637;1030.9484525773719;988.5205675247499;1020.5050550483047;1030.340582027503;1033.700226934188;1034.8395998620226;1081.8052750504905;1083.3337497439059;1084.219362059316;1085.9891401086006;1086.1853609792656;1062.4634559547417;1078.1428764132534;1080.200651517875;1083.0469187115366;1085.1210519479248;803.6865622407266;803.7548547692951;804.995975020127;805.2430715127762;806.6450761664848;16933;17193.054382008984;17462.825371746247;18042.639147217113;18266.543336416562;14599.854001459955;14761.852381476156;14786.334614942354;14888.776668349983;15163.862710296771;10455.529501172465;10764.623238186705;11903.523859045628;12191.939040304831;12335.814962775565;3950.7432016919097;3981.8009099544906;3995.5604883462797;4097.631213190797;4115.444415003966;81970;110579.89420105857;114431;114476;115602;9196.310276729271;11246.887531124748;11402.144839137098;11693.824592631117;12250.203736757083;23778;24137.51724965491;24570.140045098335;24598.877005614857;24615.3846153844;8065.435419520577;8165.63254653542;8312.33501319893;8332.958335208285;8349.66601335952;4626.606738427204;4647.651426143053;4765.880852978634;4770.356001939694;4781.784819683123;3496.2133519957806;3596.460530920383;3610.494530765695;3877.1276462795645;4101.651359634465;642.2549842183046;729.8868675355328;734.313416955138;740.4964624055586;751.9624018799038;669.9665016749143;693.9097917270723;732.238471989136;746.5931067568189;760.4182800157884;9666.806663866684;10920.18098642589;11016.504257308441;11020.118390528738;11197.776044479227;601.461691785858;679.8008442687901;736.8342865631689;740.1820987808502;758.2455456820499;5046.823361182414;5289.814856480005;5295.655782374133;5298.125809241432;5298.231756395378;105383.94616053741;106788.46605767077;108449;109442.45278773714;110567;91434;95016.52491737636;95642.52178739062;96533.51733241288;97364.51317743366;80375;81520.9999999988;82572;82682;82860;9958.751031223988;10164.390136591828;10219.23355748307;10252.538635761259;10271.589136434459;53519.46480535145;54295.91408171893;54580;55674.44325556693;56409;68834.31165688479;70261.64869175621;70365.29634703792;70557.94163087661;70578;11818.290902545874;11828.40857957116;11830;12045.277283363026;12295.508179672714;10323.432211228153;11820.229646758646;12334.876651233373;13165.47338106465;13730.038897277494;12135.818815676534;12459.377031148588;12683;12787.61637150887;13063;12279.324637145042;12414.565490207799;12555.309457979534;12615.495380185057;13194.472221111047;11031.172662050218;11197.552097915992;12356.62930112098;12496.187747796364;12776.297303648389;7838.725644602411;8885;8937.28501719874;9389.577469014048;9779.853302200616;8437.64997600381;9809.607615695293;10248.180145588185;10442.425666588411;10707 01:55:24 INFO - PROCESS | 1637 | __end_tp_report 01:55:24 INFO - PROCESS | 1637 | __start_cc_report 01:55:24 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,394 01:55:24 INFO - PROCESS | 1637 | __end_cc_report 01:55:24 INFO - PROCESS | 1637 | __startTimestamp1496307324107__endTimestamp 01:55:24 INFO - PROCESS | 1637 | 01:55:24 INFO - PROCESS | 1637 | ------- Summary: start ------- 01:55:24 INFO - PROCESS | 1637 | Number of tests: 6 01:55:24 INFO - PROCESS | 1637 | 01:55:24 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:6163.41 Median:6338.68 stddev:700.50 (11.1%) stddev-sans-first:672.91 01:55:24 INFO - PROCESS | 1637 | Values: 3589.2 3672.2 3686.9 3720.6 3748.6 6221.6 6227.8 6245.8 6286.5 6292.7 6260.2 6279.8 6294.4 6299.0 6306.6 5405.9 5583.8 5731.8 5762.9 5810.3 4926.2 5640.3 5784.2 5803.7 5805.1 5535.9 5580.8 5712.4 5757.7 5768.1 5705.4 5715.0 5739.7 5760.3 5773.1 4698.2 5632.9 5742.7 5748.4 5803.9 6301.1 6314.7 6328.1 6343.8 6357.2 6370.0 6416.0 6445.3 6447.5 6514.0 6655.8 6677.8 6687.6 6695.1 6700.5 6290.5 6328.3 6343.5 6348.6 6365.7 6075.9 6383.5 6673.3 6690.2 6861.3 5953.0 6256.5 6555.6 6600.8 6602.0 6791.8 6810.3 6825.6 6841.8 6856.9 6357.5 6419.3 6606.2 6701.8 6718.4 5582.4 6377.8 6558.8 6652.3 6681.1 5309.4 5322.1 5387.2 5400.0 5410.6 4656.5 4745.9 4827.5 4861.5 4902.5 6235.5 6274.4 6308.3 6330.9 6364.5 5649.3 5695.3 6761.0 6830.2 6871.1 5377.2 6357.2 6357.9 6366.1 6374.9 5786.5 5828.7 5971.3 5986.2 6002.1 6944.1 6988.4 6995.0 6995.7 7020.7 6793.5 6800.8 6846.8 6865.1 6869.5 6700.0 7042.2 7042.6 7076.3 7076.8 6533.6 6608.6 6624.5 6728.8 6750.6 6825.1 6838.7 6842.7 6845.1 6846.2 6573.5 6578.8 6606.7 6652.1 6661.2 5320.5 5508.3 6264.5 6314.7 6458.9 5715.9 6114.4 6151.0 6338.7 6406.6 6320.5 6326.8 6520.0 6607.5 6607.8 4929.0 6327.0 6388.1 6390.3 6400.8 01:55:24 INFO - PROCESS | 1637 | 01:55:24 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:52762.56 Median:17668.50 stddev:102348.02 (579.3%) stddev-sans-first:102560.70 01:55:24 INFO - PROCESS | 1637 | Values: 771.8 813.3 848.5 987.7 1007.9 40378.2 41393.6 42987.8 43038.4 43232.6 41688.6 41880.6 41905.8 41953.6 42020.0 8342.0 8482.8 11179.4 11351.4 11581.0 10441.6 10998.2 11367.9 11382.3 11486.8 11415.1 11457.9 11483.5 11488.9 11500.8 9536.8 11318.7 11397.6 11400.5 11415.0 9197.0 11133.2 11155.9 11169.0 11183.1 36012.8 36250.6 36345.6 36356.8 36490.8 3960.3 3975.2 4017.5 4023.5 4037.5 26734.5 26736.5 26949.3 28240.6 28387.7 3210.2 3275.2 3289.6 3296.9 3415.7 326160.0 350165.0 357907.0 358776.0 360424.6 35658.8 39956.4 43785.8 44243.3 44252.5 30764.7 34415.7 35342.1 38365.0 38531.8 14343.8 14438.8 14483.3 14489.4 14557.8 12547.2 12649.5 12661.0 12690.2 12723.2 11980.0 12152.4 12161.8 12185.5 12266.4 4864.5 5633.5 5661.2 5705.7 5711.9 1014.2 1176.9 1186.6 1205.1 1251.1 23500.9 26110.1 26788.9 27200.6 27325.0 1275.2 1291.1 1310.1 1340.7 1345.3 11604.8 12484.2 13472.9 13957.7 14227.0 417229.0 420072.0 420631.0 421511.0 423174.0 54370.5 56612.7 56904.7 56994.0 57346.6 1316.5 1335.0 1415.7 1534.6 1537.2 25414.0 26151.7 26392.5 26574.0 26652.6 47128.0 47253.0 47278.0 47312.8 47515.3 386211.0 386279.1 388074.0 388316.0 389547.0 27993.6 28477.0 31297.5 32006.0 33208.5 46179.8 46322.5 46979.0 46993.0 47862.0 30441.2 32126.8 32278.4 32297.2 32725.5 28635.4 31583.0 32065.5 32215.5 32745.2 34597.8 35354.6 38249.0 38279.4 38731.0 29463.1 29665.6 29756.3 30552.7 30647.5 23105.5 24454.6 24592.5 29002.0 33787.3 01:55:24 INFO - PROCESS | 1637 | 01:55:24 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:40885.85 Median:21955.29 stddev:119082.98 (542.4%) stddev-sans-first:119378.18 01:55:24 INFO - PROCESS | 1637 | Values: 370.0 398.0 409.7 428.1 471.2 14430.9 20622.2 20693.1 21701.2 22915.2 23142.3 23184.7 23536.4 23762.3 23887.6 6744.0 6941.3 6980.5 6985.3 7004.6 6606.6 6871.6 6922.2 6925.7 6944.1 6634.7 6905.9 6913.4 6928.9 6937.2 6886.6 6978.2 6983.2 6984.5 6991.4 6720.9 6738.2 6771.9 6789.3 6815.9 24110.3 26369.0 27474.7 27657.7 27666.0 3059.7 3067.9 3126.2 3128.0 3128.8 16297.9 16348.7 16937.4 17016.8 17331.2 2104.2 2122.6 2128.5 2132.9 2136.6 113256.0 134740.0 140027.0 141878.3 143736.0 22209.3 22718.7 23514.4 23585.5 23726.0 35452.8 35947.7 36214.3 36445.6 36634.6 11290.5 11383.4 11563.0 11572.1 11804.8 9322.6 9324.6 9326.6 9342.0 9515.8 10702.2 10823.4 10871.3 10892.8 10945.6 3888.6 4151.2 4186.8 4197.7 4198.7 1106.8 1112.0 1120.2 1126.8 1139.7 23161.0 23640.0 23664.0 23672.4 23711.0 1123.3 1124.4 1125.5 1168.8 1171.4 11957.3 12003.2 12397.7 12433.4 12504.9 547645.0 721496.0 749968.0 763242.0 783846.0 110920.9 117929.0 137681.6 137940.2 167736.0 1331.5 1344.5 1396.4 1399.1 1401.1 18355.4 18782.5 19520.8 20540.2 20628.8 40438.8 55473.7 57292.0 57476.6 58837.0 72447.9 75146.0 75861.0 77920.0 79252.0 11391.8 11554.8 11558.4 11999.9 12022.2 10322.5 12671.3 12687.2 12808.6 12989.9 9370.0 10904.8 10936.3 10989.5 11069.0 9415.5 10466.7 10479.1 10598.8 10784.7 10661.0 10757.9 10949.2 11015.5 11071.7 11607.4 11628.1 11671.3 11683.3 11991.0 8828.0 10871.7 10874.8 11162.2 11350.7 01:55:24 INFO - PROCESS | 1637 | 01:55:24 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:6866.31 Median:2518.40 stddev:4293.64 (170.5%) stddev-sans-first:4285.68 01:55:24 INFO - PROCESS | 1637 | Values: 1350.8 1426.7 1618.9 1629.3 1630.1 9925.7 10220.4 10254.2 11770.7 11795.2 9614.4 11150.0 11695.8 11702.5 11710.3 2501.7 2538.7 2540.1 2541.5 2542.0 2468.3 2526.7 2527.1 2529.9 2530.8 2479.8 2485.3 2500.7 2507.7 2510.1 2471.8 2474.6 2483.0 2486.2 2498.7 2447.4 2449.6 2467.5 2470.9 2474.2 11520.5 11758.3 11763.9 11781.7 11790.9 6027.5 6193.3 6196.5 6210.1 6220.5 3789.5 3816.5 3819.6 3851.6 3877.4 1256.2 1261.7 1272.9 1309.2 1326.9 14579.6 15907.4 16017.2 16028.0 16046.4 4486.4 4801.4 4926.6 4983.2 5010.9 12160.2 12287.3 12309.0 12310.0 12310.9 6078.1 6149.0 6151.7 6157.1 6163.0 4810.8 4898.4 5589.2 5634.5 5700.4 3383.4 3987.4 4090.4 4128.7 4174.9 1094.1 1097.2 1098.6 1105.4 1109.5 771.8 861.3 961.5 994.8 1053.0 5900.7 6875.7 6932.7 6937.7 7004.6 1030.6 1043.6 1066.7 1112.5 1117.3 4235.0 4339.8 4351.6 4415.4 4539.8 13091.9 13300.5 13944.4 14103.6 14401.9 13994.5 14066.0 14329.7 14355.5 14419.2 10343.1 10649.9 10660.6 10671.7 10726.7 6100.6 6658.5 6997.7 7038.6 7083.9 11136.3 12486.1 12645.9 12839.5 13002.1 10912.4 11260.9 12951.4 13046.4 13046.5 8196.3 8491.3 9174.9 9535.4 9682.0 10151.7 10217.3 10343.2 10348.9 10356.6 8593.5 8615.2 8645.9 8684.7 8773.4 8465.7 8469.9 8473.8 8490.7 8634.4 7764.6 7855.2 7900.1 7913.7 7915.2 5815.5 5830.0 5855.0 5971.3 6022.5 4624.3 5571.6 5621.8 5636.0 5722.4 01:55:24 INFO - PROCESS | 1637 | 01:55:24 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4691.68 Median:5141.73 stddev:1294.92 (25.2%) stddev-sans-first:1257.90 01:55:24 INFO - PROCESS | 1637 | Values: 402.9 405.5 406.1 407.4 412.7 4911.8 5445.0 5542.7 5580.3 5585.9 4630.4 4663.4 4972.3 5435.7 5458.8 3838.3 4219.8 4275.3 4319.3 4328.2 3556.3 3615.1 3704.3 3834.3 4258.0 3769.8 3953.5 4251.4 4257.9 4284.0 3752.4 3897.8 4153.4 4184.5 4203.8 3734.5 3769.7 3771.8 3772.2 3778.7 4837.5 5151.0 5304.0 5379.3 5436.4 3815.7 4351.3 4361.8 4573.7 4924.4 5381.2 5498.8 5648.6 5789.8 5794.9 3491.5 3702.3 4044.6 4171.4 4187.8 5001.5 5628.5 5772.1 5817.0 5894.3 4024.7 4474.4 4598.6 4604.7 4627.0 4431.7 5274.8 5275.6 5292.3 5306.8 5028.6 5062.6 5260.7 5387.5 5482.6 5121.5 5402.0 5558.4 5804.7 5820.7 1591.3 1703.4 1785.0 1811.0 1814.8 1369.3 1426.5 1431.9 1444.7 1462.1 4460.9 4801.7 4929.6 5431.0 5458.3 4845.8 5040.6 5349.9 5855.7 5905.5 4750.4 5302.8 5332.9 5385.9 5601.9 2709.8 3098.2 3116.9 3154.0 3159.9 5126.8 5596.4 5622.8 5658.3 6015.5 5145.6 5318.0 5414.0 5975.2 5984.3 5215.0 5361.5 5761.6 5766.5 6000.5 4767.3 5021.7 5528.9 5709.3 6041.3 5179.8 5350.4 5696.1 5720.3 5869.8 5330.4 5384.9 5529.0 5950.6 6220.5 4441.4 4660.9 4821.3 5082.4 5137.8 4680.6 5835.0 5838.6 5947.7 5970.9 5204.6 5381.7 5385.6 5793.2 5808.0 5239.2 5684.7 5857.0 5863.7 5874.0 4966.4 5224.8 5267.4 5342.6 5705.6 4957.5 5017.6 5433.6 5437.9 5793.9 4896.9 4974.5 5128.0 5740.3 5768.7 01:55:24 INFO - PROCESS | 1637 | 01:55:24 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:21271.68 Median:21022.27 stddev:30855.35 (146.8%) stddev-sans-first:30903.48 01:55:24 INFO - PROCESS | 1637 | Values: 763.9 774.6 799.7 814.3 918.7 15672.2 17534.0 17660.6 17667.7 17802.4 15639.0 16112.7 16124.4 16329.0 16353.4 919.5 995.5 1024.1 1027.7 1030.9 988.5 1020.5 1030.3 1033.7 1034.8 1081.8 1083.3 1084.2 1086.0 1086.2 1062.5 1078.1 1080.2 1083.0 1085.1 803.7 803.8 805.0 805.2 806.6 16933.0 17193.1 17462.8 18042.6 18266.5 14599.9 14761.9 14786.3 14888.8 15163.9 10455.5 10764.6 11903.5 12191.9 12335.8 3950.7 3981.8 3995.6 4097.6 4115.4 81970.0 110579.9 114431.0 114476.0 115602.0 9196.3 11246.9 11402.1 11693.8 12250.2 23778.0 24137.5 24570.1 24598.9 24615.4 8065.4 8165.6 8312.3 8333.0 8349.7 4626.6 4647.7 4765.9 4770.4 4781.8 3496.2 3596.5 3610.5 3877.1 4101.7 642.3 729.9 734.3 740.5 752.0 670.0 693.9 732.2 746.6 760.4 9666.8 10920.2 11016.5 11020.1 11197.8 601.5 679.8 736.8 740.2 758.2 5046.8 5289.8 5295.7 5298.1 5298.2 105383.9 106788.5 108449.0 109442.5 110567.0 91434.0 95016.5 95642.5 96533.5 97364.5 80375.0 81521.0 82572.0 82682.0 82860.0 9958.8 10164.4 10219.2 10252.5 10271.6 53519.5 54295.9 54580.0 55674.4 56409.0 68834.3 70261.6 70365.3 70557.9 70578.0 11818.3 11828.4 11830.0 12045.3 12295.5 10323.4 11820.2 12334.9 13165.5 13730.0 12135.8 12459.4 12683.0 12787.6 13063.0 12279.3 12414.6 12555.3 12615.5 13194.5 11031.2 11197.6 12356.6 12496.2 12776.3 7838.7 8885.0 8937.3 9389.6 9779.9 8437.6 9809.6 10248.2 10442.4 10707.0 01:55:24 INFO - PROCESS | 1637 | -------- Summary: end -------- 01:55:24 INFO - PROCESS | 1637 | 01:55:24 INFO - TEST-INFO | 1637: exit 0 01:55:24 INFO - TEST-OK | dromaeo_css | took 1115347ms 01:55:24 INFO - TEST-START | kraken 01:55:24 INFO - operating with platform_type : mac_ 01:55:24 INFO - Initialising browser for kraken test... 01:55:24 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpHXdu84/profile http://localhost:49219/getInfo.html) 01:55:27 INFO - PROCESS | 1650 | 2017-06-01 01:55:27.325 plugin-container[1652:10271] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853b, name = 'com.apple.tsm.portname' 01:55:27 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:55:27 INFO - PROCESS | 1650 | 2017-06-01 01:55:27.326 plugin-container[1652:10271] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x870b, name = 'com.apple.CFPasteboardClient' 01:55:27 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:55:27 INFO - PROCESS | 1650 | 2017-06-01 01:55:27.326 plugin-container[1652:10271] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:55:27 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 01:55:27 INFO - PROCESS | 1650 | colorDepth:24 01:55:27 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 01:55:27 INFO - PROCESS | 1650 | __metrics 01:55:27 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 01:55:28 INFO - TEST-INFO | 1650: exit 0 01:55:28 INFO - Browser initialized. 01:55:28 INFO - Running cycle 1/1 for kraken test... 01:55:28 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpHXdu84/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:55:30 INFO - PROCESS | 1653 | 2017-06-01 01:55:30.242 plugin-container[1654:10398] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 01:55:30 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:55:30 INFO - PROCESS | 1653 | 2017-06-01 01:55:30.243 plugin-container[1654:10398] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c17, name = 'com.apple.CFPasteboardClient' 01:55:30 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:55:30 INFO - PROCESS | 1653 | 2017-06-01 01:55:30.243 plugin-container[1654:10398] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:55:40 INFO - PROCESS | 1653 | RSS: Main: 174399488 01:55:40 INFO - PROCESS | 1653 | 01:55:42 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection) 01:55:43 INFO - PROCESS | 1653 | RSS: Main: 187969536 01:55:43 INFO - PROCESS | 1653 | 01:55:46 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-dft) 01:55:47 INFO - PROCESS | 1653 | RSS: Main: 196030464 01:55:47 INFO - PROCESS | 1653 | 01:55:50 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-fft) 01:55:51 INFO - PROCESS | 1653 | RSS: Main: 199774208 01:55:51 INFO - PROCESS | 1653 | 01:55:52 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator) 01:55:53 INFO - PROCESS | 1653 | RSS: Main: 200392704 01:55:53 INFO - PROCESS | 1653 | 01:55:55 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:55:56 INFO - PROCESS | 1653 | RSS: Main: 201535488 01:55:56 INFO - PROCESS | 1653 | 01:55:59 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom) 01:55:59 INFO - PROCESS | 1653 | RSS: Main: 205783040 01:55:59 INFO - PROCESS | 1653 | 01:56:02 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate) 01:56:03 INFO - PROCESS | 1653 | RSS: Main: 205877248 01:56:03 INFO - PROCESS | 1653 | 01:56:06 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial) 01:56:07 INFO - PROCESS | 1653 | RSS: Main: 205201408 01:56:07 INFO - PROCESS | 1653 | 01:56:08 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:56:09 INFO - PROCESS | 1653 | RSS: Main: 204668928 01:56:09 INFO - PROCESS | 1653 | 01:56:11 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:56:12 INFO - PROCESS | 1653 | RSS: Main: 204726272 01:56:12 INFO - PROCESS | 1653 | 01:56:14 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:56:14 INFO - PROCESS | 1653 | RSS: Main: 204685312 01:56:14 INFO - PROCESS | 1653 | 01:56:17 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:56:18 INFO - PROCESS | 1653 | RSS: Main: 205103104 01:56:18 INFO - PROCESS | 1653 | 01:56:21 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:56:21 INFO - PROCESS | 1653 | RSS: Main: 205127680 01:56:21 INFO - PROCESS | 1653 | 01:56:23 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49219/tests/kraken/driver.html?testName=ai-astar) 01:56:23 INFO - PROCESS | 1653 | RSS: Main: 207114240 01:56:23 INFO - PROCESS | 1653 | 01:56:23 INFO - PROCESS | 1653 | __start_tp_report 01:56:23 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 01:56:23 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 01:56:23 INFO - PROCESS | 1653 | |i|pagename|runs| 01:56:23 INFO - PROCESS | 1653 | |0;ai-astar;106;121;120;124;171;119;121;121;120;121 01:56:23 INFO - PROCESS | 1653 | |1;audio-beat-detection;165;141;98;178;95;140;143;142;147;136 01:56:23 INFO - PROCESS | 1653 | |2;audio-dft;196;194;199;199;195;195;195;239;225;195 01:56:23 INFO - PROCESS | 1653 | |3;audio-fft;81;58;61;84;60;61;60;59;69;59 01:56:23 INFO - PROCESS | 1653 | |4;audio-oscillator;88;69;102;102;101;79;81;70;70;81 01:56:23 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;88;90;89;89;90;93;89;106;119;138 01:56:23 INFO - PROCESS | 1653 | |6;imaging-darkroom;94;101;97;95;96;96;97;96;134;97 01:56:23 INFO - PROCESS | 1653 | |7;imaging-desaturate;92;94;92;77;146;139;94;90;116;145 01:56:23 INFO - PROCESS | 1653 | |8;json-parse-financial;64;63;62;67;68;68;69;68;71;98 01:56:23 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;55;64;57;63;61;59;66;86;86;84 01:56:23 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;83;86;82;84;83;87;100;84;83;87 01:56:23 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;138;130;145;138;147;145;149;140;204;177 01:56:23 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;197;175;164;166;163;165;164;157;161;162 01:56:23 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;59;69;83;77;64;66;64;63;63;63 01:56:23 INFO - PROCESS | 1653 | __end_tp_report 01:56:23 INFO - PROCESS | 1653 | __start_cc_report 01:56:23 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,997 01:56:23 INFO - PROCESS | 1653 | __end_cc_report 01:56:23 INFO - PROCESS | 1653 | __startTimestamp1496307383839__endTimestamp 01:56:23 INFO - PROCESS | 1653 | 01:56:23 INFO - PROCESS | 1653 | ------- Summary: start ------- 01:56:23 INFO - PROCESS | 1653 | Number of tests: 14 01:56:23 INFO - PROCESS | 1653 | 01:56:23 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:124.40 Median:121.00 stddev:17.08 (14.1%) stddev-sans-first:16.76 01:56:23 INFO - PROCESS | 1653 | Values: 106.0 121.0 120.0 124.0 171.0 119.0 121.0 121.0 120.0 121.0 01:56:23 INFO - PROCESS | 1653 | 01:56:23 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:138.50 Median:156.00 stddev:25.64 (16.4%) stddev-sans-first:25.33 01:56:23 INFO - PROCESS | 1653 | Values: 165.0 141.0 98.0 178.0 95.0 140.0 143.0 142.0 147.0 136.0 01:56:23 INFO - PROCESS | 1653 | 01:56:23 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:203.20 Median:197.50 stddev:15.63 (7.9%) stddev-sans-first:16.36 01:56:23 INFO - PROCESS | 1653 | Values: 196.0 194.0 199.0 199.0 195.0 195.0 195.0 239.0 225.0 195.0 01:56:23 INFO - PROCESS | 1653 | 01:56:23 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:65.20 Median:61.00 stddev:9.64 (15.8%) stddev-sans-first:8.35 01:56:23 INFO - PROCESS | 1653 | Values: 81.0 58.0 61.0 84.0 60.0 61.0 60.0 59.0 69.0 59.0 01:56:23 INFO - PROCESS | 1653 | 01:56:23 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:84.30 Median:74.50 stddev:13.38 (18.0%) stddev-sans-first:14.13 01:56:23 INFO - PROCESS | 1653 | Values: 88.0 69.0 102.0 102.0 101.0 79.0 81.0 70.0 70.0 81.0 01:56:23 INFO - PROCESS | 1653 | 01:56:23 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:99.10 Median:89.00 stddev:16.96 (19.1%) stddev-sans-first:17.51 01:56:23 INFO - PROCESS | 1653 | Values: 88.0 90.0 89.0 89.0 90.0 93.0 89.0 106.0 119.0 138.0 01:56:23 INFO - PROCESS | 1653 | 01:56:23 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:100.30 Median:96.00 stddev:11.98 (12.5%) stddev-sans-first:12.49 01:56:23 INFO - PROCESS | 1653 | Values: 94.0 101.0 97.0 95.0 96.0 96.0 97.0 96.0 134.0 97.0 01:56:23 INFO - PROCESS | 1653 | 01:56:23 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:108.50 Median:91.00 stddev:25.87 (28.4%) stddev-sans-first:26.74 01:56:23 INFO - PROCESS | 1653 | Values: 92.0 94.0 92.0 77.0 146.0 139.0 94.0 90.0 116.0 145.0 01:56:23 INFO - PROCESS | 1653 | 01:56:23 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:69.80 Median:68.00 stddev:10.30 (15.2%) stddev-sans-first:10.71 01:56:23 INFO - PROCESS | 1653 | Values: 64.0 63.0 62.0 67.0 68.0 68.0 69.0 68.0 71.0 98.0 01:56:23 INFO - PROCESS | 1653 | 01:56:23 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:68.10 Median:65.00 stddev:12.33 (19.0%) stddev-sans-first:12.14 01:56:23 INFO - PROCESS | 1653 | Values: 55.0 64.0 57.0 63.0 61.0 59.0 66.0 86.0 86.0 84.0 01:56:23 INFO - PROCESS | 1653 | 01:56:23 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:85.90 Median:84.00 stddev:5.26 (6.3%) stddev-sans-first:5.47 01:56:23 INFO - PROCESS | 1653 | Values: 83.0 86.0 82.0 84.0 83.0 87.0 100.0 84.0 83.0 87.0 01:56:23 INFO - PROCESS | 1653 | 01:56:23 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:151.30 Median:146.00 stddev:22.30 (15.3%) stddev-sans-first:23.13 01:56:23 INFO - PROCESS | 1653 | Values: 138.0 130.0 145.0 138.0 147.0 145.0 149.0 140.0 204.0 177.0 01:56:23 INFO - PROCESS | 1653 | 01:56:23 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.40 Median:164.50 stddev:11.36 (6.9%) stddev-sans-first:4.86 01:56:23 INFO - PROCESS | 1653 | Values: 197.0 175.0 164.0 166.0 163.0 165.0 164.0 157.0 161.0 162.0 01:56:23 INFO - PROCESS | 1653 | 01:56:23 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:65.00 stddev:7.39 (11.4%) stddev-sans-first:7.23 01:56:23 INFO - PROCESS | 1653 | Values: 59.0 69.0 83.0 77.0 64.0 66.0 64.0 63.0 63.0 63.0 01:56:23 INFO - PROCESS | 1653 | -------- Summary: end -------- 01:56:23 INFO - PROCESS | 1653 | 01:56:24 INFO - TEST-INFO | 1653: exit 0 01:56:24 INFO - TEST-OK | kraken | took 59941ms 01:56:24 INFO - SUITE-END | took 1175s 01:56:25 INFO - Completed test suite (00:19:35) 01:56:25 INFO - javascript benchmark 01:56:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7910.00876588461, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3589.1744898673287, 3672.1737609037987, 3686.944695829562, 3720.609336019717, 3748.587655357912, 6221.626702397848, 6227.750889964407, 6245.781397651083, 6286.497080233582, 6292.653904035276, 6260.217472815898, 6279.780207692732, 6294.370562943704, 6298.968505157468, 6306.558540902139, 5405.945940540603, 5583.832485025446, 5731.828045158652, 5762.9135562966585, 5810.273715785527, 4926.236433352836, 5640.294963129609, 5784.1902133701315, 5803.680797556132, 5805.071188609823, 5535.861603459905, 5580.776768929238, 5712.400197979215, 5757.654540727549, 5768.077107662775, 5705.372409035002, 5714.9714251428895, 5739.741711622987, 5760.279965004374, 5773.134029895507, 4698.224792909165, 5632.887342253132, 5742.7415766290615, 5748.396418376094, 5803.88392232153, 6301.117843501914, 6314.71583778731, 6328.082428047907, 6343.8414039648915, 6357.205349331333, 6370.0, 6416.037594360883, 6445.291017988017, 6447.54867159299, 6514.0, 6655.76704815334, 6677.833054173636, 6687.565308254948, 6695.129633147709, 6700.497462690318, 6290.496760259169, 6328.272248691414, 6343.46080583151, 6348.587341822813, 6365.681715914186, 6075.875962946869, 6383.489320854321, 6673.265940746514, 6690.2306234782645, 6861.2795656455355, 5953.0, 6256.499480041586, 6555.639439830759, 6600.801975940633, 6602.0, 6791.762288319885, 6810.250872404032, 6825.624590647462, 6841.760538381132, 6856.897146542806, 6357.4914006879335, 6419.293877673452, 6606.207255129415, 6701.765438209639, 6718.428933540606, 5582.385937546866, 6377.7767778127545, 6558.770443034471, 6652.301508341555, 6681.098051763047, 5309.41596424393, 5322.0952438085615, 5387.24578559002, 5399.973000134974, 5410.567154627621, 4656.534346565384, 4745.857624271277, 4827.541383568555, 4861.465238823726, 4902.460729319772, 6235.501159907197, 6274.372562743689, 6308.337624549448, 6330.873382532415, 6364.522660800459, 5649.293838270216, 5695.345035320991, 6761.0, 6830.248672646005, 6871.14110736158, 5377.193420986883, 6357.1735674362035, 6357.936420635827, 6366.076918846741, 6374.936250637527, 5786.508146807569, 5828.708564571754, 5971.253593300838, 5986.161937328778, 6002.069679199731, 6944.062551555577, 6988.440924726111, 6994.965025174842, 6995.685194166376, 7020.719171233093, 6793.5244532882225, 6800.795976120724, 6846.760363387258, 6865.107536020286, 6869.450443964571, 6699.9665001674675, 7042.154941406859, 7042.647867606497, 7076.32774886385, 7076.787696369117, 6533.607983521004, 6608.603483790988, 6624.4700423965005, 6728.764493242713, 6750.628715420699, 6825.1127353443735, 6838.700646877078, 6842.726290948306, 6845.14435695538, 6846.246912839683, 6573.539852210491, 6578.76974305897, 6606.669666516559, 6652.135222421054, 6661.16735408074, 5320.5477534408465, 5508.338999320107, 6264.49884009269, 6314.684265786784, 6458.903116453351, 5715.942260786197, 6114.419130182626, 6151.0, 6338.683065846782, 6406.583572067801, 6320.525960553069, 6326.810195694136, 6520.0, 6607.5044371673275, 6607.80176594703, 4929.0, 6326.9683651581445, 6388.137601423934, 6390.265119511129, 6400.807975760735], "value": 6122.00533704084, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [771.7723271634867, 813.2680587471275, 848.5205858689842, 987.7234374375178, 1007.9193664506836, 40378.192436151294, 41393.58606413935, 42987.78506107473, 43038.35442468357, 43232.56767432325, 41688.58311416885, 41880.581194188126, 41905.7904710476, 41953.58046419535, 42020.0, 8342.0, 8482.830343393129, 11179.385133817637, 11351.43242837859, 11580.957713805756, 10441.590385298001, 10998.230123891331, 11367.886321136806, 11382.260153090065, 11486.770264594703, 11415.086793056576, 11457.94271028648, 11483.540658373697, 11488.942555287213, 11500.769984600345, 9536.809263814685, 11318.660440186808, 11397.601083962063, 11400.543978240861, 11415.029722473517, 9197.0, 11133.220674552784, 11155.944220278847, 11169.0, 11183.105351571854, 36012.81993590015, 36250.63749362499, 36345.636543634755, 36356.818215909014, 36490.81754591237, 3960.2871483133026, 3975.2447035063246, 4017.4777278953547, 4023.517177938637, 4037.4953130858644, 26734.46531069387, 26736.465270694476, 26949.32626684329, 28240.576391354145, 28387.716122838712, 3210.1653570071717, 3275.164832967578, 3289.5723555937816, 3296.884609038696, 3415.743819213544, 326160.0, 350165.0, 357907.0, 358776.0, 360424.5936310957, 35658.82170589182, 39956.40174392992, 43785.78107109508, 44243.33634995478, 44252.451164209095, 30764.69235307663, 34415.65584344124, 35342.11644708851, 38365.0, 38531.844044678706, 14343.78484322736, 14438.844892408582, 14483.275836208359, 14489.420423183166, 14557.78163327551, 12547.184433011826, 12649.494020239088, 12661.0, 12690.238585684889, 12723.23660580368, 11979.98170155529, 12152.392380381123, 12161.817572736416, 12185.51257949672, 12266.386680665932, 4864.489228631013, 5633.492985631313, 5661.235733176051, 5705.74324155414, 5711.914736200109, 1014.2241185493103, 1176.9411529423494, 1186.6440067979572, 1205.108219917255, 1251.0742050882282, 23500.88249558741, 26110.086146985144, 26788.866055669987, 27200.59199112015, 27325.0, 1275.1775105057186, 1291.1155858237137, 1310.1418570835926, 1340.6715354737958, 1345.307166809094, 11604.824254340772, 12484.188527745668, 13472.922166226874, 13957.720845583031, 14227.0, 417229.0, 420072.0, 420631.0, 421511.0, 423174.0, 54370.45629543654, 56612.716936415054, 56904.71547642235, 56994.0, 57346.56633584194, 1316.453671726242, 1335.00542096139, 1415.6743948891813, 1534.6163459135216, 1537.1929736888, 25414.0, 26151.738482615692, 26392.472150556496, 26574.0, 26652.60021099646, 47128.0, 47253.0, 47278.0, 47312.7634361826, 47515.28727069028, 386211.0, 386279.06860465516, 388074.0, 388316.0, 389547.0, 27993.580096298163, 28477.0, 31297.530537041508, 32006.0, 33208.501872472414, 46179.76910115428, 46322.53677463182, 46979.0, 46993.0, 47862.0, 30441.23896902595, 32126.83936580302, 32278.35443291168, 32297.192570184994, 32725.50911736278, 28635.42729145447, 31583.0, 32065.519017214294, 32215.51676724898, 32745.18137046593, 34597.827010864785, 35354.58581656705, 38249.0, 38279.42580861234, 38731.03172420712, 29463.116106516838, 29665.555016674334, 29756.256093596967, 30552.694473055875, 30647.54028689527, 23105.537889241783, 24454.633180502664, 24592.508149836543, 29002.0, 33787.32425351528], "value": 18807.55803534468, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.9556053273607, 397.9661728753056, 409.6927304521609, 428.11166828830164, 471.1895539671763, 14430.855691443083, 20622.17511299546, 20693.068811903464, 21701.240456584022, 22915.19796807112, 23142.30573082806, 23184.65223021656, 23536.411589710267, 23762.287131386085, 23887.641685374732, 6743.9662801686045, 6941.305869413069, 6980.476464265175, 6985.336393042653, 7004.64976751163, 6606.6036037837885, 6871.622060786654, 6922.169339679245, 6925.65371731414, 6944.097267355237, 6634.734610615569, 6905.896411553831, 6913.377795998359, 6928.861422771542, 6937.167539895195, 6886.552374095668, 6978.232394436608, 6983.1620205575655, 6984.5110842241065, 6991.370776630128, 6720.899186512206, 6738.157730283715, 6771.932280677229, 6789.287124851919, 6815.863682726367, 24110.276691699102, 26369.0, 27474.725252747416, 27657.723422765717, 27666.0, 3059.724624783758, 3067.9233019174476, 3126.1715645353997, 3127.9530807037913, 3128.765342599314, 16297.87550509435, 16348.673026539522, 16937.407190748265, 17016.829831701772, 17331.220095095752, 2104.2109209046607, 2122.5754849030254, 2128.4891626009644, 2132.9146834126773, 2136.5940471310555, 113256.0, 134740.0, 140027.0, 141878.29060854734, 143736.0, 22209.333719988426, 22718.659220111713, 23514.412139696302, 23585.52828943446, 23726.0, 35452.82273588667, 35947.741829035855, 36214.275714485564, 36445.635543644225, 36634.63365366312, 11290.54837806495, 11383.373914434786, 11563.017143542726, 11572.074234061254, 11804.822927656092, 9322.580483878242, 9324.627014919462, 9326.580303886341, 9341.953290233505, 9515.809683806423, 10702.197335199891, 10823.350598964087, 10871.29336593119, 10892.836607450894, 10945.616903408501, 3888.6305800948867, 4151.232022075926, 4186.832526698898, 4197.664186865043, 4198.706090573692, 1106.8212353843132, 1111.9944400277948, 1120.2270433400927, 1126.7915435644431, 1139.6979800352913, 23161.0, 23640.0, 23664.0, 23672.408189795395, 23711.0, 1123.3484578944192, 1124.4377811094453, 1125.5385292030228, 1168.8305195746568, 1171.4084387384487, 11957.282563046245, 12003.219790713576, 12397.690057748629, 12433.37833108341, 12504.874951250553, 547645.0, 721496.0, 749968.0, 763242.0, 783846.0, 110920.89079109266, 117929.0, 137681.62318376687, 137940.24119517553, 167736.0, 1331.5472739268696, 1344.4622151139622, 1396.427464739452, 1399.0696187035508, 1401.1453013661658, 18355.35756248525, 18782.530436738645, 19520.804791952465, 20540.200062988395, 20628.79371206269, 40438.79780601078, 55473.72263138658, 57292.0, 57476.56308592319, 58837.0, 72447.91328129977, 75146.0, 75861.0, 77920.0, 79252.0, 11391.772164556829, 11554.768904621693, 11558.422078895854, 11999.880001200225, 12022.218555793845, 10322.535485903303, 12671.30307833078, 12687.238765674088, 12808.615741527768, 12989.935050324688, 9369.953150234205, 10904.800471947921, 10936.289141206114, 10989.450527473435, 11069.0, 9415.529223538933, 10466.68599942003, 10479.10927571165, 10598.788024239626, 10784.730381740206, 10661.0, 10757.9462102689, 10949.178811589003, 11015.504302306255, 11071.667849964513, 11607.361595112012, 11628.069754419796, 11671.299722016705, 11683.299002059903, 11991.040716742466, 8827.955860220658, 10871.728206794893, 10874.782504350027, 11162.21404575264, 11350.659480215607], "value": 11818.283533534706, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1350.8446528649206, 1426.6576021754781, 1618.8947718398304, 1629.2749726371762, 1630.144174308488, 9925.652602158925, 10220.437875916821, 10254.230932680051, 11770.705732356684, 11795.233309834855, 9614.423134611929, 11150.0, 11695.824562631567, 11702.473388697508, 11710.297382157076, 2501.6747822783013, 2538.733432989537, 2540.1490500682294, 2541.504406640706, 2541.9872900635564, 2468.259522143359, 2526.696796384427, 2527.140772137473, 2529.949401011979, 2530.8101892358054, 2479.826412151141, 2485.3413845331, 2500.712418071918, 2507.736687647798, 2510.1089113364765, 2471.80117642943, 2474.5545801755675, 2482.950340993188, 2486.2292689266296, 2498.675172227617, 2447.36368544178, 2449.5590793657134, 2467.506498700267, 2470.8641024743633, 2474.220620504553, 11520.481578328996, 11758.294502329802, 11763.941180294129, 11781.705457363549, 11790.882091179064, 6027.487663548603, 6193.256809182882, 6196.481861943807, 6210.099535567362, 6220.47125994291, 3789.450529673182, 3816.46569480273, 3819.618038196186, 3851.634094761021, 3877.4377715231135, 1256.1960345378964, 1261.6530454125098, 1272.9490820367175, 1309.1883032519804, 1326.8739469750358, 14579.635509112251, 15907.363705451768, 16017.19914004295, 16028.038317700975, 16046.35814567416, 4486.394336764495, 4801.375821143229, 4926.605871530269, 4983.152864013126, 5010.9477009827615, 12160.209586376857, 12287.262764233996, 12309.0, 12310.0, 12310.876891231152, 6078.1186727924205, 6149.0, 6151.692415379213, 6157.138000679908, 6163.0, 4810.831620893252, 4898.412190537087, 5589.189567512688, 5634.4647258511195, 5700.40145784695, 3383.3740757959363, 3987.3819557968563, 4090.365993271048, 4128.710990230655, 4174.874753757392, 1094.075506197262, 1097.1880808201872, 1098.566066403766, 1105.3754628634795, 1109.5395410904384, 771.7954741993376, 861.3023451004706, 961.4904100826573, 994.7565543071161, 1052.9631462898915, 5900.675462849585, 6875.656217189221, 6932.6533673316135, 6937.7224911004005, 7004.579725216503, 1030.6238223048683, 1043.5564884923876, 1066.7013236293851, 1112.5160555158532, 1117.3072694929197, 4234.957650423518, 4339.848105316299, 4351.586599273064, 4415.403920470695, 4539.773011349485, 13091.869081309256, 13300.467981280834, 13944.442222310994, 14103.647408814657, 14401.855981440262, 13994.510192143227, 14066.0, 14329.713405732036, 14355.497557585435, 14419.206943617992, 10343.120834728981, 10649.893501065046, 10660.626878059076, 10671.733206669895, 10726.678199654023, 6100.603460775035, 6658.500612454182, 6997.685104170223, 7038.577685338896, 7083.858322833411, 11136.33182009082, 12486.063545233963, 12645.93677031609, 12839.486420543073, 13002.08985371015, 10912.399818009768, 11260.88739112631, 12951.352432378531, 13046.41291141882, 13046.478140874258, 8196.344292456708, 8491.320694344315, 9174.908250917406, 9535.427874327284, 9682.031796820262, 10151.7462063449, 10217.33587316822, 10343.224258180517, 10348.948255258676, 10356.637517686844, 8593.484390936565, 8615.22462978335, 8645.870311945451, 8684.696035638723, 8773.38586298953, 8465.661373545236, 8469.872951905849, 8473.83052338962, 8490.702825401082, 8634.395592308729, 7764.5729484878875, 7855.214478552099, 7900.051993760785, 7913.723019694283, 7915.168907264884, 5815.534757219328, 5829.970850145722, 5855.03391940325, 5971.343152253336, 6022.518198544193, 4624.30635404692, 5571.637101363349, 5621.831345059655, 5635.971820140873, 5722.3991480895575], "value": 5263.696126712235, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.8992751812047, 405.5154090861421, 406.09440946688875, 407.3889166250624, 412.6863583676407, 4911.80352785889, 5445.0, 5542.722863856801, 5580.302462192226, 5585.8603534911645, 4630.37489938859, 4663.393758811359, 4972.30387745715, 5435.728213589315, 5458.781648734046, 3838.309104361214, 4219.789010549475, 4275.294576394891, 4319.265724826779, 4328.242557552431, 3556.2887422515473, 3615.138203825032, 3704.2776658551593, 3834.3098242316373, 4257.956800583861, 3769.830357633913, 3953.5058117735284, 4251.404803327521, 4257.914841703164, 4284.0, 3752.4183751518694, 3897.7527191298796, 4153.439285696422, 4184.497860256758, 4203.831846726127, 3734.495843061179, 3769.6984241260634, 3771.830267637963, 3772.1512659651635, 3778.678812300958, 4837.5109115849855, 5150.995555866608, 5303.992241474108, 5379.273798037293, 5436.396263102394, 3815.6565909068318, 4351.347297905308, 4361.80371883266, 4573.725576465423, 4924.384451943507, 5381.2197231401615, 5498.835034948918, 5648.587853036741, 5789.797357092523, 5794.884102317972, 3491.5286436331025, 3702.2965636529243, 4044.5550989391145, 4171.416001759756, 4187.811548480326, 5001.474550262169, 5628.521575666074, 5772.105323674838, 5817.0, 5894.322152952465, 4024.7383920045104, 4474.351219073281, 4598.620413875824, 4604.677672562889, 4627.005193883331, 4431.692650668061, 5274.7890084396195, 5275.60432967529, 5292.285541451932, 5306.8142615009065, 5028.572571331405, 5062.620303477181, 5260.736963151827, 5387.515123638891, 5482.5613950883835, 5121.539061484485, 5402.0, 5558.416366281563, 5804.65172089676, 5820.650760954356, 1591.2759694339047, 1703.4208369154298, 1784.9553761155814, 1810.958698748225, 1814.818518148201, 1369.274284629148, 1426.5149849051372, 1431.935562899672, 1444.6605047813755, 1462.1081140504284, 4460.8661740147845, 4801.727542201319, 4929.630277729185, 5431.049566325877, 5458.344998600193, 4845.781939812647, 5040.57155141817, 5349.919751203735, 5855.745537967828, 5905.498032667271, 4750.365970032927, 5302.750395341583, 5332.880095179978, 5385.865353366119, 5601.943980560224, 2709.7832173426077, 3098.209956461133, 3116.937661246762, 3153.952690709641, 3159.8578063987175, 5126.820561280412, 5596.356419011866, 5622.791099913538, 5658.274226361, 6015.4586087252355, 5145.639805213674, 5318.016167009038, 5413.972930135403, 5975.223220981332, 5984.3417224105315, 5214.97392513035, 5361.544268737123, 5761.567882408836, 5766.481016708516, 6000.519958403404, 4767.33257343979, 5021.723805190603, 5528.889422211614, 5709.344290155808, 6041.281423359594, 5179.81870634526, 5350.411454740053, 5696.132983027323, 5720.255322126625, 5869.828163579503, 5330.360356757059, 5384.892302154013, 5529.009556559555, 5950.613210141327, 6220.471259942956, 4441.423294730324, 4660.883477912944, 4821.300911367762, 5082.364704411949, 5137.8201762938115, 4680.642613642006, 5834.970825146017, 5838.649681019152, 5947.672877991752, 5970.940290597038, 5204.609654276021, 5381.67709937405, 5385.569154467712, 5793.246877905845, 5808.0252714077415, 5239.166291797993, 5684.658920464785, 5857.0, 5863.677497737665, 5873.970630146993, 4966.379202599675, 5224.843254702365, 5267.446918073547, 5342.599305052059, 5705.605164926862, 4957.480189065804, 5017.623678224075, 5433.592480563894, 5437.912417516592, 5793.855153621024, 4896.853094407174, 4974.477679843709, 5127.974360128175, 5740.282464691913, 5768.682722450306], "value": 4337.489001524114, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [763.9006929099216, 774.6126936531734, 799.7480793550031, 814.279362763954, 918.7381596245069, 15672.216389180538, 17534.0, 17660.646787064266, 17667.734983975235, 17802.376916807916, 15639.0, 16112.677746445093, 16124.355025798954, 16329.0, 16353.427630032951, 919.4804935211617, 995.4972738766912, 1024.0629823711295, 1027.712240572637, 1030.9484525773719, 988.5205675247499, 1020.5050550483047, 1030.340582027503, 1033.700226934188, 1034.8395998620226, 1081.8052750504905, 1083.3337497439059, 1084.219362059316, 1085.9891401086006, 1086.1853609792656, 1062.4634559547417, 1078.1428764132534, 1080.200651517875, 1083.0469187115366, 1085.1210519479248, 803.6865622407266, 803.7548547692951, 804.995975020127, 805.2430715127762, 806.6450761664848, 16933.0, 17193.054382008984, 17462.825371746247, 18042.639147217113, 18266.543336416562, 14599.854001459955, 14761.852381476156, 14786.334614942354, 14888.776668349983, 15163.862710296771, 10455.529501172465, 10764.623238186705, 11903.523859045628, 12191.939040304831, 12335.814962775565, 3950.7432016919097, 3981.8009099544906, 3995.5604883462797, 4097.631213190797, 4115.444415003966, 81970.0, 110579.89420105857, 114431.0, 114476.0, 115602.0, 9196.310276729271, 11246.887531124748, 11402.144839137098, 11693.824592631117, 12250.203736757083, 23778.0, 24137.51724965491, 24570.140045098335, 24598.877005614857, 24615.3846153844, 8065.435419520577, 8165.63254653542, 8312.33501319893, 8332.958335208285, 8349.66601335952, 4626.606738427204, 4647.651426143053, 4765.880852978634, 4770.356001939694, 4781.784819683123, 3496.2133519957806, 3596.460530920383, 3610.494530765695, 3877.1276462795645, 4101.651359634465, 642.2549842183046, 729.8868675355328, 734.313416955138, 740.4964624055586, 751.9624018799038, 669.9665016749143, 693.9097917270723, 732.238471989136, 746.5931067568189, 760.4182800157884, 9666.806663866684, 10920.18098642589, 11016.504257308441, 11020.118390528738, 11197.776044479227, 601.461691785858, 679.8008442687901, 736.8342865631689, 740.1820987808502, 758.2455456820499, 5046.823361182414, 5289.814856480005, 5295.655782374133, 5298.125809241432, 5298.231756395378, 105383.94616053741, 106788.46605767077, 108449.0, 109442.45278773714, 110567.0, 91434.0, 95016.52491737636, 95642.52178739062, 96533.51733241288, 97364.51317743366, 80375.0, 81520.9999999988, 82572.0, 82682.0, 82860.0, 9958.751031223988, 10164.390136591828, 10219.23355748307, 10252.538635761259, 10271.589136434459, 53519.46480535145, 54295.91408171893, 54580.0, 55674.44325556693, 56409.0, 68834.31165688479, 70261.64869175621, 70365.29634703792, 70557.94163087661, 70578.0, 11818.290902545874, 11828.40857957116, 11830.0, 12045.277283363026, 12295.508179672714, 10323.432211228153, 11820.229646758646, 12334.876651233373, 13165.47338106465, 13730.038897277494, 12135.818815676534, 12459.377031148588, 12683.0, 12787.61637150887, 13063.0, 12279.324637145042, 12414.565490207799, 12555.309457979534, 12615.495380185057, 13194.472221111047, 11031.172662050218, 11197.552097915992, 12356.62930112098, 12496.187747796364, 12776.297303648389, 7838.725644602411, 8885.0, 8937.28501719874, 9389.577469014048, 9779.853302200616, 8437.64997600381, 9809.607615695293, 10248.180145588185, 10442.425666588411, 10707.0], "value": 7883.389917146563, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1533.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 121.0, 120.0, 124.0, 171.0, 119.0, 121.0, 121.0, 120.0, 121.0], "value": 124.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 141.0, 98.0, 178.0, 95.0, 140.0, 143.0, 142.0, 147.0, 136.0], "value": 138.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 194.0, 199.0, 199.0, 195.0, 195.0, 195.0, 239.0, 225.0, 195.0], "value": 203.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 61.0, 84.0, 60.0, 61.0, 60.0, 59.0, 69.0, 59.0], "value": 65.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 69.0, 102.0, 102.0, 101.0, 79.0, 81.0, 70.0, 70.0, 81.0], "value": 84.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 89.0, 89.0, 90.0, 93.0, 89.0, 106.0, 119.0, 138.0], "value": 99.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 101.0, 97.0, 95.0, 96.0, 96.0, 97.0, 96.0, 134.0, 97.0], "value": 100.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 92.0, 77.0, 146.0, 139.0, 94.0, 90.0, 116.0, 145.0], "value": 108.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 62.0, 67.0, 68.0, 68.0, 69.0, 68.0, 71.0, 98.0], "value": 69.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 64.0, 57.0, 63.0, 61.0, 59.0, 66.0, 86.0, 86.0, 84.0], "value": 68.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 82.0, 84.0, 83.0, 87.0, 100.0, 84.0, 83.0, 87.0], "value": 85.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 130.0, 145.0, 138.0, 147.0, 145.0, 149.0, 140.0, 204.0, 177.0], "value": 151.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 175.0, 164.0, 166.0, 163.0, 165.0, 164.0, 157.0, 161.0, 162.0], "value": 167.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 69.0, 83.0, 77.0, 64.0, 66.0, 64.0, 63.0, 63.0, 63.0], "value": 67.1, "unit": "score"}]}]} 01:56:25 INFO - Return code: 0 01:56:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:56:25 ERROR - # TBPL SUCCESS # 01:56:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:56:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:56:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:56:25 INFO - 2017-06-01 01:56:25.160 screenresolution[1658:10780] starting screenresolution argv=screenresolution get 01:56:25 INFO - 2017-06-01 01:56:25.162 screenresolution[1658:10780] Display 0: 1600x1200x32@60 01:56:25 INFO - 2017-06-01 01:56:25.168 screenresolution[1659:10783] starting screenresolution argv=screenresolution list 01:56:25 INFO - Available Modes on Display 0 01:56:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:56:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:56:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:56:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:56:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:56:25 INFO - Intel Iris: 01:56:25 INFO - Chipset Model: Intel Iris 01:56:25 INFO - Type: GPU 01:56:25 INFO - Bus: Built-In 01:56:25 INFO - VRAM (Dynamic, Max): 1536 MB 01:56:25 INFO - Vendor: Intel (0x8086) 01:56:25 INFO - Device ID: 0x0a2e 01:56:25 INFO - Revision ID: 0x0009 01:56:25 INFO - Displays: 01:56:25 INFO - Display: 01:56:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:56:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:56:25 INFO - Display Serial Number: 1600x1200 60 01:56:25 INFO - Main Display: Yes 01:56:25 INFO - Mirror: Off 01:56:25 INFO - Online: Yes 01:56:25 INFO - Rotation: Supported 01:56:25 INFO - Return code: 0 01:56:25 INFO - Running post-action listener: _resource_record_post_action 01:56:25 INFO - [mozharness: 2017-06-01 08:56:25.404496Z] Finished run-tests step (success) 01:56:25 INFO - Running post-run listener: _resource_record_post_run 01:56:25 INFO - Total resource usage - Wall time: 1192s; CPU: 55.0%; Read bytes: 51665920; Write bytes: 438270976; Read time: 396; Write time: 1515 01:56:25 INFO - TinderboxPrint: CPU usage
54.6% 01:56:25 INFO - TinderboxPrint: I/O read bytes / time
51,665,920 / 396 01:56:25 INFO - TinderboxPrint: I/O write bytes / time
438,270,976 / 1,515 01:56:25 INFO - TinderboxPrint: CPU idle
2,163.8 (45.4%) 01:56:25 INFO - TinderboxPrint: CPU system
961.8 (20.2%) 01:56:25 INFO - TinderboxPrint: CPU user
1,640.2 (34.4%) 01:56:25 INFO - TinderboxPrint: Swap in / out
166,883,328 / 0 01:56:25 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 119863808; Write bytes: 116217856; Read time: 10755; Write time: 253 01:56:25 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 49495040; Write bytes: 310854656; Read time: 375; Write time: 1225 01:56:25 INFO - Running post-run listener: _upload_blobber_files 01:56:25 INFO - Blob upload gear active. 01:56:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:56:25 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 01:56:25 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'] 01:56:25 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 01:56:26 INFO - (blobuploader) - INFO - Open directory for files ... 01:56:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 01:56:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:56:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:56:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 01:56:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:56:27 INFO - (blobuploader) - INFO - Done attempting. 01:56:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 01:56:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:56:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:56:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 01:56:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:56:28 INFO - (blobuploader) - INFO - Done attempting. 01:56:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 01:56:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:56:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:56:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 01:56:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:56:29 INFO - (blobuploader) - INFO - Done attempting. 01:56:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:56:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:56:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:56:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:56:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:56:31 INFO - (blobuploader) - INFO - Done attempting. 01:56:31 INFO - (blobuploader) - INFO - Iteration through files over. 01:56:31 INFO - Return code: 0 01:56:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:56:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:56:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/64b33d82a90192c54753f9de3a0f6d8f9e8eefab4c555249e5a04f38a2e0f386e3a9c9d6424003bbd709c9229410faef1b4b85e1fb1cf1ec567e65cb20afe741", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/192870aa01bcc4c878c02b9791b4cbe1306c0017dee938b8e196482b286049da31c1f05ba2ecaed0c91c42c5d2b03fd697c0a062764c26768d93e66ea5830171", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9037be4dce96a6d3b87f061d48aa3611431aa215b34777ddeb8387d67d2a108b6a9e57d8d19259896b309d6f083724276a5f5a225e1b7c1763d61965395ba956", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c867740dbbbc49a48a62ca376b8a5ebd028d3142dc5b05621087199c7b8a9a5edbe8b919911b236575ce575d5e773d0d68edb4f435c36b262af92efea2112586"} 01:56:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:56:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:56:31 INFO - Contents: 01:56:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/64b33d82a90192c54753f9de3a0f6d8f9e8eefab4c555249e5a04f38a2e0f386e3a9c9d6424003bbd709c9229410faef1b4b85e1fb1cf1ec567e65cb20afe741", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/192870aa01bcc4c878c02b9791b4cbe1306c0017dee938b8e196482b286049da31c1f05ba2ecaed0c91c42c5d2b03fd697c0a062764c26768d93e66ea5830171", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9037be4dce96a6d3b87f061d48aa3611431aa215b34777ddeb8387d67d2a108b6a9e57d8d19259896b309d6f083724276a5f5a225e1b7c1763d61965395ba956", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c867740dbbbc49a48a62ca376b8a5ebd028d3142dc5b05621087199c7b8a9a5edbe8b919911b236575ce575d5e773d0d68edb4f435c36b262af92efea2112586"} 01:56:31 INFO - Running post-run listener: copy_logs_to_upload_dir 01:56:31 INFO - Copying logs to upload dir... 01:56:31 INFO - mkdir: /builds/slave/test/build/upload/logs 01:56:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.718000 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-06-01 01:56:31.488936) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 01:56:31.492198) ========= 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.eiTgUi7EgR/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.scD9Rwchgx/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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/64b33d82a90192c54753f9de3a0f6d8f9e8eefab4c555249e5a04f38a2e0f386e3a9c9d6424003bbd709c9229410faef1b4b85e1fb1cf1ec567e65cb20afe741", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/192870aa01bcc4c878c02b9791b4cbe1306c0017dee938b8e196482b286049da31c1f05ba2ecaed0c91c42c5d2b03fd697c0a062764c26768d93e66ea5830171", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9037be4dce96a6d3b87f061d48aa3611431aa215b34777ddeb8387d67d2a108b6a9e57d8d19259896b309d6f083724276a5f5a225e1b7c1763d61965395ba956", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c867740dbbbc49a48a62ca376b8a5ebd028d3142dc5b05621087199c7b8a9a5edbe8b919911b236575ce575d5e773d0d68edb4f435c36b262af92efea2112586"} build_url:https://queue.taskcluster.net/v1/task/BSTph80pTuydBM8Qh1BuDw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011163 build_url: 'https://queue.taskcluster.net/v1/task/BSTph80pTuydBM8Qh1BuDw/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/64b33d82a90192c54753f9de3a0f6d8f9e8eefab4c555249e5a04f38a2e0f386e3a9c9d6424003bbd709c9229410faef1b4b85e1fb1cf1ec567e65cb20afe741", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/192870aa01bcc4c878c02b9791b4cbe1306c0017dee938b8e196482b286049da31c1f05ba2ecaed0c91c42c5d2b03fd697c0a062764c26768d93e66ea5830171", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9037be4dce96a6d3b87f061d48aa3611431aa215b34777ddeb8387d67d2a108b6a9e57d8d19259896b309d6f083724276a5f5a225e1b7c1763d61965395ba956", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c867740dbbbc49a48a62ca376b8a5ebd028d3142dc5b05621087199c7b8a9a5edbe8b919911b236575ce575d5e773d0d68edb4f435c36b262af92efea2112586"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 01:56:31.528047) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 01:56:31.528409) ========= 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.eiTgUi7EgR/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.scD9Rwchgx/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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.005196 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 01:56:31.577872) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 01:56:31.578193) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 01:56:31.578531) ========= ========= Total master_lag: 0.18 =========