builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0101 starttime: 1495637367.71 results: success (0) buildid: 20170524055525 builduid: 4ea5015101734f388cb31d278689bad1 revision: 7166b44658df0723b16d43b7f884deb73c99f0ba ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 07:49:27.707621) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 07:49:27.708046) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 07:49:27.708330) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 07:49:27.759179) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-05-24 07:49:27.759578) ========= 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.DSY2mu0QQp/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.tYo4Gyv8yj/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-24 07:49:27-- 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% 18.3M=0.001s 2017-05-24 07:49:27 (18.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.139702 ========= master_lag: 3.87 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-05-24 07:49:31.770145) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 07:49:31.773529) ========= 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.DSY2mu0QQp/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.tYo4Gyv8yj/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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.029495 ========= master_lag: 0.32 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 07:49:32.127577) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-24 07:49:32.127932) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 7166b44658df0723b16d43b7f884deb73c99f0ba --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 7166b44658df0723b16d43b7f884deb73c99f0ba --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DSY2mu0QQp/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.tYo4Gyv8yj/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-24 07:49:32,245 truncating revision to first 12 chars 2017-05-24 07:49:32,245 Setting DEBUG logging. 2017-05-24 07:49:32,245 attempt 1/10 2017-05-24 07:49:32,245 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/7166b44658df?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-24 07:49:33,064 unpacking tar archive at: mozilla-beta-7166b44658df/testing/mozharness/ program finished with exit code 0 elapsedTime=1.102653 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-24 07:49:33.255256) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 07:49:33.258625) ========= script_repo_revision: 7166b44658df0723b16d43b7f884deb73c99f0ba ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 07:49:33.259030) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 07:49:33.259302) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 07:49:33.278372) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-24 07:49:33.278780) ========= /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.DSY2mu0QQp/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.tYo4Gyv8yj/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 07:49:33 INFO - MultiFileLogger online at 20170524 07:49:33 in /builds/slave/test 07:49:33 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 07:49:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:49:33 INFO - {'append_to_log': False, 07:49:33 INFO - 'base_work_dir': '/builds/slave/test', 07:49:33 INFO - 'blob_upload_branch': 'Mozilla-Beta', 07:49:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:49:33 INFO - 'branch': 'Mozilla-Beta', 07:49:33 INFO - 'buildbot_json_path': 'buildprops.json', 07:49:33 INFO - 'config_files': ('talos/mac_config.py',), 07:49:33 INFO - 'default_actions': ('clobber', 07:49:33 INFO - 'read-buildbot-config', 07:49:33 INFO - 'download-and-extract', 07:49:33 INFO - 'populate-webroot', 07:49:33 INFO - 'create-virtualenv', 07:49:33 INFO - 'install', 07:49:33 INFO - 'run-tests'), 07:49:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:49:33 INFO - 'download_minidump_stackwalk': True, 07:49:33 INFO - 'download_symbols': 'ondemand', 07:49:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:49:33 INFO - 'tooltool.py': '/tools/tooltool.py', 07:49:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:49:33 INFO - '/tools/misc-python/virtualenv.py')}, 07:49:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:49:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:49:33 INFO - 'gecko_profile': False, 07:49:33 INFO - 'gecko_profile_interval': 0, 07:49:33 INFO - 'installer_path': 'installer.exe', 07:49:33 INFO - 'log_level': 'info', 07:49:33 INFO - 'log_name': 'talos', 07:49:33 INFO - 'log_to_console': True, 07:49:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:49:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:49:33 INFO - 'opt_config_files': (), 07:49:33 INFO - 'pip_index': False, 07:49:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:49:33 INFO - 'cmd': ('bash', 07:49:33 INFO - '-c', 07:49:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:49:33 INFO - 'enabled': True, 07:49:33 INFO - 'halt_on_failure': False, 07:49:33 INFO - 'name': 'check_screen_resolution'},), 07:49:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:49:33 INFO - 'cmd': ('bash', 07:49:33 INFO - '-c', 07:49:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:49:33 INFO - 'enabled': True, 07:49:33 INFO - 'halt_on_failure': False, 07:49:33 INFO - 'name': 'check_screen_resolution'},), 07:49:33 INFO - 'run_cmd_checks_enabled': True, 07:49:33 INFO - 'suite': 'dromaeojs-e10s', 07:49:33 INFO - 'system_bits': '32', 07:49:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:49:33 INFO - 'title': 't-yosemite-r7-0101', 07:49:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:49:33 INFO - 'use_talos_json': True, 07:49:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:49:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:49:33 INFO - 'work_dir': 'build'} 07:49:33 INFO - [mozharness: 2017-05-24 14:49:33.502893Z] Running clobber step. 07:49:33 INFO - Running pre-action listener: _resource_record_pre_action 07:49:33 INFO - Running main action method: clobber 07:49:33 INFO - rmtree: /builds/slave/test/build 07:49:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:49:36 INFO - Running post-action listener: _resource_record_post_action 07:49:36 INFO - [mozharness: 2017-05-24 14:49:36.182720Z] Finished clobber step (success) 07:49:36 INFO - [mozharness: 2017-05-24 14:49:36.182929Z] Running read-buildbot-config step. 07:49:36 INFO - Running pre-action listener: _resource_record_pre_action 07:49:36 INFO - Running main action method: read_buildbot_config 07:49:36 INFO - Using buildbot properties: 07:49:36 INFO - { 07:49:36 INFO - "project": "", 07:49:36 INFO - "product": "firefox", 07:49:36 INFO - "script_repo_revision": "production", 07:49:36 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 07:49:36 INFO - "repository": "", 07:49:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 07:49:36 INFO - "buildid": "20170524055525", 07:49:36 INFO - "pgo_build": "False", 07:49:36 INFO - "basedir": "/builds/slave/test", 07:49:36 INFO - "buildnumber": 0, 07:49:36 INFO - "slavename": "t-yosemite-r7-0101", 07:49:36 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 07:49:36 INFO - "platform": "yosemite_r7_devedition", 07:49:36 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 07:49:36 INFO - "branch": "mozilla-beta", 07:49:36 INFO - "repo_path": "releases/mozilla-beta", 07:49:36 INFO - "revision": "7166b44658df0723b16d43b7f884deb73c99f0ba", 07:49:36 INFO - "stage_platform": "macosx64-devedition", 07:49:36 INFO - "builduid": "4ea5015101734f388cb31d278689bad1", 07:49:36 INFO - "slavebuilddir": "test" 07:49:36 INFO - } 07:49:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 07:49:36 INFO - Running post-action listener: _resource_record_post_action 07:49:36 INFO - [mozharness: 2017-05-24 14:49:36.188709Z] Finished read-buildbot-config step (success) 07:49:36 INFO - [mozharness: 2017-05-24 14:49:36.188868Z] Running download-and-extract step. 07:49:36 INFO - Running pre-action listener: _resource_record_pre_action 07:49:36 INFO - Running main action method: download_and_extract 07:49:36 INFO - mkdir: /builds/slave/test/build/tests 07:49:36 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')]} 07:49:36 INFO - trying https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 07:49:36 INFO - Downloading https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/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 07:49:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/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 07:49:37 INFO - Downloaded 1287 bytes. 07:49:37 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 07:49:37 INFO - Using the following test package requirements: 07:49:37 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:49:37 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 07:49:37 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 07:49:37 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:49:37 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 07:49:37 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:49:37 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 07:49:37 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 07:49:37 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:49:37 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 07:49:37 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 07:49:37 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:49:37 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 07:49:37 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:49:37 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 07:49:37 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:49:37 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 07:49:37 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:49:37 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 07:49:37 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 07:49:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 07:49:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 07:49:37 INFO - Fetch https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 07:49:39 INFO - Expected file size: 51964669 07:49:39 INFO - Obtained file size: 51964669 07:49:55 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 07:49:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 07:49:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 07:49:55 INFO - Fetch https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 07:49:57 INFO - Expected file size: 51964669 07:49:57 INFO - Obtained file size: 51964669 07:50:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 07:50:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 07:50:12 INFO - Fetch https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 07:50:13 INFO - Expected file size: 14404449 07:50:13 INFO - Obtained file size: 14404449 07:50: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')]} 07:50:14 INFO - trying https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 07:50:14 INFO - Downloading https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 07:50:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/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 07:50:16 INFO - Downloaded 74891043 bytes. 07:50:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 07:50:16 INFO - mkdir: /builds/slave/test/properties 07:50:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:50:16 INFO - Writing to file /builds/slave/test/properties/build_url 07:50:16 INFO - Contents: 07:50:16 INFO - build_url:https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 07:50:16 INFO - Running post-action listener: _resource_record_post_action 07:50:16 INFO - Running post-action listener: set_extra_try_arguments 07:50:16 INFO - [mozharness: 2017-05-24 14:50:16.789030Z] Finished download-and-extract step (success) 07:50:16 INFO - [mozharness: 2017-05-24 14:50:16.789208Z] Running populate-webroot step. 07:50:16 INFO - Running pre-action listener: _resource_record_pre_action 07:50:16 INFO - Running main action method: populate_webroot 07:50:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:50:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:50:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:50:16 INFO - '--tppagecycles', 07:50:16 INFO - '7'], 07:50:16 INFO - 'tests': ['tsvgm']}}, 07:50:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:50:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:50:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:50:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:50:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:50:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:50:16 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:50:16 INFO - 'talos_options': ['--disable-e10s'], 07:50:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:50:16 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:50:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:50:16 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:50:16 INFO - 'talos_options': ['--disable-e10s'], 07:50:16 INFO - 'tests': ['damp', 'tps']}, 07:50:16 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:50:16 INFO - 'tests': ['damp', 'tps']}, 07:50:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:50:16 INFO - 'tests': ['dromaeo_dom']}, 07:50:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:50:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:50:16 INFO - 'tests': ['basic_compositor_video']}, 07:50:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:50:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:50:16 INFO - 'tests': ['a11yr', 07:50:16 INFO - 'ts_paint', 07:50:16 INFO - 'tpaint', 07:50:16 INFO - 'sessionrestore', 07:50:16 INFO - 'sessionrestore_no_auto_restore', 07:50:16 INFO - 'tabpaint']}, 07:50:16 INFO - 'other-e10s': {'tests': ['a11yr', 07:50:16 INFO - 'ts_paint', 07:50:16 INFO - 'tpaint', 07:50:16 INFO - 'sessionrestore', 07:50:16 INFO - 'sessionrestore_no_auto_restore', 07:50:16 INFO - 'tabpaint']}, 07:50:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:50:16 INFO - 'ts_paint', 07:50:16 INFO - 'tpaint', 07:50:16 INFO - 'sessionrestore', 07:50:16 INFO - 'sessionrestore_no_auto_restore', 07:50:16 INFO - 'tabpaint']}, 07:50:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:50:16 INFO - 'ts_paint', 07:50:16 INFO - 'tpaint', 07:50:16 INFO - 'sessionrestore', 07:50:16 INFO - 'sessionrestore_no_auto_restore', 07:50:16 INFO - 'tabpaint']}, 07:50:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:50:16 INFO - 'tests': ['a11yr', 07:50:16 INFO - 'ts_paint', 07:50:16 INFO - 'tpaint', 07:50:16 INFO - 'sessionrestore', 07:50:16 INFO - 'sessionrestore_no_auto_restore', 07:50:16 INFO - 'tabpaint']}, 07:50:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:50:16 INFO - 'tests': ['a11yr', 07:50:16 INFO - 'ts_paint', 07:50:16 INFO - 'tpaint', 07:50:16 INFO - 'sessionrestore', 07:50:16 INFO - 'sessionrestore_no_auto_restore', 07:50:16 INFO - 'tabpaint']}, 07:50:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:50:16 INFO - 'tests': ['tsvgx', 07:50:16 INFO - 'tsvgr_opacity', 07:50:16 INFO - 'tart', 07:50:16 INFO - 'tscrollx', 07:50:16 INFO - 'cart', 07:50:16 INFO - 'tsvg_static']}, 07:50:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:50:16 INFO - 'tsvgr_opacity', 07:50:16 INFO - 'tart', 07:50:16 INFO - 'tscrollx', 07:50:16 INFO - 'cart', 07:50:16 INFO - 'tsvg_static']}, 07:50:16 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:50:16 INFO - 'talos_options': ['--disable-e10s'], 07:50:16 INFO - 'tests': ['tp5o']}, 07:50:16 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:50:16 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:50:16 INFO - 'talos_options': ['--disable-e10s', 07:50:16 INFO - '--xperf_path', 07:50:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:50:16 INFO - 'tests': ['tp5n']}, 07:50:16 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:50:16 INFO - 'talos_options': ['--xperf_path', 07:50:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:50:16 INFO - 'tests': ['tp5n']}}, 07:50:16 INFO - 'talos.zip': {'path': '', 07:50:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:50:16 INFO - Running post-action listener: _resource_record_post_action 07:50:16 INFO - [mozharness: 2017-05-24 14:50:16.808449Z] Finished populate-webroot step (success) 07:50:16 INFO - [mozharness: 2017-05-24 14:50:16.808562Z] Running create-virtualenv step. 07:50:16 INFO - Running pre-action listener: _resource_record_pre_action 07:50:16 INFO - Running main action method: create_virtualenv 07:50:16 INFO - Creating virtualenv /builds/slave/test/build/venv 07:50:16 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 07:50:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:50:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:50:16 INFO - Using real prefix '/tools/python27' 07:50:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:50:17 INFO - Installing distribute.............................................................................................................................................................................................done. 07:50:20 INFO - Installing pip.................done. 07:50:20 INFO - Return code: 0 07:50:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:50:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:50:20 INFO - Reading from file tmpfile_stdout 07:50:20 INFO - Output received: 07:50:20 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) 07:50:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:50: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')]} 07:50:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:50:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d458b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d45b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6fb5e1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d236d30>, '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 0x10d15ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d22bbe8>, '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.DSY2mu0QQp/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.tYo4Gyv8yj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 07:50:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:50:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:50:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DSY2mu0QQp/Render', 07:50:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:50:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:50:21 INFO - 'HOME': '/Users/cltbld', 07:50:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:50:21 INFO - 'LOGNAME': 'cltbld', 07:50:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:21 INFO - 'MOZ_NO_REMOTE': '1', 07:50:21 INFO - 'NO_EM_RESTART': '1', 07:50:21 INFO - 'PAGER': '/bin/cat', 07:50:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:50:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:21 INFO - 'PWD': '/builds/slave/test', 07:50:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:50:21 INFO - 'SHELL': '/bin/bash', 07:50:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tYo4Gyv8yj/Listeners', 07:50:21 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 07:50:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:50:21 INFO - 'USER': 'cltbld', 07:50:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:50:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:50:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:21 INFO - 'XPC_FLAGS': '0x0', 07:50:21 INFO - 'XPC_SERVICE_NAME': '0', 07:50:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:50:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:21 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 07:50:21 INFO - Cleaning up... 07:50:21 INFO - Return code: 0 07:50:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:50:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:50:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:50:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d458b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d45b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6fb5e1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d236d30>, '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 0x10d15ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d22bbe8>, '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.DSY2mu0QQp/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.tYo4Gyv8yj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 07:50:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:50:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:50:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DSY2mu0QQp/Render', 07:50:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:50:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:50:22 INFO - 'HOME': '/Users/cltbld', 07:50:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:50:22 INFO - 'LOGNAME': 'cltbld', 07:50:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:22 INFO - 'MOZ_NO_REMOTE': '1', 07:50:22 INFO - 'NO_EM_RESTART': '1', 07:50:22 INFO - 'PAGER': '/bin/cat', 07:50:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:50:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:22 INFO - 'PWD': '/builds/slave/test', 07:50:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:50:22 INFO - 'SHELL': '/bin/bash', 07:50:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tYo4Gyv8yj/Listeners', 07:50:22 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 07:50:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:50:22 INFO - 'USER': 'cltbld', 07:50:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:50:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:50:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:22 INFO - 'XPC_FLAGS': '0x0', 07:50:22 INFO - 'XPC_SERVICE_NAME': '0', 07:50:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:50:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:22 INFO - Downloading/unpacking psutil>=3.1.1 07:50: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 07:50: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 07:50: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 07:50: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 07:50:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:50:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:50:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:50:24 INFO - Installing collected packages: psutil 07:50:24 INFO - Running setup.py install for psutil 07:50:24 INFO - building 'psutil._psutil_osx' extension 07:50:24 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 07:50:25 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 07:50:25 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 07:50:25 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 07:50:25 INFO - building 'psutil._psutil_posix' extension 07:50:25 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 07:50:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:50:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:50:25 INFO - ^ 07:50:25 INFO - 1 warning generated. 07:50:25 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 07:50:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:50:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:50:26 INFO - Successfully installed psutil 07:50:26 INFO - Cleaning up... 07:50:26 INFO - Return code: 0 07:50:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:50:26 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')]} 07:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:26 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')]} 07:50:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:26 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 0x10d458b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d45b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6fb5e1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d236d30>, '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 0x10d15ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d22bbe8>, '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.DSY2mu0QQp/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.tYo4Gyv8yj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 07:50:26 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 07:50:26 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 07:50:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DSY2mu0QQp/Render', 07:50:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:50:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:50:26 INFO - 'HOME': '/Users/cltbld', 07:50:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:50:26 INFO - 'LOGNAME': 'cltbld', 07:50:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:26 INFO - 'MOZ_NO_REMOTE': '1', 07:50:26 INFO - 'NO_EM_RESTART': '1', 07:50:26 INFO - 'PAGER': '/bin/cat', 07:50:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:50:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:26 INFO - 'PWD': '/builds/slave/test', 07:50:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:50:26 INFO - 'SHELL': '/bin/bash', 07:50:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tYo4Gyv8yj/Listeners', 07:50:26 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 07:50:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:50:26 INFO - 'USER': 'cltbld', 07:50:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:50:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:50:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:26 INFO - 'XPC_FLAGS': '0x0', 07:50:26 INFO - 'XPC_SERVICE_NAME': '0', 07:50:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:50:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:50:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:50:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:50:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:50:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:50:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:50:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:50:27 INFO - Installing collected packages: mozsystemmonitor 07:50:27 INFO - Running setup.py install for mozsystemmonitor 07:50:27 INFO - Successfully installed mozsystemmonitor 07:50:27 INFO - Cleaning up... 07:50:28 INFO - Return code: 0 07:50:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:50: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')]} 07:50:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50: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')]} 07:50:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:28 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 0x10d458b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d45b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6fb5e1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d236d30>, '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 0x10d15ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d22bbe8>, '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.DSY2mu0QQp/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.tYo4Gyv8yj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 07:50:28 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 07:50:28 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 07:50:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DSY2mu0QQp/Render', 07:50:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:50:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:50:28 INFO - 'HOME': '/Users/cltbld', 07:50:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:50:28 INFO - 'LOGNAME': 'cltbld', 07:50:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:28 INFO - 'MOZ_NO_REMOTE': '1', 07:50:28 INFO - 'NO_EM_RESTART': '1', 07:50:28 INFO - 'PAGER': '/bin/cat', 07:50:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:50:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:28 INFO - 'PWD': '/builds/slave/test', 07:50:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:50:28 INFO - 'SHELL': '/bin/bash', 07:50:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tYo4Gyv8yj/Listeners', 07:50:28 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 07:50:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:50:28 INFO - 'USER': 'cltbld', 07:50:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:50:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:50:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:28 INFO - 'XPC_FLAGS': '0x0', 07:50:28 INFO - 'XPC_SERVICE_NAME': '0', 07:50:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:50:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:28 INFO - Downloading/unpacking jsonschema==2.5.1 07:50:28 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 07:50:28 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 07:50:28 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 07:50:28 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 07:50:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:50:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:50:29 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 07:50:29 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 07:50:29 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 07:50:29 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 07:50:29 INFO - Downloading functools32-3.2.3-2.tar.gz 07:50:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:50:30 INFO - warning: no files found matching '*.txt' 07:50:30 INFO - no previously-included directories found matching 'build' 07:50:30 INFO - no previously-included directories found matching 'dist' 07:50:30 INFO - no previously-included directories found matching '.git*' 07:50:30 INFO - Installing collected packages: jsonschema, functools32 07:50:30 INFO - Running setup.py install for functools32 07:50:30 INFO - warning: no files found matching '*.txt' 07:50:30 INFO - no previously-included directories found matching 'build' 07:50:30 INFO - no previously-included directories found matching 'dist' 07:50:30 INFO - no previously-included directories found matching '.git*' 07:50:30 INFO - Successfully installed jsonschema functools32 07:50:30 INFO - Cleaning up... 07:50:30 INFO - Return code: 0 07:50:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:50:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:50:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:50:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:30 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 0x10d458b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d45b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6fb5e1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d236d30>, '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 0x10d15ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d22bbe8>, '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.DSY2mu0QQp/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.tYo4Gyv8yj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 07:50:30 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 07:50:30 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 07:50:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DSY2mu0QQp/Render', 07:50:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:50:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:50:30 INFO - 'HOME': '/Users/cltbld', 07:50:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:50:30 INFO - 'LOGNAME': 'cltbld', 07:50:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:30 INFO - 'MOZ_NO_REMOTE': '1', 07:50:30 INFO - 'NO_EM_RESTART': '1', 07:50:30 INFO - 'PAGER': '/bin/cat', 07:50:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:50:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:30 INFO - 'PWD': '/builds/slave/test', 07:50:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:50:30 INFO - 'SHELL': '/bin/bash', 07:50:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tYo4Gyv8yj/Listeners', 07:50:30 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 07:50:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:50:30 INFO - 'USER': 'cltbld', 07:50:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:50:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:50:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:30 INFO - 'XPC_FLAGS': '0x0', 07:50:30 INFO - 'XPC_SERVICE_NAME': '0', 07:50:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:50:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:50:30 INFO - Cleaning up... 07:50:30 INFO - Return code: 0 07:50:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:50:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:50:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:50:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:30 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 0x10d458b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d45b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6fb5e1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d236d30>, '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 0x10d15ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d22bbe8>, '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.DSY2mu0QQp/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.tYo4Gyv8yj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 07:50:30 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 07:50:30 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 07:50:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DSY2mu0QQp/Render', 07:50:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:50:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:50:30 INFO - 'HOME': '/Users/cltbld', 07:50:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:50:30 INFO - 'LOGNAME': 'cltbld', 07:50:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:30 INFO - 'MOZ_NO_REMOTE': '1', 07:50:30 INFO - 'NO_EM_RESTART': '1', 07:50:30 INFO - 'PAGER': '/bin/cat', 07:50:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:50:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:30 INFO - 'PWD': '/builds/slave/test', 07:50:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:50:30 INFO - 'SHELL': '/bin/bash', 07:50:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tYo4Gyv8yj/Listeners', 07:50:30 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 07:50:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:50:30 INFO - 'USER': 'cltbld', 07:50:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:50:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:50:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:30 INFO - 'XPC_FLAGS': '0x0', 07:50:30 INFO - 'XPC_SERVICE_NAME': '0', 07:50:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:50:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:30 INFO - Downloading/unpacking blobuploader==1.2.4 07:50: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 07:50: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 07:50: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 07:50: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 07:50:32 INFO - Downloading blobuploader-1.2.4.tar.gz 07:50:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:50:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:50:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:50:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:50:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:50:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:50:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:50:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:50:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:50:32 INFO - Downloading docopt-0.6.1.tar.gz 07:50:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:50:33 INFO - Installing collected packages: blobuploader, requests, docopt 07:50:33 INFO - Running setup.py install for blobuploader 07:50:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:50:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:50:33 INFO - Running setup.py install for requests 07:50:33 INFO - Running setup.py install for docopt 07:50:33 INFO - Successfully installed blobuploader requests docopt 07:50:33 INFO - Cleaning up... 07:50:34 INFO - Return code: 0 07:50:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:50:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:50:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:50:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:34 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 0x10d458b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d45b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6fb5e1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d236d30>, '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 0x10d15ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d22bbe8>, '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.DSY2mu0QQp/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.tYo4Gyv8yj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 07:50:34 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 07:50:34 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 07:50:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DSY2mu0QQp/Render', 07:50:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:50:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:50:34 INFO - 'HOME': '/Users/cltbld', 07:50:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:50:34 INFO - 'LOGNAME': 'cltbld', 07:50:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:34 INFO - 'MOZ_NO_REMOTE': '1', 07:50:34 INFO - 'NO_EM_RESTART': '1', 07:50:34 INFO - 'PAGER': '/bin/cat', 07:50:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:50:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:34 INFO - 'PWD': '/builds/slave/test', 07:50:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:50:34 INFO - 'SHELL': '/bin/bash', 07:50:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tYo4Gyv8yj/Listeners', 07:50:34 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 07:50:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:50:34 INFO - 'USER': 'cltbld', 07:50:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:50:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:50:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:34 INFO - 'XPC_FLAGS': '0x0', 07:50:34 INFO - 'XPC_SERVICE_NAME': '0', 07:50:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:50:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:50:34 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-qNqTkY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:50:34 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-Y2o46a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:50:34 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-Y7ju6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:50:34 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-xvhTQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:50:34 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-TBfVLs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:50:34 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-qhLqqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:50:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:50:34 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-eE2QsZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:50:35 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-DsMyU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:50:35 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-pKN2u6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:50:35 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-Bt10kd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:50:35 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-HH7ZhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:50:35 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-WRSWy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:50:35 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-CKhypI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:50:35 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-NW8vmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:50:35 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-7_8xQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:50:35 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-cpGbnZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:50:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:50:35 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-vjuIKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:50:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:50:36 INFO - Running setup.py install for manifestparser 07:50:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:50:36 INFO - Running setup.py install for mozcrash 07:50:36 INFO - Running setup.py install for mozdebug 07:50:36 INFO - Running setup.py install for mozdevice 07:50:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:50:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:50:36 INFO - Running setup.py install for mozfile 07:50:36 INFO - Running setup.py install for mozhttpd 07:50:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:50:36 INFO - Running setup.py install for mozinfo 07:50:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:50:37 INFO - Running setup.py install for mozInstall 07:50:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:50:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:50:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:50:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:50:37 INFO - Running setup.py install for mozleak 07:50:37 INFO - Running setup.py install for mozlog 07:50:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:50:37 INFO - Running setup.py install for moznetwork 07:50:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:50:37 INFO - Running setup.py install for mozprocess 07:50:37 INFO - Running setup.py install for mozprofile 07:50:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:50:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:50:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:50:38 INFO - Running setup.py install for mozrunner 07:50:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:50:38 INFO - Running setup.py install for mozscreenshot 07:50:38 INFO - Running setup.py install for moztest 07:50:38 INFO - Running setup.py install for mozversion 07:50:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:50:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:50:38 INFO - Cleaning up... 07:50:38 INFO - Return code: 0 07:50:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:50:38 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')]} 07:50:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:38 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')]} 07:50:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:38 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 0x10d458b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d45b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6fb5e1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d236d30>, '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 0x10d15ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d22bbe8>, '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.DSY2mu0QQp/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.tYo4Gyv8yj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 07:50:38 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 07:50:38 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 07:50:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DSY2mu0QQp/Render', 07:50:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:50:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:50:38 INFO - 'HOME': '/Users/cltbld', 07:50:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:50:38 INFO - 'LOGNAME': 'cltbld', 07:50:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:38 INFO - 'MOZ_NO_REMOTE': '1', 07:50:38 INFO - 'NO_EM_RESTART': '1', 07:50:38 INFO - 'PAGER': '/bin/cat', 07:50:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:50:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:38 INFO - 'PWD': '/builds/slave/test', 07:50:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:50:38 INFO - 'SHELL': '/bin/bash', 07:50:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tYo4Gyv8yj/Listeners', 07:50:38 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 07:50:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:50:38 INFO - 'USER': 'cltbld', 07:50:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:50:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:50:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:38 INFO - 'XPC_FLAGS': '0x0', 07:50:38 INFO - 'XPC_SERVICE_NAME': '0', 07:50:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:50:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:50:39 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-dFqWlb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:50:39 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)) 07:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:50:39 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-EedbRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:50:39 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)) 07:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:50:39 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-lyICsD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:50:39 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)) 07:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:50:39 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-p0M9Ci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:50:39 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)) 07:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:50:39 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-uVJDGY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:50:39 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)) 07:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:50:39 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-CdPmgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:50:39 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)) 07:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:50:39 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-v3tfsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:50:39 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)) 07:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:50:39 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-dQkpk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:50:39 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)) 07:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:50:39 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-Zv1cNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:50:39 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)) 07:50:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:50:39 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-STW1u2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:50:40 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)) 07:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:50:40 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-RI4lw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:50:40 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)) 07:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:50:40 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-JbIDpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:50:40 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)) 07:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:50:40 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-7rZDCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:50:40 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)) 07:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:50:40 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-cbkvUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:50:40 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)) 07:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:50:40 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-YmYbOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:50:40 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)) 07:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:50:40 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-sqd7GU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:50:40 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)) 07:50:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:50:40 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-1A1b4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:50:40 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)) 07:50:40 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)) 07:50:40 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)) 07:50:40 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)) 07:50:40 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)) 07:50:40 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)) 07:50:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:50:40 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 07:50:40 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 07:50:40 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 07:50:40 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 07:50:42 INFO - Downloading blessings-1.6.tar.gz 07:50:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:50:42 INFO - Installing collected packages: blessings 07:50:42 INFO - Running setup.py install for blessings 07:50:42 INFO - Successfully installed blessings 07:50:42 INFO - Cleaning up... 07:50:42 INFO - Return code: 0 07:50:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:50:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:50:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:50:42 INFO - Reading from file tmpfile_stdout 07:50:42 INFO - Current package versions: 07:50:42 INFO - blessings == 1.6 07:50:42 INFO - blobuploader == 1.2.4 07:50:42 INFO - docopt == 0.6.1 07:50:42 INFO - functools32 == 3.2.3-2 07:50:42 INFO - jsonschema == 2.5.1 07:50:42 INFO - manifestparser == 1.1 07:50:42 INFO - mozInstall == 1.12 07:50:42 INFO - mozcrash == 1.0 07:50:42 INFO - mozdebug == 0.1 07:50:42 INFO - mozdevice == 0.49 07:50:42 INFO - mozfile == 1.2 07:50:42 INFO - mozhttpd == 0.7 07:50:42 INFO - mozinfo == 0.9 07:50:42 INFO - mozleak == 0.1 07:50:42 INFO - mozlog == 3.4 07:50:42 INFO - moznetwork == 0.27 07:50:42 INFO - mozprocess == 0.25 07:50:42 INFO - mozprofile == 0.28 07:50:42 INFO - mozrunner == 6.13 07:50:42 INFO - mozscreenshot == 0.1 07:50:42 INFO - mozsystemmonitor == 0.3 07:50:42 INFO - moztest == 0.8 07:50:42 INFO - mozversion == 1.4 07:50:42 INFO - psutil == 3.1.1 07:50:42 INFO - requests == 1.2.3 07:50:42 INFO - wsgiref == 0.1.2 07:50:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:50:42 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')]} 07:50:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:42 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')]} 07:50:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:42 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 0x10d458b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d45b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6fb5e1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d236d30>, '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 0x10d15ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d22bbe8>, '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.DSY2mu0QQp/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.tYo4Gyv8yj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 07:50:42 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 07:50:42 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 07:50:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DSY2mu0QQp/Render', 07:50:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:50:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:50:42 INFO - 'HOME': '/Users/cltbld', 07:50:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:50:42 INFO - 'LOGNAME': 'cltbld', 07:50:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:42 INFO - 'MOZ_NO_REMOTE': '1', 07:50:42 INFO - 'NO_EM_RESTART': '1', 07:50:42 INFO - 'PAGER': '/bin/cat', 07:50:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:50:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:42 INFO - 'PWD': '/builds/slave/test', 07:50:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:50:42 INFO - 'SHELL': '/bin/bash', 07:50:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tYo4Gyv8yj/Listeners', 07:50:42 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 07:50:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:50:42 INFO - 'USER': 'cltbld', 07:50:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:50:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:50:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:42 INFO - 'XPC_FLAGS': '0x0', 07:50:42 INFO - 'XPC_SERVICE_NAME': '0', 07:50:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:50:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:43 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)) 07:50:43 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)) 07:50:43 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)) 07:50:43 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)) 07:50:43 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)) 07:50:43 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)) 07:50:43 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)) 07:50:43 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)) 07:50:43 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)) 07:50:43 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)) 07:50:43 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)) 07:50:43 INFO - Cleaning up... 07:50:43 INFO - Return code: 0 07:50:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:50:43 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')]} 07:50:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:43 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')]} 07:50:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:43 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 0x10d458b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d45b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6fb5e1240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d236d30>, '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 0x10d15ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d22bbe8>, '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.DSY2mu0QQp/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.tYo4Gyv8yj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 07:50:43 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 07:50:43 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 07:50:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DSY2mu0QQp/Render', 07:50:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:50:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:50:43 INFO - 'HOME': '/Users/cltbld', 07:50:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:50:43 INFO - 'LOGNAME': 'cltbld', 07:50:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:43 INFO - 'MOZ_NO_REMOTE': '1', 07:50:43 INFO - 'NO_EM_RESTART': '1', 07:50:43 INFO - 'PAGER': '/bin/cat', 07:50:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:50:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:43 INFO - 'PWD': '/builds/slave/test', 07:50:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:50:43 INFO - 'SHELL': '/bin/bash', 07:50:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tYo4Gyv8yj/Listeners', 07:50:43 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 07:50:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:50:43 INFO - 'USER': 'cltbld', 07:50:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:50:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:50:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:43 INFO - 'XPC_FLAGS': '0x0', 07:50:43 INFO - 'XPC_SERVICE_NAME': '0', 07:50:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:50:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:50:43 INFO - Cleaning up... 07:50:43 INFO - Return code: 0 07:50:43 INFO - Running post-action listener: _resource_record_post_action 07:50:43 INFO - Running post-action listener: _start_resource_monitoring 07:50:43 INFO - Starting resource monitoring. 07:50:43 INFO - [mozharness: 2017-05-24 14:50:43.384417Z] Finished create-virtualenv step (success) 07:50:43 INFO - [mozharness: 2017-05-24 14:50:43.385351Z] Running install step. 07:50:43 INFO - Running pre-action listener: _resource_record_pre_action 07:50:43 INFO - Running main action method: install 07:50:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:50:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:50:43 INFO - Reading from file tmpfile_stdout 07:50:43 INFO - Detecting whether we're running mozinstall >=1.0... 07:50:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:50:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:50:43 INFO - Reading from file tmpfile_stdout 07:50:43 INFO - Output received: 07:50:43 INFO - Usage: mozinstall [options] installer 07:50:43 INFO - Options: 07:50:43 INFO - -h, --help show this help message and exit 07:50:43 INFO - -d DEST, --destination=DEST 07:50:43 INFO - Directory to install application into. [default: 07:50:43 INFO - "/builds/slave/test"] 07:50:43 INFO - --app=APP Application being installed. [default: firefox] 07:50:43 INFO - mkdir: /builds/slave/test/build/application 07:50:43 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'] 07:50:43 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 07:51:03 INFO - Reading from file tmpfile_stdout 07:51:03 INFO - Output received: 07:51:03 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:51:03 INFO - Running post-action listener: _resource_record_post_action 07:51:03 INFO - [mozharness: 2017-05-24 14:51:03.573309Z] Finished install step (success) 07:51:03 INFO - [mozharness: 2017-05-24 14:51:03.573449Z] Running run-tests step. 07:51:03 INFO - Running pre-action listener: _resource_record_pre_action 07:51:03 INFO - Running main action method: run_tests 07:51:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:51:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:51:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:51:03 INFO - 2017-05-24 07:51:03.586 screenresolution[1623:7377] starting screenresolution argv=screenresolution get 07:51:03 INFO - 2017-05-24 07:51:03.588 screenresolution[1623:7377] Display 0: 1600x1200x32@60 07:51:03 INFO - 2017-05-24 07:51:03.593 screenresolution[1624:7380] starting screenresolution argv=screenresolution list 07:51:03 INFO - Available Modes on Display 0 07:51:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:51:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:51:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:51:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:51:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:51:03 INFO - Intel Iris: 07:51:03 INFO - Chipset Model: Intel Iris 07:51:03 INFO - Type: GPU 07:51:03 INFO - Bus: Built-In 07:51:03 INFO - VRAM (Dynamic, Max): 1536 MB 07:51:03 INFO - Vendor: Intel (0x8086) 07:51:03 INFO - Device ID: 0x0a2e 07:51:03 INFO - Revision ID: 0x0009 07:51:03 INFO - Displays: 07:51:03 INFO - Display: 07:51:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:51:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:51:03 INFO - Display Serial Number: 1600x1200 60 07:51:03 INFO - Main Display: Yes 07:51:03 INFO - Mirror: Off 07:51:03 INFO - Online: Yes 07:51:03 INFO - Rotation: Supported 07:51:03 INFO - Return code: 0 07:51:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:51:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:51:03 INFO - Python 2.7.3 07:51:03 INFO - Return code: 0 07:51:03 INFO - grabbing minidump binary from tooltool 07:51:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:51:03 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 0x10d236d30>, '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 0x10d15ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d22bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:51:03 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 07:51:03 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 07:51:03 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 07:51:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:51:03 INFO - Return code: 0 07:51:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:51:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:51:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:51:03 INFO - ENV: RUST_BACKTRACE is now 1 07:51:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:51:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:51:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0101', '--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 07:51:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0101 --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 07:51:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DSY2mu0QQp/Render', 07:51:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:51:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:51:03 INFO - 'HOME': '/Users/cltbld', 07:51:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:51:03 INFO - 'LOGNAME': 'cltbld', 07:51:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:51:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:51:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:03 INFO - 'MOZ_NO_REMOTE': '1', 07:51:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:51:03 INFO - 'NO_EM_RESTART': '1', 07:51:03 INFO - 'PAGER': '/bin/cat', 07:51:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:51:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:03 INFO - 'PWD': '/builds/slave/test', 07:51:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:51:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:51:03 INFO - 'RUST_BACKTRACE': '1', 07:51:03 INFO - 'SHELL': '/bin/bash', 07:51:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tYo4Gyv8yj/Listeners', 07:51:03 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 07:51:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:51:03 INFO - 'USER': 'cltbld', 07:51:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:51:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:51:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:03 INFO - 'XPC_FLAGS': '0x0', 07:51:03 INFO - 'XPC_SERVICE_NAME': '0', 07:51:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:51:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0101', '--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 07:51:04 INFO - mozversion application_buildid: 20170524055525 07:51:04 INFO - mozversion application_changeset: 7166b44658df0723b16d43b7f884deb73c99f0ba 07:51:04 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:51:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:51:04 INFO - mozversion application_name: Firefox 07:51:04 INFO - mozversion application_remotingname: firefox-dev 07:51:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 07:51:04 INFO - mozversion application_vendor: Mozilla 07:51:04 INFO - mozversion application_version: 54.0 07:51:04 INFO - mozversion platform_buildid: 20170524055525 07:51:04 INFO - mozversion platform_changeset: 7166b44658df0723b16d43b7f884deb73c99f0ba 07:51:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 07:51:04 INFO - mozversion platform_version: 54.0 07:51:04 INFO - using testdate: 1495637464 07:51:04 INFO - actual date: 1495637464 07:51:04 INFO - starting webserver on 'localhost:49224' 07:51:04 INFO - SUITE-START | Running 2 tests 07:51:04 INFO - TEST-START | dromaeo_css 07:51:04 INFO - operating with platform_type : mac_ 07:51:04 INFO - Initialising browser for dromaeo_css test... 07:51:04 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/tmpfHnniZ/profile http://localhost:49224/getInfo.html) 07:51:07 INFO - PROCESS | 1634 | 2017-05-24 07:51:07.081 plugin-container[1637:7564] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b4f, name = 'com.apple.tsm.portname' 07:51:07 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:51:07 INFO - PROCESS | 1634 | 2017-05-24 07:51:07.082 plugin-container[1637:7564] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 07:51:07 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:51:07 INFO - PROCESS | 1634 | 2017-05-24 07:51:07.082 plugin-container[1637:7564] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:51:07 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 07:51:07 INFO - PROCESS | 1634 | colorDepth:24 07:51:07 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 07:51:07 INFO - PROCESS | 1634 | __metrics 07:51:07 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 07:51:07 INFO - TEST-INFO | 1634: exit 0 07:51:07 INFO - Browser initialized. 07:51:07 INFO - Running cycle 1/1 for dromaeo_css test... 07:51:07 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/tmpfHnniZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:51:10 INFO - PROCESS | 1638 | 2017-05-24 07:51:10.210 plugin-container[1639:7687] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 07:51:10 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:51:10 INFO - PROCESS | 1638 | 2017-05-24 07:51:10.211 plugin-container[1639:7687] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x891b, name = 'com.apple.CFPasteboardClient' 07:51:10 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 07:51:10 INFO - PROCESS | 1638 | 2017-05-24 07:51:10.211 plugin-container[1639:7687] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 07:51:19 INFO - PROCESS | 1638 | RSS: Main: 203296768 07:51:19 INFO - PROCESS | 1638 | 07:54:22 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49224/tests/dromaeo/cssquery-ext.html) 07:54:24 INFO - PROCESS | 1638 | RSS: Main: 216154112 07:54:24 INFO - PROCESS | 1638 | 07:57:26 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-ext.html (next: http://localhost:49224/tests/dromaeo/cssquery-jquery.html) 07:57:27 INFO - PROCESS | 1638 | RSS: Main: 227807232 07:57:27 INFO - PROCESS | 1638 | 08:00:28 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49224/tests/dromaeo/cssquery-mootools.html) 08:00:29 INFO - PROCESS | 1638 | RSS: Main: 232747008 08:00:29 INFO - PROCESS | 1638 | 08:03:31 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49224/tests/dromaeo/cssquery-prototype.html) 08:03:32 INFO - PROCESS | 1638 | RSS: Main: 236306432 08:03:32 INFO - PROCESS | 1638 | 08:06:34 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49224/tests/dromaeo/cssquery-yui.html) 08:06:35 INFO - PROCESS | 1638 | RSS: Main: 238497792 08:06:35 INFO - PROCESS | 1638 | 08:09:38 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-yui.html (next: http://localhost:49224/tests/dromaeo/cssquery-dojo.html) 08:09:38 INFO - PROCESS | 1638 | RSS: Main: 236670976 08:09:38 INFO - PROCESS | 1638 | 08:09:38 INFO - PROCESS | 1638 | __start_tp_report 08:09:38 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 08:09:38 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 08:09:38 INFO - PROCESS | 1638 | |i|pagename|runs| 08:09:38 INFO - PROCESS | 1638 | |0;dojo.html;2933.5013047781877;3508.6842184203433;3531.947020794687;3537.062678390226;3557.1818481749197;6033.487153591945;6042.516598672107;6074.726637301321;6090.8477288067825;6099.237595300582;6032;6058.575899687024;6078.118672792442;6088.939110608892;6100.176476175715;4430.623397011258;5477.397486276506;5480.095784195623;5490.6431081979545;5570.86072848178;5497.038018346793;5544.500994910457;5548.112302031675;5579.107342825148;5645.181448689958;4398.670099742516;5378.811741589046;5478.342598888119;5560.388357280696;5567.832965011036;4600.723956562617;5437.700926449043;5441.863953401177;5452.6455780374545;5488.835334939959;5449.3460784706085;5545.972270138664;5548.44515548446;5556.86107847303;5613.691246981414;6166.167567378391;6167.044108163242;6170.382961703838;6174.166487524217;6186;6233.719482623293;6332.33510481402;6343.207099112611;6442.48460123189;6458.773942912023;5202.687838729688;6434.131392262031;6567.802965911031;6582.9670851645915;6601.669916504204;6148.415900489446;6194.690265486753;6199.3180750117;6224.533160013018;6227.1282020517165;6359.8092057238355;6713.597184168965;6724.193096828412;6742.089817874622;6749.460043196533;6286.9056964145575;6295.559310848288;6306.684665766693;6327.778527751507;6345.302016778151;6454.128692626436;6468.353164683494;6485.935140648628;6500.3174666659315;6505.544611877123;6110.388961103854;6348.365163483615;6371.362863713684;6374.872502550016;6387.201599800025;6282.654454004982;6320.178376810985;6349.333320001426;6352.872942541216;6359.459445947054;4937.7777999990085;4964.131277026579;4966.751662416865;4976.894062398535;4991.825286115041;4415.161119387306;4416.1609294233995;4422.380866678667;4437.622802061796;4446.911061778746;5714.628549144292;5726.427357264323;5833.854153646108;5838.2702162229725;5966;6396.84007899797;6402.679866006681;6417.7753778617525;6421.133147025185;6493.188351456068;5785.537157027427;5963.910541341883;5968.731407086691;5983.431574000463;6020.729067191987;5551.861203469864;5635;5675.035244008528;5694.9430505695245;5702.486776190163;6578.144841170617;6600.471962242913;6730.663466826639;6768;6789.388954994074;5538.280023597068;5842.5325973921135;6434.581752186093;6491.642959637265;6492.513061520499;6123.938760612336;6758.898616520658;6776.525643204928;6791.898121528082;6808.421284190701;6304.085907543289;6326.2092238470195;6349.587276826992;6422.32565580626;6437.613743175424;6507.284198738041;6541.182352205974;6541.476681865344;6547.1161393211305;6554.705038273194;6015.639061656315;6343.429091381798;6345.619262844244;6349.523785716182;6352.333005034589;6206.875862482815;6283.151774510383;6309.526785491014;6355.078513615592;6375.298717141018;5775.162601422854;6231.470325022243;6273.466755325666;6340.080688300263;6353.110564521063;6345.650989195638;6362.9681851592295;6372.522060845363;6374.58565193261;6381.425671689571;5019.548240658358;6144.846378840565;6280.623162610258;6333.96833015832;6341.4292713656 08:09:38 INFO - PROCESS | 1638 | |1;ext.html;787.3779714025919;824.5671022713075;840.1766269056322;976.9706908792739;978.2711879649657;30490.39019219617;39400.80299598502;41256.58743412565;41350.17299654005;41417.171656566854;39562;39670.404943925794;39703.20593588126;39792;39796;7963.880541791863;8120.106788253287;10928.071113955324;11187.105031597453;11214.719632009224;10794.244402891762;10931.125509959184;10937.507812148473;10940.45297735114;10949.233553651247;10938.835917461245;10942.617008404708;10953.95937385947;10954.561817527288;10977.231593788438;10924.907509249051;10927.508262128144;10941.452927353641;10957.890421095766;10967.670969870916;10728.409937453436;10803.297785643908;10809.405482698448;10815.78368432627;10832.945835270773;34150.658493414994;34209.6579034209;34373.31253374919;34383.96848094561;34394;3782.56500502443;3794.31702293587;3809.6380843819793;3811.9809400953095;3821.885343439701;25063;25427.157604545682;25659.74340256611;26541.46917061648;26967.595486067723;3055.8252908186487;3112.937741245162;3155.637101733307;3164.098232003868;3209.679032096795;266448;272004;360072.1996390001;370502.4424633633;374323;41106.17787644231;41174.38238426426;41308.79345603253;41352.79323603363;41382;43803.12393752107;43904;43963.68108956736;44045;44066;13426.932865335611;13487.123336983064;13513.391897364641;13514.256715880721;13514.32428378577;9924.858641256204;10839.241253112365;11698.88301116995;11756.35340056288;11760.88239117615;11616.709582260512;11617.825732614017;11655.475503602358;11701.590444334408;11702.239354441934;4911.8280860169725;5579.967705974326;5672.901026620095;5709.68596727184;5766.711664416763;1145.9656210313703;1197.3534291482501;1198.8021976373975;1203.7171264752776;1240.925544467335;24302;25988.350291242492;26318.342041449116;26347.868260658575;26361;1296.118639325249;1301.4729034740944;1305.8171855940177;1328.3955800110925;1350.1628990026247;13560.932195339157;13696.972727045511;13706.725865482635;13719;13754.31228438594;370246;483313;497229;592976.0351198303;594449;47837.76081119572;47933.80165495891;48389.758051209516;48494.757526212146;48542;1416.2493878244547;1438.5252866554222;1443.762168638627;1446.1467734036758;1449.463698431587;26629;26795.86602066977;26969.325766855985;27295;27460.626968651886;33072.50391244181;39167.41248881212;39272;39308.80345598254;39529.61646342364;463701;465845;466680;467040;467538;24028.87985560131;25168.49663006766;28118.437631246852;29131.563026555043;29238.853805731687;32763.672363276062;36065.81967090148;36700.89897303085;37347.69283075079;37554.24891502209;26353.868230658725;26665.86667066652;26770.866145669926;26805.463890722465;27353.726462735118;26449.074282400023;26522;26958.730412696408;27335.043273486128;27669.44661106807;26149.477010460065;26423.867880660473;30402.93589724349;31517.842410787798;31661.733530658516;26164.73835261699;27010.72989270161;27273;27625.585616216176;28410;18364.357247496275;22126.889365553714;22619.660705089766;22759;22881.77118228863 08:09:38 INFO - PROCESS | 1638 | |2;jquery.html;385.66061865558305;419.3353534647583;419.3667561981411;428.7470392468443;455.3261173463274;19756.506087347825;20847;22234.444138896535;22237.110515579356;22250.88874555627;21465.67801482979;22222.66666000011;22379.44051398716;22465.539739916894;22530.324090277278;6817.761378351771;6948.548344357626;6966.860662786741;7016.859662806741;7052.435805135602;6540.771073012445;6923;6989.650517474106;7002.859942801167;7032.472564557653;6738.797836064926;6904.4821638377325;6960.686769095403;6976.127984002;6996.7201311947465;6673.833154171136;6858.794236172923;6863.897041544381;6904.9654751726175;6912.758053468155;6018.578699491038;6758.594484330956;6791.898121528132;6796.524243302971;6806.897896531556;27414;27486.312842178904;27812.582811257846;28137.718622813714;28230.15309540717;2483.962740558893;2976.2113040044405;2987.5518672199128;3013.2015016020773;3023.5011223148153;16324.75512867308;17100.144992750313;17167.828321716748;17232.310707571683;17841.821581784276;1939.2049259803555;2032.78655741148;2067.7311949446475;2108.768035516092;2108.9050992705365;97145;103707;117155.41422292919;119305;121445.99999999911;23532.529349413257;23651.526969460516;23678.28965131049;23897.371289158178;23919.043238270624;37064.07339816472;37321.81339093287;37952.43071353932;38038;38060;10385.065344119066;11025;11243.550257989753;11314.377709226072;11367.147463940073;7617.200193979663;9094.226990705863;9124.133207345292;9152.405093668891;9313.022132675971;9190.448573085636;9252.722418327461;10839.916008399097;11175.72060698489;11283.492242849092;3881.728279020498;4495.775211239425;4611.354410382549;4645.326427668037;4647.233206520887;1059.4702648675661;1081.405227125078;1082.6643740440331;1125.808612535871;1130.5929865248504;21507.67738483924;22773.886130569572;22787.860606969916;22791.658125128135;22855.542889142125;939.3753154152412;939.7180845746249;1057.7249915021996;1104.304288298383;1111.9332840029624;11132.888671113185;11761.235519691192;11890.346030968207;11999.160058795975;12000.519979200908;767508;770686;780476;781942;788635;122029;122129.38935305267;124427.51906603882;124725;127362.36318818346;1327.188387850577;1327.8141060251573;1396.4553824008644;1401.5094716849226;1407.549584133088;20401.89799050995;20461.386158415273;21021.843798591224;21193.576128477653;21283.46791330229;54321.91356172776;57155.71422142863;60735.39264607297;63046.68476657771;65686.67156664377;92441.53779231061;95231.52384238034;95347;95412.56881146927;96219.51890240503;11446.771064578828;11626.302421854716;11675.591354302558;11717.472713728073;12105.636830895088;12468.937655311665;12884;13013.609591712264;13282.53511127106;13334.466621335037;5915.142304365929;11215.88784112181;11453.885461145283;11455.427228638702;11712.238704484182;10385.84421233667;10431.739206519595;10507.369557826554;10729.302595331279;10743;11335.659930202108;11550.191486595857;11640.708982275512;11756.882431175922;11757.059435244988;10546.472676366304;11407.486663100015;11582;11614.419279036183;11890.929816316573;11205.60780372683;11246.66260012201;11489.54041838317;11490.023348015513;11587.292066538626 08:09:38 INFO - PROCESS | 1638 | |3;mootools.html;1564.522820539735;1615.8626516746076;1618.554897403214;1621.9270132844028;1631.0784406810155;10364;10870.239083264174;10887.618933337335;11170.273932194401;11496.482658280376;11248.437578121102;11425.42872856356;11439.828402573947;11452.599159029436;11478;2039.6430624640702;2291.564602725481;2343.0510643189536;2343.1330407749156;2347.6243800991847;2008.9955022488755;2134.285014520138;2347.2606129069372;2353.9646905296436;2369.206315884181;1954.3159894037044;2289.347535952254;2335.995521925571;2342.02805835578;2359.268626725712;2250.414892128042;2313.363824948136;2314.1784666443423;2319.5476882008015;2334.6498025296255;2329.7204335479682;2367.0650093213258;2375.489269807;2375.7743014413604;2380.6071998120287;11479.655610331703;11511.30932144074;11546.769064618744;11547.307161570332;11577.01595364395;5168.043911876315;5470.425605311425;5522.779088836442;5699.344575373843;5743.081107022898;3169.3344397676515;3229.677032296775;3241.740660747158;3436.74224433166;3461.584609846809;1105.2815669814686;1107.4628805029554;1132.9943350283195;1141.8058929981923;1145.1297014269132;13895.79156312656;14197.36111874968;15094.24528773557;15645;15724.842751572452;4351.717138385995;4439.445069366329;4550.613197878151;4550.795214215302;4592.6555508337005;11956.581519646945;11961.40192990347;11961.46173422198;11976.88023119775;11996.880031199576;5561.805336813192;5572.832815015556;5577.525910297589;5600.215969764236;5614.382417934024;5032.6980381177245;5050.242463630485;5081.212412076134;5110.8466745997675;5128.487151284916;3628.854845806191;3711.9072023199637;3731.533558305212;3757.492738480325;3812.218495208476;934.1592566690033;939.4175611121012;940.948247846375;956.516958935733;957.3585697582637;959.2326139088701;960.3757557587485;964.9421034737938;972.615816752393;1000.1848493477793;6721.4958878084335;6731.46148308144;6756.358145976124;6941.548799328028;6957.721691132298;972.8783902012249;995.3679413572434;1004.4324956399611;1014.2291858187683;1026.6919924022764;4027.496562929634;4244.3209086546;4249.1501699659575;4250.659947204216;4298.355246712956;13233;13918.30408479572;13952.441902323997;13953.581392558239;13970.580882573538;13849.376778045013;13849.723005539832;13859.445622175203;13868.306584670727;13872.890168786473;8240.340772738167;8714.954205495507;9958.30291879576;10320.225983051301;10408.895911040943;6430.903536447051;6866.695627424967;6951.860962780817;7061;7140.285971402818;8953.686620968236;11801.586944456903;12051.638450846489;12264.141510094209;12290.75418491643;11980;12030.338331391857;12144;12234.327112008925;12354.196977196454;10197.694069177936;10460.424676642857;10488.31825931312;10557.049865512141;10564.683059508227;10410.1671866249;10422.478876056319;10545.57817687284;10672.146228301872;10673.039426451656;8774.122587741174;8831.867521987304;8834.895638045244;8875.600597973236;8974.775630609287;8580.65677372898;8609.397342185986;8850.955745221232;8919.73240802777;8977.147171018743;7813.609319534115;7936.563489007929;8041.718539851077;8060.952076230053;8080.919190808252;6031.155638210566;6098.512119030555;6099.146119543178;6113.694315284307;6296.6851657417865;5250.422453530032;6047.304559975659;6077.848053798691;6091.543134264859;6333.145025421693 08:09:38 INFO - PROCESS | 1638 | |4;prototype.html;379.17529373612405;395.286507853325;399.12193175014966;399.3510545363784;404.7814180342615;4526.773661316937;4695.83991363973;5242.868928276795;5285.841424757254;5435.429279925605;4896.583790377813;4951.455339912607;5029.290041385928;5223.791048358071;5343.118385466403;4131.173765246947;4647.117047760931;4656.208444564424;4687.7656117194;4709.858704238878;3876.4960555127795;3979.880603581897;4154.688398370119;4683.484816670163;4695.8356457524;4327.545607711192;4367.890802729926;4375.7155784873885;4401.22978478765;4437.069874604541;3941.994791328193;4035.8901302142;4410.184115938529;4424.429327588692;4494.831343850622;3803.5055442792536;3837.4435706822355;3978.741381810202;4115.8887100483;4123.546409894909;4258.233517966764;4524.981879077214;4561.973555949918;4620.722756634613;4907.576802727205;5015.39815222172;5094.796208151706;5098.745062746848;5131.512506311894;5141.228815677641;4472.1502914446155;4904.008554781581;5168.638195326329;5222.668219604015;5718.256626638552;3828.712846536521;4299.484061912559;4346.782660866944;4474.753888536129;4497.707649002804;5092.567131793765;5732.5127364174095;5829.883402331972;6009.669468179249;6070.841473590604;4353.498043175099;4371.868843934687;4546.272596384562;4606.907861842745;4614.907701846011;4072.022714548487;4216.9789151054665;4331.523532411432;4705.976470117627;4721.763911804396;4458.509563947963;4477.7984990675495;4550.795214215368;4866.172750632401;5087.219473184356;5201.505856943584;5382.703951282639;5604.719764011783;5648.237487939158;5681.545476361881;1417.9291035448186;1535.7807967487097;1547.8545876051867;1594.3976303814593;1596.8243493215737;1003.0922015575916;1025.0006243912126;1086.0225796782959;1113.9695781402334;1210.8062709966364;4536.88657783558;4581.404417425713;5076.213186956028;5084.77118529667;5094;5480.232767412565;5552.305961754781;5758.3665796762325;5856.707164641751;5905.852353691193;4419.519460980543;4671.602913752369;5006.974965125151;5029.321041659329;5049.899002020013;2673.4385778986243;2844.4444444444443;2963.740410325603;3008.3381656035635;3044.512877939563;5151.913475042614;5159.25190847325;5623;6058.545609079337;6060.090986352083;5065.56942659878;5187.584993200535;5445.767235433454;5504.53211477029;5860.652050028432;5428.6199966002005;5881.9705901470215;5907.49786268172;6066.090086487062;6139.903879779772;4772.828363094853;5155.453363990863;5197.948020519746;5436.266104075899;5799.4780469757925;5044.4451110377095;5163.66436181647;5558.833235002957;5768.740406681625;5802.6808525531505;5147.56245719118;5531.419200984;5611.446791709844;5836.972488982588;6018.789342372996;4030.173814368106;4428.9149158456385;4487.730736155841;4580.65645076614;4615.100055489147;5270.552003079782;5842.532597392284;5916.319623243382;5937.554683398849;5995.46040856325;4977.303177555217;4986.80052797884;5140.820071297487;5688.089905615082;5738.340090889435;5380.246765452762;5437.4018857926385;5617.522510586482;5633.352164501135;5697.7151142443545;4763.570579004663;5034.974825125851;5301.985245606645;5366.490183432567;5567.24842146305;5459.426760190123;5725.914111288417;5774.566907481872;5788.408187748403;5800.535957123336;4827.589654879374;4860.756962151808;5241.528262456397;5512.6692398456225;5628.831135065954 08:09:38 INFO - PROCESS | 1638 | |5;yui.html;742.2614498573917;762.9465937384385;777.9066512018555;844.4300097434232;863.7236084452974;14413.135211887295;14753.557393278192;14994.925025374885;15169.620759481018;15222.08667479952;13791.10357826743;14014.019018668698;14060.85939140611;14202.076865003794;14225.075370100962;967.7725734452398;990.8166989107038;990.8910019897805;991.008991008991;991.5438898106879;947.9526023698822;964.2912459342377;965.1362030982302;976.2677991506371;980.9509524523745;1016.3038318751679;1021.7037059252808;1024.8309029010204;1026.9897301027006;1027.0807627173674;876.7238319929202;1008.2841182760249;1014.2697257974247;1014.8477728340735;1016.7915577306671;686.9381755642016;758.287210022577;765.8698021336386;768.3584207187006;769.2691942654501;15110.093394396372;15346.309416076192;15349.846501534954;15461.304241309168;15814;11644.825327620008;11985.880141198564;12963.52304786082;13077.803832942513;13480.325983700874;11201.775964480747;11370.658880233523;11481;11527;11567.363794991272;3582.3551760683067;3644.5444319460066;3668.321360548307;3706.092007458163;3838.0404898775305;86814;106393.46803266011;110428;110879.89120108777;113786;10342.276040677081;10552.841707374395;10713.464326783629;10935;11485.655430337103;21316.680249796267;21480.355589332346;21675.566488670454;21833.235836745636;22209.666854997187;7459.627018649046;7520.21037791035;7560.206178351304;7763.4953728007495;7788.649510772028;3849.7690138591774;4159.168166366739;4186.769727665008;4317.050248945227;4325.653947684178;3217.758668099902;3288.786228895114;3304.9919774469017;3305.405027095098;3634.7637403568683;629.6285191736897;697.8988046733195;705.1855107350915;705.7141857547701;712.8431745016088;676.2628734679223;683.8256244657581;705.5625512182377;708.8015355700412;737.2995654128599;8762.693305734269;10134.23993200513;10194.490275486196;10257.948710256402;10310;687.556526040711;700.3766647683566;716.3023633987483;721.1742554774753;721.9027078840238;4586.610138138296;4860.538248866352;4866.29438731389;4866.6106711463;4875.609951203895;81959.59020204861;84818.57590712131;97338.51330743301;98676;100564;87287.56356218178;89451.55274223587;94407.11185776246;95360.04639953512;99557;59445;68142;70935.64532177306;76513;76617;10480.895191047992;10661.520894726244;11008.064314533354;11016.339019658846;11070.501827417947;44214.778926105166;47618.52381476141;49485.50514494809;50164;50461;63300.366996329445;66224;67265.66367168132;68004.63990720257;68069.97895031479;9935.453550054817;10300.690979270334;11785.88214117848;11962.923336899721;12102.878971210175;11726.824097638371;12062.879371206527;12071.637850864488;12170.634880953585;12553;12694.746105078031;12823.781740734616;13128.803067953797;13537.052406331462;13602.93198534001;12610.936945315214;12736.363181841056;12966.481340746264;13130.803037954629;13582.388792504164;11358.659240222807;12806.551770687982;12910.67723306925;13287;13479.528216512375;8887.688930887389;9091.272698184262;9190.21883139914;9198.586063627019;9282.666812698813;9160.404573702688;10205.591776328863;10825.512851921525;10838.837417438903;10930.125589952626 08:09:38 INFO - PROCESS | 1638 | __end_tp_report 08:09:38 INFO - PROCESS | 1638 | __start_cc_report 08:09:38 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,384 08:09:38 INFO - PROCESS | 1638 | __end_cc_report 08:09:38 INFO - PROCESS | 1638 | __startTimestamp1495638578537__endTimestamp 08:09:38 INFO - PROCESS | 1638 | 08:09:38 INFO - PROCESS | 1638 | ------- Summary: start ------- 08:09:38 INFO - PROCESS | 1638 | Number of tests: 6 08:09:38 INFO - PROCESS | 1638 | 08:09:38 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5952.87 Median:6206.88 stddev:717.40 (11.6%) stddev-sans-first:679.37 08:09:38 INFO - PROCESS | 1638 | Values: 2933.5 3508.7 3531.9 3537.1 3557.2 6033.5 6042.5 6074.7 6090.8 6099.2 6032.0 6058.6 6078.1 6088.9 6100.2 4430.6 5477.4 5480.1 5490.6 5570.9 5497.0 5544.5 5548.1 5579.1 5645.2 4398.7 5378.8 5478.3 5560.4 5567.8 4600.7 5437.7 5441.9 5452.6 5488.8 5449.3 5546.0 5548.4 5556.9 5613.7 6166.2 6167.0 6170.4 6174.2 6186.0 6233.7 6332.3 6343.2 6442.5 6458.8 5202.7 6434.1 6567.8 6583.0 6601.7 6148.4 6194.7 6199.3 6224.5 6227.1 6359.8 6713.6 6724.2 6742.1 6749.5 6286.9 6295.6 6306.7 6327.8 6345.3 6454.1 6468.4 6485.9 6500.3 6505.5 6110.4 6348.4 6371.4 6374.9 6387.2 6282.7 6320.2 6349.3 6352.9 6359.5 4937.8 4964.1 4966.8 4976.9 4991.8 4415.2 4416.2 4422.4 4437.6 4446.9 5714.6 5726.4 5833.9 5838.3 5966.0 6396.8 6402.7 6417.8 6421.1 6493.2 5785.5 5963.9 5968.7 5983.4 6020.7 5551.9 5635.0 5675.0 5694.9 5702.5 6578.1 6600.5 6730.7 6768.0 6789.4 5538.3 5842.5 6434.6 6491.6 6492.5 6123.9 6758.9 6776.5 6791.9 6808.4 6304.1 6326.2 6349.6 6422.3 6437.6 6507.3 6541.2 6541.5 6547.1 6554.7 6015.6 6343.4 6345.6 6349.5 6352.3 6206.9 6283.2 6309.5 6355.1 6375.3 5775.2 6231.5 6273.5 6340.1 6353.1 6345.7 6363.0 6372.5 6374.6 6381.4 5019.5 6144.8 6280.6 6334.0 6341.4 08:09:38 INFO - PROCESS | 1638 | 08:09:38 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:55047.96 Median:27284.00 stddev:118629.33 (434.8%) stddev-sans-first:118892.16 08:09:38 INFO - PROCESS | 1638 | Values: 787.4 824.6 840.2 977.0 978.3 30490.4 39400.8 41256.6 41350.2 41417.2 39562.0 39670.4 39703.2 39792.0 39796.0 7963.9 8120.1 10928.1 11187.1 11214.7 10794.2 10931.1 10937.5 10940.5 10949.2 10938.8 10942.6 10954.0 10954.6 10977.2 10924.9 10927.5 10941.5 10957.9 10967.7 10728.4 10803.3 10809.4 10815.8 10832.9 34150.7 34209.7 34373.3 34384.0 34394.0 3782.6 3794.3 3809.6 3812.0 3821.9 25063.0 25427.2 25659.7 26541.5 26967.6 3055.8 3112.9 3155.6 3164.1 3209.7 266448.0 272004.0 360072.2 370502.4 374323.0 41106.2 41174.4 41308.8 41352.8 41382.0 43803.1 43904.0 43963.7 44045.0 44066.0 13426.9 13487.1 13513.4 13514.3 13514.3 9924.9 10839.2 11698.9 11756.4 11760.9 11616.7 11617.8 11655.5 11701.6 11702.2 4911.8 5580.0 5672.9 5709.7 5766.7 1146.0 1197.4 1198.8 1203.7 1240.9 24302.0 25988.4 26318.3 26347.9 26361.0 1296.1 1301.5 1305.8 1328.4 1350.2 13560.9 13697.0 13706.7 13719.0 13754.3 370246.0 483313.0 497229.0 592976.0 594449.0 47837.8 47933.8 48389.8 48494.8 48542.0 1416.2 1438.5 1443.8 1446.1 1449.5 26629.0 26795.9 26969.3 27295.0 27460.6 33072.5 39167.4 39272.0 39308.8 39529.6 463701.0 465845.0 466680.0 467040.0 467538.0 24028.9 25168.5 28118.4 29131.6 29238.9 32763.7 36065.8 36700.9 37347.7 37554.2 26353.9 26665.9 26770.9 26805.5 27353.7 26449.1 26522.0 26958.7 27335.0 27669.4 26149.5 26423.9 30402.9 31517.8 31661.7 26164.7 27010.7 27273.0 27625.6 28410.0 18364.4 22126.9 22619.7 22759.0 22881.8 08:09:38 INFO - PROCESS | 1638 | 08:09:38 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:42554.76 Median:22244.00 stddev:128066.68 (575.7%) stddev-sans-first:128386.80 08:09:38 INFO - PROCESS | 1638 | Values: 385.7 419.3 419.4 428.7 455.3 19756.5 20847.0 22234.4 22237.1 22250.9 21465.7 22222.7 22379.4 22465.5 22530.3 6817.8 6948.5 6966.9 7016.9 7052.4 6540.8 6923.0 6989.7 7002.9 7032.5 6738.8 6904.5 6960.7 6976.1 6996.7 6673.8 6858.8 6863.9 6905.0 6912.8 6018.6 6758.6 6791.9 6796.5 6806.9 27414.0 27486.3 27812.6 28137.7 28230.2 2484.0 2976.2 2987.6 3013.2 3023.5 16324.8 17100.1 17167.8 17232.3 17841.8 1939.2 2032.8 2067.7 2108.8 2108.9 97145.0 103707.0 117155.4 119305.0 121446.0 23532.5 23651.5 23678.3 23897.4 23919.0 37064.1 37321.8 37952.4 38038.0 38060.0 10385.1 11025.0 11243.6 11314.4 11367.1 7617.2 9094.2 9124.1 9152.4 9313.0 9190.4 9252.7 10839.9 11175.7 11283.5 3881.7 4495.8 4611.4 4645.3 4647.2 1059.5 1081.4 1082.7 1125.8 1130.6 21507.7 22773.9 22787.9 22791.7 22855.5 939.4 939.7 1057.7 1104.3 1111.9 11132.9 11761.2 11890.3 11999.2 12000.5 767508.0 770686.0 780476.0 781942.0 788635.0 122029.0 122129.4 124427.5 124725.0 127362.4 1327.2 1327.8 1396.5 1401.5 1407.5 20401.9 20461.4 21021.8 21193.6 21283.5 54321.9 57155.7 60735.4 63046.7 65686.7 92441.5 95231.5 95347.0 95412.6 96219.5 11446.8 11626.3 11675.6 11717.5 12105.6 12468.9 12884.0 13013.6 13282.5 13334.5 5915.1 11215.9 11453.9 11455.4 11712.2 10385.8 10431.7 10507.4 10729.3 10743.0 11335.7 11550.2 11640.7 11756.9 11757.1 10546.5 11407.5 11582.0 11614.4 11890.9 11205.6 11246.7 11489.5 11490.0 11587.3 08:09:38 INFO - PROCESS | 1638 | 08:09:38 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:6751.71 Median:2368.14 stddev:4275.32 (180.5%) stddev-sans-first:4269.54 08:09:38 INFO - PROCESS | 1638 | Values: 1564.5 1615.9 1618.6 1621.9 1631.1 10364.0 10870.2 10887.6 11170.3 11496.5 11248.4 11425.4 11439.8 11452.6 11478.0 2039.6 2291.6 2343.1 2343.1 2347.6 2009.0 2134.3 2347.3 2354.0 2369.2 1954.3 2289.3 2336.0 2342.0 2359.3 2250.4 2313.4 2314.2 2319.5 2334.6 2329.7 2367.1 2375.5 2375.8 2380.6 11479.7 11511.3 11546.8 11547.3 11577.0 5168.0 5470.4 5522.8 5699.3 5743.1 3169.3 3229.7 3241.7 3436.7 3461.6 1105.3 1107.5 1133.0 1141.8 1145.1 13895.8 14197.4 15094.2 15645.0 15724.8 4351.7 4439.4 4550.6 4550.8 4592.7 11956.6 11961.4 11961.5 11976.9 11996.9 5561.8 5572.8 5577.5 5600.2 5614.4 5032.7 5050.2 5081.2 5110.8 5128.5 3628.9 3711.9 3731.5 3757.5 3812.2 934.2 939.4 940.9 956.5 957.4 959.2 960.4 964.9 972.6 1000.2 6721.5 6731.5 6756.4 6941.5 6957.7 972.9 995.4 1004.4 1014.2 1026.7 4027.5 4244.3 4249.2 4250.7 4298.4 13233.0 13918.3 13952.4 13953.6 13970.6 13849.4 13849.7 13859.4 13868.3 13872.9 8240.3 8715.0 9958.3 10320.2 10408.9 6430.9 6866.7 6951.9 7061.0 7140.3 8953.7 11801.6 12051.6 12264.1 12290.8 11980.0 12030.3 12144.0 12234.3 12354.2 10197.7 10460.4 10488.3 10557.0 10564.7 10410.2 10422.5 10545.6 10672.1 10673.0 8774.1 8831.9 8834.9 8875.6 8974.8 8580.7 8609.4 8851.0 8919.7 8977.1 7813.6 7936.6 8041.7 8061.0 8080.9 6031.2 6098.5 6099.1 6113.7 6296.7 5250.4 6047.3 6077.8 6091.5 6333.1 08:09:38 INFO - PROCESS | 1638 | 08:09:38 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4648.04 Median:5011.19 stddev:1294.92 (25.8%) stddev-sans-first:1258.29 08:09:38 INFO - PROCESS | 1638 | Values: 379.2 395.3 399.1 399.4 404.8 4526.8 4695.8 5242.9 5285.8 5435.4 4896.6 4951.5 5029.3 5223.8 5343.1 4131.2 4647.1 4656.2 4687.8 4709.9 3876.5 3979.9 4154.7 4683.5 4695.8 4327.5 4367.9 4375.7 4401.2 4437.1 3942.0 4035.9 4410.2 4424.4 4494.8 3803.5 3837.4 3978.7 4115.9 4123.5 4258.2 4525.0 4562.0 4620.7 4907.6 5015.4 5094.8 5098.7 5131.5 5141.2 4472.2 4904.0 5168.6 5222.7 5718.3 3828.7 4299.5 4346.8 4474.8 4497.7 5092.6 5732.5 5829.9 6009.7 6070.8 4353.5 4371.9 4546.3 4606.9 4614.9 4072.0 4217.0 4331.5 4706.0 4721.8 4458.5 4477.8 4550.8 4866.2 5087.2 5201.5 5382.7 5604.7 5648.2 5681.5 1417.9 1535.8 1547.9 1594.4 1596.8 1003.1 1025.0 1086.0 1114.0 1210.8 4536.9 4581.4 5076.2 5084.8 5094.0 5480.2 5552.3 5758.4 5856.7 5905.9 4419.5 4671.6 5007.0 5029.3 5049.9 2673.4 2844.4 2963.7 3008.3 3044.5 5151.9 5159.3 5623.0 6058.5 6060.1 5065.6 5187.6 5445.8 5504.5 5860.7 5428.6 5882.0 5907.5 6066.1 6139.9 4772.8 5155.5 5197.9 5436.3 5799.5 5044.4 5163.7 5558.8 5768.7 5802.7 5147.6 5531.4 5611.4 5837.0 6018.8 4030.2 4428.9 4487.7 4580.7 4615.1 5270.6 5842.5 5916.3 5937.6 5995.5 4977.3 4986.8 5140.8 5688.1 5738.3 5380.2 5437.4 5617.5 5633.4 5697.7 4763.6 5035.0 5302.0 5366.5 5567.2 5459.4 5725.9 5774.6 5788.4 5800.5 4827.6 4860.8 5241.5 5512.7 5628.8 08:09:38 INFO - PROCESS | 1638 | 08:09:38 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:19766.86 Median:3608.56 stddev:28545.03 (791.0%) stddev-sans-first:28589.36 08:09:38 INFO - PROCESS | 1638 | Values: 742.3 762.9 777.9 844.4 863.7 14413.1 14753.6 14994.9 15169.6 15222.1 13791.1 14014.0 14060.9 14202.1 14225.1 967.8 990.8 990.9 991.0 991.5 948.0 964.3 965.1 976.3 981.0 1016.3 1021.7 1024.8 1027.0 1027.1 876.7 1008.3 1014.3 1014.8 1016.8 686.9 758.3 765.9 768.4 769.3 15110.1 15346.3 15349.8 15461.3 15814.0 11644.8 11985.9 12963.5 13077.8 13480.3 11201.8 11370.7 11481.0 11527.0 11567.4 3582.4 3644.5 3668.3 3706.1 3838.0 86814.0 106393.5 110428.0 110879.9 113786.0 10342.3 10552.8 10713.5 10935.0 11485.7 21316.7 21480.4 21675.6 21833.2 22209.7 7459.6 7520.2 7560.2 7763.5 7788.6 3849.8 4159.2 4186.8 4317.1 4325.7 3217.8 3288.8 3305.0 3305.4 3634.8 629.6 697.9 705.2 705.7 712.8 676.3 683.8 705.6 708.8 737.3 8762.7 10134.2 10194.5 10257.9 10310.0 687.6 700.4 716.3 721.2 721.9 4586.6 4860.5 4866.3 4866.6 4875.6 81959.6 84818.6 97338.5 98676.0 100564.0 87287.6 89451.6 94407.1 95360.0 99557.0 59445.0 68142.0 70935.6 76513.0 76617.0 10480.9 10661.5 11008.1 11016.3 11070.5 44214.8 47618.5 49485.5 50164.0 50461.0 63300.4 66224.0 67265.7 68004.6 68070.0 9935.5 10300.7 11785.9 11962.9 12102.9 11726.8 12062.9 12071.6 12170.6 12553.0 12694.7 12823.8 13128.8 13537.1 13602.9 12610.9 12736.4 12966.5 13130.8 13582.4 11358.7 12806.6 12910.7 13287.0 13479.5 8887.7 9091.3 9190.2 9198.6 9282.7 9160.4 10205.6 10825.5 10838.8 10930.1 08:09:38 INFO - PROCESS | 1638 | -------- Summary: end -------- 08:09:38 INFO - PROCESS | 1638 | 08:09:38 INFO - TEST-INFO | 1638: exit 0 08:09:38 INFO - TEST-OK | dromaeo_css | took 1114798ms 08:09:38 INFO - TEST-START | kraken 08:09:38 INFO - operating with platform_type : mac_ 08:09:38 INFO - Initialising browser for kraken test... 08:09:38 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/tmp1i1V8d/profile http://localhost:49224/getInfo.html) 08:09:41 INFO - PROCESS | 1649 | 2017-05-24 08:09:41.653 plugin-container[1651:10097] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853f, name = 'com.apple.tsm.portname' 08:09:41 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:09:41 INFO - PROCESS | 1649 | 2017-05-24 08:09:41.654 plugin-container[1651:10097] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4543, name = 'com.apple.CFPasteboardClient' 08:09:41 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:09:41 INFO - PROCESS | 1649 | 2017-05-24 08:09:41.654 plugin-container[1651:10097] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:09:41 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 08:09:41 INFO - PROCESS | 1649 | colorDepth:24 08:09:41 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 08:09:41 INFO - PROCESS | 1649 | __metrics 08:09:41 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 08:09:42 INFO - TEST-INFO | 1649: exit 0 08:09:42 INFO - Browser initialized. 08:09:42 INFO - Running cycle 1/1 for kraken test... 08:09:42 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/tmp1i1V8d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:09:44 INFO - PROCESS | 1652 | 2017-05-24 08:09:44.971 plugin-container[1653:10218] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 08:09:44 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:09:44 INFO - PROCESS | 1652 | 2017-05-24 08:09:44.972 plugin-container[1653:10218] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c13, name = 'com.apple.CFPasteboardClient' 08:09:44 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:09:44 INFO - PROCESS | 1652 | 2017-05-24 08:09:44.972 plugin-container[1653:10218] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:09:54 INFO - PROCESS | 1652 | RSS: Main: 202993664 08:09:54 INFO - PROCESS | 1652 | 08:09:57 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection) 08:09:58 INFO - PROCESS | 1652 | RSS: Main: 216416256 08:09:58 INFO - PROCESS | 1652 | 08:10:01 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-dft) 08:10:01 INFO - PROCESS | 1652 | RSS: Main: 224346112 08:10:01 INFO - PROCESS | 1652 | 08:10:05 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-fft) 08:10:05 INFO - PROCESS | 1652 | RSS: Main: 228077568 08:10:05 INFO - PROCESS | 1652 | 08:10:07 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator) 08:10:08 INFO - PROCESS | 1652 | RSS: Main: 228458496 08:10:08 INFO - PROCESS | 1652 | 08:10:10 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:10:10 INFO - PROCESS | 1652 | RSS: Main: 228511744 08:10:10 INFO - PROCESS | 1652 | 08:10:13 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom) 08:10:14 INFO - PROCESS | 1652 | RSS: Main: 234024960 08:10:14 INFO - PROCESS | 1652 | 08:10:17 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate) 08:10:18 INFO - PROCESS | 1652 | RSS: Main: 233394176 08:10:18 INFO - PROCESS | 1652 | 08:10:21 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial) 08:10:22 INFO - PROCESS | 1652 | RSS: Main: 233738240 08:10:22 INFO - PROCESS | 1652 | 08:10:23 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:10:24 INFO - PROCESS | 1652 | RSS: Main: 232734720 08:10:24 INFO - PROCESS | 1652 | 08:10:26 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:10:27 INFO - PROCESS | 1652 | RSS: Main: 232767488 08:10:27 INFO - PROCESS | 1652 | 08:10:29 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:10:29 INFO - PROCESS | 1652 | RSS: Main: 232804352 08:10:29 INFO - PROCESS | 1652 | 08:10:32 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:10:33 INFO - PROCESS | 1652 | RSS: Main: 233136128 08:10:33 INFO - PROCESS | 1652 | 08:10:36 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:10:37 INFO - PROCESS | 1652 | RSS: Main: 233250816 08:10:37 INFO - PROCESS | 1652 | 08:10:38 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49224/tests/kraken/driver.html?testName=ai-astar) 08:10:39 INFO - PROCESS | 1652 | RSS: Main: 235429888 08:10:39 INFO - PROCESS | 1652 | 08:10:39 INFO - PROCESS | 1652 | __start_tp_report 08:10:39 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 08:10:39 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 08:10:39 INFO - PROCESS | 1652 | |i|pagename|runs| 08:10:39 INFO - PROCESS | 1652 | |0;ai-astar;118;116;120;118;119;118;119;118;119;135 08:10:39 INFO - PROCESS | 1652 | |1;audio-beat-detection;142;135;141;145;131;138;142;117;143;141 08:10:39 INFO - PROCESS | 1652 | |2;audio-dft;196;200;199;225;276;209;197;198;198;195 08:10:39 INFO - PROCESS | 1652 | |3;audio-fft;83;60;61;89;64;75;49;78;69;68 08:10:39 INFO - PROCESS | 1652 | |4;audio-oscillator;82;68;84;73;72;70;81;70;71;81 08:10:39 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;132;89;89;89;90;103;90;92;88;98 08:10:39 INFO - PROCESS | 1652 | |6;imaging-darkroom;133;151;154;96;96;97;96;96;138;144 08:10:39 INFO - PROCESS | 1652 | |7;imaging-desaturate;97;74;129;97;145;141;91;91;93;126 08:10:39 INFO - PROCESS | 1652 | |8;json-parse-financial;62;64;63;65;67;76;95;95;75;57 08:10:39 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;58;65;59;64;57;59;56;57;59;60 08:10:39 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;83;83;86;84;86;84;86;86;84;86 08:10:39 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;138;150;138;139;139;142;144;146;179;140 08:10:39 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;184;162;212;229;182;168;169;173;220;212 08:10:39 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;62;79;84;87;83;67;67;67;81;60 08:10:39 INFO - PROCESS | 1652 | __end_tp_report 08:10:39 INFO - PROCESS | 1652 | __start_cc_report 08:10:39 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1009 08:10:39 INFO - PROCESS | 1652 | __end_cc_report 08:10:39 INFO - PROCESS | 1652 | __startTimestamp1495638639201__endTimestamp 08:10:39 INFO - PROCESS | 1652 | 08:10:39 INFO - PROCESS | 1652 | ------- Summary: start ------- 08:10:39 INFO - PROCESS | 1652 | Number of tests: 14 08:10:39 INFO - PROCESS | 1652 | 08:10:39 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:120.00 Median:119.00 stddev:5.37 (4.5%) stddev-sans-first:5.65 08:10:39 INFO - PROCESS | 1652 | Values: 118.0 116.0 120.0 118.0 119.0 118.0 119.0 118.0 119.0 135.0 08:10:39 INFO - PROCESS | 1652 | 08:10:39 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:137.50 Median:141.50 stddev:8.30 (5.9%) stddev-sans-first:8.65 08:10:39 INFO - PROCESS | 1652 | Values: 142.0 135.0 141.0 145.0 131.0 138.0 142.0 117.0 143.0 141.0 08:10:39 INFO - PROCESS | 1652 | 08:10:39 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:209.30 Median:199.50 stddev:25.11 (12.6%) stddev-sans-first:26.17 08:10:39 INFO - PROCESS | 1652 | Values: 196.0 200.0 199.0 225.0 276.0 209.0 197.0 198.0 198.0 195.0 08:10:39 INFO - PROCESS | 1652 | 08:10:39 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:69.60 Median:72.00 stddev:11.93 (16.6%) stddev-sans-first:11.62 08:10:39 INFO - PROCESS | 1652 | Values: 83.0 60.0 61.0 89.0 64.0 75.0 49.0 78.0 69.0 68.0 08:10:39 INFO - PROCESS | 1652 | 08:10:39 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:75.20 Median:77.00 stddev:6.05 (7.9%) stddev-sans-first:5.90 08:10:39 INFO - PROCESS | 1652 | Values: 82.0 68.0 84.0 73.0 72.0 70.0 81.0 70.0 71.0 81.0 08:10:39 INFO - PROCESS | 1652 | 08:10:39 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:96.00 Median:89.50 stddev:13.53 (15.1%) stddev-sans-first:5.10 08:10:39 INFO - PROCESS | 1652 | Values: 132.0 89.0 89.0 89.0 90.0 103.0 90.0 92.0 88.0 98.0 08:10:39 INFO - PROCESS | 1652 | 08:10:39 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:120.10 Median:96.00 stddev:25.86 (26.9%) stddev-sans-first:27.00 08:10:39 INFO - PROCESS | 1652 | Values: 133.0 151.0 154.0 96.0 96.0 97.0 96.0 96.0 138.0 144.0 08:10:39 INFO - PROCESS | 1652 | 08:10:39 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:108.40 Median:91.00 stddev:24.55 (27.0%) stddev-sans-first:25.69 08:10:39 INFO - PROCESS | 1652 | Values: 97.0 74.0 129.0 97.0 145.0 141.0 91.0 91.0 93.0 126.0 08:10:39 INFO - PROCESS | 1652 | 08:10:39 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:71.90 Median:71.00 stddev:13.44 (18.9%) stddev-sans-first:13.77 08:10:39 INFO - PROCESS | 1652 | Values: 62.0 64.0 63.0 65.0 67.0 76.0 95.0 95.0 75.0 57.0 08:10:39 INFO - PROCESS | 1652 | 08:10:39 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:59.40 Median:59.00 stddev:2.95 (5.0%) stddev-sans-first:3.09 08:10:39 INFO - PROCESS | 1652 | Values: 58.0 65.0 59.0 64.0 57.0 59.0 56.0 57.0 59.0 60.0 08:10:39 INFO - PROCESS | 1652 | 08:10:39 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:84.80 Median:86.00 stddev:1.32 (1.5%) stddev-sans-first:1.22 08:10:39 INFO - PROCESS | 1652 | Values: 83.0 83.0 86.0 84.0 86.0 84.0 86.0 86.0 84.0 86.0 08:10:39 INFO - PROCESS | 1652 | 08:10:39 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:145.50 Median:143.00 stddev:12.40 (8.7%) stddev-sans-first:12.85 08:10:39 INFO - PROCESS | 1652 | Values: 138.0 150.0 138.0 139.0 139.0 142.0 144.0 146.0 179.0 140.0 08:10:39 INFO - PROCESS | 1652 | 08:10:39 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.10 Median:198.00 stddev:24.66 (12.5%) stddev-sans-first:26.03 08:10:39 INFO - PROCESS | 1652 | Values: 184.0 162.0 212.0 229.0 182.0 168.0 169.0 173.0 220.0 212.0 08:10:39 INFO - PROCESS | 1652 | 08:10:39 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.70 Median:80.00 stddev:10.06 (12.6%) stddev-sans-first:9.73 08:10:39 INFO - PROCESS | 1652 | Values: 62.0 79.0 84.0 87.0 83.0 67.0 67.0 67.0 81.0 60.0 08:10:39 INFO - PROCESS | 1652 | -------- Summary: end -------- 08:10:39 INFO - PROCESS | 1652 | 08:10:39 INFO - TEST-INFO | 1652: exit 0 08:10:39 INFO - TEST-OK | kraken | took 60901ms 08:10:39 INFO - SUITE-END | took 1175s 08:10:40 INFO - Completed test suite (00:19:36) 08:10:40 INFO - javascript benchmark 08:10:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7672.211275222505, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2933.5013047781877, 3508.6842184203433, 3531.947020794687, 3537.062678390226, 3557.1818481749197, 6033.487153591945, 6042.516598672107, 6074.726637301321, 6090.8477288067825, 6099.237595300582, 6032.0, 6058.575899687024, 6078.118672792442, 6088.939110608892, 6100.176476175715, 4430.623397011258, 5477.397486276506, 5480.095784195623, 5490.6431081979545, 5570.86072848178, 5497.038018346793, 5544.500994910457, 5548.112302031675, 5579.107342825148, 5645.181448689958, 4398.670099742516, 5378.811741589046, 5478.342598888119, 5560.388357280696, 5567.832965011036, 4600.723956562617, 5437.700926449043, 5441.863953401177, 5452.6455780374545, 5488.835334939959, 5449.3460784706085, 5545.972270138664, 5548.44515548446, 5556.86107847303, 5613.691246981414, 6166.167567378391, 6167.044108163242, 6170.382961703838, 6174.166487524217, 6186.0, 6233.719482623293, 6332.33510481402, 6343.207099112611, 6442.48460123189, 6458.773942912023, 5202.687838729688, 6434.131392262031, 6567.802965911031, 6582.9670851645915, 6601.669916504204, 6148.415900489446, 6194.690265486753, 6199.3180750117, 6224.533160013018, 6227.1282020517165, 6359.8092057238355, 6713.597184168965, 6724.193096828412, 6742.089817874622, 6749.460043196533, 6286.9056964145575, 6295.559310848288, 6306.684665766693, 6327.778527751507, 6345.302016778151, 6454.128692626436, 6468.353164683494, 6485.935140648628, 6500.3174666659315, 6505.544611877123, 6110.388961103854, 6348.365163483615, 6371.362863713684, 6374.872502550016, 6387.201599800025, 6282.654454004982, 6320.178376810985, 6349.333320001426, 6352.872942541216, 6359.459445947054, 4937.7777999990085, 4964.131277026579, 4966.751662416865, 4976.894062398535, 4991.825286115041, 4415.161119387306, 4416.1609294233995, 4422.380866678667, 4437.622802061796, 4446.911061778746, 5714.628549144292, 5726.427357264323, 5833.854153646108, 5838.2702162229725, 5966.0, 6396.84007899797, 6402.679866006681, 6417.7753778617525, 6421.133147025185, 6493.188351456068, 5785.537157027427, 5963.910541341883, 5968.731407086691, 5983.431574000463, 6020.729067191987, 5551.861203469864, 5635.0, 5675.035244008528, 5694.9430505695245, 5702.486776190163, 6578.144841170617, 6600.471962242913, 6730.663466826639, 6768.0, 6789.388954994074, 5538.280023597068, 5842.5325973921135, 6434.581752186093, 6491.642959637265, 6492.513061520499, 6123.938760612336, 6758.898616520658, 6776.525643204928, 6791.898121528082, 6808.421284190701, 6304.085907543289, 6326.2092238470195, 6349.587276826992, 6422.32565580626, 6437.613743175424, 6507.284198738041, 6541.182352205974, 6541.476681865344, 6547.1161393211305, 6554.705038273194, 6015.639061656315, 6343.429091381798, 6345.619262844244, 6349.523785716182, 6352.333005034589, 6206.875862482815, 6283.151774510383, 6309.526785491014, 6355.078513615592, 6375.298717141018, 5775.162601422854, 6231.470325022243, 6273.466755325666, 6340.080688300263, 6353.110564521063, 6345.650989195638, 6362.9681851592295, 6372.522060845363, 6374.58565193261, 6381.425671689571, 5019.548240658358, 6144.846378840565, 6280.623162610258, 6333.96833015832, 6341.4292713656], "value": 5905.574040505303, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [787.3779714025919, 824.5671022713075, 840.1766269056322, 976.9706908792739, 978.2711879649657, 30490.39019219617, 39400.80299598502, 41256.58743412565, 41350.17299654005, 41417.171656566854, 39562.0, 39670.404943925794, 39703.20593588126, 39792.0, 39796.0, 7963.880541791863, 8120.106788253287, 10928.071113955324, 11187.105031597453, 11214.719632009224, 10794.244402891762, 10931.125509959184, 10937.507812148473, 10940.45297735114, 10949.233553651247, 10938.835917461245, 10942.617008404708, 10953.95937385947, 10954.561817527288, 10977.231593788438, 10924.907509249051, 10927.508262128144, 10941.452927353641, 10957.890421095766, 10967.670969870916, 10728.409937453436, 10803.297785643908, 10809.405482698448, 10815.78368432627, 10832.945835270773, 34150.658493414994, 34209.6579034209, 34373.31253374919, 34383.96848094561, 34394.0, 3782.56500502443, 3794.31702293587, 3809.6380843819793, 3811.9809400953095, 3821.885343439701, 25063.0, 25427.157604545682, 25659.74340256611, 26541.46917061648, 26967.595486067723, 3055.8252908186487, 3112.937741245162, 3155.637101733307, 3164.098232003868, 3209.679032096795, 266448.0, 272004.0, 360072.1996390001, 370502.4424633633, 374323.0, 41106.17787644231, 41174.38238426426, 41308.79345603253, 41352.79323603363, 41382.0, 43803.12393752107, 43904.0, 43963.68108956736, 44045.0, 44066.0, 13426.932865335611, 13487.123336983064, 13513.391897364641, 13514.256715880721, 13514.32428378577, 9924.858641256204, 10839.241253112365, 11698.88301116995, 11756.35340056288, 11760.88239117615, 11616.709582260512, 11617.825732614017, 11655.475503602358, 11701.590444334408, 11702.239354441934, 4911.8280860169725, 5579.967705974326, 5672.901026620095, 5709.68596727184, 5766.711664416763, 1145.9656210313703, 1197.3534291482501, 1198.8021976373975, 1203.7171264752776, 1240.925544467335, 24302.0, 25988.350291242492, 26318.342041449116, 26347.868260658575, 26361.0, 1296.118639325249, 1301.4729034740944, 1305.8171855940177, 1328.3955800110925, 1350.1628990026247, 13560.932195339157, 13696.972727045511, 13706.725865482635, 13719.0, 13754.31228438594, 370246.0, 483313.0, 497229.0, 592976.0351198303, 594449.0, 47837.76081119572, 47933.80165495891, 48389.758051209516, 48494.757526212146, 48542.0, 1416.2493878244547, 1438.5252866554222, 1443.762168638627, 1446.1467734036758, 1449.463698431587, 26629.0, 26795.86602066977, 26969.325766855985, 27295.0, 27460.626968651886, 33072.50391244181, 39167.41248881212, 39272.0, 39308.80345598254, 39529.61646342364, 463701.0, 465845.0, 466680.0, 467040.0, 467538.0, 24028.87985560131, 25168.49663006766, 28118.437631246852, 29131.563026555043, 29238.853805731687, 32763.672363276062, 36065.81967090148, 36700.89897303085, 37347.69283075079, 37554.24891502209, 26353.868230658725, 26665.86667066652, 26770.866145669926, 26805.463890722465, 27353.726462735118, 26449.074282400023, 26522.0, 26958.730412696408, 27335.043273486128, 27669.44661106807, 26149.477010460065, 26423.867880660473, 30402.93589724349, 31517.842410787798, 31661.733530658516, 26164.73835261699, 27010.72989270161, 27273.0, 27625.585616216176, 28410.0, 18364.357247496275, 22126.889365553714, 22619.660705089766, 22759.0, 22881.77118228863], "value": 17934.743429087972, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.66061865558305, 419.3353534647583, 419.3667561981411, 428.7470392468443, 455.3261173463274, 19756.506087347825, 20847.0, 22234.444138896535, 22237.110515579356, 22250.88874555627, 21465.67801482979, 22222.66666000011, 22379.44051398716, 22465.539739916894, 22530.324090277278, 6817.761378351771, 6948.548344357626, 6966.860662786741, 7016.859662806741, 7052.435805135602, 6540.771073012445, 6923.0, 6989.650517474106, 7002.859942801167, 7032.472564557653, 6738.797836064926, 6904.4821638377325, 6960.686769095403, 6976.127984002, 6996.7201311947465, 6673.833154171136, 6858.794236172923, 6863.897041544381, 6904.9654751726175, 6912.758053468155, 6018.578699491038, 6758.594484330956, 6791.898121528132, 6796.524243302971, 6806.897896531556, 27414.0, 27486.312842178904, 27812.582811257846, 28137.718622813714, 28230.15309540717, 2483.962740558893, 2976.2113040044405, 2987.5518672199128, 3013.2015016020773, 3023.5011223148153, 16324.75512867308, 17100.144992750313, 17167.828321716748, 17232.310707571683, 17841.821581784276, 1939.2049259803555, 2032.78655741148, 2067.7311949446475, 2108.768035516092, 2108.9050992705365, 97145.0, 103707.0, 117155.41422292919, 119305.0, 121445.99999999911, 23532.529349413257, 23651.526969460516, 23678.28965131049, 23897.371289158178, 23919.043238270624, 37064.07339816472, 37321.81339093287, 37952.43071353932, 38038.0, 38060.0, 10385.065344119066, 11025.0, 11243.550257989753, 11314.377709226072, 11367.147463940073, 7617.200193979663, 9094.226990705863, 9124.133207345292, 9152.405093668891, 9313.022132675971, 9190.448573085636, 9252.722418327461, 10839.916008399097, 11175.72060698489, 11283.492242849092, 3881.728279020498, 4495.775211239425, 4611.354410382549, 4645.326427668037, 4647.233206520887, 1059.4702648675661, 1081.405227125078, 1082.6643740440331, 1125.808612535871, 1130.5929865248504, 21507.67738483924, 22773.886130569572, 22787.860606969916, 22791.658125128135, 22855.542889142125, 939.3753154152412, 939.7180845746249, 1057.7249915021996, 1104.304288298383, 1111.9332840029624, 11132.888671113185, 11761.235519691192, 11890.346030968207, 11999.160058795975, 12000.519979200908, 767508.0, 770686.0, 780476.0, 781942.0, 788635.0, 122029.0, 122129.38935305267, 124427.51906603882, 124725.0, 127362.36318818346, 1327.188387850577, 1327.8141060251573, 1396.4553824008644, 1401.5094716849226, 1407.549584133088, 20401.89799050995, 20461.386158415273, 21021.843798591224, 21193.576128477653, 21283.46791330229, 54321.91356172776, 57155.71422142863, 60735.39264607297, 63046.68476657771, 65686.67156664377, 92441.53779231061, 95231.52384238034, 95347.0, 95412.56881146927, 96219.51890240503, 11446.771064578828, 11626.302421854716, 11675.591354302558, 11717.472713728073, 12105.636830895088, 12468.937655311665, 12884.0, 13013.609591712264, 13282.53511127106, 13334.466621335037, 5915.142304365929, 11215.88784112181, 11453.885461145283, 11455.427228638702, 11712.238704484182, 10385.84421233667, 10431.739206519595, 10507.369557826554, 10729.302595331279, 10743.0, 11335.659930202108, 11550.191486595857, 11640.708982275512, 11756.882431175922, 11757.059435244988, 10546.472676366304, 11407.486663100015, 11582.0, 11614.419279036183, 11890.929816316573, 11205.60780372683, 11246.66260012201, 11489.54041838317, 11490.023348015513, 11587.292066538626], "value": 11859.311260320163, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1564.522820539735, 1615.8626516746076, 1618.554897403214, 1621.9270132844028, 1631.0784406810155, 10364.0, 10870.239083264174, 10887.618933337335, 11170.273932194401, 11496.482658280376, 11248.437578121102, 11425.42872856356, 11439.828402573947, 11452.599159029436, 11478.0, 2039.6430624640702, 2291.564602725481, 2343.0510643189536, 2343.1330407749156, 2347.6243800991847, 2008.9955022488755, 2134.285014520138, 2347.2606129069372, 2353.9646905296436, 2369.206315884181, 1954.3159894037044, 2289.347535952254, 2335.995521925571, 2342.02805835578, 2359.268626725712, 2250.414892128042, 2313.363824948136, 2314.1784666443423, 2319.5476882008015, 2334.6498025296255, 2329.7204335479682, 2367.0650093213258, 2375.489269807, 2375.7743014413604, 2380.6071998120287, 11479.655610331703, 11511.30932144074, 11546.769064618744, 11547.307161570332, 11577.01595364395, 5168.043911876315, 5470.425605311425, 5522.779088836442, 5699.344575373843, 5743.081107022898, 3169.3344397676515, 3229.677032296775, 3241.740660747158, 3436.74224433166, 3461.584609846809, 1105.2815669814686, 1107.4628805029554, 1132.9943350283195, 1141.8058929981923, 1145.1297014269132, 13895.79156312656, 14197.36111874968, 15094.24528773557, 15645.0, 15724.842751572452, 4351.717138385995, 4439.445069366329, 4550.613197878151, 4550.795214215302, 4592.6555508337005, 11956.581519646945, 11961.40192990347, 11961.46173422198, 11976.88023119775, 11996.880031199576, 5561.805336813192, 5572.832815015556, 5577.525910297589, 5600.215969764236, 5614.382417934024, 5032.6980381177245, 5050.242463630485, 5081.212412076134, 5110.8466745997675, 5128.487151284916, 3628.854845806191, 3711.9072023199637, 3731.533558305212, 3757.492738480325, 3812.218495208476, 934.1592566690033, 939.4175611121012, 940.948247846375, 956.516958935733, 957.3585697582637, 959.2326139088701, 960.3757557587485, 964.9421034737938, 972.615816752393, 1000.1848493477793, 6721.4958878084335, 6731.46148308144, 6756.358145976124, 6941.548799328028, 6957.721691132298, 972.8783902012249, 995.3679413572434, 1004.4324956399611, 1014.2291858187683, 1026.6919924022764, 4027.496562929634, 4244.3209086546, 4249.1501699659575, 4250.659947204216, 4298.355246712956, 13233.0, 13918.30408479572, 13952.441902323997, 13953.581392558239, 13970.580882573538, 13849.376778045013, 13849.723005539832, 13859.445622175203, 13868.306584670727, 13872.890168786473, 8240.340772738167, 8714.954205495507, 9958.30291879576, 10320.225983051301, 10408.895911040943, 6430.903536447051, 6866.695627424967, 6951.860962780817, 7061.0, 7140.285971402818, 8953.686620968236, 11801.586944456903, 12051.638450846489, 12264.141510094209, 12290.75418491643, 11980.0, 12030.338331391857, 12144.0, 12234.327112008925, 12354.196977196454, 10197.694069177936, 10460.424676642857, 10488.31825931312, 10557.049865512141, 10564.683059508227, 10410.1671866249, 10422.478876056319, 10545.57817687284, 10672.146228301872, 10673.039426451656, 8774.122587741174, 8831.867521987304, 8834.895638045244, 8875.600597973236, 8974.775630609287, 8580.65677372898, 8609.397342185986, 8850.955745221232, 8919.73240802777, 8977.147171018743, 7813.609319534115, 7936.563489007929, 8041.718539851077, 8060.952076230053, 8080.919190808252, 6031.155638210566, 6098.512119030555, 6099.146119543178, 6113.694315284307, 6296.6851657417865, 5250.422453530032, 6047.304559975659, 6077.848053798691, 6091.543134264859, 6333.145025421693], "value": 5099.792478917508, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.17529373612405, 395.286507853325, 399.12193175014966, 399.3510545363784, 404.7814180342615, 4526.773661316937, 4695.83991363973, 5242.868928276795, 5285.841424757254, 5435.429279925605, 4896.583790377813, 4951.455339912607, 5029.290041385928, 5223.791048358071, 5343.118385466403, 4131.173765246947, 4647.117047760931, 4656.208444564424, 4687.7656117194, 4709.858704238878, 3876.4960555127795, 3979.880603581897, 4154.688398370119, 4683.484816670163, 4695.8356457524, 4327.545607711192, 4367.890802729926, 4375.7155784873885, 4401.22978478765, 4437.069874604541, 3941.994791328193, 4035.8901302142, 4410.184115938529, 4424.429327588692, 4494.831343850622, 3803.5055442792536, 3837.4435706822355, 3978.741381810202, 4115.8887100483, 4123.546409894909, 4258.233517966764, 4524.981879077214, 4561.973555949918, 4620.722756634613, 4907.576802727205, 5015.39815222172, 5094.796208151706, 5098.745062746848, 5131.512506311894, 5141.228815677641, 4472.1502914446155, 4904.008554781581, 5168.638195326329, 5222.668219604015, 5718.256626638552, 3828.712846536521, 4299.484061912559, 4346.782660866944, 4474.753888536129, 4497.707649002804, 5092.567131793765, 5732.5127364174095, 5829.883402331972, 6009.669468179249, 6070.841473590604, 4353.498043175099, 4371.868843934687, 4546.272596384562, 4606.907861842745, 4614.907701846011, 4072.022714548487, 4216.9789151054665, 4331.523532411432, 4705.976470117627, 4721.763911804396, 4458.509563947963, 4477.7984990675495, 4550.795214215368, 4866.172750632401, 5087.219473184356, 5201.505856943584, 5382.703951282639, 5604.719764011783, 5648.237487939158, 5681.545476361881, 1417.9291035448186, 1535.7807967487097, 1547.8545876051867, 1594.3976303814593, 1596.8243493215737, 1003.0922015575916, 1025.0006243912126, 1086.0225796782959, 1113.9695781402334, 1210.8062709966364, 4536.88657783558, 4581.404417425713, 5076.213186956028, 5084.77118529667, 5094.0, 5480.232767412565, 5552.305961754781, 5758.3665796762325, 5856.707164641751, 5905.852353691193, 4419.519460980543, 4671.602913752369, 5006.974965125151, 5029.321041659329, 5049.899002020013, 2673.4385778986243, 2844.4444444444443, 2963.740410325603, 3008.3381656035635, 3044.512877939563, 5151.913475042614, 5159.25190847325, 5623.0, 6058.545609079337, 6060.090986352083, 5065.56942659878, 5187.584993200535, 5445.767235433454, 5504.53211477029, 5860.652050028432, 5428.6199966002005, 5881.9705901470215, 5907.49786268172, 6066.090086487062, 6139.903879779772, 4772.828363094853, 5155.453363990863, 5197.948020519746, 5436.266104075899, 5799.4780469757925, 5044.4451110377095, 5163.66436181647, 5558.833235002957, 5768.740406681625, 5802.6808525531505, 5147.56245719118, 5531.419200984, 5611.446791709844, 5836.972488982588, 6018.789342372996, 4030.173814368106, 4428.9149158456385, 4487.730736155841, 4580.65645076614, 4615.100055489147, 5270.552003079782, 5842.532597392284, 5916.319623243382, 5937.554683398849, 5995.46040856325, 4977.303177555217, 4986.80052797884, 5140.820071297487, 5688.089905615082, 5738.340090889435, 5380.246765452762, 5437.4018857926385, 5617.522510586482, 5633.352164501135, 5697.7151142443545, 4763.570579004663, 5034.974825125851, 5301.985245606645, 5366.490183432567, 5567.24842146305, 5459.426760190123, 5725.914111288417, 5774.566907481872, 5788.408187748403, 5800.535957123336, 4827.589654879374, 4860.756962151808, 5241.528262456397, 5512.6692398456225, 5628.831135065954], "value": 4268.927426313526, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [742.2614498573917, 762.9465937384385, 777.9066512018555, 844.4300097434232, 863.7236084452974, 14413.135211887295, 14753.557393278192, 14994.925025374885, 15169.620759481018, 15222.08667479952, 13791.10357826743, 14014.019018668698, 14060.85939140611, 14202.076865003794, 14225.075370100962, 967.7725734452398, 990.8166989107038, 990.8910019897805, 991.008991008991, 991.5438898106879, 947.9526023698822, 964.2912459342377, 965.1362030982302, 976.2677991506371, 980.9509524523745, 1016.3038318751679, 1021.7037059252808, 1024.8309029010204, 1026.9897301027006, 1027.0807627173674, 876.7238319929202, 1008.2841182760249, 1014.2697257974247, 1014.8477728340735, 1016.7915577306671, 686.9381755642016, 758.287210022577, 765.8698021336386, 768.3584207187006, 769.2691942654501, 15110.093394396372, 15346.309416076192, 15349.846501534954, 15461.304241309168, 15814.0, 11644.825327620008, 11985.880141198564, 12963.52304786082, 13077.803832942513, 13480.325983700874, 11201.775964480747, 11370.658880233523, 11481.0, 11527.0, 11567.363794991272, 3582.3551760683067, 3644.5444319460066, 3668.321360548307, 3706.092007458163, 3838.0404898775305, 86814.0, 106393.46803266011, 110428.0, 110879.89120108777, 113786.0, 10342.276040677081, 10552.841707374395, 10713.464326783629, 10935.0, 11485.655430337103, 21316.680249796267, 21480.355589332346, 21675.566488670454, 21833.235836745636, 22209.666854997187, 7459.627018649046, 7520.21037791035, 7560.206178351304, 7763.4953728007495, 7788.649510772028, 3849.7690138591774, 4159.168166366739, 4186.769727665008, 4317.050248945227, 4325.653947684178, 3217.758668099902, 3288.786228895114, 3304.9919774469017, 3305.405027095098, 3634.7637403568683, 629.6285191736897, 697.8988046733195, 705.1855107350915, 705.7141857547701, 712.8431745016088, 676.2628734679223, 683.8256244657581, 705.5625512182377, 708.8015355700412, 737.2995654128599, 8762.693305734269, 10134.23993200513, 10194.490275486196, 10257.948710256402, 10310.0, 687.556526040711, 700.3766647683566, 716.3023633987483, 721.1742554774753, 721.9027078840238, 4586.610138138296, 4860.538248866352, 4866.29438731389, 4866.6106711463, 4875.609951203895, 81959.59020204861, 84818.57590712131, 97338.51330743301, 98676.0, 100564.0, 87287.56356218178, 89451.55274223587, 94407.11185776246, 95360.04639953512, 99557.0, 59445.0, 68142.0, 70935.64532177306, 76513.0, 76617.0, 10480.895191047992, 10661.520894726244, 11008.064314533354, 11016.339019658846, 11070.501827417947, 44214.778926105166, 47618.52381476141, 49485.50514494809, 50164.0, 50461.0, 63300.366996329445, 66224.0, 67265.66367168132, 68004.63990720257, 68069.97895031479, 9935.453550054817, 10300.690979270334, 11785.88214117848, 11962.923336899721, 12102.878971210175, 11726.824097638371, 12062.879371206527, 12071.637850864488, 12170.634880953585, 12553.0, 12694.746105078031, 12823.781740734616, 13128.803067953797, 13537.052406331462, 13602.93198534001, 12610.936945315214, 12736.363181841056, 12966.481340746264, 13130.803037954629, 13582.388792504164, 11358.659240222807, 12806.551770687982, 12910.67723306925, 13287.0, 13479.528216512375, 8887.688930887389, 9091.272698184262, 9190.21883139914, 9198.586063627019, 9282.666812698813, 9160.404573702688, 10205.591776328863, 10825.512851921525, 10838.837417438903, 10930.125589952626], "value": 7457.537323050828, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1562.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 116.0, 120.0, 118.0, 119.0, 118.0, 119.0, 118.0, 119.0, 135.0], "value": 120.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 135.0, 141.0, 145.0, 131.0, 138.0, 142.0, 117.0, 143.0, 141.0], "value": 137.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 200.0, 199.0, 225.0, 276.0, 209.0, 197.0, 198.0, 198.0, 195.0], "value": 209.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 60.0, 61.0, 89.0, 64.0, 75.0, 49.0, 78.0, 69.0, 68.0], "value": 69.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 68.0, 84.0, 73.0, 72.0, 70.0, 81.0, 70.0, 71.0, 81.0], "value": 75.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 89.0, 89.0, 89.0, 90.0, 103.0, 90.0, 92.0, 88.0, 98.0], "value": 96.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 151.0, 154.0, 96.0, 96.0, 97.0, 96.0, 96.0, 138.0, 144.0], "value": 120.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 74.0, 129.0, 97.0, 145.0, 141.0, 91.0, 91.0, 93.0, 126.0], "value": 108.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 63.0, 65.0, 67.0, 76.0, 95.0, 95.0, 75.0, 57.0], "value": 71.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 65.0, 59.0, 64.0, 57.0, 59.0, 56.0, 57.0, 59.0, 60.0], "value": 59.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 86.0, 84.0, 86.0, 84.0, 86.0, 86.0, 84.0, 86.0], "value": 84.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 150.0, 138.0, 139.0, 139.0, 142.0, 144.0, 146.0, 179.0, 140.0], "value": 145.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 162.0, 212.0, 229.0, 182.0, 168.0, 169.0, 173.0, 220.0, 212.0], "value": 191.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 79.0, 84.0, 87.0, 83.0, 67.0, 67.0, 67.0, 81.0, 60.0], "value": 73.7, "unit": "score"}]}]} 08:10:40 INFO - Return code: 0 08:10:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:10:40 ERROR - # TBPL SUCCESS # 08:10:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:10:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:10:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:10:40 INFO - 2017-05-24 08:10:40.552 screenresolution[1657:10602] starting screenresolution argv=screenresolution get 08:10:40 INFO - 2017-05-24 08:10:40.555 screenresolution[1657:10602] Display 0: 1600x1200x32@60 08:10:40 INFO - 2017-05-24 08:10:40.561 screenresolution[1658:10605] starting screenresolution argv=screenresolution list 08:10:40 INFO - Available Modes on Display 0 08:10:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:10:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:10:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:10:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:10:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:10:40 INFO - Intel Iris: 08:10:40 INFO - Chipset Model: Intel Iris 08:10:40 INFO - Type: GPU 08:10:40 INFO - Bus: Built-In 08:10:40 INFO - VRAM (Dynamic, Max): 1536 MB 08:10:40 INFO - Vendor: Intel (0x8086) 08:10:40 INFO - Device ID: 0x0a2e 08:10:40 INFO - Revision ID: 0x0009 08:10:40 INFO - Displays: 08:10:40 INFO - Display: 08:10:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:10:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:10:40 INFO - Display Serial Number: 1600x1200 60 08:10:40 INFO - Main Display: Yes 08:10:40 INFO - Mirror: Off 08:10:40 INFO - Online: Yes 08:10:40 INFO - Rotation: Supported 08:10:40 INFO - Return code: 0 08:10:40 INFO - Running post-action listener: _resource_record_post_action 08:10:40 INFO - [mozharness: 2017-05-24 15:10:40.797759Z] Finished run-tests step (success) 08:10:40 INFO - Running post-run listener: _resource_record_post_run 08:10:40 INFO - Total resource usage - Wall time: 1196s; CPU: 55.0%; Read bytes: 39839744; Write bytes: 512074752; Read time: 398; Write time: 1670 08:10:40 INFO - TinderboxPrint: CPU usage
54.9% 08:10:40 INFO - TinderboxPrint: I/O read bytes / time
39,839,744 / 398 08:10:40 INFO - TinderboxPrint: I/O write bytes / time
512,074,752 / 1,670 08:10:40 INFO - TinderboxPrint: CPU idle
2,159.0 (45.1%) 08:10:40 INFO - TinderboxPrint: CPU system
965.3 (20.2%) 08:10:40 INFO - TinderboxPrint: CPU user
1,661.3 (34.7%) 08:10:40 INFO - TinderboxPrint: Swap in / out
248,528,896 / 0 08:10:40 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 208425472; Write bytes: 202237952; Read time: 15829; Write time: 390 08:10:40 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 35903488; Write bytes: 299686912; Read time: 361; Write time: 1247 08:10:41 INFO - Running post-run listener: _upload_blobber_files 08:10:41 INFO - Blob upload gear active. 08:10:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:10:41 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 08:10:41 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'] 08:10:41 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 08:10:41 INFO - (blobuploader) - INFO - Open directory for files ... 08:10:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:10:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:10:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:10:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:10:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:10:42 INFO - (blobuploader) - INFO - Done attempting. 08:10:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:10:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:10:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:10:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:10:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:10:43 INFO - (blobuploader) - INFO - Done attempting. 08:10:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:10:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:10:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:10:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:10:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:10:45 INFO - (blobuploader) - INFO - Done attempting. 08:10:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:10:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:10:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:10:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:10:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:10:47 INFO - (blobuploader) - INFO - Done attempting. 08:10:47 INFO - (blobuploader) - INFO - Iteration through files over. 08:10:47 INFO - Return code: 0 08:10:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:10:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:10:47 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5b8ce0b884e4113feb2c0fd093259f9b31a2bcaad7578920b26aaa6dd354fc3c45e78e75f2005be1c96d61577ed26faf509ddd4c00ae8da616d55ea65d98c4dd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9a34d39beb9f94d8b2fdf2b266089ffea8d99cfb5c0fb62120584552677adfd2babf1f0f5ad8aeb6c97c6c47810ee4ec596c7b6cdd665af4019d2bc2c3848d4b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/980a7fd8c9b03c61a2a3929133335fedd5d4532a286436c8e59043fbe54c846b114bd59f3c07149a8234377fbe936a8c1dbc1eee74751cc55478bf13e7e1984b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c0f63e2b10a3a55669b6278df543d6e48726e399d6bdf3317db7a0816ccca928054b1029ed21867c54799f69b7ebbe0319a7fd66a043c60813b897c2e5774a47"} 08:10:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:10:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:10:47 INFO - Contents: 08:10:47 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5b8ce0b884e4113feb2c0fd093259f9b31a2bcaad7578920b26aaa6dd354fc3c45e78e75f2005be1c96d61577ed26faf509ddd4c00ae8da616d55ea65d98c4dd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9a34d39beb9f94d8b2fdf2b266089ffea8d99cfb5c0fb62120584552677adfd2babf1f0f5ad8aeb6c97c6c47810ee4ec596c7b6cdd665af4019d2bc2c3848d4b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/980a7fd8c9b03c61a2a3929133335fedd5d4532a286436c8e59043fbe54c846b114bd59f3c07149a8234377fbe936a8c1dbc1eee74751cc55478bf13e7e1984b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c0f63e2b10a3a55669b6278df543d6e48726e399d6bdf3317db7a0816ccca928054b1029ed21867c54799f69b7ebbe0319a7fd66a043c60813b897c2e5774a47"} 08:10:47 INFO - Running post-run listener: copy_logs_to_upload_dir 08:10:47 INFO - Copying logs to upload dir... 08:10:47 INFO - mkdir: /builds/slave/test/build/upload/logs 08:10:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.005896 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-05-24 08:10:47.316462) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 08:10:47.319912) ========= 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.DSY2mu0QQp/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.tYo4Gyv8yj/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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/5b8ce0b884e4113feb2c0fd093259f9b31a2bcaad7578920b26aaa6dd354fc3c45e78e75f2005be1c96d61577ed26faf509ddd4c00ae8da616d55ea65d98c4dd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9a34d39beb9f94d8b2fdf2b266089ffea8d99cfb5c0fb62120584552677adfd2babf1f0f5ad8aeb6c97c6c47810ee4ec596c7b6cdd665af4019d2bc2c3848d4b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/980a7fd8c9b03c61a2a3929133335fedd5d4532a286436c8e59043fbe54c846b114bd59f3c07149a8234377fbe936a8c1dbc1eee74751cc55478bf13e7e1984b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c0f63e2b10a3a55669b6278df543d6e48726e399d6bdf3317db7a0816ccca928054b1029ed21867c54799f69b7ebbe0319a7fd66a043c60813b897c2e5774a47"} build_url:https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011211 build_url: 'https://queue.taskcluster.net/v1/task/JTn8fTxVRGGUUFt1dKQXyQ/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/5b8ce0b884e4113feb2c0fd093259f9b31a2bcaad7578920b26aaa6dd354fc3c45e78e75f2005be1c96d61577ed26faf509ddd4c00ae8da616d55ea65d98c4dd", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9a34d39beb9f94d8b2fdf2b266089ffea8d99cfb5c0fb62120584552677adfd2babf1f0f5ad8aeb6c97c6c47810ee4ec596c7b6cdd665af4019d2bc2c3848d4b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/980a7fd8c9b03c61a2a3929133335fedd5d4532a286436c8e59043fbe54c846b114bd59f3c07149a8234377fbe936a8c1dbc1eee74751cc55478bf13e7e1984b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c0f63e2b10a3a55669b6278df543d6e48726e399d6bdf3317db7a0816ccca928054b1029ed21867c54799f69b7ebbe0319a7fd66a043c60813b897c2e5774a47"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 08:10:47.353091) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 08:10:47.353379) ========= 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.DSY2mu0QQp/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.tYo4Gyv8yj/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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.005302 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 08:10:47.401926) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 08:10:47.402195) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 08:10:47.402497) ========= ========= Total master_lag: 4.32 =========