builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0005 starttime: 1497861037.21 results: success (0) buildid: 20170619000122 builduid: 83a33b190e0b492f8dbfe2da47b79d8a revision: c2316b058cb69c5b08b59894ccdce32b5ecd29fc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-19 01:30:37.212132) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-19 01:30:37.212610) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-19 01:30:37.212889) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-19 01:30:37.237901) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-19 01:30:37.238222) ========= 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.nd1f81VNIQ/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.a7LKUh3utp/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-19 01:30:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.60M=0.001s 2017-06-19 01:30:37 (9.60 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.129604 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-19 01:30:37.389875) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-19 01:30:37.390210) ========= 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.nd1f81VNIQ/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.a7LKUh3utp/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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.029338 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-19 01:30:37.439884) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-19 01:30:37.440274) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c2316b058cb69c5b08b59894ccdce32b5ecd29fc --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 c2316b058cb69c5b08b59894ccdce32b5ecd29fc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nd1f81VNIQ/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.a7LKUh3utp/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-19 01:30:37,485 truncating revision to first 12 chars 2017-06-19 01:30:37,485 Setting DEBUG logging. 2017-06-19 01:30:37,485 attempt 1/10 2017-06-19 01:30:37,485 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/c2316b058cb6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-19 01:30:38,765 unpacking tar archive at: mozilla-beta-c2316b058cb6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.557101 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-19 01:30:39.022536) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-19 01:30:39.023369) ========= script_repo_revision: c2316b058cb69c5b08b59894ccdce32b5ecd29fc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-19 01:30:39.024402) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-19 01:30:39.025040) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-19 01:30:39.044610) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-06-19 01:30:39.045054) ========= /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.nd1f81VNIQ/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.a7LKUh3utp/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 01:30:39 INFO - MultiFileLogger online at 20170619 01:30:39 in /builds/slave/test 01:30:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 01:30:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:30:39 INFO - {'append_to_log': False, 01:30:39 INFO - 'base_work_dir': '/builds/slave/test', 01:30:39 INFO - 'blob_upload_branch': 'Mozilla-Beta', 01:30:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:30:39 INFO - 'branch': 'Mozilla-Beta', 01:30:39 INFO - 'buildbot_json_path': 'buildprops.json', 01:30:39 INFO - 'config_files': ('talos/mac_config.py',), 01:30:39 INFO - 'default_actions': ('clobber', 01:30:39 INFO - 'read-buildbot-config', 01:30:39 INFO - 'download-and-extract', 01:30:39 INFO - 'populate-webroot', 01:30:39 INFO - 'create-virtualenv', 01:30:39 INFO - 'install', 01:30:39 INFO - 'setup-mitmproxy', 01:30:39 INFO - 'run-tests'), 01:30:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:30:39 INFO - 'download_minidump_stackwalk': True, 01:30:39 INFO - 'download_symbols': 'ondemand', 01:30:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:30:39 INFO - 'tooltool.py': '/tools/tooltool.py', 01:30:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:30:39 INFO - '/tools/misc-python/virtualenv.py')}, 01:30:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:30:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:30:39 INFO - 'gecko_profile': False, 01:30:39 INFO - 'gecko_profile_interval': 0, 01:30:39 INFO - 'installer_path': 'installer.exe', 01:30:39 INFO - 'log_level': 'info', 01:30:39 INFO - 'log_name': 'talos', 01:30:39 INFO - 'log_to_console': True, 01:30:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:30:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:30:39 INFO - 'opt_config_files': (), 01:30:39 INFO - 'pip_index': False, 01:30:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:30:39 INFO - 'cmd': ('bash', 01:30:39 INFO - '-c', 01:30:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:30:39 INFO - 'enabled': True, 01:30:39 INFO - 'halt_on_failure': False, 01:30:39 INFO - 'name': 'check_screen_resolution'},), 01:30:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:30:39 INFO - 'cmd': ('bash', 01:30:39 INFO - '-c', 01:30:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:30:39 INFO - 'enabled': True, 01:30:39 INFO - 'halt_on_failure': False, 01:30:39 INFO - 'name': 'check_screen_resolution'},), 01:30:39 INFO - 'run_cmd_checks_enabled': True, 01:30:39 INFO - 'suite': 'dromaeojs-e10s', 01:30:39 INFO - 'system_bits': '32', 01:30:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:30:39 INFO - 'title': 't-yosemite-r7-0005', 01:30:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:30:39 INFO - 'use_talos_json': True, 01:30:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:30:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:30:39 INFO - 'work_dir': 'build'} 01:30:39 INFO - [mozharness: 2017-06-19 08:30:39.202767Z] Running clobber step. 01:30:39 INFO - Running pre-action listener: _resource_record_pre_action 01:30:39 INFO - Running main action method: clobber 01:30:39 INFO - rmtree: /builds/slave/test/build 01:30:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:30:41 INFO - Running post-action listener: _resource_record_post_action 01:30:41 INFO - [mozharness: 2017-06-19 08:30:41.725322Z] Finished clobber step (success) 01:30:41 INFO - [mozharness: 2017-06-19 08:30:41.725454Z] Running read-buildbot-config step. 01:30:41 INFO - Running pre-action listener: _resource_record_pre_action 01:30:41 INFO - Running main action method: read_buildbot_config 01:30:41 INFO - Using buildbot properties: 01:30:41 INFO - { 01:30:41 INFO - "project": "", 01:30:41 INFO - "product": "firefox", 01:30:41 INFO - "script_repo_revision": "production", 01:30:41 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 01:30:41 INFO - "repository": "", 01:30:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 01:30:41 INFO - "buildid": "20170619000122", 01:30:41 INFO - "pgo_build": "False", 01:30:41 INFO - "basedir": "/builds/slave/test", 01:30:41 INFO - "buildnumber": 7, 01:30:41 INFO - "slavename": "t-yosemite-r7-0005", 01:30:41 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 01:30:41 INFO - "platform": "yosemite_r7_devedition", 01:30:41 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 01:30:41 INFO - "branch": "mozilla-beta", 01:30:41 INFO - "repo_path": "releases/mozilla-beta", 01:30:41 INFO - "revision": "c2316b058cb69c5b08b59894ccdce32b5ecd29fc", 01:30:41 INFO - "stage_platform": "macosx64-devedition", 01:30:41 INFO - "builduid": "83a33b190e0b492f8dbfe2da47b79d8a", 01:30:41 INFO - "slavebuilddir": "test" 01:30:41 INFO - } 01:30:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/H3M_wTJnTtyBk4Bfc5JCeQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg. 01:30:41 INFO - Running post-action listener: _resource_record_post_action 01:30:41 INFO - [mozharness: 2017-06-19 08:30:41.728779Z] Finished read-buildbot-config step (success) 01:30:41 INFO - [mozharness: 2017-06-19 08:30:41.728899Z] Running download-and-extract step. 01:30:41 INFO - Running pre-action listener: _resource_record_pre_action 01:30:41 INFO - Running main action method: download_and_extract 01:30:41 INFO - mkdir: /builds/slave/test/build/tests 01:30:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:30:41 INFO - trying https://queue.taskcluster.net/v1/task/H3M_wTJnTtyBk4Bfc5JCeQ/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 01:30:41 INFO - Downloading https://queue.taskcluster.net/v1/task/H3M_wTJnTtyBk4Bfc5JCeQ/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 01:30:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H3M_wTJnTtyBk4Bfc5JCeQ/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json'}, attempt #1 01:30:43 INFO - Downloaded 1290 bytes. 01:30:43 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 01:30:43 INFO - Using the following test package requirements: 01:30:43 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 01:30:43 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 01:30:43 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 01:30:43 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 01:30:43 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 01:30:43 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 01:30:43 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 01:30:43 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 01:30:43 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 01:30:43 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 01:30:43 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 01:30:43 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 01:30:43 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 01:30:43 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 01:30:43 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 01:30:43 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 01:30:43 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 01:30:43 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 01:30:43 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 01:30:43 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 01:30:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H3M_wTJnTtyBk4Bfc5JCeQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 01:30:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H3M_wTJnTtyBk4Bfc5JCeQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 01:30:43 INFO - Fetch https://queue.taskcluster.net/v1/task/H3M_wTJnTtyBk4Bfc5JCeQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 01:30:44 INFO - Content-Length response header: 59546003 01:30:44 INFO - Bytes received: 59546003 01:31:03 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip', u'firefox-55.0.en-US.mac.talos.tests.zip'] for test suite category: talos 01:31:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H3M_wTJnTtyBk4Bfc5JCeQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 01:31:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H3M_wTJnTtyBk4Bfc5JCeQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 01:31:03 INFO - Fetch https://queue.taskcluster.net/v1/task/H3M_wTJnTtyBk4Bfc5JCeQ/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 01:31:05 INFO - Content-Length response header: 59546003 01:31:05 INFO - Bytes received: 59546003 01:31:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H3M_wTJnTtyBk4Bfc5JCeQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 01:31:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H3M_wTJnTtyBk4Bfc5JCeQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 01:31:22 INFO - Fetch https://queue.taskcluster.net/v1/task/H3M_wTJnTtyBk4Bfc5JCeQ/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 01:31:24 INFO - Content-Length response header: 14454470 01:31:24 INFO - Bytes received: 14454470 01:31:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:24 INFO - trying https://queue.taskcluster.net/v1/task/H3M_wTJnTtyBk4Bfc5JCeQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 01:31:24 INFO - Downloading https://queue.taskcluster.net/v1/task/H3M_wTJnTtyBk4Bfc5JCeQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 01:31:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H3M_wTJnTtyBk4Bfc5JCeQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg'}, attempt #1 01:31:26 INFO - Downloaded 66855472 bytes. 01:31:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/H3M_wTJnTtyBk4Bfc5JCeQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 01:31:26 INFO - mkdir: /builds/slave/test/properties 01:31:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:31:26 INFO - Writing to file /builds/slave/test/properties/build_url 01:31:26 INFO - Contents: 01:31:26 INFO - build_url:https://queue.taskcluster.net/v1/task/H3M_wTJnTtyBk4Bfc5JCeQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg 01:31:27 INFO - Running post-action listener: _resource_record_post_action 01:31:27 INFO - Running post-action listener: set_extra_try_arguments 01:31:27 INFO - [mozharness: 2017-06-19 08:31:27.191308Z] Finished download-and-extract step (success) 01:31:27 INFO - [mozharness: 2017-06-19 08:31:27.191450Z] Running populate-webroot step. 01:31:27 INFO - Running pre-action listener: _resource_record_pre_action 01:31:27 INFO - Running main action method: populate_webroot 01:31:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:31:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:31:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:31:27 INFO - '--tppagecycles', 01:31:27 INFO - '7'], 01:31:27 INFO - 'tests': ['tsvgm']}}, 01:31:27 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:31:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:31:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:31:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:31:27 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:31:27 INFO - 'talos_options': ['--disable-e10s'], 01:31:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:31:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:31:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:31:27 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:31:27 INFO - 'talos_options': ['--disable-e10s'], 01:31:27 INFO - 'tests': ['damp', 'tps']}, 01:31:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:31:27 INFO - 'tests': ['damp', 'tps']}, 01:31:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:31:27 INFO - 'tests': ['dromaeo_dom']}, 01:31:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:31:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:31:27 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 01:31:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 01:31:27 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 01:31:27 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 01:31:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:31:27 INFO - 'tests': ['a11yr', 01:31:27 INFO - 'ts_paint', 01:31:27 INFO - 'tpaint', 01:31:27 INFO - 'sessionrestore', 01:31:27 INFO - 'sessionrestore_no_auto_restore', 01:31:27 INFO - 'tabpaint']}, 01:31:27 INFO - 'other-e10s': {'tests': ['a11yr', 01:31:27 INFO - 'ts_paint', 01:31:27 INFO - 'tpaint', 01:31:27 INFO - 'sessionrestore', 01:31:27 INFO - 'sessionrestore_no_auto_restore', 01:31:27 INFO - 'tabpaint']}, 01:31:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:31:27 INFO - 'ts_paint', 01:31:27 INFO - 'tpaint', 01:31:27 INFO - 'sessionrestore', 01:31:27 INFO - 'sessionrestore_no_auto_restore', 01:31:27 INFO - 'tabpaint']}, 01:31:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:31:27 INFO - 'ts_paint', 01:31:27 INFO - 'tpaint', 01:31:27 INFO - 'sessionrestore', 01:31:27 INFO - 'sessionrestore_no_auto_restore', 01:31:27 INFO - 'tabpaint']}, 01:31:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:31:27 INFO - 'tests': ['a11yr', 01:31:27 INFO - 'ts_paint', 01:31:27 INFO - 'tpaint', 01:31:27 INFO - 'sessionrestore', 01:31:27 INFO - 'sessionrestore_no_auto_restore', 01:31:27 INFO - 'tabpaint']}, 01:31:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:31:27 INFO - 'tests': ['a11yr', 01:31:27 INFO - 'ts_paint', 01:31:27 INFO - 'tpaint', 01:31:27 INFO - 'sessionrestore', 01:31:27 INFO - 'sessionrestore_no_auto_restore', 01:31:27 INFO - 'tabpaint']}, 01:31:27 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 01:31:27 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 01:31:27 INFO - 'bloom_basic_ref']}, 01:31:27 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 01:31:27 INFO - 'talos_options': ['--mitmproxy', 01:31:27 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 01:31:27 INFO - '--firstNonBlankPaint'], 01:31:27 INFO - 'tests': ['quantum_pageload_google', 01:31:27 INFO - 'quantum_pageload_youtube', 01:31:27 INFO - 'quantum_pageload_amazon', 01:31:27 INFO - 'quantum_pageload_facebook']}, 01:31:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:31:27 INFO - 'tests': ['tsvgx', 01:31:27 INFO - 'tsvgr_opacity', 01:31:27 INFO - 'tart', 01:31:27 INFO - 'tscrollx', 01:31:27 INFO - 'cart', 01:31:27 INFO - 'tsvg_static']}, 01:31:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:31:27 INFO - 'tsvgr_opacity', 01:31:27 INFO - 'tart', 01:31:27 INFO - 'tscrollx', 01:31:27 INFO - 'cart', 01:31:27 INFO - 'tsvg_static']}, 01:31:27 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:31:27 INFO - 'talos_options': ['--disable-e10s'], 01:31:27 INFO - 'tests': ['tp5o']}, 01:31:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:31:27 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:31:27 INFO - 'talos_options': ['--disable-e10s', 01:31:27 INFO - '--xperf_path', 01:31:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:31:27 INFO - 'tests': ['tp5n']}, 01:31:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:31:27 INFO - 'talos_options': ['--xperf_path', 01:31:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:31:27 INFO - 'tests': ['tp5n']}}, 01:31:27 INFO - 'talos.zip': {'path': '', 01:31:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:31:27 INFO - Running post-action listener: _resource_record_post_action 01:31:27 INFO - [mozharness: 2017-06-19 08:31:27.212563Z] Finished populate-webroot step (success) 01:31:27 INFO - [mozharness: 2017-06-19 08:31:27.212676Z] Running create-virtualenv step. 01:31:27 INFO - Running pre-action listener: _resource_record_pre_action 01:31:27 INFO - Running main action method: create_virtualenv 01:31:27 INFO - Creating virtualenv /builds/slave/test/build/venv 01:31:27 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 01:31:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:31:27 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 01:31:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:31:27 INFO - Using real prefix '/tools/python27' 01:31:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:31:28 INFO - Installing distribute.............................................................................................................................................................................................done. 01:31:30 INFO - Installing pip.................done. 01:31:30 INFO - Return code: 0 01:31:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:31:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:31:31 INFO - Reading from file tmpfile_stdout 01:31:31 INFO - Output received: 01:31:31 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 01:31:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:31:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:31:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:31:31 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 0x10a7dac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7de768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2bbdad2d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a530d30>, '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 0x10a4facd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a521be8>, '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.nd1f81VNIQ/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.a7LKUh3utp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:31:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:31:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:31:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nd1f81VNIQ/Render', 01:31:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:31:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:31:31 INFO - 'HOME': '/Users/cltbld', 01:31:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:31:31 INFO - 'LOGNAME': 'cltbld', 01:31:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:31 INFO - 'MOZ_NO_REMOTE': '1', 01:31:31 INFO - 'NO_EM_RESTART': '1', 01:31:31 INFO - 'PAGER': '/bin/cat', 01:31:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:31:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:31 INFO - 'PWD': '/builds/slave/test', 01:31:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:31:31 INFO - 'SHELL': '/bin/bash', 01:31:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.a7LKUh3utp/Listeners', 01:31:31 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 01:31:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:31:31 INFO - 'USER': 'cltbld', 01:31:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:31:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:31:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:31 INFO - 'XPC_FLAGS': '0x0', 01:31:31 INFO - 'XPC_SERVICE_NAME': '0', 01:31:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:31:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:31:31 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 01:31:31 INFO - Cleaning up... 01:31:31 INFO - Return code: 0 01:31:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:31:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:31:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:31:31 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 0x10a7dac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7de768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2bbdad2d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a530d30>, '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 0x10a4facd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a521be8>, '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.nd1f81VNIQ/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.a7LKUh3utp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:31:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 01:31:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 01:31:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nd1f81VNIQ/Render', 01:31:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:31:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:31:31 INFO - 'HOME': '/Users/cltbld', 01:31:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:31:31 INFO - 'LOGNAME': 'cltbld', 01:31:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:31 INFO - 'MOZ_NO_REMOTE': '1', 01:31:31 INFO - 'NO_EM_RESTART': '1', 01:31:31 INFO - 'PAGER': '/bin/cat', 01:31:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:31:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:31 INFO - 'PWD': '/builds/slave/test', 01:31:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:31:31 INFO - 'SHELL': '/bin/bash', 01:31:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.a7LKUh3utp/Listeners', 01:31:31 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 01:31:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:31:31 INFO - 'USER': 'cltbld', 01:31:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:31:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:31:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:31 INFO - 'XPC_FLAGS': '0x0', 01:31:31 INFO - 'XPC_SERVICE_NAME': '0', 01:31:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:31:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:31:31 INFO - Downloading/unpacking psutil>=3.1.1 01:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:31:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:31:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:31:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:31:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:31:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:31:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:31:33 INFO - Installing collected packages: psutil 01:31:33 INFO - Running setup.py install for psutil 01:31:33 INFO - building 'psutil._psutil_osx' extension 01:31:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 01:31:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 01:31:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 01:31:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 01:31:34 INFO - building 'psutil._psutil_posix' extension 01:31:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 01:31:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:31:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:31:34 INFO - ^ 01:31:34 INFO - 1 warning generated. 01:31:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 01:31:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:31:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:31:34 INFO - Successfully installed psutil 01:31:34 INFO - Cleaning up... 01:31:34 INFO - Return code: 0 01:31:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:31: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')]} 01:31:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:31:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:31:35 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 0x10a7dac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7de768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2bbdad2d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a530d30>, '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 0x10a4facd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a521be8>, '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.nd1f81VNIQ/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.a7LKUh3utp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:31:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:31:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:31:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nd1f81VNIQ/Render', 01:31:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:31:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:31:35 INFO - 'HOME': '/Users/cltbld', 01:31:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:31:35 INFO - 'LOGNAME': 'cltbld', 01:31:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:35 INFO - 'MOZ_NO_REMOTE': '1', 01:31:35 INFO - 'NO_EM_RESTART': '1', 01:31:35 INFO - 'PAGER': '/bin/cat', 01:31:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:31:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:35 INFO - 'PWD': '/builds/slave/test', 01:31:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:31:35 INFO - 'SHELL': '/bin/bash', 01:31:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.a7LKUh3utp/Listeners', 01:31:35 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 01:31:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:31:35 INFO - 'USER': 'cltbld', 01:31:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:31:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:31:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:35 INFO - 'XPC_FLAGS': '0x0', 01:31:35 INFO - 'XPC_SERVICE_NAME': '0', 01:31:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:31:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:31:35 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:31:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:31:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:31:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:31:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:31:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:31:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:31:37 INFO - Installing collected packages: mozsystemmonitor 01:31:37 INFO - Running setup.py install for mozsystemmonitor 01:31:37 INFO - Successfully installed mozsystemmonitor 01:31:37 INFO - Cleaning up... 01:31:37 INFO - Return code: 0 01:31:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:31:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:31:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:31:37 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 0x10a7dac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7de768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2bbdad2d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a530d30>, '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 0x10a4facd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a521be8>, '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.nd1f81VNIQ/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.a7LKUh3utp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:31:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 01:31:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 01:31:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nd1f81VNIQ/Render', 01:31:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:31:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:31:37 INFO - 'HOME': '/Users/cltbld', 01:31:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:31:37 INFO - 'LOGNAME': 'cltbld', 01:31:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:37 INFO - 'MOZ_NO_REMOTE': '1', 01:31:37 INFO - 'NO_EM_RESTART': '1', 01:31:37 INFO - 'PAGER': '/bin/cat', 01:31:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:31:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:37 INFO - 'PWD': '/builds/slave/test', 01:31:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:31:37 INFO - 'SHELL': '/bin/bash', 01:31:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.a7LKUh3utp/Listeners', 01:31:37 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 01:31:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:31:37 INFO - 'USER': 'cltbld', 01:31:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:31:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:31:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:37 INFO - 'XPC_FLAGS': '0x0', 01:31:37 INFO - 'XPC_SERVICE_NAME': '0', 01:31:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:31:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:31:38 INFO - Downloading/unpacking jsonschema==2.5.1 01:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:31:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:31:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:31:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:31:39 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:31:39 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:31:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:31:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:31:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:31:39 INFO - Downloading functools32-3.2.3-2.tar.gz 01:31:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:31:39 INFO - warning: no files found matching '*.txt' 01:31:39 INFO - no previously-included directories found matching 'build' 01:31:39 INFO - no previously-included directories found matching 'dist' 01:31:39 INFO - no previously-included directories found matching '.git*' 01:31:39 INFO - Installing collected packages: jsonschema, functools32 01:31:40 INFO - Running setup.py install for functools32 01:31:40 INFO - warning: no files found matching '*.txt' 01:31:40 INFO - no previously-included directories found matching 'build' 01:31:40 INFO - no previously-included directories found matching 'dist' 01:31:40 INFO - no previously-included directories found matching '.git*' 01:31:40 INFO - Successfully installed jsonschema functools32 01:31:40 INFO - Cleaning up... 01:31:40 INFO - Return code: 0 01:31:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:31:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:31:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:31:40 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 0x10a7dac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7de768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2bbdad2d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a530d30>, '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 0x10a4facd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a521be8>, '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.nd1f81VNIQ/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.a7LKUh3utp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:31:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:31:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 01:31:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nd1f81VNIQ/Render', 01:31:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:31:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:31:40 INFO - 'HOME': '/Users/cltbld', 01:31:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:31:40 INFO - 'LOGNAME': 'cltbld', 01:31:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:40 INFO - 'MOZ_NO_REMOTE': '1', 01:31:40 INFO - 'NO_EM_RESTART': '1', 01:31:40 INFO - 'PAGER': '/bin/cat', 01:31:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:31:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:40 INFO - 'PWD': '/builds/slave/test', 01:31:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:31:40 INFO - 'SHELL': '/bin/bash', 01:31:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.a7LKUh3utp/Listeners', 01:31:40 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 01:31:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:31:40 INFO - 'USER': 'cltbld', 01:31:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:31:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:31:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:40 INFO - 'XPC_FLAGS': '0x0', 01:31:40 INFO - 'XPC_SERVICE_NAME': '0', 01:31:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:31:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:31:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:31:40 INFO - Cleaning up... 01:31:40 INFO - Return code: 0 01:31:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:31:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:31:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:31:40 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 0x10a7dac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7de768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2bbdad2d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a530d30>, '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 0x10a4facd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a521be8>, '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.nd1f81VNIQ/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.a7LKUh3utp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:31:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:31:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:31:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nd1f81VNIQ/Render', 01:31:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:31:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:31:40 INFO - 'HOME': '/Users/cltbld', 01:31:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:31:40 INFO - 'LOGNAME': 'cltbld', 01:31:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:40 INFO - 'MOZ_NO_REMOTE': '1', 01:31:40 INFO - 'NO_EM_RESTART': '1', 01:31:40 INFO - 'PAGER': '/bin/cat', 01:31:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:31:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:40 INFO - 'PWD': '/builds/slave/test', 01:31:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:31:40 INFO - 'SHELL': '/bin/bash', 01:31:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.a7LKUh3utp/Listeners', 01:31:40 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 01:31:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:31:40 INFO - 'USER': 'cltbld', 01:31:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:31:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:31:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:40 INFO - 'XPC_FLAGS': '0x0', 01:31:40 INFO - 'XPC_SERVICE_NAME': '0', 01:31:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:31:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:31:40 INFO - Downloading/unpacking blobuploader==1.2.4 01:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:31:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:31:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:31:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:31:42 INFO - Downloading blobuploader-1.2.4.tar.gz 01:31:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:31:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:31:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:31:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:31:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:31:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:31:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:31:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:31:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:31:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:31:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:31:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:31:42 INFO - Downloading docopt-0.6.1.tar.gz 01:31:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:31:43 INFO - Installing collected packages: blobuploader, requests, docopt 01:31:43 INFO - Running setup.py install for blobuploader 01:31:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:31:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:31:43 INFO - Running setup.py install for requests 01:31:43 INFO - Running setup.py install for docopt 01:31:43 INFO - Successfully installed blobuploader requests docopt 01:31:43 INFO - Cleaning up... 01:31:43 INFO - Return code: 0 01:31:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:31:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:31:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:31:43 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 0x10a7dac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7de768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2bbdad2d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a530d30>, '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 0x10a4facd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a521be8>, '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.nd1f81VNIQ/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.a7LKUh3utp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:31:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:31:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:31:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nd1f81VNIQ/Render', 01:31:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:31:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:31:43 INFO - 'HOME': '/Users/cltbld', 01:31:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:31:43 INFO - 'LOGNAME': 'cltbld', 01:31:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:43 INFO - 'MOZ_NO_REMOTE': '1', 01:31:43 INFO - 'NO_EM_RESTART': '1', 01:31:43 INFO - 'PAGER': '/bin/cat', 01:31:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:31:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:43 INFO - 'PWD': '/builds/slave/test', 01:31:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:31:43 INFO - 'SHELL': '/bin/bash', 01:31:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.a7LKUh3utp/Listeners', 01:31:43 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 01:31:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:31:43 INFO - 'USER': 'cltbld', 01:31:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:31:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:31:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:43 INFO - 'XPC_FLAGS': '0x0', 01:31:43 INFO - 'XPC_SERVICE_NAME': '0', 01:31:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:31:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:31:44 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-1bUCtP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:31:44 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-uevxdA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:31:44 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-jDnC0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:31:44 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-GJ2QA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:31:44 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-UIMxww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:31:44 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-QUJcyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:31:44 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-EC6hTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:31:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:31:44 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-I63n_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:31:45 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-81cJL_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:31:45 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-UVWVT8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:31:45 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-c7bCDe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:31:45 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-7ReaVK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:31:45 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-RrTPaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:31:45 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-iUCTMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:31:45 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-VgnMPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:31:45 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-iZhiFg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:31:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:31:45 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-iO7_Xb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:31:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:31:45 INFO - Running setup.py install for manifestparser 01:31:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:31:46 INFO - Running setup.py install for mozcrash 01:31:46 INFO - Running setup.py install for mozdebug 01:31:46 INFO - Running setup.py install for mozdevice 01:31:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:31:46 INFO - Running setup.py install for mozfile 01:31:46 INFO - Running setup.py install for mozhttpd 01:31:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:31:46 INFO - Running setup.py install for mozinfo 01:31:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:31:46 INFO - Running setup.py install for mozInstall 01:31:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:31:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:31:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:31:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:31:47 INFO - Running setup.py install for mozleak 01:31:47 INFO - Running setup.py install for mozlog 01:31:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:31:47 INFO - Running setup.py install for moznetwork 01:31:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:31:47 INFO - Running setup.py install for mozprocess 01:31:47 INFO - Running setup.py install for mozprofile 01:31:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:31:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:31:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:31:47 INFO - Running setup.py install for mozrunner 01:31:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:31:48 INFO - Running setup.py install for mozscreenshot 01:31:48 INFO - Running setup.py install for moztest 01:31:48 INFO - Running setup.py install for mozversion 01:31:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:31:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:31:48 INFO - Cleaning up... 01:31:48 INFO - Return code: 0 01:31:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:31:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:31:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:31:48 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 0x10a7dac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7de768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2bbdad2d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a530d30>, '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 0x10a4facd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a521be8>, '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.nd1f81VNIQ/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.a7LKUh3utp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:31:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:31:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:31:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nd1f81VNIQ/Render', 01:31:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:31:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:31:48 INFO - 'HOME': '/Users/cltbld', 01:31:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:31:48 INFO - 'LOGNAME': 'cltbld', 01:31:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:48 INFO - 'MOZ_NO_REMOTE': '1', 01:31:48 INFO - 'NO_EM_RESTART': '1', 01:31:48 INFO - 'PAGER': '/bin/cat', 01:31:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:31:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:48 INFO - 'PWD': '/builds/slave/test', 01:31:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:31:48 INFO - 'SHELL': '/bin/bash', 01:31:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.a7LKUh3utp/Listeners', 01:31:48 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 01:31:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:31:48 INFO - 'USER': 'cltbld', 01:31:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:31:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:31:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:48 INFO - 'XPC_FLAGS': '0x0', 01:31:48 INFO - 'XPC_SERVICE_NAME': '0', 01:31:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:31:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:31:48 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-DtYYp9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:31:48 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 01:31:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:31:48 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-twGCAy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:31:49 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-54sH4j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:31:49 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-p07Q5W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:31:49 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-1WHrAU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 01:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:31:49 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-phABGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 01:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:31:49 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-7jraSc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 01:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:31:49 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-hOWLC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 01:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:31:49 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-BJpHgV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 01:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:31:49 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-ygCjol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:31:49 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-UMKk8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:31:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 01:31:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:31:49 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-zC1Vdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:31:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 01:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:31:50 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-96siLn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:31:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 01:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:31:50 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-KEWDqY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:31:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 01:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:31:50 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Bdlh8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:31:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 01:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:31:50 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-TyqkLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:31:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 01:31:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:31:50 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-tlU5ko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:31:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 01:31:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:31:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:31:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:31:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:31:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:31:50 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 01:31:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:31:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:31:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:31:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:31:51 INFO - Downloading blessings-1.6.tar.gz 01:31:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:31:52 INFO - Installing collected packages: blessings 01:31:52 INFO - Running setup.py install for blessings 01:31:52 INFO - Successfully installed blessings 01:31:52 INFO - Cleaning up... 01:31:52 INFO - Return code: 0 01:31:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:31:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:31:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:31:52 INFO - Reading from file tmpfile_stdout 01:31:52 INFO - Current package versions: 01:31:52 INFO - blessings == 1.6 01:31:52 INFO - blobuploader == 1.2.4 01:31:52 INFO - docopt == 0.6.1 01:31:52 INFO - functools32 == 3.2.3-2 01:31:52 INFO - jsonschema == 2.5.1 01:31:52 INFO - manifestparser == 1.1 01:31:52 INFO - mozInstall == 1.13 01:31:52 INFO - mozcrash == 1.0 01:31:52 INFO - mozdebug == 0.1 01:31:52 INFO - mozdevice == 0.51 01:31:52 INFO - mozfile == 1.2 01:31:52 INFO - mozhttpd == 0.7 01:31:52 INFO - mozinfo == 0.10 01:31:52 INFO - mozleak == 0.1 01:31:52 INFO - mozlog == 3.4 01:31:52 INFO - moznetwork == 0.27 01:31:52 INFO - mozprocess == 0.25 01:31:52 INFO - mozprofile == 0.28 01:31:52 INFO - mozrunner == 6.13 01:31:52 INFO - mozscreenshot == 0.1 01:31:52 INFO - mozsystemmonitor == 0.3 01:31:52 INFO - moztest == 0.8 01:31:52 INFO - mozversion == 1.4 01:31:52 INFO - psutil == 3.1.1 01:31:52 INFO - requests == 1.2.3 01:31:52 INFO - wsgiref == 0.1.2 01:31:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:31:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:31:52 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 0x10a7dac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7de768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2bbdad2d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a530d30>, '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 0x10a4facd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a521be8>, '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.nd1f81VNIQ/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.a7LKUh3utp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:31:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 01:31:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:31:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nd1f81VNIQ/Render', 01:31:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:31:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:31:52 INFO - 'HOME': '/Users/cltbld', 01:31:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:31:52 INFO - 'LOGNAME': 'cltbld', 01:31:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:52 INFO - 'MOZ_NO_REMOTE': '1', 01:31:52 INFO - 'NO_EM_RESTART': '1', 01:31:52 INFO - 'PAGER': '/bin/cat', 01:31:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:31:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:52 INFO - 'PWD': '/builds/slave/test', 01:31:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:31:52 INFO - 'SHELL': '/bin/bash', 01:31:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.a7LKUh3utp/Listeners', 01:31:52 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 01:31:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:31:52 INFO - 'USER': 'cltbld', 01:31:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:31:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:31:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:52 INFO - 'XPC_FLAGS': '0x0', 01:31:52 INFO - 'XPC_SERVICE_NAME': '0', 01:31:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:31:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:31:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:31:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 01:31:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 01:31:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:31:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 01:31:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 01:31:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 01:31:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 01:31:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 01:31:52 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 01:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:31:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:31:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:31:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:31:54 INFO - Downloading marionette_driver-1.4.0.tar.gz 01:31:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 01:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 01:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 01:31:54 INFO - Installing collected packages: marionette-driver 01:31:54 INFO - Running setup.py install for marionette-driver 01:31:54 INFO - Successfully installed marionette-driver 01:31:54 INFO - Cleaning up... 01:31:54 INFO - Return code: 0 01:31:54 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:31: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')]} 01:31:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:31: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')]} 01:31:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:31:54 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 0x10a7dac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a7de768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd2bbdad2d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a530d30>, '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 0x10a4facd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a521be8>, '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.nd1f81VNIQ/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.a7LKUh3utp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 01:31:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 01:31:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 01:31:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nd1f81VNIQ/Render', 01:31:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:31:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:31:54 INFO - 'HOME': '/Users/cltbld', 01:31:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:31:54 INFO - 'LOGNAME': 'cltbld', 01:31:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:31:54 INFO - 'MOZ_NO_REMOTE': '1', 01:31:54 INFO - 'NO_EM_RESTART': '1', 01:31:54 INFO - 'PAGER': '/bin/cat', 01:31:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:31:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:31:54 INFO - 'PWD': '/builds/slave/test', 01:31:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:31:54 INFO - 'SHELL': '/bin/bash', 01:31:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.a7LKUh3utp/Listeners', 01:31:54 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 01:31:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:31:54 INFO - 'USER': 'cltbld', 01:31:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:31:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:31:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:31:54 INFO - 'XPC_FLAGS': '0x0', 01:31:54 INFO - 'XPC_SERVICE_NAME': '0', 01:31:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:31:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:31:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:31:54 INFO - Cleaning up... 01:31:54 INFO - Return code: 0 01:31:54 INFO - Running post-action listener: _resource_record_post_action 01:31:54 INFO - Running post-action listener: _start_resource_monitoring 01:31:54 INFO - Starting resource monitoring. 01:31:54 INFO - [mozharness: 2017-06-19 08:31:54.813048Z] Finished create-virtualenv step (success) 01:31:54 INFO - [mozharness: 2017-06-19 08:31:54.813987Z] Running install step. 01:31:54 INFO - Running pre-action listener: _resource_record_pre_action 01:31:54 INFO - Running main action method: install 01:31:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:31:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:31:55 INFO - Reading from file tmpfile_stdout 01:31:55 INFO - Detecting whether we're running mozinstall >=1.0... 01:31:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:31:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:31:55 INFO - Reading from file tmpfile_stdout 01:31:55 INFO - Output received: 01:31:55 INFO - Usage: mozinstall [options] installer 01:31:55 INFO - Options: 01:31:55 INFO - -h, --help show this help message and exit 01:31:55 INFO - -d DEST, --destination=DEST 01:31:55 INFO - Directory to install application into. [default: 01:31:55 INFO - "/builds/slave/test"] 01:31:55 INFO - --app=APP Application being installed. [default: firefox] 01:31:55 INFO - mkdir: /builds/slave/test/build/application 01:31:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:31:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0.en-US.mac.dmg --destination /builds/slave/test/build/application 01:32:16 INFO - Reading from file tmpfile_stdout 01:32:16 INFO - Output received: 01:32:16 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 01:32:16 INFO - Running post-action listener: _resource_record_post_action 01:32:16 INFO - [mozharness: 2017-06-19 08:32:16.794956Z] Finished install step (success) 01:32:16 INFO - [mozharness: 2017-06-19 08:32:16.795122Z] Running setup-mitmproxy step. 01:32:16 INFO - Running pre-action listener: _resource_record_pre_action 01:32:16 INFO - Running main action method: setup_mitmproxy 01:32:16 INFO - Skipping: mitmproxy is not required 01:32:16 INFO - Running post-action listener: _resource_record_post_action 01:32:16 INFO - [mozharness: 2017-06-19 08:32:16.795767Z] Finished setup-mitmproxy step (success) 01:32:16 INFO - [mozharness: 2017-06-19 08:32:16.795877Z] Running run-tests step. 01:32:16 INFO - Running pre-action listener: _resource_record_pre_action 01:32:16 INFO - Running main action method: run_tests 01:32:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:32:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:32:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:32:16 INFO - 2017-06-19 01:32:16.810 screenresolution[1627:7499] starting screenresolution argv=screenresolution get 01:32:16 INFO - 2017-06-19 01:32:16.813 screenresolution[1627:7499] Display 0: 1600x1200x32@60 01:32:16 INFO - 2017-06-19 01:32:16.819 screenresolution[1628:7501] starting screenresolution argv=screenresolution list 01:32:16 INFO - Available Modes on Display 0 01:32:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:32:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:32:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:32:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:32:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:32:17 INFO - Intel Iris: 01:32:17 INFO - Chipset Model: Intel Iris 01:32:17 INFO - Type: GPU 01:32:17 INFO - Bus: Built-In 01:32:17 INFO - VRAM (Dynamic, Max): 1536 MB 01:32:17 INFO - Vendor: Intel (0x8086) 01:32:17 INFO - Device ID: 0x0a2e 01:32:17 INFO - Revision ID: 0x0009 01:32:17 INFO - Displays: 01:32:17 INFO - Display: 01:32:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:32:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:32:17 INFO - Display Serial Number: 1600x1200 60 01:32:17 INFO - Main Display: Yes 01:32:17 INFO - Mirror: Off 01:32:17 INFO - Online: Yes 01:32:17 INFO - Rotation: Supported 01:32:17 INFO - Return code: 0 01:32:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:32:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:32:17 INFO - Python 2.7.3 01:32:17 INFO - Return code: 0 01:32:17 INFO - grabbing minidump binary from tooltool 01:32: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')]} 01:32:17 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 0x10a530d30>, '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 0x10a4facd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a521be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:32:17 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 01:32:17 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 01:32:17 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 01:32:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:32:17 INFO - Return code: 0 01:32:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:32:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:32:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:32:17 INFO - ENV: RUST_BACKTRACE is now 1 01:32:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:32:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:32:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/H3M_wTJnTtyBk4Bfc5JCeQ/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 01:32:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/H3M_wTJnTtyBk4Bfc5JCeQ/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0005 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 01:32:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nd1f81VNIQ/Render', 01:32:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:32:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:32:17 INFO - 'HOME': '/Users/cltbld', 01:32:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:32:17 INFO - 'LOGNAME': 'cltbld', 01:32:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:32:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:32:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:32:17 INFO - 'MOZ_NO_REMOTE': '1', 01:32:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:32:17 INFO - 'NO_EM_RESTART': '1', 01:32:17 INFO - 'PAGER': '/bin/cat', 01:32:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:32:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:32:17 INFO - 'PWD': '/builds/slave/test', 01:32:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:32:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:32:17 INFO - 'RUST_BACKTRACE': '1', 01:32:17 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 01:32:17 INFO - 'SHELL': '/bin/bash', 01:32:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.a7LKUh3utp/Listeners', 01:32:17 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 01:32:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:32:17 INFO - 'USER': 'cltbld', 01:32:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:32:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:32:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:32:17 INFO - 'XPC_FLAGS': '0x0', 01:32:17 INFO - 'XPC_SERVICE_NAME': '0', 01:32:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:32:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/H3M_wTJnTtyBk4Bfc5JCeQ/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0005', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 01:32:17 INFO - mozversion application_buildid: 20170619000122 01:32:17 INFO - mozversion application_changeset: c2316b058cb69c5b08b59894ccdce32b5ecd29fc 01:32:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:32:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:32:17 INFO - mozversion application_name: Firefox 01:32:17 INFO - mozversion application_remotingname: firefox-dev 01:32:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 01:32:17 INFO - mozversion application_vendor: Mozilla 01:32:17 INFO - mozversion application_version: 55.0 01:32:17 INFO - mozversion platform_buildid: 20170619000122 01:32:17 INFO - mozversion platform_changeset: c2316b058cb69c5b08b59894ccdce32b5ecd29fc 01:32:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 01:32:17 INFO - mozversion platform_version: 55.0 01:32:17 INFO - using testdate: 1497861137 01:32:17 INFO - actual date: 1497861137 01:32:17 INFO - starting webserver on 'localhost:49226' 01:32:17 INFO - SUITE-START | Running 2 tests 01:32:17 INFO - TEST-START | dromaeo_css 01:32:17 INFO - operating with platform_type : mac_ 01:32:17 INFO - Initialising browser for dromaeo_css test... 01:32:17 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmpW5xhIY/profile 01:32:17 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 01:32:50 INFO - TEST-INFO | 1638: killed by SIGTERM 01:32:50 INFO - Browser initialized. 01:32:50 INFO - Running cycle 1/1 for dromaeo_css test... 01:32:50 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmpW5xhIY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:32:52 INFO - PID 1647 | 2017-06-19 01:32:52.587 plugin-container[1648:8066] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 01:32:52 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:32:52 INFO - PID 1647 | 2017-06-19 01:32:52.588 plugin-container[1648:8066] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 01:32:52 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:32:52 INFO - PID 1647 | 2017-06-19 01:32:52.588 plugin-container[1648:8066] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:32:53 INFO - PID 1647 | 2017-06-19 01:32:53.329 plugin-container[1649:8111] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 01:32:53 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:32:53 INFO - PID 1647 | 2017-06-19 01:32:53.329 plugin-container[1649:8111] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9417, name = 'com.apple.CFPasteboardClient' 01:32:53 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:32:53 INFO - PID 1647 | 2017-06-19 01:32:53.329 plugin-container[1649:8111] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:33:02 INFO - PID 1647 | RSS: Main: 214859776 01:33:02 INFO - PID 1647 | 01:36:07 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 01:36:09 INFO - PID 1647 | RSS: Main: 229421056 01:36:09 INFO - PID 1647 | 01:39:11 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 01:39:11 INFO - PID 1647 | RSS: Main: 244137984 01:39:11 INFO - PID 1647 | 01:42:13 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 01:42:13 INFO - PID 1647 | RSS: Main: 245977088 01:42:13 INFO - PID 1647 | 01:45:15 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 01:45:16 INFO - PID 1647 | RSS: Main: 247857152 01:45:16 INFO - PID 1647 | 01:48:18 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 01:48:19 INFO - PID 1647 | RSS: Main: 248754176 01:48:19 INFO - PID 1647 | 01:51:21 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 01:51:21 INFO - PID 1647 | RSS: Main: 246726656 01:51:21 INFO - PID 1647 | 01:51:21 INFO - PID 1647 | __start_tp_report 01:51:21 INFO - PID 1647 | _x_x_mozilla_page_load 01:51:21 INFO - PID 1647 | _x_x_mozilla_page_load_details 01:51:21 INFO - PID 1647 | |i|pagename|runs| 01:51:21 INFO - PID 1647 | |0;dojo.html;2667.8932842686295;2805.607214989902;3441.6386279440676;3524.929501409972;3580.087077795162;6198.597091189081;6221.875562488747;6256.24925008999;6272.435480806732;6299.433051025407;6257.780977665782;6293.874122517547;6320.841978950529;6327.209098862642;6391.137196478474;5478.506934375905;5510;5549.334079910396;5593.888122237553;5614.242077319571;4486;5543;5636.520895723868;5642.379338272786;5647.774089036434;5360.571154307646;5371.946280537184;5427.592930530226;5467.535259502927;5524.502794748451;5418.106012507931;5425.213344065128;5508.889822203533;5514.889702205934;5566.582506312043;5480.342358916916;5487.972560137214;5491.615586908918;5505.201745746885;5541.9168712469345;6291.748330066792;6306.1486699295465;6311.715972781236;6330.113784070188;6379.425851673325;6770.356816102462;6809.659517024129;6823.249442561314;6824.965875170642;6826.863462730717;4916.532929371704;5565.777368905239;6615.569987950767;6696.564723293019;6933.930660693379;6063.363346848561;6314.557980941382;6378.872422551616;6407.615543067431;6433.067205255212;7099.716011359591;7115.466340024415;7122.28777122294;7122.786316410516;7145.356917877416;5501.834944951658;6452.838679033061;6528.902066469007;6563.081168636395;6568.113304703899;6695.263521012685;6732.697028633724;6738.225104112992;6743.224529179109;6789.592624442549;6787.93212067873;6791.626460544718;6801.693923773442;6806.55757375769;6816.250212476623;6792.218894827158;6828.692708828015;6852.931470685329;6856.897146542806;6874.312568743086;5326.547243484347;5452.073147564924;5452.1276595745285;5454.563634909197;5456.7544460499375;4731.148393289241;4752.097101550694;4768.61851051915;4774.3077253798;4810.7835147418455;6245.437910587978;6314.494840412756;6425.518086143558;6459.773907913294;6475.028745688184;6960.686769095403;6974.232834388214;6975.232724400312;6979.825504362431;6981;6272.5922815016875;6438.195225596801;6444.581102228341;6460.224773027267;6485.4487368573255;5877.294724633071;5991.161237426763;6054.848628784228;6093.573449858556;6123.510119190454;6983.511154219156;7048.154221493453;7088.574685518679;7103.431725462054;7105.6447177639875;6857.622830744157;6920.7231710731;6929;6963.93036069653;6966.059581956371;5735.627184233012;7088;7100.289971002859;7103.609301488468;7121.14546254432;6758.4931130166415;6777.254502004874;6798.966005169942;6802.489813263926;6816.2502124767225;6705.0612914193025;6769.356911093439;6773.424258938046;6858.382745552724;6919.930800691928;6653.168353955755;6673.265940746417;6704.597724136373;6710.16122984627;6744.662766861539;6721.22705888829;6733.225679046972;6737.9326206737305;6743.1908171019795;6757.864842703217;5073.543381095719;6151.64663773969;6496.837579060561;6567.934320656924;6602.471802255712;6482.967585162044;6525.3800888915475;6562.606243625398;6576.243731970693;6580.80257592273;6468.15913931186;6473.287938326875;6522.086907833002;6575.4410875076155;6583.901241481286 01:51:21 INFO - PID 1647 | |1;ext.html;738.7820592925087;755.176857225624;780.1067777394883;846.8948022830208;859.188067276424;36612;40140.59859401409;40141;40143.996400090015;40188.799056004755;31147.68852311476;39460.40809387854;39470.605293947054;39488.407673884845;39637;7538.7738367848915;7589.468737188399;7651;7662.003939487887;7672.0409948756405;7608;7703.191164927686;7722.691092356327;7733.072031356245;7782.883256751154;6447.677616119198;7671.769846904602;7701.0758708955;7706.113796913371;7717.305442510173;7620.352270057052;8099.068607110199;8323.50058996456;8330.208630180185;8365.07984121746;8243.381746369047;8295.377846661524;8315.459495132796;8323.1260717625;8347.373946953941;32359.51460728091;36970.63029369699;37082.81458592717;37206.44190337147;37437.25125497502;3595.7842529448053;3634.9818250908843;3635.291118231946;3638.272345530904;3649.7080233581514;30082.24794380136;30406.695933040606;30441;30527.847360763055;30639.693603063908;3062.647795503501;3108.1141874565865;3110.6422761382505;3142.889998850052;3159.0522843146964;260945;265106;265411;318130;319670;38917.6108238914;47779.5222047782;47832;48142.27786583204;48873;51541.48458515367;52156.21765673442;52164;52228.47771522312;52343.73828130835;15702.9214853925;15807.525774226562;15810.68378632444;15829.604259893364;15854.603634908988;12771;12813.423395947204;12838.871611283954;12840.293783841978;12844.614661560168;13721.245331506663;13928.651783705489;13943.930280348737;13950.302484875716;13986.02097853161;4813.243166244296;6045.697715114226;6113.174721412553;6115.388461153849;6158.445739883432;1014.1683819268277;1021.5096753558186;1022.5909636145601;1074.822654262038;1074.9193810464246;23661.88169059178;30950.69049309523;30953.226169345497;31620.841895790832;31630.36739265248;944.367273926471;1038.522279751323;1083.8374243863325;1084.8318510630866;1135.6820090374708;11968.16222864391;15187.316570754343;16651.750223746654;16742.911710738765;16873.662526749395;296761;297872;298347;298758;299362;56856.71571642115;57036;57050.714746426005;57601.71199143978;57617.13574296389;1414.5968399006233;1519.6124988127956;1545.4436402895174;1557.2213893053474;1566.0368873142936;28466;28992.13023609295;29915.401691966475;30411.695883040888;30664.386712266078;38183.61816381912;38647.613523865526;38686;38686.80656596699;38713.41929870998;282244.5887770548;282545;285127;285372;286569;28508.57237141403;28930.84276628911;28944.56583151212;29072.12783616495;33928.83035584806;38212.42681359837;39904.401433979096;40283.597164029154;40592.797036015814;42383.1523369537;27249.86375068112;27638.5854212191;28557.42885142244;29861.5520767193;30748.312921769557;29070.7092929068;29990.25024374321;30054.54918176273;30576.388472229984;30617.54073688852;35636;35860.92417227487;36310.27379452449;36327.63672363242;36346.63653363432;26677.466450671265;28994.7100528992;29330.560041598965;30659.693403065685;30761.230969225053;22880.656790148492;23988;24102.51794964126;24246.515069698155;24455.633165502175 01:51:21 INFO - PID 1647 | |2;jquery.html;486.28273296887073;500.2146629791228;515.3763945625792;521.2467983763461;521.7938914128919;21403;21942.780572194275;22195.778042219572;22909.969051392687;23015.539689206205;23990.640140397863;24081.759182408172;24101.87949060252;24352.634710479357;24380.268591942226;6191.133241346215;6317.557770956035;6393.808185754435;6394.6482943438095;6395.584287021352;6261;6366.236051673805;6372.936270637304;6392.4886009119155;6401.679916004205;5654.406287339832;6162.7534898604;6220.284667263255;6235.625862448245;6242.687865606724;6221.844453888666;6246.437820596168;6253.624782513064;6255.843603909893;6256.374362563753;5652.660840349551;5704.401037891044;5710.77156913723;5716.628419152783;5716.685582292972;23042.769572304398;25993.870030649727;27555.724442755516;27971.58042629362;28368.432631347463;1996.7703714072777;2304.101400453824;2343.718753749544;2343.7656234376595;2349.929502114939;14713.485028024075;18763.34328298503;18778.342758003542;18799.060046997733;18802.717959230624;1945.7838850718301;2186.387811412791;2187.2344679362254;2226.4990377165173;2238.261373746676;167965;176070;185430.2185467219;185832;216527;13504.662383440493;13813.309334533233;14433.35049922735;14729.116253024853;15033.849661503464;19946.800531994784;19973.800261997483;19998.800011999985;20001.399958001282;20071.698924516142;10048.748781280527;10116.443595602312;10250.231232657432;10293.794124117476;10328.122109620615;9764.267679924034;9789.706308810604;9855.408675479493;9857.408555486694;9928;8803.667028237522;8826.86759698605;8868.02451730309;8937.553122343857;8941.630467926005;2585.857777822238;2587.573050446693;2588.7540683635025;2596.337933826862;2599.8830052647677;820.8399362124328;918.7703074231442;951.6145960885852;983.8130755156641;1030.6135199300263;20001.099950502263;20111.393210082006;20210.797892021186;20355.796442035688;21503.892480537495;976.1361195342173;1007.8588997540351;1035.9015893490107;1053.210092430677;1080;10392.168626509861;10406.323588966692;10570.630027948984;10576.312539684895;10700.892991070144;591102;607251;617490;622111;626720;146400.2679986593;155810.44189558187;156563;157001.42998570096;157052;1222.9694257643453;1228.993855030719;1258.804885242789;1261.1928365845865;1271.0594160321295;17492.77550571448;17766.289348426435;17876.642467150323;18160.18279177414;18662.440126795675;65697.34302657103;67117;67565.66217168883;68218.65890670515;68827;89621.75945601412;93078.06921930694;96239;96881;97785.51107244418;11226.66320010401;11244.943775281072;11636.476358564054;11652.766944660889;11655.358955257203;13356;18419.07904604791;18690.532736681693;18749.812501874807;18794.342198023005;14957.700845983238;15096.471623493126;15162.545123646309;15181.772273416129;15213.467528636445;12894.806577901152;13276.137051091648;13434.395452204479;13457.663558411117;13793.724125517634;15378.307976140877;15388.692226155637;15437.459688910834;15502.612434689223;15659.686806263582;11525.366104864313;11560.479778409823;11629.011534019703;11691.181617286707;11736.530538778354;14316;14579.979401441797;14589;14623.561293161221;14641.26793660334 01:51:21 INFO - PID 1647 | |3;mootools.html;1440.3302464354074;1463.8536146385356;1484.3765618440254;1511.1084460168506;1511.8866085043621;13477.595672129839;13894.166350019006;13935.163890166597;14082.295885205749;14128.293585320718;13262.336883155851;13620.45518179274;14030.438782448715;14146.858531414682;14172.93702972276;2433.9269821905373;2662.347724807425;2786.2755683522228;2789.5397259452166;2826.8727907244224;2472.5796614575565;2809.3257618171697;2816.5352716801804;2818.112294627196;2818.3517790908104;2749.752522272995;2777.4167424840707;2799.454106449243;2799.8040137190405;2801.32768135647;2777.8888844446196;2803.11701813929;2814.6481689788775;2816.85915704215;2817.4928512867677;2792.6788419331833;2796.9021084261954;2799.5380762174423;2800.2579316481147;2812.803103782736;13756.793648095287;13841.30793460323;13916.095453795471;13948.930255348658;13974.371153298125;7561.432892533082;7713.807154821118;7760.844783104363;7838.255365740246;7863.13505514393;4713.505081966378;4938.80244790208;5028.195488721787;5041.470645582234;5080.415752188509;1417.55347065675;1423.1745587559294;1423.950161744334;1443.682389874226;1448.3265281644085;20834.47913802137;21031.158753649837;21101.5779684407;21104.894475527522;21114.577708445744;6576.934230657728;6721.290487773603;6839.897401538981;6873;6926.515143939876;14696.632584185481;14709.411623535152;14714.705705885823;14724.484643037656;14732.779008314883;7529.924700752922;7614.809629759189;7652;7655.119661238918;7655.540667559964;5996.37038110992;6208.72060757267;6211.937880621226;6229.4393504583895;6562.803115906435;5132.358455193101;5234.712090834964;5312.9734351328;5418.945810541844;5469.480399362054;1548.7057459082964;1549.651328451112;1551.8603325700742;1560.6956643454416;1567.5062355358025;703.7466512055656;835.5863847395457;855.4669216137313;859.3211363023266;885.0927799006071;6337.207849018872;7963.601819908981;8070.354371650254;8282.71010514629;8466.703665371682;945.4280160502934;974.9561269742878;977.1108291454742;1017.9236557258236;1032.6695457453693;4881.194602890554;5024.723640199824;5133.666311689803;5237.8428647140645;5266.945891102473;18407.447776566725;18909.621807563773;18926.716099258523;18953.43139705811;19239.422817315502;17952;18565.44303670892;18901.24395024211;19140.521486962658;19369.4189174325;9567.808643827084;9711.417314961125;9749.707508774749;9761.316707830525;9768.560414781352;7815.960920195363;7830.1386847447875;8287.461315014505;8523.977122745311;8807.735767926972;14540.200288983784;16842.831571684615;17045.062521561435;17197.65604687924;17400.303987840343;16952.06763627963;17291.221895015005;17905.925644461375;18317.267309307477;18401.53996150107;10768.676939691823;10896.346219226873;11127.666170014912;11230.94384528072;11271.549138034386;12998.935005324913;13029.674258143623;13169.736605268032;13203.273819939996;13245;10154.847677284699;10234.897651023692;10273.794524109626;10443.843342349719;10546.419946902994;9909.405435673883;9946.403215807075;10322.329048611817;10392.896071039495;10395;8981.730548083568;9097.636094556143;9132.680356187502;9148.63405463774;9156.725298241063;6615.966920165561;6805.523613347216;6873.381395674413;6990.860182796418;7140.750073747394;6986.54587451814;7011.26381729911;7107.680154392961;7229.783106506813;7244.963775181091 01:51:21 INFO - PID 1647 | |4;prototype.html;381.28509045539613;423.3121178085611;440.28673548850867;463.4068392457655;468.54317040885144;6438.195225596801;6509.9909514025285;6545.181852268466;6550.737970481175;6563.540552161345;6172.382761723826;6350.714217860196;6369.904451433221;6381.872362552746;6398.728451399745;4967;4997.875053123683;5006.173981293091;5009.098362294785;5026.1204289249235;4970.329005584254;4986.401631804189;5016.924746128811;5024.743814046488;5059.089363914494;4390.582894625006;4832.323474713543;4931.630127740415;5011.724355160465;5020.523050310233;4575.810289324766;4991.278009086859;5008.898042430659;5013.623978201649;5015.749212539395;3449.5515582974303;4058.939115913264;4252.9367658085475;4294.26997410441;4565.771711414449;6180.536459765535;6212.782552610637;6223.968880155616;6227.346128656516;6256.624602523863;6059.454649081604;6069.605475644069;6075.210222671025;6078.848028799272;6086.847828804271;5763.855903602402;6087.4825639820665;6563.949768037091;6996.615186164759;7050.153981522199;5311.707856067915;5317.707526086064;5330.600204984603;5330.92003619946;5334.3598768147685;6360.522960777914;6387.648679322682;6930.930690693079;7410.888836667455;7416;4789.850910271402;4828.66199366048;5433.673979561239;5506.504414602705;5523.751431185607;5544.695041772741;5575.386707462176;5588.1059030555725;5597.188407680864;5604.3835178130375;5106.744662766847;6061.696915154224;6080.878382432326;6108.8778224436155;6188.535859810532;6436.645984470899;6972.58164510131;6982.965085174542;6989.706904222735;7004.089468369181;1787.499500139963;1864.4127099963468;1876.3901731937176;1878.8684792064423;1880.8965506896977;1226.9509219631116;1336.5255334356164;1359.8436179839248;1369.0963963783854;1383.674836413442;6233.781817636361;6295.716692748837;6309.211348581427;6461.676916154173;6519.380658837403;6041.879162416815;6637.212370804274;7034.859302813915;7070.858582828315;7149.82125446868;6163.506919446433;6467.863086403185;6475.575373417841;6493.772717954849;6520;3278.213228825065;3640.906478774715;3660.871869484555;3667.559892812826;3692.2246328271362;6406.8718625627225;7116.003759473678;7281.034120787358;7296.416286697052;7296.70813167478;5678.422593765901;6051.606645568024;6911.516193866381;7207.747728829466;7288.890666640004;6258.276325586269;6383.585066970632;7049.048378468841;7215.927840721525;7240.927590724131;7077.256888026683;7098.219195888551;7098.645067746489;7100.751473698396;7100.751473698396;6114.327423983269;6914.377706006484;6947.201071876799;6947.826304342432;6960.5127641066665;6811.5231933766145;6827.6244806536115;7210.89183662235;7231.963840180765;7326.853462930818;4092.5676013395073;4855.6601037927;4878.609711223023;4884.413870335582;4885.584725298248;6537.738490460329;7093.574385536885;7098.609576473344;7135.8572828544175;7152.568126836901;5396.568274538106;6266.749330026748;6861.862762744817;6866.210385805496;6884.586924784529;6865.965670171817;6869.381755642016;6872.278410766998;6880.621565813728;6882.8967565485555;5583;6150.7847225346895;6670.266270710121;6716.5634233774645;6729.192496900403;5935.34711181761;6388.233411990591;6865.72983997962;6880;6980.476464265302;6685;6694.899576506454;6720.9327906722265;6725.4619630430425;6725.630090345078 01:51:21 INFO - PID 1647 | |5;yui.html;830.070321240211;838.4591938199858;844.8817165596816;863.1196179896506;867.9218870301673;16636.58408539787;16970.066646334446;17165.88421752585;17202.3119075237;17202.74195887061;16777.496675099737;18286.817131828677;18388.816111838878;18418.539536511595;18428.631427371445;827.2761333832897;925.3383830561147;937.2267878999818;950.2872845365976;950.6245033211877;911.9908800911973;966.4684423567045;967.6322997261066;969.6460791810973;971.4025874087421;816.2001238785994;989.717930389839;996.9780974501122;996.9880571220217;1004.7488127968008;911.1435250864166;972.1785091597588;979.3732011512637;979.9118079372855;980.8626792249091;720.5784615999646;721.1490441279288;722.3138018882031;723.3923504256397;726.4043484342841;17111.57221069483;17193;17435.912820435944;17482.737758933497;17621.55946101345;17456.563585910328;17511.299548018193;17540.21069051883;17860;17926.91036544822;14746.705065898623;14911;15024.699506009927;15037.849621503754;15050.397984080733;3583.802890841003;3584.551931008624;3601.657842504958;3611.3026877367597;3612.4761909523236;150488;154929;157259.42740572678;159019.20490397475;159284;13806.240656763974;13854.376553055137;13927.651808704863;14030.649233769238;14099.295035248197;26547.601785973227;26627.068052618066;27161.049363272192;27295.590566141524;27314.453710926067;7996.520208787491;8097.2712455879255;8099.230573095547;8108.635111420001;8124;4006.8397264109694;4090.611391917763;4135.91728165435;4155.459790227251;4198.9370159447635;4646.930296045562;4740.786664600101;4793.784279707422;4802.375691160127;4805.207140821795;975.351391324761;995.0994350113159;1008.0373243552536;1011.5650270383805;1011.5953618552638;573.2290069856037;619.1795870471643;658.2035736758576;667.3993405934698;688.1810645332039;11474.311541307548;11918.88081119195;12322.75354492923;12526.248425094524;12760.80858787119;621.5711159300068;643.1992169748693;656.9014647802868;672.9865402691919;685.0306815855608;5389.353277606712;5733.254676891977;5782.074868021096;5912.083627037816;5963.9264932311735;127374;146993;156166.2191689057;158039;162216.56675149882;104238.87283381511;112793;123931;124092;128323;110759.44620276846;112627.58734393626;113969;114557.85442145639;118004.40997794956;11153.442327883735;11385.03227225695;11408.144389170679;11422.25755325901;11531.654050378502;48924.77688057827;48955;49802.50592482231;49840.50159498359;50102;64915.67542162259;66325.66837165783;68422.63154736778;69513.65243173752;70100.64949675219;14724.969252152247;15261.160636165117;15326.923365383102;15516;15537.68924621524;16755.74866376981;16857.91571042137;16932.40736574214;17011.91494042522;17256.568585785455;14327.06874053225;15358.385664573292;15463.922680386526;15564;16976.151192440575;16763.07803070794;17467.12664366802;17582.472525824247;17612.471625851245;18090.81909180944;16010.963284417308;16727.581810454834;17234.96590204592;17320.82679173192;17378.131093445023;11824.82262766042;11987.460564274803;12646.355973723357;12667.619971400873;12859.16415432994;12769;12973.54592589255;13190.472381104648;13332.266725329811;13543.525976590772 01:51:21 INFO - PID 1647 | __end_tp_report 01:51:21 INFO - PID 1647 | __start_cc_report 01:51:21 INFO - PID 1647 | _x_x_mozilla_cycle_collect,394 01:51:21 INFO - PID 1647 | __end_cc_report 01:51:21 INFO - PID 1647 | __startTimestamp1497862281556__endTimestamp 01:51:21 INFO - PID 1647 | 01:51:21 INFO - PID 1647 | ------- Summary: start ------- 01:51:21 INFO - PID 1647 | Number of tests: 6 01:51:21 INFO - PID 1647 | 01:51:21 INFO - PID 1647 | [#0] dojo.html Cycles:165 Average:6232.19 Median:6475.03 stddev:824.52 (12.7%) stddev-sans-first:778.20 01:51:21 INFO - PID 1647 | Values: 2667.9 2805.6 3441.6 3524.9 3580.1 6198.6 6221.9 6256.2 6272.4 6299.4 6257.8 6293.9 6320.8 6327.2 6391.1 5478.5 5510.0 5549.3 5593.9 5614.2 4486.0 5543.0 5636.5 5642.4 5647.8 5360.6 5371.9 5427.6 5467.5 5524.5 5418.1 5425.2 5508.9 5514.9 5566.6 5480.3 5488.0 5491.6 5505.2 5541.9 6291.7 6306.1 6311.7 6330.1 6379.4 6770.4 6809.7 6823.2 6825.0 6826.9 4916.5 5565.8 6615.6 6696.6 6933.9 6063.4 6314.6 6378.9 6407.6 6433.1 7099.7 7115.5 7122.3 7122.8 7145.4 5501.8 6452.8 6528.9 6563.1 6568.1 6695.3 6732.7 6738.2 6743.2 6789.6 6787.9 6791.6 6801.7 6806.6 6816.3 6792.2 6828.7 6852.9 6856.9 6874.3 5326.5 5452.1 5452.1 5454.6 5456.8 4731.1 4752.1 4768.6 4774.3 4810.8 6245.4 6314.5 6425.5 6459.8 6475.0 6960.7 6974.2 6975.2 6979.8 6981.0 6272.6 6438.2 6444.6 6460.2 6485.4 5877.3 5991.2 6054.8 6093.6 6123.5 6983.5 7048.2 7088.6 7103.4 7105.6 6857.6 6920.7 6929.0 6963.9 6966.1 5735.6 7088.0 7100.3 7103.6 7121.1 6758.5 6777.3 6799.0 6802.5 6816.3 6705.1 6769.4 6773.4 6858.4 6919.9 6653.2 6673.3 6704.6 6710.2 6744.7 6721.2 6733.2 6737.9 6743.2 6757.9 5073.5 6151.6 6496.8 6567.9 6602.5 6483.0 6525.4 6562.6 6576.2 6580.8 6468.2 6473.3 6522.1 6575.4 6583.9 01:51:21 INFO - PID 1647 | 01:51:21 INFO - PID 1647 | [#1] ext.html Cycles:180 Average:44406.29 Median:31625.60 stddev:75880.03 (239.9%) stddev-sans-first:76022.06 01:51:21 INFO - PID 1647 | Values: 738.8 755.2 780.1 846.9 859.2 36612.0 40140.6 40141.0 40144.0 40188.8 31147.7 39460.4 39470.6 39488.4 39637.0 7538.8 7589.5 7651.0 7662.0 7672.0 7608.0 7703.2 7722.7 7733.1 7782.9 6447.7 7671.8 7701.1 7706.1 7717.3 7620.4 8099.1 8323.5 8330.2 8365.1 8243.4 8295.4 8315.5 8323.1 8347.4 32359.5 36970.6 37082.8 37206.4 37437.3 3595.8 3635.0 3635.3 3638.3 3649.7 30082.2 30406.7 30441.0 30527.8 30639.7 3062.6 3108.1 3110.6 3142.9 3159.1 260945.0 265106.0 265411.0 318130.0 319670.0 38917.6 47779.5 47832.0 48142.3 48873.0 51541.5 52156.2 52164.0 52228.5 52343.7 15702.9 15807.5 15810.7 15829.6 15854.6 12771.0 12813.4 12838.9 12840.3 12844.6 13721.2 13928.7 13943.9 13950.3 13986.0 4813.2 6045.7 6113.2 6115.4 6158.4 1014.2 1021.5 1022.6 1074.8 1074.9 23661.9 30950.7 30953.2 31620.8 31630.4 944.4 1038.5 1083.8 1084.8 1135.7 11968.2 15187.3 16651.8 16742.9 16873.7 296761.0 297872.0 298347.0 298758.0 299362.0 56856.7 57036.0 57050.7 57601.7 57617.1 1414.6 1519.6 1545.4 1557.2 1566.0 28466.0 28992.1 29915.4 30411.7 30664.4 38183.6 38647.6 38686.0 38686.8 38713.4 282244.6 282545.0 285127.0 285372.0 286569.0 28508.6 28930.8 28944.6 29072.1 33928.8 38212.4 39904.4 40283.6 40592.8 42383.2 27249.9 27638.6 28557.4 29861.6 30748.3 29070.7 29990.3 30054.5 30576.4 30617.5 35636.0 35860.9 36310.3 36327.6 36346.6 26677.5 28994.7 29330.6 30659.7 30761.2 22880.7 23988.0 24102.5 24246.5 24455.6 01:51:21 INFO - PID 1647 | 01:51:21 INFO - PID 1647 | [#2] jquery.html Cycles:180 Average:40555.80 Median:21453.45 stddev:104986.09 (489.4%) stddev-sans-first:105237.50 01:51:21 INFO - PID 1647 | Values: 486.3 500.2 515.4 521.2 521.8 21403.0 21942.8 22195.8 22910.0 23015.5 23990.6 24081.8 24101.9 24352.6 24380.3 6191.1 6317.6 6393.8 6394.6 6395.6 6261.0 6366.2 6372.9 6392.5 6401.7 5654.4 6162.8 6220.3 6235.6 6242.7 6221.8 6246.4 6253.6 6255.8 6256.4 5652.7 5704.4 5710.8 5716.6 5716.7 23042.8 25993.9 27555.7 27971.6 28368.4 1996.8 2304.1 2343.7 2343.8 2349.9 14713.5 18763.3 18778.3 18799.1 18802.7 1945.8 2186.4 2187.2 2226.5 2238.3 167965.0 176070.0 185430.2 185832.0 216527.0 13504.7 13813.3 14433.4 14729.1 15033.8 19946.8 19973.8 19998.8 20001.4 20071.7 10048.7 10116.4 10250.2 10293.8 10328.1 9764.3 9789.7 9855.4 9857.4 9928.0 8803.7 8826.9 8868.0 8937.6 8941.6 2585.9 2587.6 2588.8 2596.3 2599.9 820.8 918.8 951.6 983.8 1030.6 20001.1 20111.4 20210.8 20355.8 21503.9 976.1 1007.9 1035.9 1053.2 1080.0 10392.2 10406.3 10570.6 10576.3 10700.9 591102.0 607251.0 617490.0 622111.0 626720.0 146400.3 155810.4 156563.0 157001.4 157052.0 1223.0 1229.0 1258.8 1261.2 1271.1 17492.8 17766.3 17876.6 18160.2 18662.4 65697.3 67117.0 67565.7 68218.7 68827.0 89621.8 93078.1 96239.0 96881.0 97785.5 11226.7 11244.9 11636.5 11652.8 11655.4 13356.0 18419.1 18690.5 18749.8 18794.3 14957.7 15096.5 15162.5 15181.8 15213.5 12894.8 13276.1 13434.4 13457.7 13793.7 15378.3 15388.7 15437.5 15502.6 15659.7 11525.4 11560.5 11629.0 11691.2 11736.5 14316.0 14580.0 14589.0 14623.6 14641.3 01:51:21 INFO - PID 1647 | 01:51:21 INFO - PID 1647 | [#3] mootools.html Cycles:180 Average:8526.54 Median:2799.50 stddev:5698.71 (203.6%) stddev-sans-first:5689.82 01:51:21 INFO - PID 1647 | Values: 1440.3 1463.9 1484.4 1511.1 1511.9 13477.6 13894.2 13935.2 14082.3 14128.3 13262.3 13620.5 14030.4 14146.9 14172.9 2433.9 2662.3 2786.3 2789.5 2826.9 2472.6 2809.3 2816.5 2818.1 2818.4 2749.8 2777.4 2799.5 2799.8 2801.3 2777.9 2803.1 2814.6 2816.9 2817.5 2792.7 2796.9 2799.5 2800.3 2812.8 13756.8 13841.3 13916.1 13948.9 13974.4 7561.4 7713.8 7760.8 7838.3 7863.1 4713.5 4938.8 5028.2 5041.5 5080.4 1417.6 1423.2 1424.0 1443.7 1448.3 20834.5 21031.2 21101.6 21104.9 21114.6 6576.9 6721.3 6839.9 6873.0 6926.5 14696.6 14709.4 14714.7 14724.5 14732.8 7529.9 7614.8 7652.0 7655.1 7655.5 5996.4 6208.7 6211.9 6229.4 6562.8 5132.4 5234.7 5313.0 5418.9 5469.5 1548.7 1549.7 1551.9 1560.7 1567.5 703.7 835.6 855.5 859.3 885.1 6337.2 7963.6 8070.4 8282.7 8466.7 945.4 975.0 977.1 1017.9 1032.7 4881.2 5024.7 5133.7 5237.8 5266.9 18407.4 18909.6 18926.7 18953.4 19239.4 17952.0 18565.4 18901.2 19140.5 19369.4 9567.8 9711.4 9749.7 9761.3 9768.6 7816.0 7830.1 8287.5 8524.0 8807.7 14540.2 16842.8 17045.1 17197.7 17400.3 16952.1 17291.2 17905.9 18317.3 18401.5 10768.7 10896.3 11127.7 11230.9 11271.5 12998.9 13029.7 13169.7 13203.3 13245.0 10154.8 10234.9 10273.8 10443.8 10546.4 9909.4 9946.4 10322.3 10392.9 10395.0 8981.7 9097.6 9132.7 9148.6 9156.7 6616.0 6805.5 6873.4 6990.9 7140.8 6986.5 7011.3 7107.7 7229.8 7245.0 01:51:21 INFO - PID 1647 | 01:51:21 INFO - PID 1647 | [#4] prototype.html Cycles:180 Average:5677.05 Median:6230.56 stddev:1648.00 (26.5%) stddev-sans-first:1603.97 01:51:21 INFO - PID 1647 | Values: 381.3 423.3 440.3 463.4 468.5 6438.2 6510.0 6545.2 6550.7 6563.5 6172.4 6350.7 6369.9 6381.9 6398.7 4967.0 4997.9 5006.2 5009.1 5026.1 4970.3 4986.4 5016.9 5024.7 5059.1 4390.6 4832.3 4931.6 5011.7 5020.5 4575.8 4991.3 5008.9 5013.6 5015.7 3449.6 4058.9 4252.9 4294.3 4565.8 6180.5 6212.8 6224.0 6227.3 6256.6 6059.5 6069.6 6075.2 6078.8 6086.8 5763.9 6087.5 6563.9 6996.6 7050.2 5311.7 5317.7 5330.6 5330.9 5334.4 6360.5 6387.6 6930.9 7410.9 7416.0 4789.9 4828.7 5433.7 5506.5 5523.8 5544.7 5575.4 5588.1 5597.2 5604.4 5106.7 6061.7 6080.9 6108.9 6188.5 6436.6 6972.6 6983.0 6989.7 7004.1 1787.5 1864.4 1876.4 1878.9 1880.9 1227.0 1336.5 1359.8 1369.1 1383.7 6233.8 6295.7 6309.2 6461.7 6519.4 6041.9 6637.2 7034.9 7070.9 7149.8 6163.5 6467.9 6475.6 6493.8 6520.0 3278.2 3640.9 3660.9 3667.6 3692.2 6406.9 7116.0 7281.0 7296.4 7296.7 5678.4 6051.6 6911.5 7207.7 7288.9 6258.3 6383.6 7049.0 7215.9 7240.9 7077.3 7098.2 7098.6 7100.8 7100.8 6114.3 6914.4 6947.2 6947.8 6960.5 6811.5 6827.6 7210.9 7232.0 7326.9 4092.6 4855.7 4878.6 4884.4 4885.6 6537.7 7093.6 7098.6 7135.9 7152.6 5396.6 6266.7 6861.9 6866.2 6884.6 6866.0 6869.4 6872.3 6880.6 6882.9 5583.0 6150.8 6670.3 6716.6 6729.2 5935.3 6388.2 6865.7 6880.0 6980.5 6685.0 6694.9 6720.9 6725.5 6725.6 01:51:21 INFO - PID 1647 | 01:51:21 INFO - PID 1647 | [#5] yui.html Cycles:180 Average:26326.14 Median:17617.02 stddev:41237.29 (234.1%) stddev-sans-first:41308.53 01:51:21 INFO - PID 1647 | Values: 830.1 838.5 844.9 863.1 867.9 16636.6 16970.1 17165.9 17202.3 17202.7 16777.5 18286.8 18388.8 18418.5 18428.6 827.3 925.3 937.2 950.3 950.6 912.0 966.5 967.6 969.6 971.4 816.2 989.7 997.0 997.0 1004.7 911.1 972.2 979.4 979.9 980.9 720.6 721.1 722.3 723.4 726.4 17111.6 17193.0 17435.9 17482.7 17621.6 17456.6 17511.3 17540.2 17860.0 17926.9 14746.7 14911.0 15024.7 15037.8 15050.4 3583.8 3584.6 3601.7 3611.3 3612.5 150488.0 154929.0 157259.4 159019.2 159284.0 13806.2 13854.4 13927.7 14030.6 14099.3 26547.6 26627.1 27161.0 27295.6 27314.5 7996.5 8097.3 8099.2 8108.6 8124.0 4006.8 4090.6 4135.9 4155.5 4198.9 4646.9 4740.8 4793.8 4802.4 4805.2 975.4 995.1 1008.0 1011.6 1011.6 573.2 619.2 658.2 667.4 688.2 11474.3 11918.9 12322.8 12526.2 12760.8 621.6 643.2 656.9 673.0 685.0 5389.4 5733.3 5782.1 5912.1 5963.9 127374.0 146993.0 156166.2 158039.0 162216.6 104238.9 112793.0 123931.0 124092.0 128323.0 110759.4 112627.6 113969.0 114557.9 118004.4 11153.4 11385.0 11408.1 11422.3 11531.7 48924.8 48955.0 49802.5 49840.5 50102.0 64915.7 66325.7 68422.6 69513.7 70100.6 14725.0 15261.2 15326.9 15516.0 15537.7 16755.7 16857.9 16932.4 17011.9 17256.6 14327.1 15358.4 15463.9 15564.0 16976.2 16763.1 17467.1 17582.5 17612.5 18090.8 16011.0 16727.6 17235.0 17320.8 17378.1 11824.8 11987.5 12646.4 12667.6 12859.2 12769.0 12973.5 13190.5 13332.3 13543.5 01:51:21 INFO - PID 1647 | -------- Summary: end -------- 01:51:21 INFO - PID 1647 | 01:51:22 INFO - TEST-INFO | 1647: exit 0 01:51:22 INFO - TEST-OK | dromaeo_css | took 1144649ms 01:51:22 INFO - TEST-START | kraken 01:51:22 INFO - operating with platform_type : mac_ 01:51:22 INFO - Initialising browser for kraken test... 01:51:22 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmpx2YKxs/profile 01:51:22 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 01:51:55 INFO - TEST-INFO | 1657: killed by SIGTERM 01:51:55 INFO - Browser initialized. 01:51:55 INFO - Running cycle 1/1 for kraken test... 01:51:55 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmpx2YKxs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:51:57 INFO - PID 1661 | 2017-06-19 01:51:57.048 plugin-container[1662:10645] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 01:51:57 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:51:57 INFO - PID 1661 | 2017-06-19 01:51:57.048 plugin-container[1662:10645] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 01:51:57 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:51:57 INFO - PID 1661 | 2017-06-19 01:51:57.048 plugin-container[1662:10645] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:51:57 INFO - PID 1661 | 2017-06-19 01:51:57.842 plugin-container[1663:10691] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4843, name = 'com.apple.tsm.portname' 01:51:57 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:51:57 INFO - PID 1661 | 2017-06-19 01:51:57.842 plugin-container[1663:10691] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b03, name = 'com.apple.CFPasteboardClient' 01:51:57 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 01:51:57 INFO - PID 1661 | 2017-06-19 01:51:57.842 plugin-container[1663:10691] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 01:52:07 INFO - PID 1661 | RSS: Main: 216068096 01:52:07 INFO - PID 1661 | 01:52:09 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 01:52:10 INFO - PID 1661 | RSS: Main: 228700160 01:52:10 INFO - PID 1661 | 01:52:13 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 01:52:14 INFO - PID 1661 | RSS: Main: 238514176 01:52:14 INFO - PID 1661 | 01:52:17 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 01:52:18 INFO - PID 1661 | RSS: Main: 243679232 01:52:18 INFO - PID 1661 | 01:52:19 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 01:52:20 INFO - PID 1661 | RSS: Main: 244080640 01:52:20 INFO - PID 1661 | 01:52:22 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:52:23 INFO - PID 1661 | RSS: Main: 244998144 01:52:23 INFO - PID 1661 | 01:52:26 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 01:52:27 INFO - PID 1661 | RSS: Main: 248934400 01:52:27 INFO - PID 1661 | 01:52:30 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 01:52:30 INFO - PID 1661 | RSS: Main: 249995264 01:52:30 INFO - PID 1661 | 01:52:34 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 01:52:34 INFO - PID 1661 | RSS: Main: 252796928 01:52:34 INFO - PID 1661 | 01:52:36 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:52:36 INFO - PID 1661 | RSS: Main: 252628992 01:52:36 INFO - PID 1661 | 01:52:38 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:52:39 INFO - PID 1661 | RSS: Main: 252645376 01:52:39 INFO - PID 1661 | 01:52:41 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:52:42 INFO - PID 1661 | RSS: Main: 252354560 01:52:42 INFO - PID 1661 | 01:52:44 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:52:45 INFO - PID 1661 | RSS: Main: 252538880 01:52:45 INFO - PID 1661 | 01:52:48 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:52:49 INFO - PID 1661 | RSS: Main: 253038592 01:52:49 INFO - PID 1661 | 01:52:50 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 01:52:51 INFO - PID 1661 | RSS: Main: 254062592 01:52:51 INFO - PID 1661 | 01:52:51 INFO - PID 1661 | __start_tp_report 01:52:51 INFO - PID 1661 | _x_x_mozilla_page_load 01:52:51 INFO - PID 1661 | _x_x_mozilla_page_load_details 01:52:51 INFO - PID 1661 | |i|pagename|runs| 01:52:51 INFO - PID 1661 | |0;ai-astar;104;116;107;118;151;115;172;149;115;117 01:52:51 INFO - PID 1661 | |1;audio-beat-detection;144;113;129;180;146;133;131;114;166;111 01:52:51 INFO - PID 1661 | |2;audio-dft;239;200;196;200;199;198;193;200;202;193 01:52:51 INFO - PID 1661 | |3;audio-fft;68;83;79;154;58;59;58;65;158;49 01:52:51 INFO - PID 1661 | |4;audio-oscillator;70;82;119;67;68;66;118;96;74;104 01:52:51 INFO - PID 1661 | |5;imaging-gaussian-blur;113;89;143;141;96;116;93;95;97;92 01:52:51 INFO - PID 1661 | |6;imaging-darkroom;99;100;130;137;156;103;99;99;100;102 01:52:51 INFO - PID 1661 | |7;imaging-desaturate;93;91;92;117;148;92;130;143;93;92 01:52:51 INFO - PID 1661 | |8;json-parse-financial;51;49;53;53;51;51;51;48;71;78 01:52:51 INFO - PID 1661 | |9;json-stringify-tinderbox;54;58;58;59;57;68;48;51;58;85 01:52:51 INFO - PID 1661 | |10;stanford-crypto-aes;81;81;77;80;82;87;99;104;80;81 01:52:51 INFO - PID 1661 | |11;stanford-crypto-ccm;133;134;131;137;144;136;135;145;149;167 01:52:51 INFO - PID 1661 | |12;stanford-crypto-pbkdf2;177;184;179;182;209;222;223;172;182;183 01:52:51 INFO - PID 1661 | |13;stanford-crypto-sha256-iterative;65;67;65;68;66;67;64;63;67;63 01:52:51 INFO - PID 1661 | __end_tp_report 01:52:51 INFO - PID 1661 | __start_cc_report 01:52:51 INFO - PID 1661 | _x_x_mozilla_cycle_collect,1037 01:52:51 INFO - PID 1661 | __end_cc_report 01:52:51 INFO - PID 1661 | __startTimestamp1497862371207__endTimestamp 01:52:51 INFO - PID 1661 | 01:52:51 INFO - PID 1661 | ------- Summary: start ------- 01:52:51 INFO - PID 1661 | Number of tests: 14 01:52:51 INFO - PID 1661 | 01:52:51 INFO - PID 1661 | [#0] ai-astar Cycles:10 Average:126.40 Median:117.50 stddev:22.61 (19.2%) stddev-sans-first:22.48 01:52:51 INFO - PID 1661 | Values: 104.0 116.0 107.0 118.0 151.0 115.0 172.0 149.0 115.0 117.0 01:52:51 INFO - PID 1661 | 01:52:51 INFO - PID 1661 | [#1] audio-beat-detection Cycles:10 Average:136.70 Median:138.50 stddev:22.89 (16.5%) stddev-sans-first:24.13 01:52:51 INFO - PID 1661 | Values: 144.0 113.0 129.0 180.0 146.0 133.0 131.0 114.0 166.0 111.0 01:52:51 INFO - PID 1661 | 01:52:51 INFO - PID 1661 | [#2] audio-dft Cycles:10 Average:202.00 Median:200.00 stddev:13.35 (6.7%) stddev-sans-first:3.22 01:52:51 INFO - PID 1661 | Values: 239.0 200.0 196.0 200.0 199.0 198.0 193.0 200.0 202.0 193.0 01:52:51 INFO - PID 1661 | 01:52:51 INFO - PID 1661 | [#3] audio-fft Cycles:10 Average:83.10 Median:62.00 stddev:39.74 (64.1%) stddev-sans-first:41.77 01:52:51 INFO - PID 1661 | Values: 68.0 83.0 79.0 154.0 58.0 59.0 58.0 65.0 158.0 49.0 01:52:51 INFO - PID 1661 | 01:52:51 INFO - PID 1661 | [#4] audio-oscillator Cycles:10 Average:86.40 Median:69.00 stddev:21.18 (30.7%) stddev-sans-first:21.61 01:52:51 INFO - PID 1661 | Values: 70.0 82.0 119.0 67.0 68.0 66.0 118.0 96.0 74.0 104.0 01:52:51 INFO - PID 1661 | 01:52:51 INFO - PID 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:107.50 Median:92.50 stddev:20.21 (21.9%) stddev-sans-first:21.34 01:52:51 INFO - PID 1661 | Values: 113.0 89.0 143.0 141.0 96.0 116.0 93.0 95.0 97.0 92.0 01:52:51 INFO - PID 1661 | 01:52:51 INFO - PID 1661 | [#6] imaging-darkroom Cycles:10 Average:112.50 Median:146.50 stddev:20.71 (14.1%) stddev-sans-first:21.38 01:52:51 INFO - PID 1661 | Values: 99.0 100.0 130.0 137.0 156.0 103.0 99.0 99.0 100.0 102.0 01:52:51 INFO - PID 1661 | 01:52:51 INFO - PID 1661 | [#7] imaging-desaturate Cycles:10 Average:109.10 Median:92.00 stddev:23.30 (25.3%) stddev-sans-first:23.97 01:52:51 INFO - PID 1661 | Values: 93.0 91.0 92.0 117.0 148.0 92.0 130.0 143.0 93.0 92.0 01:52:51 INFO - PID 1661 | 01:52:51 INFO - PID 1661 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:52.00 stddev:10.21 (19.6%) stddev-sans-first:10.69 01:52:51 INFO - PID 1661 | Values: 51.0 49.0 53.0 53.0 51.0 51.0 51.0 48.0 71.0 78.0 01:52:51 INFO - PID 1661 | 01:52:51 INFO - PID 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:58.00 stddev:10.38 (17.9%) stddev-sans-first:10.81 01:52:51 INFO - PID 1661 | Values: 54.0 58.0 58.0 59.0 57.0 68.0 48.0 51.0 58.0 85.0 01:52:51 INFO - PID 1661 | 01:52:51 INFO - PID 1661 | [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:81.00 stddev:9.02 (11.1%) stddev-sans-first:9.43 01:52:51 INFO - PID 1661 | Values: 81.0 81.0 77.0 80.0 82.0 87.0 99.0 104.0 80.0 81.0 01:52:51 INFO - PID 1661 | 01:52:51 INFO - PID 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:141.10 Median:140.50 stddev:10.83 (7.7%) stddev-sans-first:11.08 01:52:51 INFO - PID 1661 | Values: 133.0 134.0 131.0 137.0 144.0 136.0 135.0 145.0 149.0 167.0 01:52:51 INFO - PID 1661 | 01:52:51 INFO - PID 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.30 Median:183.50 stddev:19.10 (10.4%) stddev-sans-first:19.55 01:52:51 INFO - PID 1661 | Values: 177.0 184.0 179.0 182.0 209.0 222.0 223.0 172.0 182.0 183.0 01:52:51 INFO - PID 1661 | 01:52:51 INFO - PID 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:66.50 stddev:1.78 (2.7%) stddev-sans-first:1.88 01:52:51 INFO - PID 1661 | Values: 65.0 67.0 65.0 68.0 66.0 67.0 64.0 63.0 67.0 63.0 01:52:51 INFO - PID 1661 | -------- Summary: end -------- 01:52:51 INFO - PID 1661 | 01:52:51 INFO - TEST-INFO | 1661: exit 0 01:52:51 INFO - TEST-OK | kraken | took 89878ms 01:52:51 INFO - SUITE-END | took 1234s 01:52:52 INFO - Completed test suite (00:20:34) 01:52:52 INFO - javascript benchmark 01:52:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8433.7526056867, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2667.8932842686295, 2805.607214989902, 3441.6386279440676, 3524.929501409972, 3580.087077795162, 6198.597091189081, 6221.875562488747, 6256.24925008999, 6272.435480806732, 6299.433051025407, 6257.780977665782, 6293.874122517547, 6320.841978950529, 6327.209098862642, 6391.137196478474, 5478.506934375905, 5510.0, 5549.334079910396, 5593.888122237553, 5614.242077319571, 4486.0, 5543.0, 5636.520895723868, 5642.379338272786, 5647.774089036434, 5360.571154307646, 5371.946280537184, 5427.592930530226, 5467.535259502927, 5524.502794748451, 5418.106012507931, 5425.213344065128, 5508.889822203533, 5514.889702205934, 5566.582506312043, 5480.342358916916, 5487.972560137214, 5491.615586908918, 5505.201745746885, 5541.9168712469345, 6291.748330066792, 6306.1486699295465, 6311.715972781236, 6330.113784070188, 6379.425851673325, 6770.356816102462, 6809.659517024129, 6823.249442561314, 6824.965875170642, 6826.863462730717, 4916.532929371704, 5565.777368905239, 6615.569987950767, 6696.564723293019, 6933.930660693379, 6063.363346848561, 6314.557980941382, 6378.872422551616, 6407.615543067431, 6433.067205255212, 7099.716011359591, 7115.466340024415, 7122.28777122294, 7122.786316410516, 7145.356917877416, 5501.834944951658, 6452.838679033061, 6528.902066469007, 6563.081168636395, 6568.113304703899, 6695.263521012685, 6732.697028633724, 6738.225104112992, 6743.224529179109, 6789.592624442549, 6787.93212067873, 6791.626460544718, 6801.693923773442, 6806.55757375769, 6816.250212476623, 6792.218894827158, 6828.692708828015, 6852.931470685329, 6856.897146542806, 6874.312568743086, 5326.547243484347, 5452.073147564924, 5452.1276595745285, 5454.563634909197, 5456.7544460499375, 4731.148393289241, 4752.097101550694, 4768.61851051915, 4774.3077253798, 4810.7835147418455, 6245.437910587978, 6314.494840412756, 6425.518086143558, 6459.773907913294, 6475.028745688184, 6960.686769095403, 6974.232834388214, 6975.232724400312, 6979.825504362431, 6981.0, 6272.5922815016875, 6438.195225596801, 6444.581102228341, 6460.224773027267, 6485.4487368573255, 5877.294724633071, 5991.161237426763, 6054.848628784228, 6093.573449858556, 6123.510119190454, 6983.511154219156, 7048.154221493453, 7088.574685518679, 7103.431725462054, 7105.6447177639875, 6857.622830744157, 6920.7231710731, 6929.0, 6963.93036069653, 6966.059581956371, 5735.627184233012, 7088.0, 7100.289971002859, 7103.609301488468, 7121.14546254432, 6758.4931130166415, 6777.254502004874, 6798.966005169942, 6802.489813263926, 6816.2502124767225, 6705.0612914193025, 6769.356911093439, 6773.424258938046, 6858.382745552724, 6919.930800691928, 6653.168353955755, 6673.265940746417, 6704.597724136373, 6710.16122984627, 6744.662766861539, 6721.22705888829, 6733.225679046972, 6737.9326206737305, 6743.1908171019795, 6757.864842703217, 5073.543381095719, 6151.64663773969, 6496.837579060561, 6567.934320656924, 6602.471802255712, 6482.967585162044, 6525.3800888915475, 6562.606243625398, 6576.243731970693, 6580.80257592273, 6468.15913931186, 6473.287938326875, 6522.086907833002, 6575.4410875076155, 6583.901241481286], "value": 6170.243076232837, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [738.7820592925087, 755.176857225624, 780.1067777394883, 846.8948022830208, 859.188067276424, 36612.0, 40140.59859401409, 40141.0, 40143.996400090015, 40188.799056004755, 31147.68852311476, 39460.40809387854, 39470.605293947054, 39488.407673884845, 39637.0, 7538.7738367848915, 7589.468737188399, 7651.0, 7662.003939487887, 7672.0409948756405, 7608.0, 7703.191164927686, 7722.691092356327, 7733.072031356245, 7782.883256751154, 6447.677616119198, 7671.769846904602, 7701.0758708955, 7706.113796913371, 7717.305442510173, 7620.352270057052, 8099.068607110199, 8323.50058996456, 8330.208630180185, 8365.07984121746, 8243.381746369047, 8295.377846661524, 8315.459495132796, 8323.1260717625, 8347.373946953941, 32359.51460728091, 36970.63029369699, 37082.81458592717, 37206.44190337147, 37437.25125497502, 3595.7842529448053, 3634.9818250908843, 3635.291118231946, 3638.272345530904, 3649.7080233581514, 30082.24794380136, 30406.695933040606, 30441.0, 30527.847360763055, 30639.693603063908, 3062.647795503501, 3108.1141874565865, 3110.6422761382505, 3142.889998850052, 3159.0522843146964, 260945.0, 265106.0, 265411.0, 318130.0, 319670.0, 38917.6108238914, 47779.5222047782, 47832.0, 48142.27786583204, 48873.0, 51541.48458515367, 52156.21765673442, 52164.0, 52228.47771522312, 52343.73828130835, 15702.9214853925, 15807.525774226562, 15810.68378632444, 15829.604259893364, 15854.603634908988, 12771.0, 12813.423395947204, 12838.871611283954, 12840.293783841978, 12844.614661560168, 13721.245331506663, 13928.651783705489, 13943.930280348737, 13950.302484875716, 13986.02097853161, 4813.243166244296, 6045.697715114226, 6113.174721412553, 6115.388461153849, 6158.445739883432, 1014.1683819268277, 1021.5096753558186, 1022.5909636145601, 1074.822654262038, 1074.9193810464246, 23661.88169059178, 30950.69049309523, 30953.226169345497, 31620.841895790832, 31630.36739265248, 944.367273926471, 1038.522279751323, 1083.8374243863325, 1084.8318510630866, 1135.6820090374708, 11968.16222864391, 15187.316570754343, 16651.750223746654, 16742.911710738765, 16873.662526749395, 296761.0, 297872.0, 298347.0, 298758.0, 299362.0, 56856.71571642115, 57036.0, 57050.714746426005, 57601.71199143978, 57617.13574296389, 1414.5968399006233, 1519.6124988127956, 1545.4436402895174, 1557.2213893053474, 1566.0368873142936, 28466.0, 28992.13023609295, 29915.401691966475, 30411.695883040888, 30664.386712266078, 38183.61816381912, 38647.613523865526, 38686.0, 38686.80656596699, 38713.41929870998, 282244.5887770548, 282545.0, 285127.0, 285372.0, 286569.0, 28508.57237141403, 28930.84276628911, 28944.56583151212, 29072.12783616495, 33928.83035584806, 38212.42681359837, 39904.401433979096, 40283.597164029154, 40592.797036015814, 42383.1523369537, 27249.86375068112, 27638.5854212191, 28557.42885142244, 29861.5520767193, 30748.312921769557, 29070.7092929068, 29990.25024374321, 30054.54918176273, 30576.388472229984, 30617.54073688852, 35636.0, 35860.92417227487, 36310.27379452449, 36327.63672363242, 36346.63653363432, 26677.466450671265, 28994.7100528992, 29330.560041598965, 30659.693403065685, 30761.230969225053, 22880.656790148492, 23988.0, 24102.51794964126, 24246.515069698155, 24455.633165502175], "value": 17504.156818516814, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [486.28273296887073, 500.2146629791228, 515.3763945625792, 521.2467983763461, 521.7938914128919, 21403.0, 21942.780572194275, 22195.778042219572, 22909.969051392687, 23015.539689206205, 23990.640140397863, 24081.759182408172, 24101.87949060252, 24352.634710479357, 24380.268591942226, 6191.133241346215, 6317.557770956035, 6393.808185754435, 6394.6482943438095, 6395.584287021352, 6261.0, 6366.236051673805, 6372.936270637304, 6392.4886009119155, 6401.679916004205, 5654.406287339832, 6162.7534898604, 6220.284667263255, 6235.625862448245, 6242.687865606724, 6221.844453888666, 6246.437820596168, 6253.624782513064, 6255.843603909893, 6256.374362563753, 5652.660840349551, 5704.401037891044, 5710.77156913723, 5716.628419152783, 5716.685582292972, 23042.769572304398, 25993.870030649727, 27555.724442755516, 27971.58042629362, 28368.432631347463, 1996.7703714072777, 2304.101400453824, 2343.718753749544, 2343.7656234376595, 2349.929502114939, 14713.485028024075, 18763.34328298503, 18778.342758003542, 18799.060046997733, 18802.717959230624, 1945.7838850718301, 2186.387811412791, 2187.2344679362254, 2226.4990377165173, 2238.261373746676, 167965.0, 176070.0, 185430.2185467219, 185832.0, 216527.0, 13504.662383440493, 13813.309334533233, 14433.35049922735, 14729.116253024853, 15033.849661503464, 19946.800531994784, 19973.800261997483, 19998.800011999985, 20001.399958001282, 20071.698924516142, 10048.748781280527, 10116.443595602312, 10250.231232657432, 10293.794124117476, 10328.122109620615, 9764.267679924034, 9789.706308810604, 9855.408675479493, 9857.408555486694, 9928.0, 8803.667028237522, 8826.86759698605, 8868.02451730309, 8937.553122343857, 8941.630467926005, 2585.857777822238, 2587.573050446693, 2588.7540683635025, 2596.337933826862, 2599.8830052647677, 820.8399362124328, 918.7703074231442, 951.6145960885852, 983.8130755156641, 1030.6135199300263, 20001.099950502263, 20111.393210082006, 20210.797892021186, 20355.796442035688, 21503.892480537495, 976.1361195342173, 1007.8588997540351, 1035.9015893490107, 1053.210092430677, 1080.0, 10392.168626509861, 10406.323588966692, 10570.630027948984, 10576.312539684895, 10700.892991070144, 591102.0, 607251.0, 617490.0, 622111.0, 626720.0, 146400.2679986593, 155810.44189558187, 156563.0, 157001.42998570096, 157052.0, 1222.9694257643453, 1228.993855030719, 1258.804885242789, 1261.1928365845865, 1271.0594160321295, 17492.77550571448, 17766.289348426435, 17876.642467150323, 18160.18279177414, 18662.440126795675, 65697.34302657103, 67117.0, 67565.66217168883, 68218.65890670515, 68827.0, 89621.75945601412, 93078.06921930694, 96239.0, 96881.0, 97785.51107244418, 11226.66320010401, 11244.943775281072, 11636.476358564054, 11652.766944660889, 11655.358955257203, 13356.0, 18419.07904604791, 18690.532736681693, 18749.812501874807, 18794.342198023005, 14957.700845983238, 15096.471623493126, 15162.545123646309, 15181.772273416129, 15213.467528636445, 12894.806577901152, 13276.137051091648, 13434.395452204479, 13457.663558411117, 13793.724125517634, 15378.307976140877, 15388.692226155637, 15437.459688910834, 15502.612434689223, 15659.686806263582, 11525.366104864313, 11560.479778409823, 11629.011534019703, 11691.181617286707, 11736.530538778354, 14316.0, 14579.979401441797, 14589.0, 14623.561293161221, 14641.26793660334], "value": 11603.172780166231, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1440.3302464354074, 1463.8536146385356, 1484.3765618440254, 1511.1084460168506, 1511.8866085043621, 13477.595672129839, 13894.166350019006, 13935.163890166597, 14082.295885205749, 14128.293585320718, 13262.336883155851, 13620.45518179274, 14030.438782448715, 14146.858531414682, 14172.93702972276, 2433.9269821905373, 2662.347724807425, 2786.2755683522228, 2789.5397259452166, 2826.8727907244224, 2472.5796614575565, 2809.3257618171697, 2816.5352716801804, 2818.112294627196, 2818.3517790908104, 2749.752522272995, 2777.4167424840707, 2799.454106449243, 2799.8040137190405, 2801.32768135647, 2777.8888844446196, 2803.11701813929, 2814.6481689788775, 2816.85915704215, 2817.4928512867677, 2792.6788419331833, 2796.9021084261954, 2799.5380762174423, 2800.2579316481147, 2812.803103782736, 13756.793648095287, 13841.30793460323, 13916.095453795471, 13948.930255348658, 13974.371153298125, 7561.432892533082, 7713.807154821118, 7760.844783104363, 7838.255365740246, 7863.13505514393, 4713.505081966378, 4938.80244790208, 5028.195488721787, 5041.470645582234, 5080.415752188509, 1417.55347065675, 1423.1745587559294, 1423.950161744334, 1443.682389874226, 1448.3265281644085, 20834.47913802137, 21031.158753649837, 21101.5779684407, 21104.894475527522, 21114.577708445744, 6576.934230657728, 6721.290487773603, 6839.897401538981, 6873.0, 6926.515143939876, 14696.632584185481, 14709.411623535152, 14714.705705885823, 14724.484643037656, 14732.779008314883, 7529.924700752922, 7614.809629759189, 7652.0, 7655.119661238918, 7655.540667559964, 5996.37038110992, 6208.72060757267, 6211.937880621226, 6229.4393504583895, 6562.803115906435, 5132.358455193101, 5234.712090834964, 5312.9734351328, 5418.945810541844, 5469.480399362054, 1548.7057459082964, 1549.651328451112, 1551.8603325700742, 1560.6956643454416, 1567.5062355358025, 703.7466512055656, 835.5863847395457, 855.4669216137313, 859.3211363023266, 885.0927799006071, 6337.207849018872, 7963.601819908981, 8070.354371650254, 8282.71010514629, 8466.703665371682, 945.4280160502934, 974.9561269742878, 977.1108291454742, 1017.9236557258236, 1032.6695457453693, 4881.194602890554, 5024.723640199824, 5133.666311689803, 5237.8428647140645, 5266.945891102473, 18407.447776566725, 18909.621807563773, 18926.716099258523, 18953.43139705811, 19239.422817315502, 17952.0, 18565.44303670892, 18901.24395024211, 19140.521486962658, 19369.4189174325, 9567.808643827084, 9711.417314961125, 9749.707508774749, 9761.316707830525, 9768.560414781352, 7815.960920195363, 7830.1386847447875, 8287.461315014505, 8523.977122745311, 8807.735767926972, 14540.200288983784, 16842.831571684615, 17045.062521561435, 17197.65604687924, 17400.303987840343, 16952.06763627963, 17291.221895015005, 17905.925644461375, 18317.267309307477, 18401.53996150107, 10768.676939691823, 10896.346219226873, 11127.666170014912, 11230.94384528072, 11271.549138034386, 12998.935005324913, 13029.674258143623, 13169.736605268032, 13203.273819939996, 13245.0, 10154.847677284699, 10234.897651023692, 10273.794524109626, 10443.843342349719, 10546.419946902994, 9909.405435673883, 9946.403215807075, 10322.329048611817, 10392.896071039495, 10395.0, 8981.730548083568, 9097.636094556143, 9132.680356187502, 9148.63405463774, 9156.725298241063, 6615.966920165561, 6805.523613347216, 6873.381395674413, 6990.860182796418, 7140.750073747394, 6986.54587451814, 7011.26381729911, 7107.680154392961, 7229.783106506813, 7244.963775181091], "value": 6285.509357015293, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [381.28509045539613, 423.3121178085611, 440.28673548850867, 463.4068392457655, 468.54317040885144, 6438.195225596801, 6509.9909514025285, 6545.181852268466, 6550.737970481175, 6563.540552161345, 6172.382761723826, 6350.714217860196, 6369.904451433221, 6381.872362552746, 6398.728451399745, 4967.0, 4997.875053123683, 5006.173981293091, 5009.098362294785, 5026.1204289249235, 4970.329005584254, 4986.401631804189, 5016.924746128811, 5024.743814046488, 5059.089363914494, 4390.582894625006, 4832.323474713543, 4931.630127740415, 5011.724355160465, 5020.523050310233, 4575.810289324766, 4991.278009086859, 5008.898042430659, 5013.623978201649, 5015.749212539395, 3449.5515582974303, 4058.939115913264, 4252.9367658085475, 4294.26997410441, 4565.771711414449, 6180.536459765535, 6212.782552610637, 6223.968880155616, 6227.346128656516, 6256.624602523863, 6059.454649081604, 6069.605475644069, 6075.210222671025, 6078.848028799272, 6086.847828804271, 5763.855903602402, 6087.4825639820665, 6563.949768037091, 6996.615186164759, 7050.153981522199, 5311.707856067915, 5317.707526086064, 5330.600204984603, 5330.92003619946, 5334.3598768147685, 6360.522960777914, 6387.648679322682, 6930.930690693079, 7410.888836667455, 7416.0, 4789.850910271402, 4828.66199366048, 5433.673979561239, 5506.504414602705, 5523.751431185607, 5544.695041772741, 5575.386707462176, 5588.1059030555725, 5597.188407680864, 5604.3835178130375, 5106.744662766847, 6061.696915154224, 6080.878382432326, 6108.8778224436155, 6188.535859810532, 6436.645984470899, 6972.58164510131, 6982.965085174542, 6989.706904222735, 7004.089468369181, 1787.499500139963, 1864.4127099963468, 1876.3901731937176, 1878.8684792064423, 1880.8965506896977, 1226.9509219631116, 1336.5255334356164, 1359.8436179839248, 1369.0963963783854, 1383.674836413442, 6233.781817636361, 6295.716692748837, 6309.211348581427, 6461.676916154173, 6519.380658837403, 6041.879162416815, 6637.212370804274, 7034.859302813915, 7070.858582828315, 7149.82125446868, 6163.506919446433, 6467.863086403185, 6475.575373417841, 6493.772717954849, 6520.0, 3278.213228825065, 3640.906478774715, 3660.871869484555, 3667.559892812826, 3692.2246328271362, 6406.8718625627225, 7116.003759473678, 7281.034120787358, 7296.416286697052, 7296.70813167478, 5678.422593765901, 6051.606645568024, 6911.516193866381, 7207.747728829466, 7288.890666640004, 6258.276325586269, 6383.585066970632, 7049.048378468841, 7215.927840721525, 7240.927590724131, 7077.256888026683, 7098.219195888551, 7098.645067746489, 7100.751473698396, 7100.751473698396, 6114.327423983269, 6914.377706006484, 6947.201071876799, 6947.826304342432, 6960.5127641066665, 6811.5231933766145, 6827.6244806536115, 7210.89183662235, 7231.963840180765, 7326.853462930818, 4092.5676013395073, 4855.6601037927, 4878.609711223023, 4884.413870335582, 4885.584725298248, 6537.738490460329, 7093.574385536885, 7098.609576473344, 7135.8572828544175, 7152.568126836901, 5396.568274538106, 6266.749330026748, 6861.862762744817, 6866.210385805496, 6884.586924784529, 6865.965670171817, 6869.381755642016, 6872.278410766998, 6880.621565813728, 6882.8967565485555, 5583.0, 6150.7847225346895, 6670.266270710121, 6716.5634233774645, 6729.192496900403, 5935.34711181761, 6388.233411990591, 6865.72983997962, 6880.0, 6980.476464265302, 6685.0, 6694.899576506454, 6720.9327906722265, 6725.4619630430425, 6725.630090345078], "value": 5182.323852522193, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [830.070321240211, 838.4591938199858, 844.8817165596816, 863.1196179896506, 867.9218870301673, 16636.58408539787, 16970.066646334446, 17165.88421752585, 17202.3119075237, 17202.74195887061, 16777.496675099737, 18286.817131828677, 18388.816111838878, 18418.539536511595, 18428.631427371445, 827.2761333832897, 925.3383830561147, 937.2267878999818, 950.2872845365976, 950.6245033211877, 911.9908800911973, 966.4684423567045, 967.6322997261066, 969.6460791810973, 971.4025874087421, 816.2001238785994, 989.717930389839, 996.9780974501122, 996.9880571220217, 1004.7488127968008, 911.1435250864166, 972.1785091597588, 979.3732011512637, 979.9118079372855, 980.8626792249091, 720.5784615999646, 721.1490441279288, 722.3138018882031, 723.3923504256397, 726.4043484342841, 17111.57221069483, 17193.0, 17435.912820435944, 17482.737758933497, 17621.55946101345, 17456.563585910328, 17511.299548018193, 17540.21069051883, 17860.0, 17926.91036544822, 14746.705065898623, 14911.0, 15024.699506009927, 15037.849621503754, 15050.397984080733, 3583.802890841003, 3584.551931008624, 3601.657842504958, 3611.3026877367597, 3612.4761909523236, 150488.0, 154929.0, 157259.42740572678, 159019.20490397475, 159284.0, 13806.240656763974, 13854.376553055137, 13927.651808704863, 14030.649233769238, 14099.295035248197, 26547.601785973227, 26627.068052618066, 27161.049363272192, 27295.590566141524, 27314.453710926067, 7996.520208787491, 8097.2712455879255, 8099.230573095547, 8108.635111420001, 8124.0, 4006.8397264109694, 4090.611391917763, 4135.91728165435, 4155.459790227251, 4198.9370159447635, 4646.930296045562, 4740.786664600101, 4793.784279707422, 4802.375691160127, 4805.207140821795, 975.351391324761, 995.0994350113159, 1008.0373243552536, 1011.5650270383805, 1011.5953618552638, 573.2290069856037, 619.1795870471643, 658.2035736758576, 667.3993405934698, 688.1810645332039, 11474.311541307548, 11918.88081119195, 12322.75354492923, 12526.248425094524, 12760.80858787119, 621.5711159300068, 643.1992169748693, 656.9014647802868, 672.9865402691919, 685.0306815855608, 5389.353277606712, 5733.254676891977, 5782.074868021096, 5912.083627037816, 5963.9264932311735, 127374.0, 146993.0, 156166.2191689057, 158039.0, 162216.56675149882, 104238.87283381511, 112793.0, 123931.0, 124092.0, 128323.0, 110759.44620276846, 112627.58734393626, 113969.0, 114557.85442145639, 118004.40997794956, 11153.442327883735, 11385.03227225695, 11408.144389170679, 11422.25755325901, 11531.654050378502, 48924.77688057827, 48955.0, 49802.50592482231, 49840.50159498359, 50102.0, 64915.67542162259, 66325.66837165783, 68422.63154736778, 69513.65243173752, 70100.64949675219, 14724.969252152247, 15261.160636165117, 15326.923365383102, 15516.0, 15537.68924621524, 16755.74866376981, 16857.91571042137, 16932.40736574214, 17011.91494042522, 17256.568585785455, 14327.06874053225, 15358.385664573292, 15463.922680386526, 15564.0, 16976.151192440575, 16763.07803070794, 17467.12664366802, 17582.472525824247, 17612.471625851245, 18090.81909180944, 16010.963284417308, 16727.581810454834, 17234.96590204592, 17320.82679173192, 17378.131093445023, 11824.82262766042, 11987.460564274803, 12646.355973723357, 12667.619971400873, 12859.16415432994, 12769.0, 12973.54592589255, 13190.472381104648, 13332.266725329811, 13543.525976590772], "value": 8814.832676534284, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1562.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 107.0, 118.0, 151.0, 115.0, 172.0, 149.0, 115.0, 117.0], "value": 126.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 113.0, 129.0, 180.0, 146.0, 133.0, 131.0, 114.0, 166.0, 111.0], "value": 136.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [239.0, 200.0, 196.0, 200.0, 199.0, 198.0, 193.0, 200.0, 202.0, 193.0], "value": 202.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 83.0, 79.0, 154.0, 58.0, 59.0, 58.0, 65.0, 158.0, 49.0], "value": 83.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 82.0, 119.0, 67.0, 68.0, 66.0, 118.0, 96.0, 74.0, 104.0], "value": 86.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 89.0, 143.0, 141.0, 96.0, 116.0, 93.0, 95.0, 97.0, 92.0], "value": 107.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 100.0, 130.0, 137.0, 156.0, 103.0, 99.0, 99.0, 100.0, 102.0], "value": 112.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 91.0, 92.0, 117.0, 148.0, 92.0, 130.0, 143.0, 93.0, 92.0], "value": 109.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 53.0, 53.0, 51.0, 51.0, 51.0, 48.0, 71.0, 78.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 58.0, 58.0, 59.0, 57.0, 68.0, 48.0, 51.0, 58.0, 85.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 77.0, 80.0, 82.0, 87.0, 99.0, 104.0, 80.0, 81.0], "value": 85.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 134.0, 131.0, 137.0, 144.0, 136.0, 135.0, 145.0, 149.0, 167.0], "value": 141.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 184.0, 179.0, 182.0, 209.0, 222.0, 223.0, 172.0, 182.0, 183.0], "value": 191.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 65.0, 68.0, 66.0, 67.0, 64.0, 63.0, 67.0, 63.0], "value": 65.5, "unit": "score"}]}]} 01:52:52 INFO - Return code: 0 01:52:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:52:52 INFO - # TBPL SUCCESS # 01:52:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:52:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:52:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:52:52 INFO - 2017-06-19 01:52:52.581 screenresolution[1669:11077] starting screenresolution argv=screenresolution get 01:52:52 INFO - 2017-06-19 01:52:52.584 screenresolution[1669:11077] Display 0: 1600x1200x32@60 01:52:52 INFO - 2017-06-19 01:52:52.590 screenresolution[1670:11080] starting screenresolution argv=screenresolution list 01:52:52 INFO - Available Modes on Display 0 01:52:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:52:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:52:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:52:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:52:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:52:52 INFO - Intel Iris: 01:52:52 INFO - Chipset Model: Intel Iris 01:52:52 INFO - Type: GPU 01:52:52 INFO - Bus: Built-In 01:52:52 INFO - VRAM (Dynamic, Max): 1536 MB 01:52:52 INFO - Vendor: Intel (0x8086) 01:52:52 INFO - Device ID: 0x0a2e 01:52:52 INFO - Revision ID: 0x0009 01:52:52 INFO - Displays: 01:52:52 INFO - Display: 01:52:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:52:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:52:52 INFO - Display Serial Number: 1600x1200 60 01:52:52 INFO - Main Display: Yes 01:52:52 INFO - Mirror: Off 01:52:52 INFO - Online: Yes 01:52:52 INFO - Rotation: Supported 01:52:52 INFO - Return code: 0 01:52:52 INFO - Running post-action listener: _resource_record_post_action 01:52:52 INFO - [mozharness: 2017-06-19 08:52:52.824541Z] Finished run-tests step (success) 01:52:52 INFO - Running post-run listener: _resource_record_post_run 01:52:52 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 45726208; Write bytes: 557533184; Read time: 524; Write time: 1737 01:52:52 INFO - TinderboxPrint: CPU usage
53.0% 01:52:52 INFO - TinderboxPrint: I/O read bytes / time
45,726,208 / 524 01:52:52 INFO - TinderboxPrint: I/O write bytes / time
557,533,184 / 1,737 01:52:52 INFO - TinderboxPrint: CPU idle
2,364.0 (47.0%) 01:52:52 INFO - TinderboxPrint: CPU system
1,019.0 (20.3%) 01:52:52 INFO - TinderboxPrint: CPU user
1,646.7 (32.7%) 01:52:52 INFO - TinderboxPrint: Swap in / out
282,107,904 / 0 01:52:52 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 223285760; Write bytes: 216221696; Read time: 16786; Write time: 423 01:52:52 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 01:52:52 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 44689920; Write bytes: 311951360; Read time: 506; Write time: 1246 01:52:53 INFO - Running post-run listener: _upload_blobber_files 01:52:53 INFO - Blob upload gear active. 01:52:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:52:53 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 01:52:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:52:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:52:53 INFO - (blobuploader) - INFO - Open directory for files ... 01:52:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 01:52:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:52:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:52:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 01:52:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:52:54 INFO - (blobuploader) - INFO - Done attempting. 01:52:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 01:52:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:52:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:52:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 01:52:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:52:56 INFO - (blobuploader) - INFO - Done attempting. 01:52:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 01:52:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:52:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:52:57 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 01:52:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:52:57 INFO - (blobuploader) - INFO - Done attempting. 01:52:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:52:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:52:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:52:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:52:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:52:59 INFO - (blobuploader) - INFO - Done attempting. 01:52:59 INFO - (blobuploader) - INFO - Iteration through files over. 01:52:59 INFO - Return code: 0 01:52:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:52:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:52:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4cf9f817433c01473b93ce61f3b79b2e425d0f20630296e7e82cc7f33b2e8bb866c07e33468ad6f1554ce36915db63c590f313dfff6eb05ed4bf4bd33a65a5b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc371ae2bc419c373fe3e5fe8c8b1c31c2840b4e79c4cca5fc37992ca41903fe382ed5ba6626e87d287b216bd3f5da3d480e81ed9958bb03093a484083b2f065", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e9746683aa18e5dc2709e09bfa24c0615edfc59e716069029d47e517d1d6f8890b089b9b8b965a145c7c425bbba4d2d213ec235acea6aebaabf919fcd2802a1c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/edd54df367f5926bebdcc62c92638de10d176396d74891296347dc045f30e24e4dcc21a438857ad2aa45b99765f14c1018587d1c07540b35988f1336142015ba"} 01:52:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:52:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:52:59 INFO - Contents: 01:52:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4cf9f817433c01473b93ce61f3b79b2e425d0f20630296e7e82cc7f33b2e8bb866c07e33468ad6f1554ce36915db63c590f313dfff6eb05ed4bf4bd33a65a5b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc371ae2bc419c373fe3e5fe8c8b1c31c2840b4e79c4cca5fc37992ca41903fe382ed5ba6626e87d287b216bd3f5da3d480e81ed9958bb03093a484083b2f065", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e9746683aa18e5dc2709e09bfa24c0615edfc59e716069029d47e517d1d6f8890b089b9b8b965a145c7c425bbba4d2d213ec235acea6aebaabf919fcd2802a1c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/edd54df367f5926bebdcc62c92638de10d176396d74891296347dc045f30e24e4dcc21a438857ad2aa45b99765f14c1018587d1c07540b35988f1336142015ba"} 01:52:59 INFO - Running post-run listener: copy_logs_to_upload_dir 01:52:59 INFO - Copying logs to upload dir... 01:52:59 INFO - mkdir: /builds/slave/test/build/upload/logs 01:52:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1340.572154 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-06-19 01:52:59.678152) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-19 01:52:59.681745) ========= 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.nd1f81VNIQ/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.a7LKUh3utp/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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/d4cf9f817433c01473b93ce61f3b79b2e425d0f20630296e7e82cc7f33b2e8bb866c07e33468ad6f1554ce36915db63c590f313dfff6eb05ed4bf4bd33a65a5b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc371ae2bc419c373fe3e5fe8c8b1c31c2840b4e79c4cca5fc37992ca41903fe382ed5ba6626e87d287b216bd3f5da3d480e81ed9958bb03093a484083b2f065", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e9746683aa18e5dc2709e09bfa24c0615edfc59e716069029d47e517d1d6f8890b089b9b8b965a145c7c425bbba4d2d213ec235acea6aebaabf919fcd2802a1c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/edd54df367f5926bebdcc62c92638de10d176396d74891296347dc045f30e24e4dcc21a438857ad2aa45b99765f14c1018587d1c07540b35988f1336142015ba"} build_url:https://queue.taskcluster.net/v1/task/H3M_wTJnTtyBk4Bfc5JCeQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011068 build_url: 'https://queue.taskcluster.net/v1/task/H3M_wTJnTtyBk4Bfc5JCeQ/artifacts/public/build/firefox-55.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d4cf9f817433c01473b93ce61f3b79b2e425d0f20630296e7e82cc7f33b2e8bb866c07e33468ad6f1554ce36915db63c590f313dfff6eb05ed4bf4bd33a65a5b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc371ae2bc419c373fe3e5fe8c8b1c31c2840b4e79c4cca5fc37992ca41903fe382ed5ba6626e87d287b216bd3f5da3d480e81ed9958bb03093a484083b2f065", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e9746683aa18e5dc2709e09bfa24c0615edfc59e716069029d47e517d1d6f8890b089b9b8b965a145c7c425bbba4d2d213ec235acea6aebaabf919fcd2802a1c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/edd54df367f5926bebdcc62c92638de10d176396d74891296347dc045f30e24e4dcc21a438857ad2aa45b99765f14c1018587d1c07540b35988f1336142015ba"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-19 01:52:59.715015) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-19 01:52:59.715379) ========= 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.nd1f81VNIQ/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.a7LKUh3utp/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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.005258 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-19 01:52:59.764724) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-19 01:52:59.765140) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-19 01:52:59.765482) ========= ========= Total master_lag: 0.19 =========