builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0341 starttime: 1495824989.34 results: success (0) buildid: 20170526101616 builduid: 4ca1ed669ad24a59a147e7442a3e4d2f revision: 9664d83f5c056a32a3cc29de9d5934b82809dbfd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 11:56:29.344740) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 11:56:29.345229) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 11:56:29.345501) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 11:56:29.371190) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 11:56:29.371571) ========= 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.685ygcmxOU/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.HdVGHChWrn/Listeners TMPDIR=/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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-26 11:56:29-- 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.5M=0.001s 2017-05-26 11:56:29 (18.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.199715 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 11:56:29.594464) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 11:56:29.594769) ========= 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.685ygcmxOU/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.HdVGHChWrn/Listeners TMPDIR=/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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.031412 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 11:56:29.648481) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-26 11:56:29.648844) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9664d83f5c056a32a3cc29de9d5934b82809dbfd --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 9664d83f5c056a32a3cc29de9d5934b82809dbfd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.685ygcmxOU/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.HdVGHChWrn/Listeners TMPDIR=/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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-26 11:56:29,747 truncating revision to first 12 chars 2017-05-26 11:56:29,747 Setting DEBUG logging. 2017-05-26 11:56:29,747 attempt 1/10 2017-05-26 11:56:29,747 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/9664d83f5c05?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-26 11:56:31,484 unpacking tar archive at: mozilla-beta-9664d83f5c05/testing/mozharness/ program finished with exit code 0 elapsedTime=2.022677 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-26 11:56:31.694327) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 11:56:31.694643) ========= script_repo_revision: 9664d83f5c056a32a3cc29de9d5934b82809dbfd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 11:56:31.695023) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 11:56:31.695283) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 11:56:31.714203) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-05-26 11:56:31.714532) ========= /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.685ygcmxOU/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.HdVGHChWrn/Listeners TMPDIR=/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 11:56:31 INFO - MultiFileLogger online at 20170526 11:56:31 in /builds/slave/test 11:56:31 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 11:56:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:56:31 INFO - {'append_to_log': False, 11:56:31 INFO - 'base_work_dir': '/builds/slave/test', 11:56:31 INFO - 'blob_upload_branch': 'Mozilla-Beta', 11:56:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:56:31 INFO - 'branch': 'Mozilla-Beta', 11:56:31 INFO - 'buildbot_json_path': 'buildprops.json', 11:56:31 INFO - 'config_files': ('talos/mac_config.py',), 11:56:31 INFO - 'default_actions': ('clobber', 11:56:31 INFO - 'read-buildbot-config', 11:56:31 INFO - 'download-and-extract', 11:56:31 INFO - 'populate-webroot', 11:56:31 INFO - 'create-virtualenv', 11:56:31 INFO - 'install', 11:56:31 INFO - 'run-tests'), 11:56:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:56:31 INFO - 'download_minidump_stackwalk': True, 11:56:31 INFO - 'download_symbols': 'ondemand', 11:56:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:56:31 INFO - 'tooltool.py': '/tools/tooltool.py', 11:56:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:56:31 INFO - '/tools/misc-python/virtualenv.py')}, 11:56:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:56:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:56:31 INFO - 'gecko_profile': False, 11:56:31 INFO - 'gecko_profile_interval': 0, 11:56:31 INFO - 'installer_path': 'installer.exe', 11:56:31 INFO - 'log_level': 'info', 11:56:31 INFO - 'log_name': 'talos', 11:56:31 INFO - 'log_to_console': True, 11:56:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:56:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:56:31 INFO - 'opt_config_files': (), 11:56:31 INFO - 'pip_index': False, 11:56:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:56:31 INFO - 'cmd': ('bash', 11:56:31 INFO - '-c', 11:56:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:56:31 INFO - 'enabled': True, 11:56:31 INFO - 'halt_on_failure': False, 11:56:31 INFO - 'name': 'check_screen_resolution'},), 11:56:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:56:31 INFO - 'cmd': ('bash', 11:56:31 INFO - '-c', 11:56:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:56:31 INFO - 'enabled': True, 11:56:31 INFO - 'halt_on_failure': False, 11:56:31 INFO - 'name': 'check_screen_resolution'},), 11:56:31 INFO - 'run_cmd_checks_enabled': True, 11:56:31 INFO - 'suite': 'dromaeojs-e10s', 11:56:31 INFO - 'system_bits': '32', 11:56:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:56:31 INFO - 'title': 't-yosemite-r7-0341', 11:56:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:56:31 INFO - 'use_talos_json': True, 11:56:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:56:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:56:31 INFO - 'work_dir': 'build'} 11:56:31 INFO - [mozharness: 2017-05-26 18:56:31.918471Z] Running clobber step. 11:56:31 INFO - Running pre-action listener: _resource_record_pre_action 11:56:31 INFO - Running main action method: clobber 11:56:31 INFO - rmtree: /builds/slave/test/build 11:56:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:56:34 INFO - Running post-action listener: _resource_record_post_action 11:56:34 INFO - [mozharness: 2017-05-26 18:56:34.334066Z] Finished clobber step (success) 11:56:34 INFO - [mozharness: 2017-05-26 18:56:34.334222Z] Running read-buildbot-config step. 11:56:34 INFO - Running pre-action listener: _resource_record_pre_action 11:56:34 INFO - Running main action method: read_buildbot_config 11:56:34 INFO - Using buildbot properties: 11:56:34 INFO - { 11:56:34 INFO - "project": "", 11:56:34 INFO - "product": "firefox", 11:56:34 INFO - "script_repo_revision": "production", 11:56:34 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 11:56:34 INFO - "repository": "", 11:56:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 11:56:34 INFO - "buildid": "20170526101616", 11:56:34 INFO - "pgo_build": "False", 11:56:34 INFO - "basedir": "/builds/slave/test", 11:56:34 INFO - "buildnumber": 36, 11:56:34 INFO - "slavename": "t-yosemite-r7-0341", 11:56:34 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 11:56:34 INFO - "platform": "yosemite_r7", 11:56:34 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 11:56:34 INFO - "branch": "mozilla-beta", 11:56:34 INFO - "repo_path": "releases/mozilla-beta", 11:56:34 INFO - "revision": "9664d83f5c056a32a3cc29de9d5934b82809dbfd", 11:56:34 INFO - "stage_platform": "macosx64", 11:56:34 INFO - "builduid": "4ca1ed669ad24a59a147e7442a3e4d2f", 11:56:34 INFO - "slavebuilddir": "test" 11:56:34 INFO - } 11:56:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/XazcW0iSTz2e7eUi5pYyWA/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 11:56:34 INFO - Running post-action listener: _resource_record_post_action 11:56:34 INFO - [mozharness: 2017-05-26 18:56:34.337465Z] Finished read-buildbot-config step (success) 11:56:34 INFO - [mozharness: 2017-05-26 18:56:34.337579Z] Running download-and-extract step. 11:56:34 INFO - Running pre-action listener: _resource_record_pre_action 11:56:34 INFO - Running main action method: download_and_extract 11:56:34 INFO - mkdir: /builds/slave/test/build/tests 11:56: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')]} 11:56:34 INFO - trying https://queue.taskcluster.net/v1/task/XazcW0iSTz2e7eUi5pYyWA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 11:56:34 INFO - Downloading https://queue.taskcluster.net/v1/task/XazcW0iSTz2e7eUi5pYyWA/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 11:56:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XazcW0iSTz2e7eUi5pYyWA/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 11:56:35 INFO - Downloaded 1287 bytes. 11:56:35 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 11:56:35 INFO - Using the following test package requirements: 11:56:35 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:56:35 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 11:56:35 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 11:56:35 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:56:35 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 11:56:35 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:56:35 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 11:56:35 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 11:56:35 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:56:35 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 11:56:35 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 11:56:35 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:56:35 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 11:56:35 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:56:35 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 11:56:35 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:56:35 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 11:56:35 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:56:35 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 11:56:35 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 11:56:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XazcW0iSTz2e7eUi5pYyWA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 11:56:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XazcW0iSTz2e7eUi5pYyWA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 11:56:35 INFO - Fetch https://queue.taskcluster.net/v1/task/XazcW0iSTz2e7eUi5pYyWA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 11:56:37 INFO - Expected file size: 51393590 11:56:37 INFO - Obtained file size: 51393590 11:56:52 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 11:56:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XazcW0iSTz2e7eUi5pYyWA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 11:56:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XazcW0iSTz2e7eUi5pYyWA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 11:56:52 INFO - Fetch https://queue.taskcluster.net/v1/task/XazcW0iSTz2e7eUi5pYyWA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 11:56:54 INFO - Expected file size: 51393590 11:56:54 INFO - Obtained file size: 51393590 11:57:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XazcW0iSTz2e7eUi5pYyWA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 11:57:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XazcW0iSTz2e7eUi5pYyWA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 11:57:09 INFO - Fetch https://queue.taskcluster.net/v1/task/XazcW0iSTz2e7eUi5pYyWA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 11:57:10 INFO - Expected file size: 14404449 11:57:10 INFO - Obtained file size: 14404449 11:57:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:10 INFO - trying https://queue.taskcluster.net/v1/task/XazcW0iSTz2e7eUi5pYyWA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 11:57:10 INFO - Downloading https://queue.taskcluster.net/v1/task/XazcW0iSTz2e7eUi5pYyWA/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 11:57:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XazcW0iSTz2e7eUi5pYyWA/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 11:57:12 INFO - Downloaded 59647559 bytes. 11:57:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XazcW0iSTz2e7eUi5pYyWA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 11:57:12 INFO - mkdir: /builds/slave/test/properties 11:57:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:57:12 INFO - Writing to file /builds/slave/test/properties/build_url 11:57:12 INFO - Contents: 11:57:12 INFO - build_url:https://queue.taskcluster.net/v1/task/XazcW0iSTz2e7eUi5pYyWA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 11:57:13 INFO - Running post-action listener: _resource_record_post_action 11:57:13 INFO - Running post-action listener: set_extra_try_arguments 11:57:13 INFO - [mozharness: 2017-05-26 18:57:13.130186Z] Finished download-and-extract step (success) 11:57:13 INFO - [mozharness: 2017-05-26 18:57:13.130345Z] Running populate-webroot step. 11:57:13 INFO - Running pre-action listener: _resource_record_pre_action 11:57:13 INFO - Running main action method: populate_webroot 11:57:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:57:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:57:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:57:13 INFO - '--tppagecycles', 11:57:13 INFO - '7'], 11:57:13 INFO - 'tests': ['tsvgm']}}, 11:57:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:57:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:57:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:57:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:57:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:57:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:57:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:57:13 INFO - 'talos_options': ['--disable-e10s'], 11:57:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:57:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:57:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:57:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:57:13 INFO - 'talos_options': ['--disable-e10s'], 11:57:13 INFO - 'tests': ['damp', 'tps']}, 11:57:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:57:13 INFO - 'tests': ['damp', 'tps']}, 11:57:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:57:13 INFO - 'tests': ['dromaeo_dom']}, 11:57:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:57:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:57:13 INFO - 'tests': ['basic_compositor_video']}, 11:57:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:57:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:57:13 INFO - 'tests': ['a11yr', 11:57:13 INFO - 'ts_paint', 11:57:13 INFO - 'tpaint', 11:57:13 INFO - 'sessionrestore', 11:57:13 INFO - 'sessionrestore_no_auto_restore', 11:57:13 INFO - 'tabpaint']}, 11:57:13 INFO - 'other-e10s': {'tests': ['a11yr', 11:57:13 INFO - 'ts_paint', 11:57:13 INFO - 'tpaint', 11:57:13 INFO - 'sessionrestore', 11:57:13 INFO - 'sessionrestore_no_auto_restore', 11:57:13 INFO - 'tabpaint']}, 11:57:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:57:13 INFO - 'ts_paint', 11:57:13 INFO - 'tpaint', 11:57:13 INFO - 'sessionrestore', 11:57:13 INFO - 'sessionrestore_no_auto_restore', 11:57:13 INFO - 'tabpaint']}, 11:57:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:57:13 INFO - 'ts_paint', 11:57:13 INFO - 'tpaint', 11:57:13 INFO - 'sessionrestore', 11:57:13 INFO - 'sessionrestore_no_auto_restore', 11:57:13 INFO - 'tabpaint']}, 11:57:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:57:13 INFO - 'tests': ['a11yr', 11:57:13 INFO - 'ts_paint', 11:57:13 INFO - 'tpaint', 11:57:13 INFO - 'sessionrestore', 11:57:13 INFO - 'sessionrestore_no_auto_restore', 11:57:13 INFO - 'tabpaint']}, 11:57:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:57:13 INFO - 'tests': ['a11yr', 11:57:13 INFO - 'ts_paint', 11:57:13 INFO - 'tpaint', 11:57:13 INFO - 'sessionrestore', 11:57:13 INFO - 'sessionrestore_no_auto_restore', 11:57:13 INFO - 'tabpaint']}, 11:57:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:57:13 INFO - 'tests': ['tsvgx', 11:57:13 INFO - 'tsvgr_opacity', 11:57:13 INFO - 'tart', 11:57:13 INFO - 'tscrollx', 11:57:13 INFO - 'cart', 11:57:13 INFO - 'tsvg_static']}, 11:57:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:57:13 INFO - 'tsvgr_opacity', 11:57:13 INFO - 'tart', 11:57:13 INFO - 'tscrollx', 11:57:13 INFO - 'cart', 11:57:13 INFO - 'tsvg_static']}, 11:57:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:57:13 INFO - 'talos_options': ['--disable-e10s'], 11:57:13 INFO - 'tests': ['tp5o']}, 11:57:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:57:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:57:13 INFO - 'talos_options': ['--disable-e10s', 11:57:13 INFO - '--xperf_path', 11:57:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:57:13 INFO - 'tests': ['tp5n']}, 11:57:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:57:13 INFO - 'talos_options': ['--xperf_path', 11:57:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:57:13 INFO - 'tests': ['tp5n']}}, 11:57:13 INFO - 'talos.zip': {'path': '', 11:57:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:57:13 INFO - Running post-action listener: _resource_record_post_action 11:57:13 INFO - [mozharness: 2017-05-26 18:57:13.150396Z] Finished populate-webroot step (success) 11:57:13 INFO - [mozharness: 2017-05-26 18:57:13.150498Z] Running create-virtualenv step. 11:57:13 INFO - Running pre-action listener: _resource_record_pre_action 11:57:13 INFO - Running main action method: create_virtualenv 11:57:13 INFO - Creating virtualenv /builds/slave/test/build/venv 11:57:13 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 11:57:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:57:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:57:13 INFO - Using real prefix '/tools/python27' 11:57:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:57:14 INFO - Installing distribute.............................................................................................................................................................................................done. 11:57:16 INFO - Installing pip.................done. 11:57:16 INFO - Return code: 0 11:57:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:57:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:57:17 INFO - Reading from file tmpfile_stdout 11:57:17 INFO - Output received: 11:57:17 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) 11:57:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:57:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d9cdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9d0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7b2d9aa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7abd30>, '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 0x10d6d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7a0be8>, '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.685ygcmxOU/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.HdVGHChWrn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 11:57:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:57:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:57:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.685ygcmxOU/Render', 11:57:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:17 INFO - 'HOME': '/Users/cltbld', 11:57:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:17 INFO - 'LOGNAME': 'cltbld', 11:57:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:17 INFO - 'MOZ_NO_REMOTE': '1', 11:57:17 INFO - 'NO_EM_RESTART': '1', 11:57:17 INFO - 'PAGER': '/bin/cat', 11:57:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:17 INFO - 'PWD': '/builds/slave/test', 11:57:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:17 INFO - 'SHELL': '/bin/bash', 11:57:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HdVGHChWrn/Listeners', 11:57:17 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 11:57:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:17 INFO - 'USER': 'cltbld', 11:57:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:17 INFO - 'XPC_FLAGS': '0x0', 11:57:17 INFO - 'XPC_SERVICE_NAME': '0', 11:57:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:17 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 11:57:17 INFO - Cleaning up... 11:57:17 INFO - Return code: 0 11:57:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:57:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d9cdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9d0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7b2d9aa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7abd30>, '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 0x10d6d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7a0be8>, '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.685ygcmxOU/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.HdVGHChWrn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 11:57:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:57:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:57:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.685ygcmxOU/Render', 11:57:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:17 INFO - 'HOME': '/Users/cltbld', 11:57:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:17 INFO - 'LOGNAME': 'cltbld', 11:57:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:17 INFO - 'MOZ_NO_REMOTE': '1', 11:57:17 INFO - 'NO_EM_RESTART': '1', 11:57:17 INFO - 'PAGER': '/bin/cat', 11:57:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:17 INFO - 'PWD': '/builds/slave/test', 11:57:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:17 INFO - 'SHELL': '/bin/bash', 11:57:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HdVGHChWrn/Listeners', 11:57:17 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 11:57:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:17 INFO - 'USER': 'cltbld', 11:57:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:17 INFO - 'XPC_FLAGS': '0x0', 11:57:17 INFO - 'XPC_SERVICE_NAME': '0', 11:57:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:17 INFO - Downloading/unpacking psutil>=3.1.1 11:57:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:57:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:57:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:57:19 INFO - Installing collected packages: psutil 11:57:19 INFO - Running setup.py install for psutil 11:57:19 INFO - building 'psutil._psutil_osx' extension 11:57:19 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 11:57:20 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 11:57:20 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 11:57:20 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 11:57:20 INFO - building 'psutil._psutil_posix' extension 11:57:20 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 11:57:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:57:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:57:20 INFO - ^ 11:57:20 INFO - 1 warning generated. 11:57:20 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 11:57:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:57:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:57:20 INFO - Successfully installed psutil 11:57:20 INFO - Cleaning up... 11:57:20 INFO - Return code: 0 11:57:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:57: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')]} 11:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57: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')]} 11:57:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d9cdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9d0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7b2d9aa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7abd30>, '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 0x10d6d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7a0be8>, '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.685ygcmxOU/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.HdVGHChWrn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 11:57:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:57:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:57:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.685ygcmxOU/Render', 11:57:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:20 INFO - 'HOME': '/Users/cltbld', 11:57:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:20 INFO - 'LOGNAME': 'cltbld', 11:57:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:20 INFO - 'MOZ_NO_REMOTE': '1', 11:57:20 INFO - 'NO_EM_RESTART': '1', 11:57:20 INFO - 'PAGER': '/bin/cat', 11:57:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:20 INFO - 'PWD': '/builds/slave/test', 11:57:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:20 INFO - 'SHELL': '/bin/bash', 11:57:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HdVGHChWrn/Listeners', 11:57:20 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 11:57:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:20 INFO - 'USER': 'cltbld', 11:57:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:20 INFO - 'XPC_FLAGS': '0x0', 11:57:20 INFO - 'XPC_SERVICE_NAME': '0', 11:57:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:57:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:57:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:57:22 INFO - Installing collected packages: mozsystemmonitor 11:57:22 INFO - Running setup.py install for mozsystemmonitor 11:57:22 INFO - Successfully installed mozsystemmonitor 11:57:22 INFO - Cleaning up... 11:57:22 INFO - Return code: 0 11:57:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:57:22 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')]} 11:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:22 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')]} 11:57:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57: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', '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 0x10d9cdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9d0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7b2d9aa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7abd30>, '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 0x10d6d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7a0be8>, '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.685ygcmxOU/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.HdVGHChWrn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 11:57: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:57: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 jsonschema==2.5.1 11:57:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.685ygcmxOU/Render', 11:57:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:22 INFO - 'HOME': '/Users/cltbld', 11:57:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:22 INFO - 'LOGNAME': 'cltbld', 11:57:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:22 INFO - 'MOZ_NO_REMOTE': '1', 11:57:22 INFO - 'NO_EM_RESTART': '1', 11:57:22 INFO - 'PAGER': '/bin/cat', 11:57:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:22 INFO - 'PWD': '/builds/slave/test', 11:57:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:22 INFO - 'SHELL': '/bin/bash', 11:57:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HdVGHChWrn/Listeners', 11:57:22 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 11:57:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:22 INFO - 'USER': 'cltbld', 11:57:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:22 INFO - 'XPC_FLAGS': '0x0', 11:57:22 INFO - 'XPC_SERVICE_NAME': '0', 11:57:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:22 INFO - Downloading/unpacking jsonschema==2.5.1 11:57: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 11:57: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 11:57: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 11:57: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 11:57:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:57:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:57:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:24 INFO - Downloading functools32-3.2.3-2.tar.gz 11:57:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:57:24 INFO - warning: no files found matching '*.txt' 11:57:24 INFO - no previously-included directories found matching 'build' 11:57:24 INFO - no previously-included directories found matching 'dist' 11:57:24 INFO - no previously-included directories found matching '.git*' 11:57:24 INFO - Installing collected packages: jsonschema, functools32 11:57:24 INFO - Running setup.py install for functools32 11:57:24 INFO - warning: no files found matching '*.txt' 11:57:24 INFO - no previously-included directories found matching 'build' 11:57:24 INFO - no previously-included directories found matching 'dist' 11:57:24 INFO - no previously-included directories found matching '.git*' 11:57:24 INFO - Successfully installed jsonschema functools32 11:57:24 INFO - Cleaning up... 11:57:24 INFO - Return code: 0 11:57:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:57:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:24 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 0x10d9cdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9d0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7b2d9aa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7abd30>, '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 0x10d6d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7a0be8>, '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.685ygcmxOU/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.HdVGHChWrn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 11:57:24 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 11:57:24 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 11:57:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.685ygcmxOU/Render', 11:57:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:25 INFO - 'HOME': '/Users/cltbld', 11:57:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:25 INFO - 'LOGNAME': 'cltbld', 11:57:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:25 INFO - 'MOZ_NO_REMOTE': '1', 11:57:25 INFO - 'NO_EM_RESTART': '1', 11:57:25 INFO - 'PAGER': '/bin/cat', 11:57:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:25 INFO - 'PWD': '/builds/slave/test', 11:57:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:25 INFO - 'SHELL': '/bin/bash', 11:57:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HdVGHChWrn/Listeners', 11:57:25 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 11:57:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:25 INFO - 'USER': 'cltbld', 11:57:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:25 INFO - 'XPC_FLAGS': '0x0', 11:57:25 INFO - 'XPC_SERVICE_NAME': '0', 11:57:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:57:25 INFO - Cleaning up... 11:57:25 INFO - Return code: 0 11:57:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:57:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:25 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 0x10d9cdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9d0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7b2d9aa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7abd30>, '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 0x10d6d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7a0be8>, '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.685ygcmxOU/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.HdVGHChWrn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 11:57:25 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 11:57:25 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 11:57:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.685ygcmxOU/Render', 11:57:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:25 INFO - 'HOME': '/Users/cltbld', 11:57:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:25 INFO - 'LOGNAME': 'cltbld', 11:57:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:25 INFO - 'MOZ_NO_REMOTE': '1', 11:57:25 INFO - 'NO_EM_RESTART': '1', 11:57:25 INFO - 'PAGER': '/bin/cat', 11:57:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:25 INFO - 'PWD': '/builds/slave/test', 11:57:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:25 INFO - 'SHELL': '/bin/bash', 11:57:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HdVGHChWrn/Listeners', 11:57:25 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 11:57:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:25 INFO - 'USER': 'cltbld', 11:57:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:25 INFO - 'XPC_FLAGS': '0x0', 11:57:25 INFO - 'XPC_SERVICE_NAME': '0', 11:57:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:25 INFO - Downloading/unpacking blobuploader==1.2.4 11:57:25 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 11:57:25 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 11:57:25 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 11:57:25 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 11:57:27 INFO - Downloading blobuploader-1.2.4.tar.gz 11:57:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:57:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:57:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:28 INFO - Downloading docopt-0.6.1.tar.gz 11:57:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:57:28 INFO - Installing collected packages: blobuploader, requests, docopt 11:57:28 INFO - Running setup.py install for blobuploader 11:57:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:57:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:57:28 INFO - Running setup.py install for requests 11:57:28 INFO - Running setup.py install for docopt 11:57:29 INFO - Successfully installed blobuploader requests docopt 11:57:29 INFO - Cleaning up... 11:57:29 INFO - Return code: 0 11:57:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:57:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:29 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 0x10d9cdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9d0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7b2d9aa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7abd30>, '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 0x10d6d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7a0be8>, '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.685ygcmxOU/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.HdVGHChWrn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 11:57:29 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 11:57:29 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 11:57:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.685ygcmxOU/Render', 11:57:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:29 INFO - 'HOME': '/Users/cltbld', 11:57:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:29 INFO - 'LOGNAME': 'cltbld', 11:57:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:29 INFO - 'MOZ_NO_REMOTE': '1', 11:57:29 INFO - 'NO_EM_RESTART': '1', 11:57:29 INFO - 'PAGER': '/bin/cat', 11:57:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:29 INFO - 'PWD': '/builds/slave/test', 11:57:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:29 INFO - 'SHELL': '/bin/bash', 11:57:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HdVGHChWrn/Listeners', 11:57:29 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 11:57:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:29 INFO - 'USER': 'cltbld', 11:57:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:29 INFO - 'XPC_FLAGS': '0x0', 11:57:29 INFO - 'XPC_SERVICE_NAME': '0', 11:57:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:57:29 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-_Zv7Vr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:57:29 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-4UsPsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:57:30 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-s_MEQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:57:30 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-YCE01E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:57:30 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-VkGW2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:57:30 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-vfHDqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:57:30 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-EXsCFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:57:30 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-B6IwNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:57:30 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-CsLMUc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:57:30 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-V5msZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:57:30 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-PWpHxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:57:30 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-D4KrUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:57:31 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-nzNdxG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:57:31 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-N4cvnw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:31 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-8Ehdu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:57:31 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-BBnBuP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:57:31 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-9Qxwjf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:57:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:57:31 INFO - Running setup.py install for manifestparser 11:57:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:57:31 INFO - Running setup.py install for mozcrash 11:57:31 INFO - Running setup.py install for mozdebug 11:57:31 INFO - Running setup.py install for mozdevice 11:57:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:57:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:57:32 INFO - Running setup.py install for mozfile 11:57:32 INFO - Running setup.py install for mozhttpd 11:57:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:57:32 INFO - Running setup.py install for mozinfo 11:57:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:57:32 INFO - Running setup.py install for mozInstall 11:57:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:57:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:57:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:57:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:57:32 INFO - Running setup.py install for mozleak 11:57:32 INFO - Running setup.py install for mozlog 11:57:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:57:33 INFO - Running setup.py install for moznetwork 11:57:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:57:33 INFO - Running setup.py install for mozprocess 11:57:33 INFO - Running setup.py install for mozprofile 11:57:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:57:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:57:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:57:33 INFO - Running setup.py install for mozrunner 11:57:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:57:33 INFO - Running setup.py install for mozscreenshot 11:57:33 INFO - Running setup.py install for moztest 11:57:34 INFO - Running setup.py install for mozversion 11:57:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:57:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:57:34 INFO - Cleaning up... 11:57:34 INFO - Return code: 0 11:57:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:57: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')]} 11:57:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57: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')]} 11:57:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:34 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 0x10d9cdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9d0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7b2d9aa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7abd30>, '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 0x10d6d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7a0be8>, '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.685ygcmxOU/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.HdVGHChWrn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 11:57:34 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 11:57:34 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 11:57:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.685ygcmxOU/Render', 11:57:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:34 INFO - 'HOME': '/Users/cltbld', 11:57:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:34 INFO - 'LOGNAME': 'cltbld', 11:57:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:34 INFO - 'MOZ_NO_REMOTE': '1', 11:57:34 INFO - 'NO_EM_RESTART': '1', 11:57:34 INFO - 'PAGER': '/bin/cat', 11:57:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:34 INFO - 'PWD': '/builds/slave/test', 11:57:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:34 INFO - 'SHELL': '/bin/bash', 11:57:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HdVGHChWrn/Listeners', 11:57:34 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 11:57:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:34 INFO - 'USER': 'cltbld', 11:57:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:34 INFO - 'XPC_FLAGS': '0x0', 11:57:34 INFO - 'XPC_SERVICE_NAME': '0', 11:57:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:57:34 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-lJ6vEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:57:34 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)) 11:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:57:34 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-DSn_cx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:57:34 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)) 11:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:57:34 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-l9p9Wa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:57:34 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)) 11:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:57:34 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-r52g8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:57:34 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)) 11:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:57:34 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-fTAptn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:57:34 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)) 11:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:57:34 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-pokTWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:57:35 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)) 11:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:57:35 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-PAe1WF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:57:35 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)) 11:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:57:35 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-vyx8MU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:57:35 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)) 11:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:57:35 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-W_9BDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:57:35 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)) 11:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:57:35 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-1lj6Hr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:57:35 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)) 11:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:57:35 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-pF9S4Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:57:35 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)) 11:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:57:35 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-NOEbG0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:57:35 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)) 11:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:57:35 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-hiuhSv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:57:35 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)) 11:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:57:35 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-PtiKgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:57:35 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)) 11:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:35 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-toKG11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:36 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)) 11:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:57:36 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-bcXWcA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:57:36 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)) 11:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:57:36 INFO - Running setup.py (path:/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/pip-DZ0YnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:57:36 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)) 11:57:36 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)) 11:57:36 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)) 11:57:36 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)) 11:57:36 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)) 11:57:36 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)) 11:57:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:57:36 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 11:57:36 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 11:57:36 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 11:57:36 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 11:57:37 INFO - Downloading blessings-1.6.tar.gz 11:57:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:57:37 INFO - Installing collected packages: blessings 11:57:37 INFO - Running setup.py install for blessings 11:57:37 INFO - Successfully installed blessings 11:57:37 INFO - Cleaning up... 11:57:37 INFO - Return code: 0 11:57:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:57:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:57:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:57:38 INFO - Reading from file tmpfile_stdout 11:57:38 INFO - Current package versions: 11:57:38 INFO - blessings == 1.6 11:57:38 INFO - blobuploader == 1.2.4 11:57:38 INFO - docopt == 0.6.1 11:57:38 INFO - functools32 == 3.2.3-2 11:57:38 INFO - jsonschema == 2.5.1 11:57:38 INFO - manifestparser == 1.1 11:57:38 INFO - mozInstall == 1.12 11:57:38 INFO - mozcrash == 1.0 11:57:38 INFO - mozdebug == 0.1 11:57:38 INFO - mozdevice == 0.49 11:57:38 INFO - mozfile == 1.2 11:57:38 INFO - mozhttpd == 0.7 11:57:38 INFO - mozinfo == 0.9 11:57:38 INFO - mozleak == 0.1 11:57:38 INFO - mozlog == 3.4 11:57:38 INFO - moznetwork == 0.27 11:57:38 INFO - mozprocess == 0.25 11:57:38 INFO - mozprofile == 0.28 11:57:38 INFO - mozrunner == 6.13 11:57:38 INFO - mozscreenshot == 0.1 11:57:38 INFO - mozsystemmonitor == 0.3 11:57:38 INFO - moztest == 0.8 11:57:38 INFO - mozversion == 1.4 11:57:38 INFO - psutil == 3.1.1 11:57:38 INFO - requests == 1.2.3 11:57:38 INFO - wsgiref == 0.1.2 11:57:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:57: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')]} 11:57:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57: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')]} 11:57:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:38 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 0x10d9cdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9d0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7b2d9aa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7abd30>, '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 0x10d6d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7a0be8>, '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.685ygcmxOU/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.HdVGHChWrn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 11:57:38 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 11:57:38 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 11:57:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.685ygcmxOU/Render', 11:57:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:38 INFO - 'HOME': '/Users/cltbld', 11:57:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:38 INFO - 'LOGNAME': 'cltbld', 11:57:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:38 INFO - 'MOZ_NO_REMOTE': '1', 11:57:38 INFO - 'NO_EM_RESTART': '1', 11:57:38 INFO - 'PAGER': '/bin/cat', 11:57:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:38 INFO - 'PWD': '/builds/slave/test', 11:57:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:38 INFO - 'SHELL': '/bin/bash', 11:57:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HdVGHChWrn/Listeners', 11:57:38 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 11:57:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:38 INFO - 'USER': 'cltbld', 11:57:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:38 INFO - 'XPC_FLAGS': '0x0', 11:57:38 INFO - 'XPC_SERVICE_NAME': '0', 11:57:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:38 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)) 11:57:38 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)) 11:57:38 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)) 11:57:38 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)) 11:57:38 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)) 11:57:38 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)) 11:57:38 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)) 11:57:38 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)) 11:57:38 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)) 11:57:38 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)) 11:57:38 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)) 11:57:38 INFO - Cleaning up... 11:57:38 INFO - Return code: 0 11:57:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:57: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')]} 11:57:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57: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')]} 11:57:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:38 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 0x10d9cdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9d0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7b2d9aa20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d7abd30>, '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 0x10d6d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7a0be8>, '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.685ygcmxOU/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.HdVGHChWrn/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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 11:57:38 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 11:57:38 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 11:57:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.685ygcmxOU/Render', 11:57:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:38 INFO - 'HOME': '/Users/cltbld', 11:57:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:38 INFO - 'LOGNAME': 'cltbld', 11:57:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:38 INFO - 'MOZ_NO_REMOTE': '1', 11:57:38 INFO - 'NO_EM_RESTART': '1', 11:57:38 INFO - 'PAGER': '/bin/cat', 11:57:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:38 INFO - 'PWD': '/builds/slave/test', 11:57:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:38 INFO - 'SHELL': '/bin/bash', 11:57:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HdVGHChWrn/Listeners', 11:57:38 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 11:57:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:38 INFO - 'USER': 'cltbld', 11:57:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:38 INFO - 'XPC_FLAGS': '0x0', 11:57:38 INFO - 'XPC_SERVICE_NAME': '0', 11:57:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:57:38 INFO - Cleaning up... 11:57:38 INFO - Return code: 0 11:57:38 INFO - Running post-action listener: _resource_record_post_action 11:57:38 INFO - Running post-action listener: _start_resource_monitoring 11:57:38 INFO - Starting resource monitoring. 11:57:38 INFO - [mozharness: 2017-05-26 18:57:38.801145Z] Finished create-virtualenv step (success) 11:57:38 INFO - [mozharness: 2017-05-26 18:57:38.802118Z] Running install step. 11:57:38 INFO - Running pre-action listener: _resource_record_pre_action 11:57:38 INFO - Running main action method: install 11:57:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:57:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:57:39 INFO - Reading from file tmpfile_stdout 11:57:39 INFO - Detecting whether we're running mozinstall >=1.0... 11:57:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:57:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:57:39 INFO - Reading from file tmpfile_stdout 11:57:39 INFO - Output received: 11:57:39 INFO - Usage: mozinstall [options] installer 11:57:39 INFO - Options: 11:57:39 INFO - -h, --help show this help message and exit 11:57:39 INFO - -d DEST, --destination=DEST 11:57:39 INFO - Directory to install application into. [default: 11:57:39 INFO - "/builds/slave/test"] 11:57:39 INFO - --app=APP Application being installed. [default: firefox] 11:57:39 INFO - mkdir: /builds/slave/test/build/application 11:57:39 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'] 11:57:39 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 11:57:53 INFO - Reading from file tmpfile_stdout 11:57:53 INFO - Output received: 11:57:53 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 11:57:53 INFO - Running post-action listener: _resource_record_post_action 11:57:53 INFO - [mozharness: 2017-05-26 18:57:53.835834Z] Finished install step (success) 11:57:53 INFO - [mozharness: 2017-05-26 18:57:53.835976Z] Running run-tests step. 11:57:53 INFO - Running pre-action listener: _resource_record_pre_action 11:57:53 INFO - Running main action method: run_tests 11:57:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:57:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:57:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:57:53 INFO - 2017-05-26 11:57:53.850 screenresolution[1621:7261] starting screenresolution argv=screenresolution get 11:57:53 INFO - 2017-05-26 11:57:53.853 screenresolution[1621:7261] Display 0: 1600x1200x32@60 11:57:53 INFO - 2017-05-26 11:57:53.858 screenresolution[1622:7263] starting screenresolution argv=screenresolution list 11:57:53 INFO - Available Modes on Display 0 11:57:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:57:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:57:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:57:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:57:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:57:54 INFO - Intel Iris: 11:57:54 INFO - Chipset Model: Intel Iris 11:57:54 INFO - Type: GPU 11:57:54 INFO - Bus: Built-In 11:57:54 INFO - VRAM (Dynamic, Max): 1536 MB 11:57:54 INFO - Vendor: Intel (0x8086) 11:57:54 INFO - Device ID: 0x0a2e 11:57:54 INFO - Revision ID: 0x0009 11:57:54 INFO - Displays: 11:57:54 INFO - Display: 11:57:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:57:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:57:54 INFO - Display Serial Number: 1600x1200 60 11:57:54 INFO - Main Display: Yes 11:57:54 INFO - Mirror: Off 11:57:54 INFO - Online: Yes 11:57:54 INFO - Rotation: Supported 11:57:54 INFO - Return code: 0 11:57:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:57:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:57:54 INFO - Python 2.7.3 11:57:54 INFO - Return code: 0 11:57:54 INFO - grabbing minidump binary from tooltool 11:57:54 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')]} 11:57:54 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 0x10d7abd30>, '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 0x10d6d2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d7a0be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:57:54 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 11:57:54 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 11:57:54 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 11:57:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:57:54 INFO - Return code: 0 11:57:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:57:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:57:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:57:54 INFO - ENV: RUST_BACKTRACE is now 1 11:57:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:57:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:57:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XazcW0iSTz2e7eUi5pYyWA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0341', '--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 11:57:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XazcW0iSTz2e7eUi5pYyWA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0341 --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 11:57:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.685ygcmxOU/Render', 11:57:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:54 INFO - 'HOME': '/Users/cltbld', 11:57:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:54 INFO - 'LOGNAME': 'cltbld', 11:57:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:57:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:57:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:54 INFO - 'MOZ_NO_REMOTE': '1', 11:57:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:57:54 INFO - 'NO_EM_RESTART': '1', 11:57:54 INFO - 'PAGER': '/bin/cat', 11:57:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:54 INFO - 'PWD': '/builds/slave/test', 11:57:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:57:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:54 INFO - 'RUST_BACKTRACE': '1', 11:57:54 INFO - 'SHELL': '/bin/bash', 11:57:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HdVGHChWrn/Listeners', 11:57:54 INFO - 'TMPDIR': '/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/', 11:57:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:54 INFO - 'USER': 'cltbld', 11:57:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:54 INFO - 'XPC_FLAGS': '0x0', 11:57:54 INFO - 'XPC_SERVICE_NAME': '0', 11:57:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XazcW0iSTz2e7eUi5pYyWA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0341', '--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 11:57:54 INFO - mozversion application_buildid: 20170526101616 11:57:54 INFO - mozversion application_changeset: 9664d83f5c056a32a3cc29de9d5934b82809dbfd 11:57:54 INFO - mozversion application_display_name: Firefox 11:57:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:57:54 INFO - mozversion application_name: Firefox 11:57:54 INFO - mozversion application_remotingname: firefox 11:57:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 11:57:54 INFO - mozversion application_vendor: Mozilla 11:57:54 INFO - mozversion application_version: 54.0 11:57:54 INFO - mozversion platform_buildid: 20170526101616 11:57:54 INFO - mozversion platform_changeset: 9664d83f5c056a32a3cc29de9d5934b82809dbfd 11:57:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 11:57:54 INFO - mozversion platform_version: 54.0 11:57:54 INFO - using testdate: 1495825074 11:57:54 INFO - actual date: 1495825074 11:57:54 INFO - starting webserver on 'localhost:49219' 11:57:54 INFO - SUITE-START | Running 2 tests 11:57:54 INFO - TEST-START | dromaeo_css 11:57:54 INFO - operating with platform_type : mac_ 11:57:54 INFO - Initialising browser for dromaeo_css test... 11:57:54 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/tmpNQRjEJ/profile http://localhost:49219/getInfo.html) 11:57:57 INFO - PROCESS | 1632 | 2017-05-26 11:57:57.173 plugin-container[1635:7437] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c43, name = 'com.apple.tsm.portname' 11:57:57 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:57:57 INFO - PROCESS | 1632 | 2017-05-26 11:57:57.174 plugin-container[1635:7437] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c07, name = 'com.apple.CFPasteboardClient' 11:57:57 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:57:57 INFO - PROCESS | 1632 | 2017-05-26 11:57:57.174 plugin-container[1635:7437] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:57:57 INFO - PROCESS | 1632 | __metrics Screen width/height:1600/1200 11:57:57 INFO - PROCESS | 1632 | colorDepth:24 11:57:57 INFO - PROCESS | 1632 | Browser inner width/height: 1024/689 11:57:57 INFO - PROCESS | 1632 | __metrics 11:57:57 ERROR - PROCESS | 1632 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 11:57:57 INFO - TEST-INFO | 1632: exit 0 11:57:57 INFO - Browser initialized. 11:57:57 INFO - Running cycle 1/1 for dromaeo_css test... 11:57:57 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/tmpNQRjEJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:58:00 INFO - PROCESS | 1636 | 2017-05-26 11:58:00.243 plugin-container[1637:7576] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 11:58:00 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:58:00 INFO - PROCESS | 1636 | 2017-05-26 11:58:00.244 plugin-container[1637:7576] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8917, name = 'com.apple.CFPasteboardClient' 11:58:00 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:58:00 INFO - PROCESS | 1636 | 2017-05-26 11:58:00.244 plugin-container[1637:7576] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:58:09 INFO - PROCESS | 1636 | RSS: Main: 174456832 11:58:09 INFO - PROCESS | 1636 | 12:01:13 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49219/tests/dromaeo/cssquery-ext.html) 12:01:14 INFO - PROCESS | 1636 | RSS: Main: 194461696 12:01:14 INFO - PROCESS | 1636 | 12:04:17 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-ext.html (next: http://localhost:49219/tests/dromaeo/cssquery-jquery.html) 12:04:17 INFO - PROCESS | 1636 | RSS: Main: 203034624 12:04:17 INFO - PROCESS | 1636 | 12:07:19 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49219/tests/dromaeo/cssquery-mootools.html) 12:07:20 INFO - PROCESS | 1636 | RSS: Main: 205619200 12:07:20 INFO - PROCESS | 1636 | 12:10:22 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49219/tests/dromaeo/cssquery-prototype.html) 12:10:22 INFO - PROCESS | 1636 | RSS: Main: 208384000 12:10:22 INFO - PROCESS | 1636 | 12:13:25 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49219/tests/dromaeo/cssquery-yui.html) 12:13:26 INFO - PROCESS | 1636 | RSS: Main: 211734528 12:13:26 INFO - PROCESS | 1636 | 12:16:29 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-yui.html (next: http://localhost:49219/tests/dromaeo/cssquery-dojo.html) 12:16:29 INFO - PROCESS | 1636 | RSS: Main: 209870848 12:16:29 INFO - PROCESS | 1636 | 12:16:29 INFO - PROCESS | 1636 | __start_tp_report 12:16:29 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 12:16:29 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 12:16:29 INFO - PROCESS | 1636 | |i|pagename|runs| 12:16:29 INFO - PROCESS | 1636 | |0;dojo.html;3108.9689103108967;3602.531670882785;3612.6026137124927;3618.4210526315796;3636.745427820052;6120.357362476937;6146.170267013952;6159.353267906872;6184.659843708594;6196.411340922617;5945.583809133363;6182.474489668383;6193.659348735818;6211.875762484748;6253.062040693898;5550.500454959053;5584.497395234428;5638.210650508932;5665;5724.1986121942755;5691.8292451226525;5714.9714251428895;5734.569907256951;5735;5740.942590574104;5459.781608735666;5478.616496845222;5689.743961521742;5695.14572814077;5710.200571919934;5585.8603534911745;5605.747741351608;5626.521745651625;5639.379668236532;5652.63257888236;5488.862778430531;5633.690147041911;5636.267285252932;5639.464250896158;5697.658140511541;5641.26663533738;6047.516198704137;6234.971229747086;6253.1245625612455;6256.343083976162;6393.296737358887;6417.711202995876;6423.293437721847;6483.870322593569;6486.870262594769;6716;6757.695903684346;6758.087658166134;6762.855272298356;6763.492738044617;6278.560500764903;6306.054091886208;6310.905336419957;6316.905246421262;6330.841728956767;5599.832005039855;6427.3251308611925;6479.870402591921;6498.675066246668;6721.596704197764;6366.681665916685;6383.585066970725;6451.709673064724;6470.223573171155;6474.902876456857;6631.30371311015;6651.501137414713;6668.366505182097;6672.86654266912;6677.432418244406;6249.437550620466;6288.465480434214;6546.443552298013;6556.901646475307;6576.967115164393;5369.301990741179;6354.491640668828;6444.7744328948265;6486.3837935397005;6560.901586476207;5154.613403994716;5192.688438693615;5266.4470230626;5306.177542480922;5314.468553144655;4300.64529673152;4351.630111440563;4711.552402521755;4727.645426592951;4751.548602882721;5164.870878227999;6323.20959880015;6361;6382.297947225801;6386.16979792624;6373.458256048195;6427.871442571216;6584.5061620378665;6595.604263744191;6689.665516724144;6258;6305.369463053749;6318.526110541636;6337.746490140343;6368.267649220306;4879.194932836113;5788.88422231553;5824.359320474744;5833.50415214703;5879;6520;6531.771388001397;6778.152730908637;6789.694463749242;6904.758333458305;6318.4313411793155;6660.100886380276;6710.496712746662;6711.463082953254;6727.495437842083;6826.317368263134;6878.27778083314;6890.786409506132;6918.066061081689;6925.792226233221;5371.4897084776885;6308.27454842699;6346.9682651586445;6463.095166676763;6580.5722628029025;6647.7008534617025;6686.598804071771;6688.030235615711;6699.7990060296315;6715.630640314635;5162.406323272871;6390.7763228286785;6458.580192287486;6469.7412103515335;6559.147310849559;6261.186045814015;6306.148669929501;6363.809085727436;6540.2151741789385;6556.016597510412;4963.429205641398;6137.714085983886;6304.432601065742;6338.049292606146;6351.68241587928;6431.067495213074;6448;6469.4824414045825;6471.029345598198;6490.9675451624325;6181.072839074175;6222;6271.686415679289;6280.623162610258;6338.397852204033 12:16:29 INFO - PROCESS | 1636 | |1;ext.html;786.7993661616287;833.2375876073393;845.7547311279727;911.5715613661577;995.8107959487701;38606.80696596517;39188.60811391885;40679.59320406795;40700.57547985821;40767.388489172685;39959.40060899089;40163;40172.19655606886;40173.19653606926;40176.59823401765;8301.54341511218;8439.535825529594;11438.542458301657;11701.707457313592;11898.8215176772;11621.651350459457;11704.18070735049;11721.76921423251;11721.882781172208;11737.765244695102;11706.824397634042;11719.648410547697;11725.061995040376;11737.11971602129;11783.469743861504;11620.883791162065;11625.651230463058;11649.534018639286;11666.475008624548;11679.124065695021;11331.150163737753;11384.71538211543;11400.030997365235;11406.258593191416;11409.42952852354;35962.82018589917;36147.81926090379;36223.73216937394;36261;36339.63660363389;3948.940765888514;3967.7817720025387;3991.6806655467494;4020.3768415895584;4026.355783074723;22596;25016.749832501806;26386.604200937003;26622.201333960013;28052.719472805216;3179.9371790397413;3245.8539365728598;3319.186799234179;3329.184349834282;3441.2429265561536;372836;375213;375498;376030;378554.99999999726;46838;46875;46930.76534617305;46986.82532936636;46990;48066.03867922622;48244.03511929742;48246.5175348249;48294.03411931742;48337.7915552114;14807;14940.850591494163;14949.252537373088;14955.925220374045;14990.550283491513;12833.614991550268;12845.550405735941;12851.871481285254;12858.357082145856;12873.678158046123;11729.35488548138;12368.134230603951;12396.690082747824;12411.131220814455;12479.376031198404;4593.874500747338;5693.202951586781;5747.511461525817;5756.309242890796;5773.509251713652;1007.37039350406;1116.391566596207;1126.7352172239682;1131.896401009026;1151.1481503687212;26336.8683156583;27724.168274951782;27941.580876286873;28176.71823281741;28227;1237.4926280225066;1280.3662187217387;1298.8245637697898;1303.3483258370813;1331.8068880012345;14362;14412.855871441361;14632.341544630517;14646.194459304628;14700.852991469948;446993;447918;449238;449538;450021;48681;50125.24812127821;58610;59101.113483297784;59173;1481.126135580013;1508.751056075757;1518.1574226304285;1526.9694606107817;1534.048889688378;31014.379712405174;31601.683983159874;31615;31748;32230.516542251415;48871;49069.50930490792;49136.75431622819;49200.75399622979;49348;437942;440275;441169;441644;441791;33043;33602.49596256009;33732.83133584316;33734.83132584322;33781.155471113416;45679;45716.085678286916;46768;46979.53020469751;47038;30798.538021930137;30969.225769355948;31178.064658060295;31989.68010319867;32241.51637725483;30755.53866692046;31501.369972600878;31706.365872681956;31730.52404213985;31899.84050079735;37025.25949481049;37742;37975.86072417832;38075.80962095172;38080.80959595184;24376.390590235387;26771;30104.946326878453;30691.386172275983;31141.68858311388;22890.885545572164;23822.404439889142;24158.154464593656;24509.019639214945;24710.752892470846 12:16:29 INFO - PROCESS | 1636 | |2;jquery.html;379.62227583554363;422.4212828425056;423.7796345900134;440.79723327269465;463.2981033733894;20591.897040514796;21026;22333.218337358197;22481.325560233177;22569.435764105907;21777.128914843426;22180;22440.55118897621;22868.65697014546;23102.537949241007;6727.394534491919;6828.6244256566;7025.156981162268;7053.400460960825;7074.327938845801;6714.865702685944;6862.725490980355;6904.654767261617;6912.135983002125;6914.965425172867;6631.635260060719;6866.931330686704;6870.3473170048765;6880.483963702717;6932.965335173317;6818.352256535622;6830;6846.931530684729;6911.758088466905;6913.89629155563;6380.596268820852;6726.125603671491;6754.5947243165565;6762.966185169092;6765.661716914135;20491.180352785872;26178.73821261782;26335.604965925526;26567;27356.6321683915;3062.601861757957;3086.42901063304;3110.12916383413;3118.656947735747;3139.0582825152364;16318.510444686559;16893.493195204042;16893.83106168935;16990.83009169905;17347.04591247481;1828.7988321284647;1864.820441300994;1866.9626607467774;1871.6069625378686;2118.4386137673528;96089.40211590283;113307.43346283215;131476;138940.30529847386;139223;21189.576208475744;21704.56590868174;21853.344399667716;21969.67045494319;22004.00981955816;33239.50140747891;33393.83303083469;33801.66198338034;33935.15162120967;34011.48982765261;10964.28732132409;11254.268472549258;11385.886141138648;11389.772204555862;11479.540818367172;9282.814343713222;9285.535723213812;9299.535023248945;9437.480938548308;9470.384425012353;8417.494950303002;9429.245660347156;9471.431714097038;10133.2906696532;10204.897951020395;3843.9813449435924;3910.7066969976795;4238.19474299882;4250.42619246404;4257.340112282601;1015.5734591471452;1065.0680654427376;1074.9301295415773;1111.3443068589409;1118.021102648308;19682.21271149138;21354.140649196397;22173;22945.655815162787;23041;1154.821002744576;1189.4290740444635;1194.4804010255566;1196.2882085159317;1210.5278941212935;11638.88361116378;11665.24175928562;12020.939895300467;12034.699132521757;12111.212771169845;771639;811034;817268;822840;842201;99932;126740;131179;134225;140754;1338.1770211319968;1356.51165580391;1371.9176849388869;1383.0871624727833;1383.8408583013083;14297.999140060512;19289.614207716048;19984;20012.399628011182;20023.09896054652;56599;57124.71437642952;57287.85424291407;57321;57748.422515774306;69083.96374054493;73391;75863.86204207051;76659.2334076652;77852.2214777845;10006.799864002825;11465.910738479832;11500.94249528747;11752.823707644564;11830.17188796776;12014.33921134346;12095.57665481704;12215.938920305342;12316.692082698004;13059.869401306245;8618.78453038679;11061.889381106086;11079.50142243585;11323.660290191307;11336;10300.690979270634;10405.687829365132;10448.373097614167;10587.36475811423;10751.677449676523;11304.49129789145;11487.712807179887;11566.248193867372;11678.24091434054;11689.298642081503;11557.42212889369;11634.751138419946;11836.822447663464;11936.761264774483;11942.164048516868;11003.724906877393;11103.056240219672;11129.054030407182;11363.659090227306;11455.369954652575 12:16:29 INFO - PROCESS | 1636 | |3;mootools.html;1589.4039735099338;1605.4862444017904;1656.3126302584428;1665.442076904237;1668.8831781775277;9747.561359738811;9844.966278540758;10192.133668638156;11058.723531911695;11749.118816088807;11042.503087361069;11427.657170284883;11437.714057148554;11444.084473242141;11454.140939429535;2452.9632055519182;2497.08856267462;2498.0007996801332;2500.03748556806;2505.7243703192635;2418.6855708757835;2469.1111199967995;2474.5793215153376;2475.0223661653636;2477.4054226985572;2443.6945381827272;2454.6931633545805;2467.2613399126953;2474.7772700456953;2483.627455881614;2409.7469765674614;2414.2274472168915;2418.7581241875846;2421.5641184586766;2426.0174629274993;2392.964105538418;2403.627437747152;2410.927672169838;2411.638254261875;2436.281297017384;9550.808983820285;11456.4844581994;11595.362255075968;11646.592369267035;11689.532418703242;5069;5873.383294754032;6038.064100064497;6084.391560843925;6088.543359248074;3016.653084895221;3590.7127429805555;3600.009997250751;3643.744937854351;3670.7797532148156;1242.8508578970582;1255.6484184428375;1290.219417252558;1297.9156354836905;1313.5336955380799;16198;16273.186340683036;16273.430429935013;16287.348506059743;16299.592510187222;4904.215325547895;4980.452150263468;5020.924686129711;5051.87370315745;5086.287919691246;13608.38762255701;13662.863371366358;13681.931590342183;13685.79471307931;13702.314884255748;5672.035753921843;6102.0541816018585;6102.145699601971;6102.450779429784;6115.449609535163;5276.551493123051;5393.4336894626285;5408.080626293539;5458.781648734085;5462.262594549765;4084.8774536763945;4160.8335666573;4162.521310049323;4212.136512015029;4236.724612900151;1144.8339990701468;1148.62095508482;1148.8741033786935;1160.460385920551;1163.988360116388;948.4262021477045;965.8840939087214;976.9364991275544;993.5777294649745;998.4673526137344;6531.640759758259;6581.506387020992;6675.332466753286;6800.829979250559;6852.931470685329;994.7115336552464;1023.0945613132283;1027.2347101409348;1035.5443604813859;1053.947302634865;3526.682598566141;4030.496187976503;4135.545090040093;4242.257604919188;4260.893477663021;11442.313461192189;13523.932380338036;13884.236366999912;14017.719645607234;14082.295885205698;12333.629991100102;13655.658608534866;13978.231197284247;14008.089474184146;14023;10390.376577405379;10700.625478108228;10738.94630526858;10751.83872241917;10778.999999999844;6635.170603674541;6836;7010.754623588354;7047.894281585678;7093.858122837618;11708.473118709508;12800.16798908068;12827.166234194747;12829.358532073546;12835.229886206484;12880.42038108306;13142.737145257233;13201.735965280832;13286.136401134281;13396.732065358834;9602.039796020343;9642.373245739005;9807.9019209807;9816.754581135528;9822.5088745561;10669.03978641926;10676.946615266874;10681.092107170978;10773.138148948223;10807.459627018774;9055.773605659911;9110.31672624569;9195;9230.861537076815;9370.344075914893;8969.461832290084;8981.775455613662;9035.232005279624;9060.54697265121;9078.31912606544;7929.246721561442;8108.797280068045;8112.10766815638;8134.918650813416;8150.2257285560145;5886;5953.434423729739;6007.7596896125415;6143.1706719592285;6168;5179.171332586768;5289.285946397187;5451.046066938191;5724.8282551523525;6045 12:16:29 INFO - PROCESS | 1636 | |4;prototype.html;396.71238352194683;397.906491974386;402.5893588539688;403.3667142586139;404.1795155833658;4599.94201333693;5373.065696349311;5386.865328366788;5455.727213639322;5465.672059676413;4576.039031803325;5042.647014708972;5324.174752913295;5328.495607064683;5356.848277620311;3806.410006449005;3822.7706337619693;4184.8744537663915;4201.138766552849;4293.570642935705;3438.2435864109857;3729.682976946949;3733.5893051764283;4263.594958478955;4350.36919646651;3945.467361906149;3978.9403158952637;4187.68592355573;4198.887294866863;4228.323468245081;4072.3891416287497;4326.870193894189;4341.263494602184;4364.781760911942;4418.97790511047;3456.7580269381156;4016.4778578784862;4020.6783457323345;4021.879343619696;4022.2905265262148;4457.3091170868565;4795.944892123727;5070.6957582545165;5161.2193793141305;5183.9632073585435;4690.833687261085;4725.3384526166365;4833.758312084382;5042.092423363793;5169.457206993249;4593.977030114828;4901.681390709628;4987.202047672355;5421.566274698054;5589.456797157283;3954.429620746509;3965.6232657897453;4210.842018444921;4389.868303950887;4446.622037126848;4808.206645903421;4875.756212189412;5231.677884543266;5571.331440227158;5663.9775378652885;4002.95775210836;4230.471191101113;4312.749302702181;4514.70654407469;4620.699654522446;4636.721796692209;4742.264948932921;4762.499937506581;4780.422460587997;4829.975850120797;4159.667226621863;4813.855584332476;4998.175301075355;5138.511841375138;5159.329287192641;5276.604254680914;5415.702136382458;5468.19549548648;5582.911332290246;5794.159846822272;1339.205464677337;1400.7828786538103;1464.3933797839861;1506.7513860213162;1625.5692241555996;989.9455529945922;1125.6735546691532;1218.4029825385496;1222.5904322052213;1229.5880879905333;4935.605151587864;5359.490848369399;5488.807891723771;5504.339479262434;5519.724013799294;5023.39719233687;5132.384113906281;5381.251093394662;5907.320658124285;5907.911381329283;4656.696125084982;4661.951061011246;5105.412877519058;5310.203469479609;5358.838285411626;2927.429151315473;2987.386811122013;3090.5364195370425;3107.9689203107678;3111.2688518198206;5759.568032397503;5766.971165144231;5795.420457954171;5973.402659733992;6157.692115394212;5308.593867090306;5626.959012582685;5930.789139710768;6071.3928607139815;6081.817545473643;5037.697738135724;5583.832485025456;5591.228192811995;5601.795613943022;5781.71091445426;5599.32808063035;5862.537734151581;5877.647341159544;5983.970080149571;6031.73809821317;5063.493650634907;5831.7375718091935;5935.2877654681715;5985.162077309267;6005.39946005396;4911.607071434205;5368.409474957673;5474.386904642916;6215.378462153929;6303.9684801575695;3944.36890097583;4265.552117027667;4563.064571762847;4674.76626168697;4755.812326329927;4546.136234115507;4782.0829294131245;5472.151816468454;5852.707364631666;5925.4370834770625;5123.28274041627;5470.72646367688;5838.591298609226;5870.387677483479;5889.823305300848;5667.8866422672145;5730.587447665358;5748.712564371681;5858.502027327554;5860.765569377178;5496.581504138167;5587.77648894056;5613.410591887793;5646.435356464321;5669.050304870591;5016.749162541785;5737.483626473638;5740;5743.74153163117;5761.452661997104;5182.922256166236;5185.870353241199;5596.832095037155;5696.832149409278;5700.800471983461 12:16:29 INFO - PROCESS | 1636 | |5;yui.html;749.775067479756;758.8671982403082;801.4429971170039;885.1856292211165;886.317535497667;16003.679926401464;17417.21622526986;17444.040577768217;17464.650706985853;17551.824481755193;16082.517524474284;16200.756988645151;16215.675686486264;16219.107949062796;16246.187690615481;963.6868017894177;988.2834944665132;1006.1397505133116;1009.2632378363799;1039.2050081687514;836.5399030533213;1043.634727845252;1043.9582416703322;1045.2056437107815;1045.3414348960182;1084.8535447714537;1088.3905013192636;1092.9180311476632;1094.2832444748703;1096.5120521367994;1075.714935542082;1083.5286650307141;1085.3596378136938;1085.7339951711801;1088.1240601315938;809.9068607110199;811.9106898241189;812.6668066092933;812.9918700812975;813.3899575318511;16463.588410289718;17037.892536985055;17146.82853171477;17375.565610859703;17695.380661676907;14003.719925601532;14626.853731462763;14854.925725371411;14996;15185;11000.339979601249;12062;12134.393280336037;12226.938865305705;12489.063320251018;3875.1087249932675;3955.940660890089;4003.459532963042;4103.753774773523;4125.8968525787095;110815.44592277068;112203;112498.43750781275;112897;113813;11256.718582035515;11339.489722962486;11399.259048162008;11514.712132196762;11613.593524226611;23505.882470587538;23798;23869.16457923999;23872.7612723874;23886.64170037451;8075.394345424117;8310.459820111673;8342.958285208535;8450.577471126418;8572.785680358042;4589.7705114744795;4615.561521655437;4634.675572709878;4666.1834179019215;4808.903821923542;3145.748340132784;3598.1544337080763;3667.339878821838;3681.871134510333;3715.758475699071;605.3492495567283;635.4058954877205;661.8742438936587;670.4870773858003;673.7911247513287;592.7569696424531;691.2396363999661;698.5319835710086;723.8733221686289;749.6326799868025;10414.895851041392;10676.839847402296;11008.284461509977;11194.888051119548;11300.547978080785;676.2426082787211;709.9609521476265;723.5369363607296;725.3399406540024;733.3949491669288;5091.770870310845;5241.606879484054;5280;5307.893842123136;5311.814086506954;105676.94323056824;107718;109526.45236773766;109681;110233.27902078563;57703.711481442326;61859;77989.61005194938;91029;93536.53231733797;71101;73952.89070663945;74356;78261.21738782653;79422.6028869852;11187.272827266203;11213.775724485302;11244.10047196206;11527.250728702607;11554.76890462203;49559;50363.74818125886;52269.7386513065;53112;54208;64480.67759661359;65188.34811651823;65888.34111658821;66367;67322;13516.932415338253;13804.792928105886;13834.308284585932;13840.723185536433;14063.859361406256;15040.924795375953;15401.383944642088;15415.61460963485;15510.457134000708;15926.761098583762;14267.286635668383;14492.710145797237;14849.183294918883;14950.32723527422;15058.472953446577;14340.784888226477;14362.066465679698;14745.26273686333;14837.851621483647;14874.776878346616;14069.577912662637;14353.928230359199;14370.209638469985;14667.413303468169;14978.850211497745;9694.75763105928;10117.645882394081;10215.284930054824;10223.437710925673;10396.22028347892;8474.426461345143;9687.518576495566;10191.483623505392;10637.468126593794;10839.67480975572 12:16:29 INFO - PROCESS | 1636 | __end_tp_report 12:16:29 INFO - PROCESS | 1636 | __start_cc_report 12:16:29 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,391 12:16:29 INFO - PROCESS | 1636 | __end_cc_report 12:16:29 INFO - PROCESS | 1636 | __startTimestamp1495826189269__endTimestamp 12:16:29 INFO - PROCESS | 1636 | 12:16:29 INFO - PROCESS | 1636 | ------- Summary: start ------- 12:16:29 INFO - PROCESS | 1636 | Number of tests: 6 12:16:29 INFO - PROCESS | 1636 | 12:16:29 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:6080.41 Median:6316.91 stddev:700.63 (11.1%) stddev-sans-first:662.87 12:16:29 INFO - PROCESS | 1636 | Values: 3109.0 3602.5 3612.6 3618.4 3636.7 6120.4 6146.2 6159.4 6184.7 6196.4 5945.6 6182.5 6193.7 6211.9 6253.1 5550.5 5584.5 5638.2 5665.0 5724.2 5691.8 5715.0 5734.6 5735.0 5740.9 5459.8 5478.6 5689.7 5695.1 5710.2 5585.9 5605.7 5626.5 5639.4 5652.6 5488.9 5633.7 5636.3 5639.5 5697.7 5641.3 6047.5 6235.0 6253.1 6256.3 6393.3 6417.7 6423.3 6483.9 6486.9 6716.0 6757.7 6758.1 6762.9 6763.5 6278.6 6306.1 6310.9 6316.9 6330.8 5599.8 6427.3 6479.9 6498.7 6721.6 6366.7 6383.6 6451.7 6470.2 6474.9 6631.3 6651.5 6668.4 6672.9 6677.4 6249.4 6288.5 6546.4 6556.9 6577.0 5369.3 6354.5 6444.8 6486.4 6560.9 5154.6 5192.7 5266.4 5306.2 5314.5 4300.6 4351.6 4711.6 4727.6 4751.5 5164.9 6323.2 6361.0 6382.3 6386.2 6373.5 6427.9 6584.5 6595.6 6689.7 6258.0 6305.4 6318.5 6337.7 6368.3 4879.2 5788.9 5824.4 5833.5 5879.0 6520.0 6531.8 6778.2 6789.7 6904.8 6318.4 6660.1 6710.5 6711.5 6727.5 6826.3 6878.3 6890.8 6918.1 6925.8 5371.5 6308.3 6347.0 6463.1 6580.6 6647.7 6686.6 6688.0 6699.8 6715.6 5162.4 6390.8 6458.6 6469.7 6559.1 6261.2 6306.1 6363.8 6540.2 6556.0 4963.4 6137.7 6304.4 6338.0 6351.7 6431.1 6448.0 6469.5 6471.0 6491.0 6181.1 6222.0 6271.7 6280.6 6338.4 12:16:29 INFO - PROCESS | 1636 | 12:16:29 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:56337.01 Median:31944.76 stddev:111969.11 (350.5%) stddev-sans-first:112205.53 12:16:29 INFO - PROCESS | 1636 | Values: 786.8 833.2 845.8 911.6 995.8 38606.8 39188.6 40679.6 40700.6 40767.4 39959.4 40163.0 40172.2 40173.2 40176.6 8301.5 8439.5 11438.5 11701.7 11898.8 11621.7 11704.2 11721.8 11721.9 11737.8 11706.8 11719.6 11725.1 11737.1 11783.5 11620.9 11625.7 11649.5 11666.5 11679.1 11331.2 11384.7 11400.0 11406.3 11409.4 35962.8 36147.8 36223.7 36261.0 36339.6 3948.9 3967.8 3991.7 4020.4 4026.4 22596.0 25016.7 26386.6 26622.2 28052.7 3179.9 3245.9 3319.2 3329.2 3441.2 372836.0 375213.0 375498.0 376030.0 378555.0 46838.0 46875.0 46930.8 46986.8 46990.0 48066.0 48244.0 48246.5 48294.0 48337.8 14807.0 14940.9 14949.3 14955.9 14990.6 12833.6 12845.6 12851.9 12858.4 12873.7 11729.4 12368.1 12396.7 12411.1 12479.4 4593.9 5693.2 5747.5 5756.3 5773.5 1007.4 1116.4 1126.7 1131.9 1151.1 26336.9 27724.2 27941.6 28176.7 28227.0 1237.5 1280.4 1298.8 1303.3 1331.8 14362.0 14412.9 14632.3 14646.2 14700.9 446993.0 447918.0 449238.0 449538.0 450021.0 48681.0 50125.2 58610.0 59101.1 59173.0 1481.1 1508.8 1518.2 1527.0 1534.0 31014.4 31601.7 31615.0 31748.0 32230.5 48871.0 49069.5 49136.8 49200.8 49348.0 437942.0 440275.0 441169.0 441644.0 441791.0 33043.0 33602.5 33732.8 33734.8 33781.2 45679.0 45716.1 46768.0 46979.5 47038.0 30798.5 30969.2 31178.1 31989.7 32241.5 30755.5 31501.4 31706.4 31730.5 31899.8 37025.3 37742.0 37975.9 38075.8 38080.8 24376.4 26771.0 30104.9 30691.4 31141.7 22890.9 23822.4 24158.2 24509.0 24710.8 12:16:29 INFO - PROCESS | 1636 | 12:16:29 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:42958.00 Median:21740.85 stddev:133884.24 (615.8%) stddev-sans-first:134221.65 12:16:29 INFO - PROCESS | 1636 | Values: 379.6 422.4 423.8 440.8 463.3 20591.9 21026.0 22333.2 22481.3 22569.4 21777.1 22180.0 22440.6 22868.7 23102.5 6727.4 6828.6 7025.2 7053.4 7074.3 6714.9 6862.7 6904.7 6912.1 6915.0 6631.6 6866.9 6870.3 6880.5 6933.0 6818.4 6830.0 6846.9 6911.8 6913.9 6380.6 6726.1 6754.6 6763.0 6765.7 20491.2 26178.7 26335.6 26567.0 27356.6 3062.6 3086.4 3110.1 3118.7 3139.1 16318.5 16893.5 16893.8 16990.8 17347.0 1828.8 1864.8 1867.0 1871.6 2118.4 96089.4 113307.4 131476.0 138940.3 139223.0 21189.6 21704.6 21853.3 21969.7 22004.0 33239.5 33393.8 33801.7 33935.2 34011.5 10964.3 11254.3 11385.9 11389.8 11479.5 9282.8 9285.5 9299.5 9437.5 9470.4 8417.5 9429.2 9471.4 10133.3 10204.9 3844.0 3910.7 4238.2 4250.4 4257.3 1015.6 1065.1 1074.9 1111.3 1118.0 19682.2 21354.1 22173.0 22945.7 23041.0 1154.8 1189.4 1194.5 1196.3 1210.5 11638.9 11665.2 12020.9 12034.7 12111.2 771639.0 811034.0 817268.0 822840.0 842201.0 99932.0 126740.0 131179.0 134225.0 140754.0 1338.2 1356.5 1371.9 1383.1 1383.8 14298.0 19289.6 19984.0 20012.4 20023.1 56599.0 57124.7 57287.9 57321.0 57748.4 69084.0 73391.0 75863.9 76659.2 77852.2 10006.8 11465.9 11500.9 11752.8 11830.2 12014.3 12095.6 12215.9 12316.7 13059.9 8618.8 11061.9 11079.5 11323.7 11336.0 10300.7 10405.7 10448.4 10587.4 10751.7 11304.5 11487.7 11566.2 11678.2 11689.3 11557.4 11634.8 11836.8 11936.8 11942.2 11003.7 11103.1 11129.1 11363.7 11455.4 12:16:29 INFO - PROCESS | 1636 | 12:16:29 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:6963.54 Median:2480.52 stddev:4402.10 (177.5%) stddev-sans-first:4395.93 12:16:29 INFO - PROCESS | 1636 | Values: 1589.4 1605.5 1656.3 1665.4 1668.9 9747.6 9845.0 10192.1 11058.7 11749.1 11042.5 11427.7 11437.7 11444.1 11454.1 2453.0 2497.1 2498.0 2500.0 2505.7 2418.7 2469.1 2474.6 2475.0 2477.4 2443.7 2454.7 2467.3 2474.8 2483.6 2409.7 2414.2 2418.8 2421.6 2426.0 2393.0 2403.6 2410.9 2411.6 2436.3 9550.8 11456.5 11595.4 11646.6 11689.5 5069.0 5873.4 6038.1 6084.4 6088.5 3016.7 3590.7 3600.0 3643.7 3670.8 1242.9 1255.6 1290.2 1297.9 1313.5 16198.0 16273.2 16273.4 16287.3 16299.6 4904.2 4980.5 5020.9 5051.9 5086.3 13608.4 13662.9 13681.9 13685.8 13702.3 5672.0 6102.1 6102.1 6102.5 6115.4 5276.6 5393.4 5408.1 5458.8 5462.3 4084.9 4160.8 4162.5 4212.1 4236.7 1144.8 1148.6 1148.9 1160.5 1164.0 948.4 965.9 976.9 993.6 998.5 6531.6 6581.5 6675.3 6800.8 6852.9 994.7 1023.1 1027.2 1035.5 1053.9 3526.7 4030.5 4135.5 4242.3 4260.9 11442.3 13523.9 13884.2 14017.7 14082.3 12333.6 13655.7 13978.2 14008.1 14023.0 10390.4 10700.6 10738.9 10751.8 10779.0 6635.2 6836.0 7010.8 7047.9 7093.9 11708.5 12800.2 12827.2 12829.4 12835.2 12880.4 13142.7 13201.7 13286.1 13396.7 9602.0 9642.4 9807.9 9816.8 9822.5 10669.0 10676.9 10681.1 10773.1 10807.5 9055.8 9110.3 9195.0 9230.9 9370.3 8969.5 8981.8 9035.2 9060.5 9078.3 7929.2 8108.8 8112.1 8134.9 8150.2 5886.0 5953.4 6007.8 6143.2 6168.0 5179.2 5289.3 5451.0 5724.8 6045.0 12:16:29 INFO - PROCESS | 1636 | 12:16:29 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4687.00 Median:5067.09 stddev:1333.45 (26.3%) stddev-sans-first:1297.72 12:16:29 INFO - PROCESS | 1636 | Values: 396.7 397.9 402.6 403.4 404.2 4599.9 5373.1 5386.9 5455.7 5465.7 4576.0 5042.6 5324.2 5328.5 5356.8 3806.4 3822.8 4184.9 4201.1 4293.6 3438.2 3729.7 3733.6 4263.6 4350.4 3945.5 3978.9 4187.7 4198.9 4228.3 4072.4 4326.9 4341.3 4364.8 4419.0 3456.8 4016.5 4020.7 4021.9 4022.3 4457.3 4795.9 5070.7 5161.2 5184.0 4690.8 4725.3 4833.8 5042.1 5169.5 4594.0 4901.7 4987.2 5421.6 5589.5 3954.4 3965.6 4210.8 4389.9 4446.6 4808.2 4875.8 5231.7 5571.3 5664.0 4003.0 4230.5 4312.7 4514.7 4620.7 4636.7 4742.3 4762.5 4780.4 4830.0 4159.7 4813.9 4998.2 5138.5 5159.3 5276.6 5415.7 5468.2 5582.9 5794.2 1339.2 1400.8 1464.4 1506.8 1625.6 989.9 1125.7 1218.4 1222.6 1229.6 4935.6 5359.5 5488.8 5504.3 5519.7 5023.4 5132.4 5381.3 5907.3 5907.9 4656.7 4662.0 5105.4 5310.2 5358.8 2927.4 2987.4 3090.5 3108.0 3111.3 5759.6 5767.0 5795.4 5973.4 6157.7 5308.6 5627.0 5930.8 6071.4 6081.8 5037.7 5583.8 5591.2 5601.8 5781.7 5599.3 5862.5 5877.6 5984.0 6031.7 5063.5 5831.7 5935.3 5985.2 6005.4 4911.6 5368.4 5474.4 6215.4 6304.0 3944.4 4265.6 4563.1 4674.8 4755.8 4546.1 4782.1 5472.2 5852.7 5925.4 5123.3 5470.7 5838.6 5870.4 5889.8 5667.9 5730.6 5748.7 5858.5 5860.8 5496.6 5587.8 5613.4 5646.4 5669.1 5016.7 5737.5 5740.0 5743.7 5761.5 5182.9 5185.9 5596.8 5696.8 5700.8 12:16:29 INFO - PROCESS | 1636 | 12:16:29 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:20894.96 Median:16750.74 stddev:29468.59 (175.9%) stddev-sans-first:29512.43 12:16:29 INFO - PROCESS | 1636 | Values: 749.8 758.9 801.4 885.2 886.3 16003.7 17417.2 17444.0 17464.7 17551.8 16082.5 16200.8 16215.7 16219.1 16246.2 963.7 988.3 1006.1 1009.3 1039.2 836.5 1043.6 1044.0 1045.2 1045.3 1084.9 1088.4 1092.9 1094.3 1096.5 1075.7 1083.5 1085.4 1085.7 1088.1 809.9 811.9 812.7 813.0 813.4 16463.6 17037.9 17146.8 17375.6 17695.4 14003.7 14626.9 14854.9 14996.0 15185.0 11000.3 12062.0 12134.4 12226.9 12489.1 3875.1 3955.9 4003.5 4103.8 4125.9 110815.4 112203.0 112498.4 112897.0 113813.0 11256.7 11339.5 11399.3 11514.7 11613.6 23505.9 23798.0 23869.2 23872.8 23886.6 8075.4 8310.5 8343.0 8450.6 8572.8 4589.8 4615.6 4634.7 4666.2 4808.9 3145.7 3598.2 3667.3 3681.9 3715.8 605.3 635.4 661.9 670.5 673.8 592.8 691.2 698.5 723.9 749.6 10414.9 10676.8 11008.3 11194.9 11300.5 676.2 710.0 723.5 725.3 733.4 5091.8 5241.6 5280.0 5307.9 5311.8 105676.9 107718.0 109526.5 109681.0 110233.3 57703.7 61859.0 77989.6 91029.0 93536.5 71101.0 73952.9 74356.0 78261.2 79422.6 11187.3 11213.8 11244.1 11527.3 11554.8 49559.0 50363.7 52269.7 53112.0 54208.0 64480.7 65188.3 65888.3 66367.0 67322.0 13516.9 13804.8 13834.3 13840.7 14063.9 15040.9 15401.4 15415.6 15510.5 15926.8 14267.3 14492.7 14849.2 14950.3 15058.5 14340.8 14362.1 14745.3 14837.9 14874.8 14069.6 14353.9 14370.2 14667.4 14978.9 9694.8 10117.6 10215.3 10223.4 10396.2 8474.4 9687.5 10191.5 10637.5 10839.7 12:16:29 INFO - PROCESS | 1636 | -------- Summary: end -------- 12:16:29 INFO - PROCESS | 1636 | 12:16:29 INFO - TEST-INFO | 1636: exit 0 12:16:29 INFO - TEST-OK | dromaeo_css | took 1115409ms 12:16:29 INFO - TEST-START | kraken 12:16:29 INFO - operating with platform_type : mac_ 12:16:29 INFO - Initialising browser for kraken test... 12:16:29 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/tmp7E3G08/profile http://localhost:49219/getInfo.html) 12:16:32 INFO - PROCESS | 1648 | 2017-05-26 12:16:32.475 plugin-container[1650:10019] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 12:16:32 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:16:32 INFO - PROCESS | 1648 | 2017-05-26 12:16:32.475 plugin-container[1650:10019] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.CFPasteboardClient' 12:16:32 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:16:32 INFO - PROCESS | 1648 | 2017-05-26 12:16:32.475 plugin-container[1650:10019] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:16:32 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 12:16:32 INFO - PROCESS | 1648 | colorDepth:24 12:16:32 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 12:16:32 INFO - PROCESS | 1648 | __metrics 12:16:32 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 12:16:33 INFO - TEST-INFO | 1648: exit 0 12:16:33 INFO - Browser initialized. 12:16:33 INFO - Running cycle 1/1 for kraken test... 12:16:33 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/T/tmp7E3G08/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:16:35 INFO - PROCESS | 1651 | 2017-05-26 12:16:35.721 plugin-container[1652:10138] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4247, name = 'com.apple.tsm.portname' 12:16:35 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:16:35 INFO - PROCESS | 1651 | 2017-05-26 12:16:35.722 plugin-container[1652:10138] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4513, name = 'com.apple.CFPasteboardClient' 12:16:35 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:16:35 INFO - PROCESS | 1651 | 2017-05-26 12:16:35.722 plugin-container[1652:10138] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:16:45 INFO - PROCESS | 1651 | RSS: Main: 174350336 12:16:45 INFO - PROCESS | 1651 | 12:16:48 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection) 12:16:49 INFO - PROCESS | 1651 | RSS: Main: 188526592 12:16:49 INFO - PROCESS | 1651 | 12:16:52 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-dft) 12:16:52 INFO - PROCESS | 1651 | RSS: Main: 195874816 12:16:52 INFO - PROCESS | 1651 | 12:16:56 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-fft) 12:16:56 INFO - PROCESS | 1651 | RSS: Main: 199417856 12:16:56 INFO - PROCESS | 1651 | 12:16:58 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator) 12:16:59 INFO - PROCESS | 1651 | RSS: Main: 200986624 12:16:59 INFO - PROCESS | 1651 | 12:17:01 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:17:02 INFO - PROCESS | 1651 | RSS: Main: 200986624 12:17:02 INFO - PROCESS | 1651 | 12:17:04 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom) 12:17:05 INFO - PROCESS | 1651 | RSS: Main: 205414400 12:17:05 INFO - PROCESS | 1651 | 12:17:08 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate) 12:17:09 INFO - PROCESS | 1651 | RSS: Main: 204427264 12:17:09 INFO - PROCESS | 1651 | 12:17:12 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial) 12:17:13 INFO - PROCESS | 1651 | RSS: Main: 204722176 12:17:13 INFO - PROCESS | 1651 | 12:17:14 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:17:15 INFO - PROCESS | 1651 | RSS: Main: 204234752 12:17:15 INFO - PROCESS | 1651 | 12:17:17 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:17:17 INFO - PROCESS | 1651 | RSS: Main: 204251136 12:17:17 INFO - PROCESS | 1651 | 12:17:19 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:17:20 INFO - PROCESS | 1651 | RSS: Main: 204746752 12:17:20 INFO - PROCESS | 1651 | 12:17:23 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:17:24 INFO - PROCESS | 1651 | RSS: Main: 204627968 12:17:24 INFO - PROCESS | 1651 | 12:17:27 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:17:27 INFO - PROCESS | 1651 | RSS: Main: 204673024 12:17:27 INFO - PROCESS | 1651 | 12:17:29 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49219/tests/kraken/driver.html?testName=ai-astar) 12:17:29 INFO - PROCESS | 1651 | RSS: Main: 206823424 12:17:29 INFO - PROCESS | 1651 | 12:17:29 INFO - PROCESS | 1651 | __start_tp_report 12:17:29 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 12:17:29 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 12:17:29 INFO - PROCESS | 1651 | |i|pagename|runs| 12:17:29 INFO - PROCESS | 1651 | |0;ai-astar;109;165;158;117;123;173;175;120;151;176 12:17:29 INFO - PROCESS | 1651 | |1;audio-beat-detection;141;108;114;177;104;141;139;114;149;113 12:17:29 INFO - PROCESS | 1651 | |2;audio-dft;197;196;191;196;195;203;196;191;202;196 12:17:29 INFO - PROCESS | 1651 | |3;audio-fft;76;59;59;87;61;76;78;60;73;62 12:17:29 INFO - PROCESS | 1651 | |4;audio-oscillator;85;72;69;90;95;57;82;97;57;89 12:17:29 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;89;89;91;89;103;89;88;88;88;102 12:17:29 INFO - PROCESS | 1651 | |6;imaging-darkroom;95;94;127;149;96;101;97;95;96;97 12:17:29 INFO - PROCESS | 1651 | |7;imaging-desaturate;130;141;118;94;89;90;91;100;142;112 12:17:29 INFO - PROCESS | 1651 | |8;json-parse-financial;66;66;66;69;69;68;70;70;70;68 12:17:29 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;59;61;62;70;67;65;59;59;74;65 12:17:29 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;82;84;83;85;84;83;81;86;86;89 12:17:29 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;139;139;146;147;144;127;137;153;194;127 12:17:29 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;163;166;162;146;162;218;220;222;166;163 12:17:29 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;63;64;64;66;66;66;66;67;76;83 12:17:29 INFO - PROCESS | 1651 | __end_tp_report 12:17:29 INFO - PROCESS | 1651 | __start_cc_report 12:17:29 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,973 12:17:29 INFO - PROCESS | 1651 | __end_cc_report 12:17:29 INFO - PROCESS | 1651 | __startTimestamp1495826249732__endTimestamp 12:17:29 INFO - PROCESS | 1651 | 12:17:29 INFO - PROCESS | 1651 | ------- Summary: start ------- 12:17:29 INFO - PROCESS | 1651 | Number of tests: 14 12:17:29 INFO - PROCESS | 1651 | 12:17:29 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:146.70 Median:161.50 stddev:26.69 (16.5%) stddev-sans-first:24.57 12:17:29 INFO - PROCESS | 1651 | Values: 109.0 165.0 158.0 117.0 123.0 173.0 175.0 120.0 151.0 176.0 12:17:29 INFO - PROCESS | 1651 | 12:17:29 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:130.00 Median:140.00 stddev:23.22 (16.6%) stddev-sans-first:24.29 12:17:29 INFO - PROCESS | 1651 | Values: 141.0 108.0 114.0 177.0 104.0 141.0 139.0 114.0 149.0 113.0 12:17:29 INFO - PROCESS | 1651 | 12:17:29 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:196.30 Median:196.00 stddev:3.89 (2.0%) stddev-sans-first:4.12 12:17:29 INFO - PROCESS | 1651 | Values: 197.0 196.0 191.0 196.0 195.0 203.0 196.0 191.0 202.0 196.0 12:17:29 INFO - PROCESS | 1651 | 12:17:29 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:69.10 Median:74.50 stddev:10.07 (13.5%) stddev-sans-first:10.37 12:17:29 INFO - PROCESS | 1651 | Values: 76.0 59.0 59.0 87.0 61.0 76.0 78.0 60.0 73.0 62.0 12:17:29 INFO - PROCESS | 1651 | 12:17:29 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:79.30 Median:87.00 stddev:14.77 (17.0%) stddev-sans-first:15.52 12:17:29 INFO - PROCESS | 1651 | Values: 85.0 72.0 69.0 90.0 95.0 57.0 82.0 97.0 57.0 89.0 12:17:29 INFO - PROCESS | 1651 | 12:17:29 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:91.60 Median:89.00 stddev:5.82 (6.5%) stddev-sans-first:6.09 12:17:29 INFO - PROCESS | 1651 | Values: 89.0 89.0 91.0 89.0 103.0 89.0 88.0 88.0 88.0 102.0 12:17:29 INFO - PROCESS | 1651 | 12:17:29 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:104.70 Median:95.50 stddev:18.40 (19.3%) stddev-sans-first:19.18 12:17:29 INFO - PROCESS | 1651 | Values: 95.0 94.0 127.0 149.0 96.0 101.0 97.0 95.0 96.0 97.0 12:17:29 INFO - PROCESS | 1651 | 12:17:29 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:110.70 Median:115.50 stddev:21.10 (18.3%) stddev-sans-first:21.19 12:17:29 INFO - PROCESS | 1651 | Values: 130.0 141.0 118.0 94.0 89.0 90.0 91.0 100.0 142.0 112.0 12:17:29 INFO - PROCESS | 1651 | 12:17:29 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:68.20 Median:69.00 stddev:1.69 (2.4%) stddev-sans-first:1.59 12:17:29 INFO - PROCESS | 1651 | Values: 66.0 66.0 66.0 69.0 69.0 68.0 70.0 70.0 70.0 68.0 12:17:29 INFO - PROCESS | 1651 | 12:17:29 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:64.10 Median:65.00 stddev:5.11 (7.9%) stddev-sans-first:5.07 12:17:29 INFO - PROCESS | 1651 | Values: 59.0 61.0 62.0 70.0 67.0 65.0 59.0 59.0 74.0 65.0 12:17:29 INFO - PROCESS | 1651 | 12:17:29 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:84.50 stddev:2.31 (2.7%) stddev-sans-first:2.30 12:17:29 INFO - PROCESS | 1651 | Values: 82.0 84.0 83.0 85.0 84.0 83.0 81.0 86.0 86.0 89.0 12:17:29 INFO - PROCESS | 1651 | 12:17:29 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:145.30 Median:145.00 stddev:19.01 (13.1%) stddev-sans-first:20.03 12:17:29 INFO - PROCESS | 1651 | Values: 139.0 139.0 146.0 147.0 144.0 127.0 137.0 153.0 194.0 127.0 12:17:29 INFO - PROCESS | 1651 | 12:17:29 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.80 Median:166.00 stddev:29.00 (17.5%) stddev-sans-first:30.19 12:17:29 INFO - PROCESS | 1651 | Values: 163.0 166.0 162.0 146.0 162.0 218.0 220.0 222.0 166.0 163.0 12:17:29 INFO - PROCESS | 1651 | 12:17:29 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:66.00 stddev:6.35 (9.6%) stddev-sans-first:6.46 12:17:29 INFO - PROCESS | 1651 | Values: 63.0 64.0 64.0 66.0 66.0 66.0 66.0 67.0 76.0 83.0 12:17:29 INFO - PROCESS | 1651 | -------- Summary: end -------- 12:17:29 INFO - PROCESS | 1651 | 12:17:30 INFO - TEST-INFO | 1651: exit 0 12:17:30 INFO - TEST-OK | kraken | took 60692ms 12:17:30 INFO - SUITE-END | took 1176s 12:17:30 INFO - Completed test suite (00:19:36) 12:17:30 INFO - javascript benchmark 12:17:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7934.722065032411, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3108.9689103108967, 3602.531670882785, 3612.6026137124927, 3618.4210526315796, 3636.745427820052, 6120.357362476937, 6146.170267013952, 6159.353267906872, 6184.659843708594, 6196.411340922617, 5945.583809133363, 6182.474489668383, 6193.659348735818, 6211.875762484748, 6253.062040693898, 5550.500454959053, 5584.497395234428, 5638.210650508932, 5665.0, 5724.1986121942755, 5691.8292451226525, 5714.9714251428895, 5734.569907256951, 5735.0, 5740.942590574104, 5459.781608735666, 5478.616496845222, 5689.743961521742, 5695.14572814077, 5710.200571919934, 5585.8603534911745, 5605.747741351608, 5626.521745651625, 5639.379668236532, 5652.63257888236, 5488.862778430531, 5633.690147041911, 5636.267285252932, 5639.464250896158, 5697.658140511541, 5641.26663533738, 6047.516198704137, 6234.971229747086, 6253.1245625612455, 6256.343083976162, 6393.296737358887, 6417.711202995876, 6423.293437721847, 6483.870322593569, 6486.870262594769, 6716.0, 6757.695903684346, 6758.087658166134, 6762.855272298356, 6763.492738044617, 6278.560500764903, 6306.054091886208, 6310.905336419957, 6316.905246421262, 6330.841728956767, 5599.832005039855, 6427.3251308611925, 6479.870402591921, 6498.675066246668, 6721.596704197764, 6366.681665916685, 6383.585066970725, 6451.709673064724, 6470.223573171155, 6474.902876456857, 6631.30371311015, 6651.501137414713, 6668.366505182097, 6672.86654266912, 6677.432418244406, 6249.437550620466, 6288.465480434214, 6546.443552298013, 6556.901646475307, 6576.967115164393, 5369.301990741179, 6354.491640668828, 6444.7744328948265, 6486.3837935397005, 6560.901586476207, 5154.613403994716, 5192.688438693615, 5266.4470230626, 5306.177542480922, 5314.468553144655, 4300.64529673152, 4351.630111440563, 4711.552402521755, 4727.645426592951, 4751.548602882721, 5164.870878227999, 6323.20959880015, 6361.0, 6382.297947225801, 6386.16979792624, 6373.458256048195, 6427.871442571216, 6584.5061620378665, 6595.604263744191, 6689.665516724144, 6258.0, 6305.369463053749, 6318.526110541636, 6337.746490140343, 6368.267649220306, 4879.194932836113, 5788.88422231553, 5824.359320474744, 5833.50415214703, 5879.0, 6520.0, 6531.771388001397, 6778.152730908637, 6789.694463749242, 6904.758333458305, 6318.4313411793155, 6660.100886380276, 6710.496712746662, 6711.463082953254, 6727.495437842083, 6826.317368263134, 6878.27778083314, 6890.786409506132, 6918.066061081689, 6925.792226233221, 5371.4897084776885, 6308.27454842699, 6346.9682651586445, 6463.095166676763, 6580.5722628029025, 6647.7008534617025, 6686.598804071771, 6688.030235615711, 6699.7990060296315, 6715.630640314635, 5162.406323272871, 6390.7763228286785, 6458.580192287486, 6469.7412103515335, 6559.147310849559, 6261.186045814015, 6306.148669929501, 6363.809085727436, 6540.2151741789385, 6556.016597510412, 4963.429205641398, 6137.714085983886, 6304.432601065742, 6338.049292606146, 6351.68241587928, 6431.067495213074, 6448.0, 6469.4824414045825, 6471.029345598198, 6490.9675451624325, 6181.072839074175, 6222.0, 6271.686415679289, 6280.623162610258, 6338.397852204033], "value": 6037.012792798061, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [786.7993661616287, 833.2375876073393, 845.7547311279727, 911.5715613661577, 995.8107959487701, 38606.80696596517, 39188.60811391885, 40679.59320406795, 40700.57547985821, 40767.388489172685, 39959.40060899089, 40163.0, 40172.19655606886, 40173.19653606926, 40176.59823401765, 8301.54341511218, 8439.535825529594, 11438.542458301657, 11701.707457313592, 11898.8215176772, 11621.651350459457, 11704.18070735049, 11721.76921423251, 11721.882781172208, 11737.765244695102, 11706.824397634042, 11719.648410547697, 11725.061995040376, 11737.11971602129, 11783.469743861504, 11620.883791162065, 11625.651230463058, 11649.534018639286, 11666.475008624548, 11679.124065695021, 11331.150163737753, 11384.71538211543, 11400.030997365235, 11406.258593191416, 11409.42952852354, 35962.82018589917, 36147.81926090379, 36223.73216937394, 36261.0, 36339.63660363389, 3948.940765888514, 3967.7817720025387, 3991.6806655467494, 4020.3768415895584, 4026.355783074723, 22596.0, 25016.749832501806, 26386.604200937003, 26622.201333960013, 28052.719472805216, 3179.9371790397413, 3245.8539365728598, 3319.186799234179, 3329.184349834282, 3441.2429265561536, 372836.0, 375213.0, 375498.0, 376030.0, 378554.99999999726, 46838.0, 46875.0, 46930.76534617305, 46986.82532936636, 46990.0, 48066.03867922622, 48244.03511929742, 48246.5175348249, 48294.03411931742, 48337.7915552114, 14807.0, 14940.850591494163, 14949.252537373088, 14955.925220374045, 14990.550283491513, 12833.614991550268, 12845.550405735941, 12851.871481285254, 12858.357082145856, 12873.678158046123, 11729.35488548138, 12368.134230603951, 12396.690082747824, 12411.131220814455, 12479.376031198404, 4593.874500747338, 5693.202951586781, 5747.511461525817, 5756.309242890796, 5773.509251713652, 1007.37039350406, 1116.391566596207, 1126.7352172239682, 1131.896401009026, 1151.1481503687212, 26336.8683156583, 27724.168274951782, 27941.580876286873, 28176.71823281741, 28227.0, 1237.4926280225066, 1280.3662187217387, 1298.8245637697898, 1303.3483258370813, 1331.8068880012345, 14362.0, 14412.855871441361, 14632.341544630517, 14646.194459304628, 14700.852991469948, 446993.0, 447918.0, 449238.0, 449538.0, 450021.0, 48681.0, 50125.24812127821, 58610.0, 59101.113483297784, 59173.0, 1481.126135580013, 1508.751056075757, 1518.1574226304285, 1526.9694606107817, 1534.048889688378, 31014.379712405174, 31601.683983159874, 31615.0, 31748.0, 32230.516542251415, 48871.0, 49069.50930490792, 49136.75431622819, 49200.75399622979, 49348.0, 437942.0, 440275.0, 441169.0, 441644.0, 441791.0, 33043.0, 33602.49596256009, 33732.83133584316, 33734.83132584322, 33781.155471113416, 45679.0, 45716.085678286916, 46768.0, 46979.53020469751, 47038.0, 30798.538021930137, 30969.225769355948, 31178.064658060295, 31989.68010319867, 32241.51637725483, 30755.53866692046, 31501.369972600878, 31706.365872681956, 31730.52404213985, 31899.84050079735, 37025.25949481049, 37742.0, 37975.86072417832, 38075.80962095172, 38080.80959595184, 24376.390590235387, 26771.0, 30104.946326878453, 30691.386172275983, 31141.68858311388, 22890.885545572164, 23822.404439889142, 24158.154464593656, 24509.019639214945, 24710.752892470846], "value": 19360.09718332781, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.62227583554363, 422.4212828425056, 423.7796345900134, 440.79723327269465, 463.2981033733894, 20591.897040514796, 21026.0, 22333.218337358197, 22481.325560233177, 22569.435764105907, 21777.128914843426, 22180.0, 22440.55118897621, 22868.65697014546, 23102.537949241007, 6727.394534491919, 6828.6244256566, 7025.156981162268, 7053.400460960825, 7074.327938845801, 6714.865702685944, 6862.725490980355, 6904.654767261617, 6912.135983002125, 6914.965425172867, 6631.635260060719, 6866.931330686704, 6870.3473170048765, 6880.483963702717, 6932.965335173317, 6818.352256535622, 6830.0, 6846.931530684729, 6911.758088466905, 6913.89629155563, 6380.596268820852, 6726.125603671491, 6754.5947243165565, 6762.966185169092, 6765.661716914135, 20491.180352785872, 26178.73821261782, 26335.604965925526, 26567.0, 27356.6321683915, 3062.601861757957, 3086.42901063304, 3110.12916383413, 3118.656947735747, 3139.0582825152364, 16318.510444686559, 16893.493195204042, 16893.83106168935, 16990.83009169905, 17347.04591247481, 1828.7988321284647, 1864.820441300994, 1866.9626607467774, 1871.6069625378686, 2118.4386137673528, 96089.40211590283, 113307.43346283215, 131476.0, 138940.30529847386, 139223.0, 21189.576208475744, 21704.56590868174, 21853.344399667716, 21969.67045494319, 22004.00981955816, 33239.50140747891, 33393.83303083469, 33801.66198338034, 33935.15162120967, 34011.48982765261, 10964.28732132409, 11254.268472549258, 11385.886141138648, 11389.772204555862, 11479.540818367172, 9282.814343713222, 9285.535723213812, 9299.535023248945, 9437.480938548308, 9470.384425012353, 8417.494950303002, 9429.245660347156, 9471.431714097038, 10133.2906696532, 10204.897951020395, 3843.9813449435924, 3910.7066969976795, 4238.19474299882, 4250.42619246404, 4257.340112282601, 1015.5734591471452, 1065.0680654427376, 1074.9301295415773, 1111.3443068589409, 1118.021102648308, 19682.21271149138, 21354.140649196397, 22173.0, 22945.655815162787, 23041.0, 1154.821002744576, 1189.4290740444635, 1194.4804010255566, 1196.2882085159317, 1210.5278941212935, 11638.88361116378, 11665.24175928562, 12020.939895300467, 12034.699132521757, 12111.212771169845, 771639.0, 811034.0, 817268.0, 822840.0, 842201.0, 99932.0, 126740.0, 131179.0, 134225.0, 140754.0, 1338.1770211319968, 1356.51165580391, 1371.9176849388869, 1383.0871624727833, 1383.8408583013083, 14297.999140060512, 19289.614207716048, 19984.0, 20012.399628011182, 20023.09896054652, 56599.0, 57124.71437642952, 57287.85424291407, 57321.0, 57748.422515774306, 69083.96374054493, 73391.0, 75863.86204207051, 76659.2334076652, 77852.2214777845, 10006.799864002825, 11465.910738479832, 11500.94249528747, 11752.823707644564, 11830.17188796776, 12014.33921134346, 12095.57665481704, 12215.938920305342, 12316.692082698004, 13059.869401306245, 8618.78453038679, 11061.889381106086, 11079.50142243585, 11323.660290191307, 11336.0, 10300.690979270634, 10405.687829365132, 10448.373097614167, 10587.36475811423, 10751.677449676523, 11304.49129789145, 11487.712807179887, 11566.248193867372, 11678.24091434054, 11689.298642081503, 11557.42212889369, 11634.751138419946, 11836.822447663464, 11936.761264774483, 11942.164048516868, 11003.724906877393, 11103.056240219672, 11129.054030407182, 11363.659090227306, 11455.369954652575], "value": 11677.45248278055, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1589.4039735099338, 1605.4862444017904, 1656.3126302584428, 1665.442076904237, 1668.8831781775277, 9747.561359738811, 9844.966278540758, 10192.133668638156, 11058.723531911695, 11749.118816088807, 11042.503087361069, 11427.657170284883, 11437.714057148554, 11444.084473242141, 11454.140939429535, 2452.9632055519182, 2497.08856267462, 2498.0007996801332, 2500.03748556806, 2505.7243703192635, 2418.6855708757835, 2469.1111199967995, 2474.5793215153376, 2475.0223661653636, 2477.4054226985572, 2443.6945381827272, 2454.6931633545805, 2467.2613399126953, 2474.7772700456953, 2483.627455881614, 2409.7469765674614, 2414.2274472168915, 2418.7581241875846, 2421.5641184586766, 2426.0174629274993, 2392.964105538418, 2403.627437747152, 2410.927672169838, 2411.638254261875, 2436.281297017384, 9550.808983820285, 11456.4844581994, 11595.362255075968, 11646.592369267035, 11689.532418703242, 5069.0, 5873.383294754032, 6038.064100064497, 6084.391560843925, 6088.543359248074, 3016.653084895221, 3590.7127429805555, 3600.009997250751, 3643.744937854351, 3670.7797532148156, 1242.8508578970582, 1255.6484184428375, 1290.219417252558, 1297.9156354836905, 1313.5336955380799, 16198.0, 16273.186340683036, 16273.430429935013, 16287.348506059743, 16299.592510187222, 4904.215325547895, 4980.452150263468, 5020.924686129711, 5051.87370315745, 5086.287919691246, 13608.38762255701, 13662.863371366358, 13681.931590342183, 13685.79471307931, 13702.314884255748, 5672.035753921843, 6102.0541816018585, 6102.145699601971, 6102.450779429784, 6115.449609535163, 5276.551493123051, 5393.4336894626285, 5408.080626293539, 5458.781648734085, 5462.262594549765, 4084.8774536763945, 4160.8335666573, 4162.521310049323, 4212.136512015029, 4236.724612900151, 1144.8339990701468, 1148.62095508482, 1148.8741033786935, 1160.460385920551, 1163.988360116388, 948.4262021477045, 965.8840939087214, 976.9364991275544, 993.5777294649745, 998.4673526137344, 6531.640759758259, 6581.506387020992, 6675.332466753286, 6800.829979250559, 6852.931470685329, 994.7115336552464, 1023.0945613132283, 1027.2347101409348, 1035.5443604813859, 1053.947302634865, 3526.682598566141, 4030.496187976503, 4135.545090040093, 4242.257604919188, 4260.893477663021, 11442.313461192189, 13523.932380338036, 13884.236366999912, 14017.719645607234, 14082.295885205698, 12333.629991100102, 13655.658608534866, 13978.231197284247, 14008.089474184146, 14023.0, 10390.376577405379, 10700.625478108228, 10738.94630526858, 10751.83872241917, 10778.999999999844, 6635.170603674541, 6836.0, 7010.754623588354, 7047.894281585678, 7093.858122837618, 11708.473118709508, 12800.16798908068, 12827.166234194747, 12829.358532073546, 12835.229886206484, 12880.42038108306, 13142.737145257233, 13201.735965280832, 13286.136401134281, 13396.732065358834, 9602.039796020343, 9642.373245739005, 9807.9019209807, 9816.754581135528, 9822.5088745561, 10669.03978641926, 10676.946615266874, 10681.092107170978, 10773.138148948223, 10807.459627018774, 9055.773605659911, 9110.31672624569, 9195.0, 9230.861537076815, 9370.344075914893, 8969.461832290084, 8981.775455613662, 9035.232005279624, 9060.54697265121, 9078.31912606544, 7929.246721561442, 8108.797280068045, 8112.10766815638, 8134.918650813416, 8150.2257285560145, 5886.0, 5953.434423729739, 6007.7596896125415, 6143.1706719592285, 6168.0, 5179.171332586768, 5289.285946397187, 5451.046066938191, 5724.8282551523525, 6045.0], "value": 5307.679319199305, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.71238352194683, 397.906491974386, 402.5893588539688, 403.3667142586139, 404.1795155833658, 4599.94201333693, 5373.065696349311, 5386.865328366788, 5455.727213639322, 5465.672059676413, 4576.039031803325, 5042.647014708972, 5324.174752913295, 5328.495607064683, 5356.848277620311, 3806.410006449005, 3822.7706337619693, 4184.8744537663915, 4201.138766552849, 4293.570642935705, 3438.2435864109857, 3729.682976946949, 3733.5893051764283, 4263.594958478955, 4350.36919646651, 3945.467361906149, 3978.9403158952637, 4187.68592355573, 4198.887294866863, 4228.323468245081, 4072.3891416287497, 4326.870193894189, 4341.263494602184, 4364.781760911942, 4418.97790511047, 3456.7580269381156, 4016.4778578784862, 4020.6783457323345, 4021.879343619696, 4022.2905265262148, 4457.3091170868565, 4795.944892123727, 5070.6957582545165, 5161.2193793141305, 5183.9632073585435, 4690.833687261085, 4725.3384526166365, 4833.758312084382, 5042.092423363793, 5169.457206993249, 4593.977030114828, 4901.681390709628, 4987.202047672355, 5421.566274698054, 5589.456797157283, 3954.429620746509, 3965.6232657897453, 4210.842018444921, 4389.868303950887, 4446.622037126848, 4808.206645903421, 4875.756212189412, 5231.677884543266, 5571.331440227158, 5663.9775378652885, 4002.95775210836, 4230.471191101113, 4312.749302702181, 4514.70654407469, 4620.699654522446, 4636.721796692209, 4742.264948932921, 4762.499937506581, 4780.422460587997, 4829.975850120797, 4159.667226621863, 4813.855584332476, 4998.175301075355, 5138.511841375138, 5159.329287192641, 5276.604254680914, 5415.702136382458, 5468.19549548648, 5582.911332290246, 5794.159846822272, 1339.205464677337, 1400.7828786538103, 1464.3933797839861, 1506.7513860213162, 1625.5692241555996, 989.9455529945922, 1125.6735546691532, 1218.4029825385496, 1222.5904322052213, 1229.5880879905333, 4935.605151587864, 5359.490848369399, 5488.807891723771, 5504.339479262434, 5519.724013799294, 5023.39719233687, 5132.384113906281, 5381.251093394662, 5907.320658124285, 5907.911381329283, 4656.696125084982, 4661.951061011246, 5105.412877519058, 5310.203469479609, 5358.838285411626, 2927.429151315473, 2987.386811122013, 3090.5364195370425, 3107.9689203107678, 3111.2688518198206, 5759.568032397503, 5766.971165144231, 5795.420457954171, 5973.402659733992, 6157.692115394212, 5308.593867090306, 5626.959012582685, 5930.789139710768, 6071.3928607139815, 6081.817545473643, 5037.697738135724, 5583.832485025456, 5591.228192811995, 5601.795613943022, 5781.71091445426, 5599.32808063035, 5862.537734151581, 5877.647341159544, 5983.970080149571, 6031.73809821317, 5063.493650634907, 5831.7375718091935, 5935.2877654681715, 5985.162077309267, 6005.39946005396, 4911.607071434205, 5368.409474957673, 5474.386904642916, 6215.378462153929, 6303.9684801575695, 3944.36890097583, 4265.552117027667, 4563.064571762847, 4674.76626168697, 4755.812326329927, 4546.136234115507, 4782.0829294131245, 5472.151816468454, 5852.707364631666, 5925.4370834770625, 5123.28274041627, 5470.72646367688, 5838.591298609226, 5870.387677483479, 5889.823305300848, 5667.8866422672145, 5730.587447665358, 5748.712564371681, 5858.502027327554, 5860.765569377178, 5496.581504138167, 5587.77648894056, 5613.410591887793, 5646.435356464321, 5669.050304870591, 5016.749162541785, 5737.483626473638, 5740.0, 5743.74153163117, 5761.452661997104, 5182.922256166236, 5185.870353241199, 5596.832095037155, 5696.832149409278, 5700.800471983461], "value": 4300.2438656482145, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [749.775067479756, 758.8671982403082, 801.4429971170039, 885.1856292211165, 886.317535497667, 16003.679926401464, 17417.21622526986, 17444.040577768217, 17464.650706985853, 17551.824481755193, 16082.517524474284, 16200.756988645151, 16215.675686486264, 16219.107949062796, 16246.187690615481, 963.6868017894177, 988.2834944665132, 1006.1397505133116, 1009.2632378363799, 1039.2050081687514, 836.5399030533213, 1043.634727845252, 1043.9582416703322, 1045.2056437107815, 1045.3414348960182, 1084.8535447714537, 1088.3905013192636, 1092.9180311476632, 1094.2832444748703, 1096.5120521367994, 1075.714935542082, 1083.5286650307141, 1085.3596378136938, 1085.7339951711801, 1088.1240601315938, 809.9068607110199, 811.9106898241189, 812.6668066092933, 812.9918700812975, 813.3899575318511, 16463.588410289718, 17037.892536985055, 17146.82853171477, 17375.565610859703, 17695.380661676907, 14003.719925601532, 14626.853731462763, 14854.925725371411, 14996.0, 15185.0, 11000.339979601249, 12062.0, 12134.393280336037, 12226.938865305705, 12489.063320251018, 3875.1087249932675, 3955.940660890089, 4003.459532963042, 4103.753774773523, 4125.8968525787095, 110815.44592277068, 112203.0, 112498.43750781275, 112897.0, 113813.0, 11256.718582035515, 11339.489722962486, 11399.259048162008, 11514.712132196762, 11613.593524226611, 23505.882470587538, 23798.0, 23869.16457923999, 23872.7612723874, 23886.64170037451, 8075.394345424117, 8310.459820111673, 8342.958285208535, 8450.577471126418, 8572.785680358042, 4589.7705114744795, 4615.561521655437, 4634.675572709878, 4666.1834179019215, 4808.903821923542, 3145.748340132784, 3598.1544337080763, 3667.339878821838, 3681.871134510333, 3715.758475699071, 605.3492495567283, 635.4058954877205, 661.8742438936587, 670.4870773858003, 673.7911247513287, 592.7569696424531, 691.2396363999661, 698.5319835710086, 723.8733221686289, 749.6326799868025, 10414.895851041392, 10676.839847402296, 11008.284461509977, 11194.888051119548, 11300.547978080785, 676.2426082787211, 709.9609521476265, 723.5369363607296, 725.3399406540024, 733.3949491669288, 5091.770870310845, 5241.606879484054, 5280.0, 5307.893842123136, 5311.814086506954, 105676.94323056824, 107718.0, 109526.45236773766, 109681.0, 110233.27902078563, 57703.711481442326, 61859.0, 77989.61005194938, 91029.0, 93536.53231733797, 71101.0, 73952.89070663945, 74356.0, 78261.21738782653, 79422.6028869852, 11187.272827266203, 11213.775724485302, 11244.10047196206, 11527.250728702607, 11554.76890462203, 49559.0, 50363.74818125886, 52269.7386513065, 53112.0, 54208.0, 64480.67759661359, 65188.34811651823, 65888.34111658821, 66367.0, 67322.0, 13516.932415338253, 13804.792928105886, 13834.308284585932, 13840.723185536433, 14063.859361406256, 15040.924795375953, 15401.383944642088, 15415.61460963485, 15510.457134000708, 15926.761098583762, 14267.286635668383, 14492.710145797237, 14849.183294918883, 14950.32723527422, 15058.472953446577, 14340.784888226477, 14362.066465679698, 14745.26273686333, 14837.851621483647, 14874.776878346616, 14069.577912662637, 14353.928230359199, 14370.209638469985, 14667.413303468169, 14978.850211497745, 9694.75763105928, 10117.645882394081, 10215.284930054824, 10223.437710925673, 10396.22028347892, 8474.426461345143, 9687.518576495566, 10191.483623505392, 10637.468126593794, 10839.67480975572], "value": 8010.776844869311, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1537.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 165.0, 158.0, 117.0, 123.0, 173.0, 175.0, 120.0, 151.0, 176.0], "value": 146.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 108.0, 114.0, 177.0, 104.0, 141.0, 139.0, 114.0, 149.0, 113.0], "value": 130.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 196.0, 191.0, 196.0, 195.0, 203.0, 196.0, 191.0, 202.0, 196.0], "value": 196.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 59.0, 59.0, 87.0, 61.0, 76.0, 78.0, 60.0, 73.0, 62.0], "value": 69.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 72.0, 69.0, 90.0, 95.0, 57.0, 82.0, 97.0, 57.0, 89.0], "value": 79.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 91.0, 89.0, 103.0, 89.0, 88.0, 88.0, 88.0, 102.0], "value": 91.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 127.0, 149.0, 96.0, 101.0, 97.0, 95.0, 96.0, 97.0], "value": 104.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 141.0, 118.0, 94.0, 89.0, 90.0, 91.0, 100.0, 142.0, 112.0], "value": 110.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 69.0, 69.0, 68.0, 70.0, 70.0, 70.0, 68.0], "value": 68.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 62.0, 70.0, 67.0, 65.0, 59.0, 59.0, 74.0, 65.0], "value": 64.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 83.0, 85.0, 84.0, 83.0, 81.0, 86.0, 86.0, 89.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 146.0, 147.0, 144.0, 127.0, 137.0, 153.0, 194.0, 127.0], "value": 145.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 166.0, 162.0, 146.0, 162.0, 218.0, 220.0, 222.0, 166.0, 163.0], "value": 178.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 64.0, 66.0, 66.0, 66.0, 66.0, 67.0, 76.0, 83.0], "value": 68.1, "unit": "score"}]}]} 12:17:30 INFO - Return code: 0 12:17:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:17:31 ERROR - # TBPL SUCCESS # 12:17:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:17:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:17:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:17:31 INFO - 2017-05-26 12:17:31.056 screenresolution[1656:10524] starting screenresolution argv=screenresolution get 12:17:31 INFO - 2017-05-26 12:17:31.059 screenresolution[1656:10524] Display 0: 1600x1200x32@60 12:17:31 INFO - 2017-05-26 12:17:31.065 screenresolution[1657:10527] starting screenresolution argv=screenresolution list 12:17:31 INFO - Available Modes on Display 0 12:17:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:17:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:17:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:17:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:17:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:17:31 INFO - Intel Iris: 12:17:31 INFO - Chipset Model: Intel Iris 12:17:31 INFO - Type: GPU 12:17:31 INFO - Bus: Built-In 12:17:31 INFO - VRAM (Dynamic, Max): 1536 MB 12:17:31 INFO - Vendor: Intel (0x8086) 12:17:31 INFO - Device ID: 0x0a2e 12:17:31 INFO - Revision ID: 0x0009 12:17:31 INFO - Displays: 12:17:31 INFO - Display: 12:17:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:17:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:17:31 INFO - Display Serial Number: 1600x1200 60 12:17:31 INFO - Main Display: Yes 12:17:31 INFO - Mirror: Off 12:17:31 INFO - Online: Yes 12:17:31 INFO - Rotation: Supported 12:17:31 INFO - Return code: 0 12:17:31 INFO - Running post-action listener: _resource_record_post_action 12:17:31 INFO - [mozharness: 2017-05-26 19:17:31.299945Z] Finished run-tests step (success) 12:17:31 INFO - Running post-run listener: _resource_record_post_run 12:17:31 INFO - Total resource usage - Wall time: 1192s; CPU: 55.0%; Read bytes: 40237056; Write bytes: 427130368; Read time: 258; Write time: 1449 12:17:31 INFO - TinderboxPrint: CPU usage
54.6% 12:17:31 INFO - TinderboxPrint: I/O read bytes / time
40,237,056 / 258 12:17:31 INFO - TinderboxPrint: I/O write bytes / time
427,130,368 / 1,449 12:17:31 INFO - TinderboxPrint: CPU idle
2,166.4 (45.4%) 12:17:31 INFO - TinderboxPrint: CPU system
960.4 (20.1%) 12:17:31 INFO - TinderboxPrint: CPU user
1,642.9 (34.4%) 12:17:31 INFO - TinderboxPrint: Swap in / out
162,369,536 / 0 12:17:31 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 123345408; Write bytes: 123047936; Read time: 11041; Write time: 281 12:17:31 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 36145152; Write bytes: 302685696; Read time: 233; Write time: 1162 12:17:31 INFO - Running post-run listener: _upload_blobber_files 12:17:31 INFO - Blob upload gear active. 12:17:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:17:31 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:17:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:17:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:17:32 INFO - (blobuploader) - INFO - Open directory for files ... 12:17:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:17:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:17:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:17:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:17:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:17:33 INFO - (blobuploader) - INFO - Done attempting. 12:17:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:17:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:17:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:17:57 INFO - (blobuploader) - CRITICAL - Unexpected error in client: Traceback (most recent call last): 12:17:57 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 145, in upload_file 12:17:57 INFO - check_status(response) 12:17:57 INFO - File "/builds/slave/test/build/venv/bin/blobberc.py", line 181, in check_status 12:17:57 INFO - ret = requests.head(blob_url) 12:17:57 INFO - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/requests/api.py", line 77, in head 12:17:57 INFO - return request('head', url, **kwargs) 12:17:57 INFO - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/requests/api.py", line 44, in request 12:17:57 INFO - return session.request(method=method, url=url, **kwargs) 12:17:57 INFO - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/requests/sessions.py", line 335, in request 12:17:57 INFO - resp = self.send(prep, **send_kwargs) 12:17:57 INFO - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/requests/sessions.py", line 438, in send 12:17:57 INFO - r = adapter.send(request, **kwargs) 12:17:57 INFO - File "/builds/slave/test/build/venv/lib/python2.7/site-packages/requests/adapters.py", line 327, in send 12:17:57 INFO - raise ConnectionError(e) 12:17:57 INFO - ConnectionError: HTTPConnectionPool(host='mozilla-releng-blobs.s3.amazonaws.com', port=80): Max retries exceeded with url: /blobs/Mozilla-Beta/sha512/4558b17a09a526a0a32b10eab09fa85d902f82c51b31fbdbf4e3fb04fa87da53d21462e17801db338e184af3e9cc5f400a43ef56ff41468f56862330f0e576aa (Caused by : [Errno 54] Connection reset by peer) 12:17:57 INFO - (blobuploader) - INFO - Done attempting. 12:17:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:17:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:17:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:17:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:17:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:17:59 INFO - (blobuploader) - INFO - Done attempting. 12:17:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:17:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:17:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:18:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:18:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:18:01 INFO - (blobuploader) - INFO - Done attempting. 12:18:01 INFO - (blobuploader) - INFO - Iteration through files over. 12:18:01 INFO - Return code: 0 12:18:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:18:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:18:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fca45d2784116a7f65b57ea60b80665dfc206fcb69992ecf29fe8ec25c5690073ad7fd56211dc049d85f0908df497918ff65db2064b322ec9722edab9095b1ab", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/72a0a883f5f9845464dd92057fa5203709f28b99119690551900482a71f77486ce7f6e023bf73eb0b27c8ab9915dc91c5c0b6c8dbbc12775ea8e3d0bcaa9c24c", "dromaeojs-e10s_raw.log": null, "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aab88d39c9eea569f25c943cabdcf86a7b7611819f2c7fe1a87103bbfbf780fdb981dcc41974377efb72a4bd21a632637b90c50be1e915eb95ee9787dcc8072c"} 12:18:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:18:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:18:01 INFO - Contents: 12:18:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/fca45d2784116a7f65b57ea60b80665dfc206fcb69992ecf29fe8ec25c5690073ad7fd56211dc049d85f0908df497918ff65db2064b322ec9722edab9095b1ab", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/72a0a883f5f9845464dd92057fa5203709f28b99119690551900482a71f77486ce7f6e023bf73eb0b27c8ab9915dc91c5c0b6c8dbbc12775ea8e3d0bcaa9c24c", "dromaeojs-e10s_raw.log": null, "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aab88d39c9eea569f25c943cabdcf86a7b7611819f2c7fe1a87103bbfbf780fdb981dcc41974377efb72a4bd21a632637b90c50be1e915eb95ee9787dcc8072c"} 12:18:01 INFO - Running post-run listener: copy_logs_to_upload_dir 12:18:01 INFO - Copying logs to upload dir... 12:18:01 INFO - mkdir: /builds/slave/test/build/upload/logs 12:18:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1289.891033 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2017-05-26 12:18:01.649544) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 12:18:01.653723) ========= 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.685ygcmxOU/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.HdVGHChWrn/Listeners TMPDIR=/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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/fca45d2784116a7f65b57ea60b80665dfc206fcb69992ecf29fe8ec25c5690073ad7fd56211dc049d85f0908df497918ff65db2064b322ec9722edab9095b1ab", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/72a0a883f5f9845464dd92057fa5203709f28b99119690551900482a71f77486ce7f6e023bf73eb0b27c8ab9915dc91c5c0b6c8dbbc12775ea8e3d0bcaa9c24c", "dromaeojs-e10s_raw.log": null, "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aab88d39c9eea569f25c943cabdcf86a7b7611819f2c7fe1a87103bbfbf780fdb981dcc41974377efb72a4bd21a632637b90c50be1e915eb95ee9787dcc8072c"} build_url:https://queue.taskcluster.net/v1/task/XazcW0iSTz2e7eUi5pYyWA/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010977 build_url: 'https://queue.taskcluster.net/v1/task/XazcW0iSTz2e7eUi5pYyWA/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/fca45d2784116a7f65b57ea60b80665dfc206fcb69992ecf29fe8ec25c5690073ad7fd56211dc049d85f0908df497918ff65db2064b322ec9722edab9095b1ab", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/72a0a883f5f9845464dd92057fa5203709f28b99119690551900482a71f77486ce7f6e023bf73eb0b27c8ab9915dc91c5c0b6c8dbbc12775ea8e3d0bcaa9c24c", "dromaeojs-e10s_raw.log": null, "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aab88d39c9eea569f25c943cabdcf86a7b7611819f2c7fe1a87103bbfbf780fdb981dcc41974377efb72a4bd21a632637b90c50be1e915eb95ee9787dcc8072c"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 12:18:01.693813) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 12:18:01.694451) ========= 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.685ygcmxOU/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.HdVGHChWrn/Listeners TMPDIR=/var/folders/k4/cmrx_0_d2gd2sqlxqp_js7l000000w/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.005018 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 12:18:01.745107) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 12:18:01.745503) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 12:18:01.745886) ========= ========= Total master_lag: 0.19 =========