builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0006 starttime: 1496055498.33 results: success (0) buildid: 20170529024916 builduid: 5ecc714c79cb4746852bc1d6e6780ea3 revision: 6fb66467485a8beee11dad38a08768c2a9955fe7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:18.335154) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:18.335696) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:18.336028) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:18.361934) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:18.362247) ========= 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.7mecxwknr6/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.2BpeUQ0igj/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-29 03:58:18-- 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% 15.0M=0.001s 2017-05-29 03:58:18 (15.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.623790 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:19.009911) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:19.010241) ========= 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.7mecxwknr6/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.2BpeUQ0igj/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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.029473 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:19.061227) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:19.061609) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6fb66467485a8beee11dad38a08768c2a9955fe7 --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 6fb66467485a8beee11dad38a08768c2a9955fe7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7mecxwknr6/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.2BpeUQ0igj/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-29 03:58:19,119 truncating revision to first 12 chars 2017-05-29 03:58:19,119 Setting DEBUG logging. 2017-05-29 03:58:19,119 attempt 1/10 2017-05-29 03:58:19,119 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6fb66467485a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-29 03:58:19,740 unpacking tar archive at: mozilla-beta-6fb66467485a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.897565 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:19.986347) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:19.987171) ========= script_repo_revision: 6fb66467485a8beee11dad38a08768c2a9955fe7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:19.987963) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:19.988276) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-29 03:58:20.007984) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-05-29 03:58:20.008876) ========= /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.7mecxwknr6/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.2BpeUQ0igj/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 03:58:20 INFO - MultiFileLogger online at 20170529 03:58:20 in /builds/slave/test 03:58:20 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 03:58:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:58:20 INFO - {'append_to_log': False, 03:58:20 INFO - 'base_work_dir': '/builds/slave/test', 03:58:20 INFO - 'blob_upload_branch': 'Mozilla-Beta', 03:58:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:58:20 INFO - 'branch': 'Mozilla-Beta', 03:58:20 INFO - 'buildbot_json_path': 'buildprops.json', 03:58:20 INFO - 'config_files': ('talos/mac_config.py',), 03:58:20 INFO - 'default_actions': ('clobber', 03:58:20 INFO - 'read-buildbot-config', 03:58:20 INFO - 'download-and-extract', 03:58:20 INFO - 'populate-webroot', 03:58:20 INFO - 'create-virtualenv', 03:58:20 INFO - 'install', 03:58:20 INFO - 'run-tests'), 03:58:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:58:20 INFO - 'download_minidump_stackwalk': True, 03:58:20 INFO - 'download_symbols': 'ondemand', 03:58:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:58:20 INFO - 'tooltool.py': '/tools/tooltool.py', 03:58:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:58:20 INFO - '/tools/misc-python/virtualenv.py')}, 03:58:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:58:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:58:20 INFO - 'gecko_profile': False, 03:58:20 INFO - 'gecko_profile_interval': 0, 03:58:20 INFO - 'installer_path': 'installer.exe', 03:58:20 INFO - 'log_level': 'info', 03:58:20 INFO - 'log_name': 'talos', 03:58:20 INFO - 'log_to_console': True, 03:58:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:58:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:58:20 INFO - 'opt_config_files': (), 03:58:20 INFO - 'pip_index': False, 03:58:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:58:20 INFO - 'cmd': ('bash', 03:58:20 INFO - '-c', 03:58:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:58:20 INFO - 'enabled': True, 03:58:20 INFO - 'halt_on_failure': False, 03:58:20 INFO - 'name': 'check_screen_resolution'},), 03:58:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:58:20 INFO - 'cmd': ('bash', 03:58:20 INFO - '-c', 03:58:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:58:20 INFO - 'enabled': True, 03:58:20 INFO - 'halt_on_failure': False, 03:58:20 INFO - 'name': 'check_screen_resolution'},), 03:58:20 INFO - 'run_cmd_checks_enabled': True, 03:58:20 INFO - 'suite': 'dromaeojs-e10s', 03:58:20 INFO - 'system_bits': '32', 03:58:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:58:20 INFO - 'title': 't-yosemite-r7-0006', 03:58:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:58:20 INFO - 'use_talos_json': True, 03:58:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:58:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:58:20 INFO - 'work_dir': 'build'} 03:58:20 INFO - [mozharness: 2017-05-29 10:58:20.185285Z] Running clobber step. 03:58:20 INFO - Running pre-action listener: _resource_record_pre_action 03:58:20 INFO - Running main action method: clobber 03:58:20 INFO - rmtree: /builds/slave/test/build 03:58:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:58:22 INFO - Running post-action listener: _resource_record_post_action 03:58:22 INFO - [mozharness: 2017-05-29 10:58:22.770963Z] Finished clobber step (success) 03:58:22 INFO - [mozharness: 2017-05-29 10:58:22.771128Z] Running read-buildbot-config step. 03:58:22 INFO - Running pre-action listener: _resource_record_pre_action 03:58:22 INFO - Running main action method: read_buildbot_config 03:58:22 INFO - Using buildbot properties: 03:58:22 INFO - { 03:58:22 INFO - "project": "", 03:58:22 INFO - "product": "firefox", 03:58:22 INFO - "script_repo_revision": "production", 03:58:22 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 03:58:22 INFO - "repository": "", 03:58:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 03:58:22 INFO - "buildid": "20170529024916", 03:58:22 INFO - "pgo_build": "False", 03:58:22 INFO - "basedir": "/builds/slave/test", 03:58:22 INFO - "buildnumber": 8, 03:58:22 INFO - "slavename": "t-yosemite-r7-0006", 03:58:22 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 03:58:22 INFO - "platform": "yosemite_r7", 03:58:22 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 03:58:22 INFO - "branch": "mozilla-beta", 03:58:22 INFO - "repo_path": "releases/mozilla-beta", 03:58:22 INFO - "revision": "6fb66467485a8beee11dad38a08768c2a9955fe7", 03:58:22 INFO - "stage_platform": "macosx64", 03:58:22 INFO - "builduid": "5ecc714c79cb4746852bc1d6e6780ea3", 03:58:22 INFO - "slavebuilddir": "test" 03:58:22 INFO - } 03:58:22 INFO - Found installer url https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 03:58:22 INFO - Running post-action listener: _resource_record_post_action 03:58:22 INFO - [mozharness: 2017-05-29 10:58:22.774498Z] Finished read-buildbot-config step (success) 03:58:22 INFO - [mozharness: 2017-05-29 10:58:22.774622Z] Running download-and-extract step. 03:58:22 INFO - Running pre-action listener: _resource_record_pre_action 03:58:22 INFO - Running main action method: download_and_extract 03:58:22 INFO - mkdir: /builds/slave/test/build/tests 03:58:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:58:22 INFO - trying https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 03:58:22 INFO - Downloading https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 03:58:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 03:58:24 INFO - Downloaded 1287 bytes. 03:58:24 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 03:58:24 INFO - Using the following test package requirements: 03:58:24 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 03:58:24 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 03:58:24 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 03:58:24 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 03:58:24 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 03:58:24 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 03:58:24 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 03:58:24 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 03:58:24 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 03:58:24 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 03:58:24 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 03:58:24 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 03:58:24 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 03:58:24 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 03:58:24 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 03:58:24 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 03:58:24 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 03:58:24 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 03:58:24 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 03:58:24 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 03:58:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 03:58:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 03:58:24 INFO - Fetch https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 03:58:26 INFO - Expected file size: 51393214 03:58:26 INFO - Obtained file size: 51393214 03:58:42 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 03:58:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 03:58:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 03:58:42 INFO - Fetch https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 03:58:43 INFO - Expected file size: 51393214 03:58:43 INFO - Obtained file size: 51393214 03:58:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 03:58:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 03:58:58 INFO - Fetch https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 03:58:59 INFO - Expected file size: 14404461 03:58:59 INFO - Obtained file size: 14404461 03:59:00 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')]} 03:59:00 INFO - trying https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 03:59:00 INFO - Downloading https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 03:59:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 03:59:01 INFO - Downloaded 59645581 bytes. 03:59:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 03:59:01 INFO - mkdir: /builds/slave/test/properties 03:59:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:59:01 INFO - Writing to file /builds/slave/test/properties/build_url 03:59:01 INFO - Contents: 03:59:01 INFO - build_url:https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 03:59:02 INFO - Running post-action listener: _resource_record_post_action 03:59:02 INFO - Running post-action listener: set_extra_try_arguments 03:59:02 INFO - [mozharness: 2017-05-29 10:59:02.474569Z] Finished download-and-extract step (success) 03:59:02 INFO - [mozharness: 2017-05-29 10:59:02.474721Z] Running populate-webroot step. 03:59:02 INFO - Running pre-action listener: _resource_record_pre_action 03:59:02 INFO - Running main action method: populate_webroot 03:59:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:59:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:59:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:59:02 INFO - '--tppagecycles', 03:59:02 INFO - '7'], 03:59:02 INFO - 'tests': ['tsvgm']}}, 03:59:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:59:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:59:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:59:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:59:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:59:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:59:02 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 03:59:02 INFO - 'talos_options': ['--disable-e10s'], 03:59:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:59:02 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 03:59:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:59:02 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 03:59:02 INFO - 'talos_options': ['--disable-e10s'], 03:59:02 INFO - 'tests': ['damp', 'tps']}, 03:59:02 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 03:59:02 INFO - 'tests': ['damp', 'tps']}, 03:59:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:59:02 INFO - 'tests': ['dromaeo_dom']}, 03:59:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:59:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:59:02 INFO - 'tests': ['basic_compositor_video']}, 03:59:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:59:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:59:02 INFO - 'tests': ['a11yr', 03:59:02 INFO - 'ts_paint', 03:59:02 INFO - 'tpaint', 03:59:02 INFO - 'sessionrestore', 03:59:02 INFO - 'sessionrestore_no_auto_restore', 03:59:02 INFO - 'tabpaint']}, 03:59:02 INFO - 'other-e10s': {'tests': ['a11yr', 03:59:02 INFO - 'ts_paint', 03:59:02 INFO - 'tpaint', 03:59:02 INFO - 'sessionrestore', 03:59:02 INFO - 'sessionrestore_no_auto_restore', 03:59:02 INFO - 'tabpaint']}, 03:59:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:59:02 INFO - 'ts_paint', 03:59:02 INFO - 'tpaint', 03:59:02 INFO - 'sessionrestore', 03:59:02 INFO - 'sessionrestore_no_auto_restore', 03:59:02 INFO - 'tabpaint']}, 03:59:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:59:02 INFO - 'ts_paint', 03:59:02 INFO - 'tpaint', 03:59:02 INFO - 'sessionrestore', 03:59:02 INFO - 'sessionrestore_no_auto_restore', 03:59:02 INFO - 'tabpaint']}, 03:59:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:59:02 INFO - 'tests': ['a11yr', 03:59:02 INFO - 'ts_paint', 03:59:02 INFO - 'tpaint', 03:59:02 INFO - 'sessionrestore', 03:59:02 INFO - 'sessionrestore_no_auto_restore', 03:59:02 INFO - 'tabpaint']}, 03:59:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:59:02 INFO - 'tests': ['a11yr', 03:59:02 INFO - 'ts_paint', 03:59:02 INFO - 'tpaint', 03:59:02 INFO - 'sessionrestore', 03:59:02 INFO - 'sessionrestore_no_auto_restore', 03:59:02 INFO - 'tabpaint']}, 03:59:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:59:02 INFO - 'tests': ['tsvgx', 03:59:02 INFO - 'tsvgr_opacity', 03:59:02 INFO - 'tart', 03:59:02 INFO - 'tscrollx', 03:59:02 INFO - 'cart', 03:59:02 INFO - 'tsvg_static']}, 03:59:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:59:02 INFO - 'tsvgr_opacity', 03:59:02 INFO - 'tart', 03:59:02 INFO - 'tscrollx', 03:59:02 INFO - 'cart', 03:59:02 INFO - 'tsvg_static']}, 03:59:02 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 03:59:02 INFO - 'talos_options': ['--disable-e10s'], 03:59:02 INFO - 'tests': ['tp5o']}, 03:59:02 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 03:59:02 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 03:59:02 INFO - 'talos_options': ['--disable-e10s', 03:59:02 INFO - '--xperf_path', 03:59:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:59:02 INFO - 'tests': ['tp5n']}, 03:59:02 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 03:59:02 INFO - 'talos_options': ['--xperf_path', 03:59:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:59:02 INFO - 'tests': ['tp5n']}}, 03:59:02 INFO - 'talos.zip': {'path': '', 03:59:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:59:02 INFO - Running post-action listener: _resource_record_post_action 03:59:02 INFO - [mozharness: 2017-05-29 10:59:02.493270Z] Finished populate-webroot step (success) 03:59:02 INFO - [mozharness: 2017-05-29 10:59:02.493409Z] Running create-virtualenv step. 03:59:02 INFO - Running pre-action listener: _resource_record_pre_action 03:59:02 INFO - Running main action method: create_virtualenv 03:59:02 INFO - Creating virtualenv /builds/slave/test/build/venv 03:59:02 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 03:59:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:59:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:59:02 INFO - Using real prefix '/tools/python27' 03:59:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:59:03 INFO - Installing distribute.............................................................................................................................................................................................done. 03:59:06 INFO - Installing pip.................done. 03:59:06 INFO - Return code: 0 03:59:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:59:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:59:06 INFO - Reading from file tmpfile_stdout 03:59:06 INFO - Output received: 03:59:06 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) 03:59:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:59:06 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')]} 03:59:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:06 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')]} 03:59:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:06 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 0x10966fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109672768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa43ac8c360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10944dd30>, '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 0x109374db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109442be8>, '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.7mecxwknr6/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.2BpeUQ0igj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 03:59:06 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 03:59:06 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 03:59:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7mecxwknr6/Render', 03:59:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:06 INFO - 'HOME': '/Users/cltbld', 03:59:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:06 INFO - 'LOGNAME': 'cltbld', 03:59:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:06 INFO - 'MOZ_NO_REMOTE': '1', 03:59:06 INFO - 'NO_EM_RESTART': '1', 03:59:06 INFO - 'PAGER': '/bin/cat', 03:59:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:06 INFO - 'PWD': '/builds/slave/test', 03:59:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:06 INFO - 'SHELL': '/bin/bash', 03:59:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BpeUQ0igj/Listeners', 03:59:06 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 03:59:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:06 INFO - 'USER': 'cltbld', 03:59:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:06 INFO - 'XPC_FLAGS': '0x0', 03:59:06 INFO - 'XPC_SERVICE_NAME': '0', 03:59:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:06 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 03:59:06 INFO - Cleaning up... 03:59:06 INFO - Return code: 0 03:59:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:59:06 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')]} 03:59:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:06 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')]} 03:59:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:06 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 0x10966fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109672768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa43ac8c360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10944dd30>, '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 0x109374db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109442be8>, '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.7mecxwknr6/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.2BpeUQ0igj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 03:59:06 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 03:59:06 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 03:59:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7mecxwknr6/Render', 03:59:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:06 INFO - 'HOME': '/Users/cltbld', 03:59:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:06 INFO - 'LOGNAME': 'cltbld', 03:59:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:06 INFO - 'MOZ_NO_REMOTE': '1', 03:59:06 INFO - 'NO_EM_RESTART': '1', 03:59:06 INFO - 'PAGER': '/bin/cat', 03:59:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:06 INFO - 'PWD': '/builds/slave/test', 03:59:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:06 INFO - 'SHELL': '/bin/bash', 03:59:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BpeUQ0igj/Listeners', 03:59:06 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 03:59:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:06 INFO - 'USER': 'cltbld', 03:59:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:06 INFO - 'XPC_FLAGS': '0x0', 03:59:06 INFO - 'XPC_SERVICE_NAME': '0', 03:59:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:06 INFO - Downloading/unpacking psutil>=3.1.1 03:59:06 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 03:59:06 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 03:59:06 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 03:59:06 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 03:59:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:59:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:59:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:59:09 INFO - Installing collected packages: psutil 03:59:09 INFO - Running setup.py install for psutil 03:59:09 INFO - building 'psutil._psutil_osx' extension 03:59:09 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 03:59:09 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 03:59:09 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 03:59:10 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 03:59:10 INFO - building 'psutil._psutil_posix' extension 03:59:10 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 03:59:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:59:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:59:10 INFO - ^ 03:59:10 INFO - 1 warning generated. 03:59:10 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 03:59:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:59:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:59:10 INFO - Successfully installed psutil 03:59:10 INFO - Cleaning up... 03:59:10 INFO - Return code: 0 03:59:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:59:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10966fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109672768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa43ac8c360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10944dd30>, '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 0x109374db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109442be8>, '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.7mecxwknr6/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.2BpeUQ0igj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 03:59:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 03:59:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 03:59:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7mecxwknr6/Render', 03:59:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:10 INFO - 'HOME': '/Users/cltbld', 03:59:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:10 INFO - 'LOGNAME': 'cltbld', 03:59:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:10 INFO - 'MOZ_NO_REMOTE': '1', 03:59:10 INFO - 'NO_EM_RESTART': '1', 03:59:10 INFO - 'PAGER': '/bin/cat', 03:59:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:10 INFO - 'PWD': '/builds/slave/test', 03:59:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:10 INFO - 'SHELL': '/bin/bash', 03:59:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BpeUQ0igj/Listeners', 03:59:10 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 03:59:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:10 INFO - 'USER': 'cltbld', 03:59:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:10 INFO - 'XPC_FLAGS': '0x0', 03:59:10 INFO - 'XPC_SERVICE_NAME': '0', 03:59:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:59:10 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 03:59:10 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 03:59:10 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 03:59:10 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 03:59:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:59:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:59:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:59:12 INFO - Installing collected packages: mozsystemmonitor 03:59:12 INFO - Running setup.py install for mozsystemmonitor 03:59:12 INFO - Successfully installed mozsystemmonitor 03:59:12 INFO - Cleaning up... 03:59:12 INFO - Return code: 0 03:59:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:59:12 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')]} 03:59:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:12 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')]} 03:59:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:12 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 0x10966fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109672768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa43ac8c360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10944dd30>, '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 0x109374db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109442be8>, '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.7mecxwknr6/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.2BpeUQ0igj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 03:59:12 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 03:59:12 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 03:59:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7mecxwknr6/Render', 03:59:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:12 INFO - 'HOME': '/Users/cltbld', 03:59:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:12 INFO - 'LOGNAME': 'cltbld', 03:59:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:12 INFO - 'MOZ_NO_REMOTE': '1', 03:59:12 INFO - 'NO_EM_RESTART': '1', 03:59:12 INFO - 'PAGER': '/bin/cat', 03:59:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:12 INFO - 'PWD': '/builds/slave/test', 03:59:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:12 INFO - 'SHELL': '/bin/bash', 03:59:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BpeUQ0igj/Listeners', 03:59:12 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 03:59:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:12 INFO - 'USER': 'cltbld', 03:59:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:12 INFO - 'XPC_FLAGS': '0x0', 03:59:12 INFO - 'XPC_SERVICE_NAME': '0', 03:59:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:12 INFO - Downloading/unpacking jsonschema==2.5.1 03:59:12 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 03:59:12 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 03:59:12 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 03:59:12 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 03:59:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:59:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:14 INFO - Downloading functools32-3.2.3-2.tar.gz 03:59:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:59:14 INFO - warning: no files found matching '*.txt' 03:59:14 INFO - no previously-included directories found matching 'build' 03:59:14 INFO - no previously-included directories found matching 'dist' 03:59:14 INFO - no previously-included directories found matching '.git*' 03:59:14 INFO - Installing collected packages: jsonschema, functools32 03:59:14 INFO - Running setup.py install for functools32 03:59:14 INFO - warning: no files found matching '*.txt' 03:59:14 INFO - no previously-included directories found matching 'build' 03:59:14 INFO - no previously-included directories found matching 'dist' 03:59:14 INFO - no previously-included directories found matching '.git*' 03:59:14 INFO - Successfully installed jsonschema functools32 03:59:14 INFO - Cleaning up... 03:59:14 INFO - Return code: 0 03:59:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:59:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10966fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109672768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa43ac8c360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10944dd30>, '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 0x109374db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109442be8>, '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.7mecxwknr6/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.2BpeUQ0igj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 03:59:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:59:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:59:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7mecxwknr6/Render', 03:59:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:14 INFO - 'HOME': '/Users/cltbld', 03:59:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:14 INFO - 'LOGNAME': 'cltbld', 03:59:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:14 INFO - 'MOZ_NO_REMOTE': '1', 03:59:14 INFO - 'NO_EM_RESTART': '1', 03:59:14 INFO - 'PAGER': '/bin/cat', 03:59:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:14 INFO - 'PWD': '/builds/slave/test', 03:59:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:14 INFO - 'SHELL': '/bin/bash', 03:59:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BpeUQ0igj/Listeners', 03:59:14 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 03:59:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:14 INFO - 'USER': 'cltbld', 03:59:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:14 INFO - 'XPC_FLAGS': '0x0', 03:59:14 INFO - 'XPC_SERVICE_NAME': '0', 03:59:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:59:15 INFO - Cleaning up... 03:59:15 INFO - Return code: 0 03:59:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:59:15 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')]} 03:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:15 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')]} 03:59:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:15 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 0x10966fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109672768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa43ac8c360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10944dd30>, '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 0x109374db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109442be8>, '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.7mecxwknr6/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.2BpeUQ0igj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 03:59:15 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 03:59:15 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 03:59:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7mecxwknr6/Render', 03:59:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:15 INFO - 'HOME': '/Users/cltbld', 03:59:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:15 INFO - 'LOGNAME': 'cltbld', 03:59:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:15 INFO - 'MOZ_NO_REMOTE': '1', 03:59:15 INFO - 'NO_EM_RESTART': '1', 03:59:15 INFO - 'PAGER': '/bin/cat', 03:59:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:15 INFO - 'PWD': '/builds/slave/test', 03:59:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:15 INFO - 'SHELL': '/bin/bash', 03:59:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BpeUQ0igj/Listeners', 03:59:15 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 03:59:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:15 INFO - 'USER': 'cltbld', 03:59:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:15 INFO - 'XPC_FLAGS': '0x0', 03:59:15 INFO - 'XPC_SERVICE_NAME': '0', 03:59:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:15 INFO - Downloading/unpacking blobuploader==1.2.4 03:59:15 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 03:59:15 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 03:59:15 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 03:59:15 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 03:59:16 INFO - Downloading blobuploader-1.2.4.tar.gz 03:59:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:59:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:59:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:59:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:17 INFO - Downloading docopt-0.6.1.tar.gz 03:59:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:59:17 INFO - Installing collected packages: blobuploader, requests, docopt 03:59:17 INFO - Running setup.py install for blobuploader 03:59:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:59:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:59:17 INFO - Running setup.py install for requests 03:59:18 INFO - Running setup.py install for docopt 03:59:18 INFO - Successfully installed blobuploader requests docopt 03:59:18 INFO - Cleaning up... 03:59:18 INFO - Return code: 0 03:59:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:59:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:18 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 0x10966fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109672768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa43ac8c360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10944dd30>, '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 0x109374db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109442be8>, '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.7mecxwknr6/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.2BpeUQ0igj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 03:59:18 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 03:59:18 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 03:59:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7mecxwknr6/Render', 03:59:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:18 INFO - 'HOME': '/Users/cltbld', 03:59:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:18 INFO - 'LOGNAME': 'cltbld', 03:59:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:18 INFO - 'MOZ_NO_REMOTE': '1', 03:59:18 INFO - 'NO_EM_RESTART': '1', 03:59:18 INFO - 'PAGER': '/bin/cat', 03:59:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:18 INFO - 'PWD': '/builds/slave/test', 03:59:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:18 INFO - 'SHELL': '/bin/bash', 03:59:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BpeUQ0igj/Listeners', 03:59:18 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 03:59:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:18 INFO - 'USER': 'cltbld', 03:59:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:18 INFO - 'XPC_FLAGS': '0x0', 03:59:18 INFO - 'XPC_SERVICE_NAME': '0', 03:59:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:59:18 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-IV_eM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:59:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:59:18 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-TGijVt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:59:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:59:18 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-Ik5Xfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:59:19 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-swz123-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:59:19 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-Mw5YQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:59:19 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-yYei_Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:59:19 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-v0BtJJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:59:19 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-TPQlE8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:59:19 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-teIVcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:59:19 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-nLob2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:59:19 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-5JKGTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:59:19 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-RVXO3n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:59:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:59:19 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-6FIkZ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:59:20 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-jZgYrz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:59:20 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-mbsmxV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:59:20 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-jEWz6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:59:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:59:20 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-HFrzvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:59:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:59:20 INFO - Running setup.py install for manifestparser 03:59:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:59:20 INFO - Running setup.py install for mozcrash 03:59:20 INFO - Running setup.py install for mozdebug 03:59:20 INFO - Running setup.py install for mozdevice 03:59:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:59:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:59:21 INFO - Running setup.py install for mozfile 03:59:21 INFO - Running setup.py install for mozhttpd 03:59:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:59:21 INFO - Running setup.py install for mozinfo 03:59:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:59:21 INFO - Running setup.py install for mozInstall 03:59:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:59:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:59:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:59:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:59:21 INFO - Running setup.py install for mozleak 03:59:21 INFO - Running setup.py install for mozlog 03:59:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:59:21 INFO - Running setup.py install for moznetwork 03:59:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:59:22 INFO - Running setup.py install for mozprocess 03:59:22 INFO - Running setup.py install for mozprofile 03:59:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:59:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:59:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:59:22 INFO - Running setup.py install for mozrunner 03:59:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:59:22 INFO - Running setup.py install for mozscreenshot 03:59:22 INFO - Running setup.py install for moztest 03:59:22 INFO - Running setup.py install for mozversion 03:59:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:59:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:59:23 INFO - Cleaning up... 03:59:23 INFO - Return code: 0 03:59:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:59:23 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')]} 03:59:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:23 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')]} 03:59:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:23 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 0x10966fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109672768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa43ac8c360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10944dd30>, '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 0x109374db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109442be8>, '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.7mecxwknr6/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.2BpeUQ0igj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 03:59:23 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 03:59:23 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 03:59:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7mecxwknr6/Render', 03:59:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:23 INFO - 'HOME': '/Users/cltbld', 03:59:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:23 INFO - 'LOGNAME': 'cltbld', 03:59:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:23 INFO - 'MOZ_NO_REMOTE': '1', 03:59:23 INFO - 'NO_EM_RESTART': '1', 03:59:23 INFO - 'PAGER': '/bin/cat', 03:59:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:23 INFO - 'PWD': '/builds/slave/test', 03:59:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:23 INFO - 'SHELL': '/bin/bash', 03:59:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BpeUQ0igj/Listeners', 03:59:23 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 03:59:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:23 INFO - 'USER': 'cltbld', 03:59:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:23 INFO - 'XPC_FLAGS': '0x0', 03:59:23 INFO - 'XPC_SERVICE_NAME': '0', 03:59:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:59:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-dDYRO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:59:23 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)) 03:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:59:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-HVEAdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:59:23 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)) 03:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:59:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-xqgwp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:59:23 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)) 03:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:59:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-fOz6bp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:59:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:59:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-BdL1YW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:59:23 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)) 03:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:59:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-60honJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:59:23 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)) 03:59:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:59:23 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-K1VUlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:59:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:59:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-peE343-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:59:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:59:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-B2wge8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:59:24 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)) 03:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:59:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-paE6uY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:59:24 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)) 03:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:59:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-ZadYQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:59:24 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)) 03:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:59:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-vxdeMe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:59:24 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)) 03:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:59:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-7hxMu6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:59:24 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)) 03:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:59:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-fz9WvP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:59:24 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)) 03:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:59:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-dcymse-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:59:24 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)) 03:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:59:24 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-67sHm7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:59:25 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)) 03:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:59:25 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-7wdZgK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:59:25 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)) 03:59:25 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)) 03:59:25 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)) 03:59:25 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)) 03:59:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:59:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:59:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:59:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:59:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:59:26 INFO - Downloading blessings-1.6.tar.gz 03:59:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:59:26 INFO - Installing collected packages: blessings 03:59:26 INFO - Running setup.py install for blessings 03:59:26 INFO - Successfully installed blessings 03:59:26 INFO - Cleaning up... 03:59:26 INFO - Return code: 0 03:59:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:59:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:59:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:59:27 INFO - Reading from file tmpfile_stdout 03:59:27 INFO - Current package versions: 03:59:27 INFO - blessings == 1.6 03:59:27 INFO - blobuploader == 1.2.4 03:59:27 INFO - docopt == 0.6.1 03:59:27 INFO - functools32 == 3.2.3-2 03:59:27 INFO - jsonschema == 2.5.1 03:59:27 INFO - manifestparser == 1.1 03:59:27 INFO - mozInstall == 1.12 03:59:27 INFO - mozcrash == 1.0 03:59:27 INFO - mozdebug == 0.1 03:59:27 INFO - mozdevice == 0.49 03:59:27 INFO - mozfile == 1.2 03:59:27 INFO - mozhttpd == 0.7 03:59:27 INFO - mozinfo == 0.9 03:59:27 INFO - mozleak == 0.1 03:59:27 INFO - mozlog == 3.4 03:59:27 INFO - moznetwork == 0.27 03:59:27 INFO - mozprocess == 0.25 03:59:27 INFO - mozprofile == 0.28 03:59:27 INFO - mozrunner == 6.13 03:59:27 INFO - mozscreenshot == 0.1 03:59:27 INFO - mozsystemmonitor == 0.3 03:59:27 INFO - moztest == 0.8 03:59:27 INFO - mozversion == 1.4 03:59:27 INFO - psutil == 3.1.1 03:59:27 INFO - requests == 1.2.3 03:59:27 INFO - wsgiref == 0.1.2 03:59:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:59:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:27 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 0x10966fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109672768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa43ac8c360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10944dd30>, '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 0x109374db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109442be8>, '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.7mecxwknr6/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.2BpeUQ0igj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 03:59:27 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 03:59:27 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 03:59:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7mecxwknr6/Render', 03:59:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:27 INFO - 'HOME': '/Users/cltbld', 03:59:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:27 INFO - 'LOGNAME': 'cltbld', 03:59:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:27 INFO - 'MOZ_NO_REMOTE': '1', 03:59:27 INFO - 'NO_EM_RESTART': '1', 03:59:27 INFO - 'PAGER': '/bin/cat', 03:59:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:27 INFO - 'PWD': '/builds/slave/test', 03:59:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:27 INFO - 'SHELL': '/bin/bash', 03:59:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BpeUQ0igj/Listeners', 03:59:27 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 03:59:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:27 INFO - 'USER': 'cltbld', 03:59:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:27 INFO - 'XPC_FLAGS': '0x0', 03:59:27 INFO - 'XPC_SERVICE_NAME': '0', 03:59:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:27 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)) 03:59:27 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)) 03:59:27 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)) 03:59:27 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)) 03:59:27 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)) 03:59:27 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)) 03:59:27 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)) 03:59:27 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)) 03:59:27 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)) 03:59:27 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)) 03:59:27 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)) 03:59:27 INFO - Cleaning up... 03:59:27 INFO - Return code: 0 03:59:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:59:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:59:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:59:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:59:27 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 0x10966fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109672768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa43ac8c360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10944dd30>, '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 0x109374db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109442be8>, '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.7mecxwknr6/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.2BpeUQ0igj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 03:59:27 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 03:59:27 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 03:59:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7mecxwknr6/Render', 03:59:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:27 INFO - 'HOME': '/Users/cltbld', 03:59:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:27 INFO - 'LOGNAME': 'cltbld', 03:59:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:27 INFO - 'MOZ_NO_REMOTE': '1', 03:59:27 INFO - 'NO_EM_RESTART': '1', 03:59:27 INFO - 'PAGER': '/bin/cat', 03:59:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:27 INFO - 'PWD': '/builds/slave/test', 03:59:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:27 INFO - 'SHELL': '/bin/bash', 03:59:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BpeUQ0igj/Listeners', 03:59:27 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 03:59:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:27 INFO - 'USER': 'cltbld', 03:59:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:27 INFO - 'XPC_FLAGS': '0x0', 03:59:27 INFO - 'XPC_SERVICE_NAME': '0', 03:59:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:59:27 INFO - Cleaning up... 03:59:27 INFO - Return code: 0 03:59:27 INFO - Running post-action listener: _resource_record_post_action 03:59:27 INFO - Running post-action listener: _start_resource_monitoring 03:59:27 INFO - Starting resource monitoring. 03:59:27 INFO - [mozharness: 2017-05-29 10:59:27.741572Z] Finished create-virtualenv step (success) 03:59:27 INFO - [mozharness: 2017-05-29 10:59:27.742565Z] Running install step. 03:59:27 INFO - Running pre-action listener: _resource_record_pre_action 03:59:27 INFO - Running main action method: install 03:59:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:59:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:59:28 INFO - Reading from file tmpfile_stdout 03:59:28 INFO - Detecting whether we're running mozinstall >=1.0... 03:59:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:59:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:59:28 INFO - Reading from file tmpfile_stdout 03:59:28 INFO - Output received: 03:59:28 INFO - Usage: mozinstall [options] installer 03:59:28 INFO - Options: 03:59:28 INFO - -h, --help show this help message and exit 03:59:28 INFO - -d DEST, --destination=DEST 03:59:28 INFO - Directory to install application into. [default: 03:59:28 INFO - "/builds/slave/test"] 03:59:28 INFO - --app=APP Application being installed. [default: firefox] 03:59:28 INFO - mkdir: /builds/slave/test/build/application 03:59:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:59:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 03:59:42 INFO - Reading from file tmpfile_stdout 03:59:42 INFO - Output received: 03:59:42 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 03:59:42 INFO - Running post-action listener: _resource_record_post_action 03:59:42 INFO - [mozharness: 2017-05-29 10:59:42.948104Z] Finished install step (success) 03:59:42 INFO - [mozharness: 2017-05-29 10:59:42.948242Z] Running run-tests step. 03:59:42 INFO - Running pre-action listener: _resource_record_pre_action 03:59:42 INFO - Running main action method: run_tests 03:59:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:59:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:59:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:59:42 INFO - 2017-05-29 03:59:42.962 screenresolution[1666:14836] starting screenresolution argv=screenresolution get 03:59:42 INFO - 2017-05-29 03:59:42.965 screenresolution[1666:14836] Display 0: 1600x1200x32@60 03:59:42 INFO - 2017-05-29 03:59:42.971 screenresolution[1667:14838] starting screenresolution argv=screenresolution list 03:59:42 INFO - Available Modes on Display 0 03:59:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:59:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:59:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:59:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:59:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:59:43 INFO - Intel Iris: 03:59:43 INFO - Chipset Model: Intel Iris 03:59:43 INFO - Type: GPU 03:59:43 INFO - Bus: Built-In 03:59:43 INFO - VRAM (Dynamic, Max): 1536 MB 03:59:43 INFO - Vendor: Intel (0x8086) 03:59:43 INFO - Device ID: 0x0a2e 03:59:43 INFO - Revision ID: 0x0009 03:59:43 INFO - Displays: 03:59:43 INFO - Display: 03:59:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:59:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:59:43 INFO - Display Serial Number: 1600x1200 60 03:59:43 INFO - Main Display: Yes 03:59:43 INFO - Mirror: Off 03:59:43 INFO - Online: Yes 03:59:43 INFO - Rotation: Supported 03:59:43 INFO - Return code: 0 03:59:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:59:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:59:43 INFO - Python 2.7.3 03:59:43 INFO - Return code: 0 03:59:43 INFO - grabbing minidump binary from tooltool 03:59: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')]} 03:59:43 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 0x10944dd30>, '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 0x109374db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109442be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:59:43 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 03:59:43 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 03:59:43 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 03:59:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:59:43 INFO - Return code: 0 03:59:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:59:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:59:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:59:43 INFO - ENV: RUST_BACKTRACE is now 1 03:59:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:59:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:59:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0006', '--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 03:59:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0006 --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 03:59:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7mecxwknr6/Render', 03:59:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:59:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:59:43 INFO - 'HOME': '/Users/cltbld', 03:59:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:59:43 INFO - 'LOGNAME': 'cltbld', 03:59:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:59:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:59:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:59:43 INFO - 'MOZ_NO_REMOTE': '1', 03:59:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:59:43 INFO - 'NO_EM_RESTART': '1', 03:59:43 INFO - 'PAGER': '/bin/cat', 03:59:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:59:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:59:43 INFO - 'PWD': '/builds/slave/test', 03:59:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:59:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:59:43 INFO - 'RUST_BACKTRACE': '1', 03:59:43 INFO - 'SHELL': '/bin/bash', 03:59:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2BpeUQ0igj/Listeners', 03:59:43 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 03:59:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:59:43 INFO - 'USER': 'cltbld', 03:59:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:59:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:59:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:59:43 INFO - 'XPC_FLAGS': '0x0', 03:59:43 INFO - 'XPC_SERVICE_NAME': '0', 03:59:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:59:43 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0006', '--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 03:59:43 INFO - mozversion application_buildid: 20170529024916 03:59:43 INFO - mozversion application_changeset: 6fb66467485a8beee11dad38a08768c2a9955fe7 03:59:43 INFO - mozversion application_display_name: Firefox 03:59:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:59:43 INFO - mozversion application_name: Firefox 03:59:43 INFO - mozversion application_remotingname: firefox 03:59:43 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 03:59:43 INFO - mozversion application_vendor: Mozilla 03:59:43 INFO - mozversion application_version: 54.0 03:59:43 INFO - mozversion platform_buildid: 20170529024916 03:59:43 INFO - mozversion platform_changeset: 6fb66467485a8beee11dad38a08768c2a9955fe7 03:59:43 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 03:59:43 INFO - mozversion platform_version: 54.0 03:59:43 INFO - using testdate: 1496055583 03:59:43 INFO - actual date: 1496055583 03:59:43 INFO - starting webserver on 'localhost:49224' 03:59:43 INFO - SUITE-START | Running 2 tests 03:59:43 INFO - TEST-START | dromaeo_css 03:59:43 INFO - operating with platform_type : mac_ 03:59:43 INFO - Initialising browser for dromaeo_css test... 03:59:43 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpkIzDo8/profile http://localhost:49224/getInfo.html) 03:59:46 INFO - PROCESS | 1677 | 2017-05-29 03:59:46.659 plugin-container[1681:15014] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d43, name = 'com.apple.tsm.portname' 03:59:46 INFO - PROCESS | 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:59:46 INFO - PROCESS | 1677 | 2017-05-29 03:59:46.660 plugin-container[1681:15014] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 03:59:46 INFO - PROCESS | 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:59:46 INFO - PROCESS | 1677 | 2017-05-29 03:59:46.660 plugin-container[1681:15014] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:59:46 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 03:59:46 INFO - PROCESS | 1677 | colorDepth:24 03:59:46 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 03:59:46 INFO - PROCESS | 1677 | __metrics 03:59:46 ERROR - PROCESS | 1677 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 03:59:47 INFO - TEST-INFO | 1677: exit 0 03:59:47 INFO - Browser initialized. 03:59:47 INFO - Running cycle 1/1 for dromaeo_css test... 03:59:47 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpkIzDo8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:59:49 INFO - PROCESS | 1682 | 2017-05-29 03:59:49.700 plugin-container[1683:15149] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8647, name = 'com.apple.tsm.portname' 03:59:49 INFO - PROCESS | 1682 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:59:49 INFO - PROCESS | 1682 | 2017-05-29 03:59:49.701 plugin-container[1683:15149] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431b, name = 'com.apple.CFPasteboardClient' 03:59:49 INFO - PROCESS | 1682 | See /usr/include/servers/bootstrap_defs.h for the error codes. 03:59:49 INFO - PROCESS | 1682 | 2017-05-29 03:59:49.701 plugin-container[1683:15149] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 03:59:59 INFO - PROCESS | 1682 | RSS: Main: 174395392 03:59:59 INFO - PROCESS | 1682 | 04:03:02 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49224/tests/dromaeo/cssquery-ext.html) 04:03:04 INFO - PROCESS | 1682 | RSS: Main: 195125248 04:03:04 INFO - PROCESS | 1682 | 04:06:06 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-ext.html (next: http://localhost:49224/tests/dromaeo/cssquery-jquery.html) 04:06:06 INFO - PROCESS | 1682 | RSS: Main: 202772480 04:06:06 INFO - PROCESS | 1682 | 04:09:08 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49224/tests/dromaeo/cssquery-mootools.html) 04:09:09 INFO - PROCESS | 1682 | RSS: Main: 204505088 04:09:09 INFO - PROCESS | 1682 | 04:12:11 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49224/tests/dromaeo/cssquery-prototype.html) 04:12:11 INFO - PROCESS | 1682 | RSS: Main: 207495168 04:12:11 INFO - PROCESS | 1682 | 04:15:14 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49224/tests/dromaeo/cssquery-yui.html) 04:15:15 INFO - PROCESS | 1682 | RSS: Main: 209911808 04:15:15 INFO - PROCESS | 1682 | 04:18:18 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-yui.html (next: http://localhost:49224/tests/dromaeo/cssquery-dojo.html) 04:18:18 INFO - PROCESS | 1682 | RSS: Main: 207925248 04:18:18 INFO - PROCESS | 1682 | 04:18:18 INFO - PROCESS | 1682 | __start_tp_report 04:18:18 INFO - PROCESS | 1682 | _x_x_mozilla_page_load 04:18:18 INFO - PROCESS | 1682 | _x_x_mozilla_page_load_details 04:18:18 INFO - PROCESS | 1682 | |i|pagename|runs| 04:18:18 INFO - PROCESS | 1682 | |0;dojo.html;3179.7933134346267;3226.241833169205;3631.7276204284667;3651.580068292147;3689.0224090615993;6236.282827474842;6238.656873871935;6258.468030217431;6273.7490500380045;6274.498040156782;6197.1324014637985;6239.188905442297;6267.059941008851;6278.874422511547;6295.43341099301;5552.888942221153;5643.887122257553;5660.886782264372;5665.830025099253;5723.914141287884;5693.146028095777;5702.515286200678;5714.285714285715;5716.8856622867725;5725.169850371693;5478.863028424282;5499.202615620733;5600.103983362663;5683.090705487123;5716.085426331788;4629.768511574424;5621.8594535136535;5710.800121995751;5729.140628905656;5783.363829978699;5472.398036216013;5628.43715628438;5633.8591535211535;5634.605577609569;5700.401457846908;6285.151504546919;6285.182926219608;6307.369263073701;6318.43134117927;6336.936630633681;6251.87496250077;6297.811065668037;6359.682015899187;6379.872402551923;6398.680065996728;6617.7022034008105;6645.169353830771;6653.3346665333565;6663.86672266552;6686.899696504556;5179.741012949337;6244.438000579969;6318.399752023551;6354.205724284465;6396.5202609804455;5614.466625670555;6413.839654008594;6471.964485682268;6476.773312934025;6567.802965911031;5283.445238250005;6313.2424109107205;6326.46225070865;6399.840003999937;6402.583832050902;5458.235846981426;6592.340765923369;6624.039514270404;6635;6640.601563906181;6200.162977998003;6443.323451037667;6468.256150542654;6598.802035938929;6603.7358505660195;6345.111684364193;6348.77779277723;6500.122483464672;6520.282768895418;6547.279799222082;5318.813841515605;5334.359876814807;5355.357357117171;5356.330458692663;5363.0614642438195;4677.391939047902;4714.952850471452;4745.762711864462;4752.453467851172;4768.523147685273;6140.995089002448;6152.630842149485;6158.414950579688;6158.599691020159;6312.810615681537;6640.800775976729;6709;6721.630310332881;6723.260441351447;6744.629045402452;6138.817583427401;6160.322364539897;6174.641578852646;6241.625502469867;6255.40573645503;5851.268591426072;5948.405159484104;5953.553483488756;5963.105534169909;6000.33996260411;5422.5119739223655;6474.4496717778575;6547.247066587213;6743;6755.3582409671;6520.706568204348;6530.869382612416;6547.247066587403;6571.704273307808;6653.434458071119;6043.697815109315;6635.303293154342;6774.42417394527;6804.897926531007;6823.078884350748;6283.654399008098;6330.208723909512;6405.679716014088;6422.100905873088;6476.060971159061;6499.967500162469;6590.5716128451495;6602.900956485752;6611.669416529058;6632.900506492503;6341.778037768656;6428.389303016206;6436.485081193399;6446.742130314735;6454.096426500388;5774.451427114585;6211.81364559064;6251.9687401562705;6415.743370265137;6417.935820641734;6261.24865016201;6300.810975670737;6302.275238347649;6355.745770169141;6374.681265936591;6422.582532135396;6424.871502570016;6436.420722135031;6442.452391546771;6462.806115816533;6152.630842149485;6221.595596286227;6265.592736472115;6333.429991300805;6334.619922804646 04:18:18 INFO - PROCESS | 1682 | |1;ext.html;784.9803754906127;802.5987006496752;824.4311425116668;978.9902100978989;983.6901376066543;39390.60609393905;40288.19423611526;42408.57591424092;42450.57549424505;42476.938076548104;40969;41034.58965410345;41286.38070428938;41334.58665413345;41374;8311.667533298676;8429.620667069996;11126.49930753114;11277.774444511106;11557.53769849205;11167.218294719372;11187.105031597494;11220.326780393161;11252.15608829337;11252.774944501105;9424.81150376992;11224.719382015433;11260.155488338367;11264.887351126466;11279.210455268134;9389.859152112724;11172.664820055412;11172.832407513853;11238.887611123866;11247.156463265246;9298.86051709225;11029.669109926635;11094.389808560525;11108.055815255713;11126.165537584633;34458.827705861564;34637.653623463695;34693.47959780605;34755.6524434755;34788.304233915434;3860.942085868714;3881.6506514413554;3893.669038131762;3920.3923391874305;3946.427767973628;26072.60891086635;26107.73892261091;26400.86799566009;27598.516133548077;27700.584491232647;3157.494800831879;3230.9353812923846;3245.837708114585;3268.869245230188;3282.868685252587;342353.86469202983;346771;347400;347418.9999999975;347607;45134;45137.09725805466;45178.77410612926;45310;45322.093558128654;46163;46223.76888115538;46450;46530.534694653295;46710.065798683834;14219.36012879423;14482.782758258423;14595.854041459663;14606.926965365317;14641.828653707678;12484.75030499385;12594;12651;12669.873301267055;12681.809772853414;11925.22486038422;12122.030237580972;12176.025917926545;12212.572559960536;12288.262704237774;5611.102223644198;5660.235868157828;5663.584103974006;5665.745041473061;5669.603127781098;1119.2948442481186;1177.8822117788152;1194.904407647386;1210.9878901211052;1237.2823762217893;23831.761682382956;27694.5845812313;28274.2931426716;28365.290867728057;28558.857205713837;1302.1145620977827;1307.7123032932739;1313.258009224804;1315.0728736240926;1325.3969443903002;13771.517996870061;14340.928295358457;14358.641033974025;14658;14674.486392976194;445197.77401113434;445629;447156;447790;449530;49194;59957.70021149866;60257;60269;60297.397026030056;1448.7826825975976;1487.9732984240839;1491.843356447579;1498.572906721601;1501.3018946190075;27207.047753328538;27422.5886611697;27988.16035518938;28291.2927176814;28354.85822570874;41965.37051944284;42091;42187;42227.788861055495;42270.36594451024;448822.75588621845;448969;451027;451288;451500;29890;30073.045252058546;30187.547186791777;30215.09354719362;30241;37517.4372384409;38232.80883595564;38559.65041223656;38704.03239919025;39112.21775564416;27923.162305130878;27986.580201297405;28268.293292667848;28336.433271334045;29042.27394315159;23794.88102559476;25878.964841406887;27538.724612754417;27864.16407507778;28494;31016.480499905818;31609.841950790098;32057;32151.196220094684;32330.676693232766;26876.59685104686;27507.862460688368;27778.166655000383;28398.148055558366;28449.573256401585;21983.890080549496;22982.770172298064;23121;23256;23598.056077756697 04:18:18 INFO - PROCESS | 1682 | |2;jquery.html;367.34429044156184;387.84486205517806;434.7369841246047;438.0013569062539;444.4599811229356;20430.672006319615;20570.177192912284;21920.45198870029;21975.450613734665;21998.230061947834;22615.208467703633;23540.529189416244;23679.763202367973;23828.404289892762;24080.397990050256;6149.800788846177;6803.149606299213;7021.36807687308;7043.295670432942;7063.258357872426;6645.966770166143;6971.930280697205;7022.052022976884;7023.859522809541;7041.15506139264;5965.433283838029;6674.365935236145;6958.304169583026;7009.649517524103;7031.542949708278;6694.531382803206;6994.4404447644065;7056.647167641649;7079.787606371791;7090.822729431754;6765.293882447011;6801.115854938876;6834.555753875982;6843;6863.313668633146;27390.452190956068;27520;27546.44907101867;27601.585976210372;27643.723562764317;2897.7392034716977;3098.1789825695982;3102;3120.0795765249304;3130.4365214261425;16542.338306467725;16847.241874115694;16901.91549042247;17374.56563585908;17783.911080444643;2151.0201639442375;2161.9243326483497;2209.856359336654;2232;2270.2735124760084;176186;177627.11186444113;183744;184174.07912960483;184799;21012.579748404947;21200.681989770164;22011.11955521793;22040.77959220387;22260.443488912584;32136.517952230733;32554.511682324784;32589.511157332658;32766.017019489453;32819.671803281664;8956.596953137125;9215.63137474507;10694.304870183569;11028.117750579935;11214.215004949738;8147.696368581117;9184.448933064037;9224.907750922404;9254.676086336945;9408.764780880396;7587.355074818703;8168.714095006765;8929.687460938965;9206.125418085272;9227.90772092284;3368.898933032013;3401.863925443004;3440.8451619677176;4155.812988415529;4158.126793373425;945.4374647084974;1032.746976990642;1054.4464156317995;1060.3637817309552;1096.6381094238882;18936.431907042534;19481.902590486956;21534.461638459165;21747.021384037755;21748.782512174992;988.3378136648319;1026.9486525673688;1037.2998226197437;1043.2019505078622;1069.2568664777905;10110.949445252874;10620.24765913631;10942.234043617029;10961.451927403758;10983.286086404358;641363;745789.2710536412;772873;772881;800309;171754;173480;175469;177428;177770;1284.9807252891214;1318.7824009038402;1345.892328613689;1348.4875747216192;1351.3851197705217;13534.59396218115;17900.194491248185;18128.365507207185;18397.08014599238;18435.631287374446;61663.691681541306;62012.37987620066;62599.06101408391;63165.68417157885;64631.353686462535;92057;92182.53908730681;93340.13319733703;93656.53171734097;94636.10727785269;9398;9618.903810961801;11534.019608333385;11720.120990925885;11900.107491937966;12816.231026138461;12846.935765321114;13024.609261722164;13250.138740982191;13270.203787772765;11266.718332041764;11456.770864582828;11533.82699259495;11627.244229125079;11635.709107272385;10563.894361056291;10635.989580989793;10640.6015337713;10866.782664346827;11004;11567.479463423997;11799.17405781587;11817.409129543317;11826.586069487526;11939.76120477603;11544.653660390202;11712.180147389601;11791.35147566892;11820;11837.82243266369;11252.606158784403;11393.316401015965;11481;11484.94257528707;11651.41742912868 04:18:18 INFO - PROCESS | 1682 | |3;mootools.html;1575.3304845440684;1622.5619082847631;1624.6994306053382;1634.6158652716613;1635.8773092018102;11093.999999999989;11450.026747726453;11756.588519401812;11782.175247732663;11786.94106529466;11393.316401015923;11779.587714429998;11810.232334898226;11847.289162650248;11857.46641401137;2352.200251914349;2416;2416.0577374823833;2423.333583264599;2432.4891772727747;2302.6085565453827;2354.0701422937927;2385.272491889973;2388.5819981503255;2398.1726304424947;2301.7007788987407;2349.3069544484333;2358.8230882683783;2405.795507381875;2407.7712617301418;1920.9615807683838;2251.4371407148215;2273.5111950930545;2284.4745708486976;2304.308707387777;2318.084356679119;2329.0683726509365;2329.871857047862;2340.7659234076627;2344.3318654183477;10453.529591168415;10473.31923424975;10528.947355263175;10531.26281160319;10549.470326802648;5490.3411590608985;5537.723113844291;5648.4633959773755;5692.7153642318135;5703.230063941356;3350.430426827445;3440.776349537272;3560.5193298904574;3561.626029266916;3574.0528759878653;908.8227795579865;1124.2804605052772;1179.1804695736466;1194.6953526850684;1237.993810030953;14206.502772403019;14531.055481393782;14759.114453132739;16016.75974860378;16119.919400402923;4162.62536371728;4481.955180448219;4624.12141693084;4795.592374648124;4806.086843499794;13593.048486605843;13608.251546165056;13612;13618;13626.863731362757;5816.854578635568;5901.439363260483;5940.851478712981;5952.672603006789;5971.313298970587;5090.643654944119;5177.119889618773;5244.580433565306;5248.606354523426;5261.605379596545;3959.821808018646;4019.959800402017;4060.8578699745367;4109.753414795122;4113.424120623115;1023.2939271902363;1039.9184847758247;1040.5265604150093;1050.6532844161409;1051.8001579699833;811.5495899775564;813.4875028731861;825.9215374539409;829.7801082713086;836.6444261189092;5526.246438569348;5845.357010728816;5980.342162362137;6548.967255163694;6747.561408508432;940.1632547033146;956.8947415784259;987.111599560387;1006.6023920551488;1016.8068067067234;4089.407035979766;4098.549159592442;4137.234611596864;4223.408722778813;4248.065425606362;13802.861971380158;14309.713805723828;14315.28423578817;14338;14347.426102955973;13907.930460347836;14140.222287774066;14225.430982760574;14274.286285685674;14345.497907573184;9045.231155351737;10581.206409519316;10589.470526473646;10693.83959240612;10714.571417143226;5069.949300506948;6303.527235457451;6786.151731033621;6870.45036397077;6918.377346038881;11980.880191197977;12035.338056406632;12174.695632609255;12198.02415806751;12214.75570488603;10361.430121343097;11864.754200808791;12347.320897350732;12350.3824808761;12354.567590134302;9419.481928493999;9429.669961551313;9540.141387275178;9546.570404331684;9589.376690515093;10461.790764184825;10489.265751397328;10586.523606437575;10604.946975265075;10651.89348106509;8862.601182946653;8948.776280592776;9054.230390416888;9104.772380690536;9255.814883702153;8833.02836687951;8903.243224326005;8924.241439477717;9001;9003.729888103368;8029.116797152192;8094.838103237785;8169.632366543638;8177.632006559838;8222.712205072794;5847.473727364558;5879.7060146993335;5885.529157667462;6036.517078633787;6085;4986.950130498793;5968.4926781224085;6114.694265286629;6203.4416902479;6225.906611400923 04:18:18 INFO - PROCESS | 1682 | |4;prototype.html;330.6363000699231;385.90738222826513;392.1686025625674;401.0435112257267;406.00731212186196;4637.675362724611;5146.279520867077;5283.418823929364;5325.801694618709;5539.806106786264;5107.923381149286;5256.947430525694;5320.255164277004;5354.518093371596;5367.865803354918;4232.243618898154;4332.935005974913;4423.867283981485;4474.798634061475;4515.367848501212;3924.156306394125;3963.9009024774323;4228.429162063128;4456.955430445686;4465.665075119363;3635.745497815154;3897.7856217908;3989.421533877586;4014.1570270243283;4447.777611119447;3653.7442379033478;3957.6543975048335;4118.876433706979;4353.542877997828;4498.52765459627;4053.209624123297;4056.837726490937;4066;4105.938410923838;4115.97942010288;4506.504284528732;4664.160451118797;4862.836037963108;5218.686878787247;5249.136556522418;4214.262504061808;5057.4689657585795;5128.769205385729;5135.589152867761;5153.3558305211845;5270.628217302191;5644.899244647296;5668.930840243323;5694.373618901917;5764.884702305931;3915.314819906505;4602;4602.746848923308;4646.256598944153;4691.554302341272;5101.336826212606;5461.317335333083;5496.203050557647;5511.779528818843;5820.923129221108;4058.8782336529953;4357.150355680612;4471.6401508528925;4605.953940460619;4608.861734147981;3950.802459876995;4417.911641767147;4483.968687201962;4781.784819683123;4797;4143.461350024478;4622.774964634307;5002.74986250686;5148.227765835155;5167.379914410295;5021.593953692899;5580.414056524007;5627.803026894039;5634.67585117497;5658.773649053992;1434.684369438722;1479.9112053276838;1604.1898841085176;1628.4951664983894;1631.274083033039;1016.893226211252;1103.4882211370473;1213.5145941623246;1216.403962058585;1238.7584421037811;4565.269556870885;4894.821804297125;4921.458639549646;5373.086575282211;5404.02752995154;5463.863403414946;5519.199716041152;5864.70676466175;5999.160117583542;6013.969930150409;4963.45402005779;5035.446100928895;5280.67983004249;5436.347638283352;5469.808556700496;2414.539203781722;2995.3974623576282;3056.264643144373;3069.815811051344;3141.89003384885;5341.169180334429;5846.118491060012;5874.353821079678;6052.550046473468;6179.876402471926;5102.489751024942;5312.831177141023;5801.679328268726;5890.204822348928;6098.761360854164;5841.649501029951;5890.67601281925;5951.9107213391835;6151.138840562415;6173.753049878044;4977.726225057657;5085.46602606721;5847.649141051551;5857.912131318119;6035;4728.479867214535;5184.185535062697;5318.893861892627;5884.880583591417;5906.911396328973;5197.688138711689;5217.582593392595;6167.475764560063;6218.9378106218355;6231.345708700703;3915.691240901434;4668.906621867612;4671.813127474863;4678.906421871611;4700.106979673851;4904.754762261801;5067.949320506747;5744.368119506935;5899.734511946886;5985.4912332450485;5345.4654534546235;5505.284313039279;5697.658140511582;5821.76712931478;5865.618734782225;5089.8741761532665;5275.076861549291;5510.476504732044;5727.255456790591;5734.283214598176;4763.476017638126;4888.9755551222015;5696.544276457957;5698.091139468324;5737.9384813809575;5159.194282001269;5450.236966824568;5813.6337960578385;5829.854253643693;5839.678817665069;4673.719576825401;5260.9736951315;5314.581865251224;5501.0098182327565;5682.204491371294 04:18:18 INFO - PROCESS | 1682 | |5;yui.html;739.945577551989;748.8801791713327;761.7143571160815;818.9467684600504;885.6280362247858;15826.366945322174;17173.91413042933;17184.656306873854;17213.913930430346;17225;15874.285657145427;15876;16120.274587643555;16123.758143627825;16147.515574532783;958.2238386906628;979.6326377608397;1000.9049140331693;1002.5889385352007;1026.7535791410082;975.4488713876673;978.0708327089258;982.5234761140841;983.6753871222479;984.7045886234137;1045.8117538843005;1054.3357684658693;1055.7360659835042;1057.799018186546;1059.0204061243358;1034.7154532503546;1042.0725554256717;1050.56926660069;1052.2108418686023;1053.7260312318815;766.7469734987441;777.0481160578303;779.9883001754978;782.3741007194221;782.7319742017911;14787.964842461031;17398;17499.387521436816;17669.469915902417;17880.55298617532;14498.927505362512;14555.636109097357;14674.779878301833;14803.481878134322;15044.849551504454;11841.644750657493;12139.514419423213;12147.574834880737;12160.331181784999;12288.324142172178;3794.298054859832;3868.323043467382;3895.7272990890647;3933.468981687493;4058.269511487931;98835;121253.99999999911;122918;126857;127176;10501.579936802442;10696.197785166143;11229.55081796719;11471.770564588662;11611.477483513263;23500.412489687893;23571.64642530329;23822.404439889142;23825.404364891016;23961.400964975666;8129.065157506962;8197.549134797524;8220.712275070464;8239.588020598947;8297;4578.613603746271;4614.607758340579;4683.953160468352;4758.3100450434495;4762.547557981986;3552.946705799415;3657.085728567858;3663.6153203913204;3666.7433279670167;3674.1365779041903;645.3997782062727;672.8923372260513;695.214407719284;716.913970323554;720.9567425954459;572.83101485062;595.6098755315276;604.619089973314;613.1936503497886;622.7228883146956;9367.484788336707;10900.563977440972;11187.272827266203;11218.08329834249;11220;600.6546235914367;626.8840264551072;632.7753647455179;649.1398896462207;649.2047242128355;5193.169092945186;5271.4728527147745;5295.179247216687;5302.655327403706;5366.221897824794;110759.44620276846;111251;112702;112881;113297.43351283355;63538.68230658817;75723.62138189384;77637;90826.54586727024;94542.52728736312;70144.24639384056;70256.29743702497;72859;74490;76186;9682.838059432914;10590.788184236118;10826.592542969363;11013.559457621926;11351.262167959387;42887;49779;50286.74856625694;50491.990160197325;51173;56965.71517142388;61261.38738612557;64402;65114;66119.66940165269;11431.371274579978;12592.118551701293;12639;12704.36478176069;13347.466101356225;14935.327910243848;15056.397744090114;15264.694706105594;15910.965787223793;15981.920090399473;13481.2585307809;14152.43390264378;14289.214093224557;14303.713925721218;14390.424383024561;10705.982931621484;13061.934690326487;14066.648333791738;14133.858661413666;14599.343029563483;11426.600068997544;13247.403866825824;14194.219317937612;14380.640483987983;14469.204193769443;10149.6609477532;10255.897441025494;10354.223433242687;10396.272260941661;10399.584016639552;10447.746270447595;10926.836097458385;11304.095672346357;11542.13433992437;11546.711332216762 04:18:18 INFO - PROCESS | 1682 | __end_tp_report 04:18:18 INFO - PROCESS | 1682 | __start_cc_report 04:18:18 INFO - PROCESS | 1682 | _x_x_mozilla_cycle_collect,397 04:18:18 INFO - PROCESS | 1682 | __end_cc_report 04:18:18 INFO - PROCESS | 1682 | __startTimestamp1496056698550__endTimestamp 04:18:18 INFO - PROCESS | 1682 | 04:18:18 INFO - PROCESS | 1682 | ------- Summary: start ------- 04:18:18 INFO - PROCESS | 1682 | Number of tests: 6 04:18:18 INFO - PROCESS | 1682 | 04:18:18 INFO - PROCESS | 1682 | [#0] dojo.html Cycles:165 Average:6076.05 Median:6295.43 stddev:662.77 (10.5%) stddev-sans-first:624.65 04:18:18 INFO - PROCESS | 1682 | Values: 3179.8 3226.2 3631.7 3651.6 3689.0 6236.3 6238.7 6258.5 6273.7 6274.5 6197.1 6239.2 6267.1 6278.9 6295.4 5552.9 5643.9 5660.9 5665.8 5723.9 5693.1 5702.5 5714.3 5716.9 5725.2 5478.9 5499.2 5600.1 5683.1 5716.1 4629.8 5621.9 5710.8 5729.1 5783.4 5472.4 5628.4 5633.9 5634.6 5700.4 6285.2 6285.2 6307.4 6318.4 6336.9 6251.9 6297.8 6359.7 6379.9 6398.7 6617.7 6645.2 6653.3 6663.9 6686.9 5179.7 6244.4 6318.4 6354.2 6396.5 5614.5 6413.8 6472.0 6476.8 6567.8 5283.4 6313.2 6326.5 6399.8 6402.6 5458.2 6592.3 6624.0 6635.0 6640.6 6200.2 6443.3 6468.3 6598.8 6603.7 6345.1 6348.8 6500.1 6520.3 6547.3 5318.8 5334.4 5355.4 5356.3 5363.1 4677.4 4715.0 4745.8 4752.5 4768.5 6141.0 6152.6 6158.4 6158.6 6312.8 6640.8 6709.0 6721.6 6723.3 6744.6 6138.8 6160.3 6174.6 6241.6 6255.4 5851.3 5948.4 5953.6 5963.1 6000.3 5422.5 6474.4 6547.2 6743.0 6755.4 6520.7 6530.9 6547.2 6571.7 6653.4 6043.7 6635.3 6774.4 6804.9 6823.1 6283.7 6330.2 6405.7 6422.1 6476.1 6500.0 6590.6 6602.9 6611.7 6632.9 6341.8 6428.4 6436.5 6446.7 6454.1 5774.5 6211.8 6252.0 6415.7 6417.9 6261.2 6300.8 6302.3 6355.7 6374.7 6422.6 6424.9 6436.4 6442.5 6462.8 6152.6 6221.6 6265.6 6333.4 6334.6 04:18:18 INFO - PROCESS | 1682 | 04:18:18 INFO - PROCESS | 1682 | [#1] ext.html Cycles:180 Average:54611.49 Median:29981.52 stddev:110740.38 (369.4%) stddev-sans-first:110977.29 04:18:18 INFO - PROCESS | 1682 | Values: 785.0 802.6 824.4 979.0 983.7 39390.6 40288.2 42408.6 42450.6 42476.9 40969.0 41034.6 41286.4 41334.6 41374.0 8311.7 8429.6 11126.5 11277.8 11557.5 11167.2 11187.1 11220.3 11252.2 11252.8 9424.8 11224.7 11260.2 11264.9 11279.2 9389.9 11172.7 11172.8 11238.9 11247.2 9298.9 11029.7 11094.4 11108.1 11126.2 34458.8 34637.7 34693.5 34755.7 34788.3 3860.9 3881.7 3893.7 3920.4 3946.4 26072.6 26107.7 26400.9 27598.5 27700.6 3157.5 3230.9 3245.8 3268.9 3282.9 342353.9 346771.0 347400.0 347419.0 347607.0 45134.0 45137.1 45178.8 45310.0 45322.1 46163.0 46223.8 46450.0 46530.5 46710.1 14219.4 14482.8 14595.9 14606.9 14641.8 12484.8 12594.0 12651.0 12669.9 12681.8 11925.2 12122.0 12176.0 12212.6 12288.3 5611.1 5660.2 5663.6 5665.7 5669.6 1119.3 1177.9 1194.9 1211.0 1237.3 23831.8 27694.6 28274.3 28365.3 28558.9 1302.1 1307.7 1313.3 1315.1 1325.4 13771.5 14340.9 14358.6 14658.0 14674.5 445197.8 445629.0 447156.0 447790.0 449530.0 49194.0 59957.7 60257.0 60269.0 60297.4 1448.8 1488.0 1491.8 1498.6 1501.3 27207.0 27422.6 27988.2 28291.3 28354.9 41965.4 42091.0 42187.0 42227.8 42270.4 448822.8 448969.0 451027.0 451288.0 451500.0 29890.0 30073.0 30187.5 30215.1 30241.0 37517.4 38232.8 38559.7 38704.0 39112.2 27923.2 27986.6 28268.3 28336.4 29042.3 23794.9 25879.0 27538.7 27864.2 28494.0 31016.5 31609.8 32057.0 32151.2 32330.7 26876.6 27507.9 27778.2 28398.1 28449.6 21983.9 22982.8 23121.0 23256.0 23598.1 04:18:18 INFO - PROCESS | 1682 | 04:18:18 INFO - PROCESS | 1682 | [#2] jquery.html Cycles:180 Average:44659.12 Median:12235.15 stddev:126277.70 (1032.1%) stddev-sans-first:126588.15 04:18:18 INFO - PROCESS | 1682 | Values: 367.3 387.8 434.7 438.0 444.5 20430.7 20570.2 21920.5 21975.5 21998.2 22615.2 23540.5 23679.8 23828.4 24080.4 6149.8 6803.1 7021.4 7043.3 7063.3 6646.0 6971.9 7022.1 7023.9 7041.2 5965.4 6674.4 6958.3 7009.6 7031.5 6694.5 6994.4 7056.6 7079.8 7090.8 6765.3 6801.1 6834.6 6843.0 6863.3 27390.5 27520.0 27546.4 27601.6 27643.7 2897.7 3098.2 3102.0 3120.1 3130.4 16542.3 16847.2 16901.9 17374.6 17783.9 2151.0 2161.9 2209.9 2232.0 2270.3 176186.0 177627.1 183744.0 184174.1 184799.0 21012.6 21200.7 22011.1 22040.8 22260.4 32136.5 32554.5 32589.5 32766.0 32819.7 8956.6 9215.6 10694.3 11028.1 11214.2 8147.7 9184.4 9224.9 9254.7 9408.8 7587.4 8168.7 8929.7 9206.1 9227.9 3368.9 3401.9 3440.8 4155.8 4158.1 945.4 1032.7 1054.4 1060.4 1096.6 18936.4 19481.9 21534.5 21747.0 21748.8 988.3 1026.9 1037.3 1043.2 1069.3 10110.9 10620.2 10942.2 10961.5 10983.3 641363.0 745789.3 772873.0 772881.0 800309.0 171754.0 173480.0 175469.0 177428.0 177770.0 1285.0 1318.8 1345.9 1348.5 1351.4 13534.6 17900.2 18128.4 18397.1 18435.6 61663.7 62012.4 62599.1 63165.7 64631.4 92057.0 92182.5 93340.1 93656.5 94636.1 9398.0 9618.9 11534.0 11720.1 11900.1 12816.2 12846.9 13024.6 13250.1 13270.2 11266.7 11456.8 11533.8 11627.2 11635.7 10563.9 10636.0 10640.6 10866.8 11004.0 11567.5 11799.2 11817.4 11826.6 11939.8 11544.7 11712.2 11791.4 11820.0 11837.8 11252.6 11393.3 11481.0 11484.9 11651.4 04:18:18 INFO - PROCESS | 1682 | 04:18:18 INFO - PROCESS | 1682 | [#3] mootools.html Cycles:180 Average:6836.05 Median:2411.89 stddev:4380.14 (181.6%) stddev-sans-first:4374.59 04:18:18 INFO - PROCESS | 1682 | Values: 1575.3 1622.6 1624.7 1634.6 1635.9 11094.0 11450.0 11756.6 11782.2 11786.9 11393.3 11779.6 11810.2 11847.3 11857.5 2352.2 2416.0 2416.1 2423.3 2432.5 2302.6 2354.1 2385.3 2388.6 2398.2 2301.7 2349.3 2358.8 2405.8 2407.8 1921.0 2251.4 2273.5 2284.5 2304.3 2318.1 2329.1 2329.9 2340.8 2344.3 10453.5 10473.3 10528.9 10531.3 10549.5 5490.3 5537.7 5648.5 5692.7 5703.2 3350.4 3440.8 3560.5 3561.6 3574.1 908.8 1124.3 1179.2 1194.7 1238.0 14206.5 14531.1 14759.1 16016.8 16119.9 4162.6 4482.0 4624.1 4795.6 4806.1 13593.0 13608.3 13612.0 13618.0 13626.9 5816.9 5901.4 5940.9 5952.7 5971.3 5090.6 5177.1 5244.6 5248.6 5261.6 3959.8 4020.0 4060.9 4109.8 4113.4 1023.3 1039.9 1040.5 1050.7 1051.8 811.5 813.5 825.9 829.8 836.6 5526.2 5845.4 5980.3 6549.0 6747.6 940.2 956.9 987.1 1006.6 1016.8 4089.4 4098.5 4137.2 4223.4 4248.1 13802.9 14309.7 14315.3 14338.0 14347.4 13907.9 14140.2 14225.4 14274.3 14345.5 9045.2 10581.2 10589.5 10693.8 10714.6 5069.9 6303.5 6786.2 6870.5 6918.4 11980.9 12035.3 12174.7 12198.0 12214.8 10361.4 11864.8 12347.3 12350.4 12354.6 9419.5 9429.7 9540.1 9546.6 9589.4 10461.8 10489.3 10586.5 10604.9 10651.9 8862.6 8948.8 9054.2 9104.8 9255.8 8833.0 8903.2 8924.2 9001.0 9003.7 8029.1 8094.8 8169.6 8177.6 8222.7 5847.5 5879.7 5885.5 6036.5 6085.0 4987.0 5968.5 6114.7 6203.4 6225.9 04:18:18 INFO - PROCESS | 1682 | 04:18:18 INFO - PROCESS | 1682 | [#4] prototype.html Cycles:180 Average:4713.37 Median:5105.21 stddev:1330.63 (26.1%) stddev-sans-first:1293.06 04:18:18 INFO - PROCESS | 1682 | Values: 330.6 385.9 392.2 401.0 406.0 4637.7 5146.3 5283.4 5325.8 5539.8 5107.9 5256.9 5320.3 5354.5 5367.9 4232.2 4332.9 4423.9 4474.8 4515.4 3924.2 3963.9 4228.4 4457.0 4465.7 3635.7 3897.8 3989.4 4014.2 4447.8 3653.7 3957.7 4118.9 4353.5 4498.5 4053.2 4056.8 4066.0 4105.9 4116.0 4506.5 4664.2 4862.8 5218.7 5249.1 4214.3 5057.5 5128.8 5135.6 5153.4 5270.6 5644.9 5668.9 5694.4 5764.9 3915.3 4602.0 4602.7 4646.3 4691.6 5101.3 5461.3 5496.2 5511.8 5820.9 4058.9 4357.2 4471.6 4606.0 4608.9 3950.8 4417.9 4484.0 4781.8 4797.0 4143.5 4622.8 5002.7 5148.2 5167.4 5021.6 5580.4 5627.8 5634.7 5658.8 1434.7 1479.9 1604.2 1628.5 1631.3 1016.9 1103.5 1213.5 1216.4 1238.8 4565.3 4894.8 4921.5 5373.1 5404.0 5463.9 5519.2 5864.7 5999.2 6014.0 4963.5 5035.4 5280.7 5436.3 5469.8 2414.5 2995.4 3056.3 3069.8 3141.9 5341.2 5846.1 5874.4 6052.6 6179.9 5102.5 5312.8 5801.7 5890.2 6098.8 5841.6 5890.7 5951.9 6151.1 6173.8 4977.7 5085.5 5847.6 5857.9 6035.0 4728.5 5184.2 5318.9 5884.9 5906.9 5197.7 5217.6 6167.5 6218.9 6231.3 3915.7 4668.9 4671.8 4678.9 4700.1 4904.8 5067.9 5744.4 5899.7 5985.5 5345.5 5505.3 5697.7 5821.8 5865.6 5089.9 5275.1 5510.5 5727.3 5734.3 4763.5 4889.0 5696.5 5698.1 5737.9 5159.2 5450.2 5813.6 5829.9 5839.7 4673.7 5261.0 5314.6 5501.0 5682.2 04:18:18 INFO - PROCESS | 1682 | 04:18:18 INFO - PROCESS | 1682 | [#5] yui.html Cycles:180 Average:20927.38 Median:17311.50 stddev:30316.33 (175.1%) stddev-sans-first:30363.48 04:18:18 INFO - PROCESS | 1682 | Values: 739.9 748.9 761.7 818.9 885.6 15826.4 17173.9 17184.7 17213.9 17225.0 15874.3 15876.0 16120.3 16123.8 16147.5 958.2 979.6 1000.9 1002.6 1026.8 975.4 978.1 982.5 983.7 984.7 1045.8 1054.3 1055.7 1057.8 1059.0 1034.7 1042.1 1050.6 1052.2 1053.7 766.7 777.0 780.0 782.4 782.7 14788.0 17398.0 17499.4 17669.5 17880.6 14498.9 14555.6 14674.8 14803.5 15044.8 11841.6 12139.5 12147.6 12160.3 12288.3 3794.3 3868.3 3895.7 3933.5 4058.3 98835.0 121254.0 122918.0 126857.0 127176.0 10501.6 10696.2 11229.6 11471.8 11611.5 23500.4 23571.6 23822.4 23825.4 23961.4 8129.1 8197.5 8220.7 8239.6 8297.0 4578.6 4614.6 4684.0 4758.3 4762.5 3552.9 3657.1 3663.6 3666.7 3674.1 645.4 672.9 695.2 716.9 721.0 572.8 595.6 604.6 613.2 622.7 9367.5 10900.6 11187.3 11218.1 11220.0 600.7 626.9 632.8 649.1 649.2 5193.2 5271.5 5295.2 5302.7 5366.2 110759.4 111251.0 112702.0 112881.0 113297.4 63538.7 75723.6 77637.0 90826.5 94542.5 70144.2 70256.3 72859.0 74490.0 76186.0 9682.8 10590.8 10826.6 11013.6 11351.3 42887.0 49779.0 50286.7 50492.0 51173.0 56965.7 61261.4 64402.0 65114.0 66119.7 11431.4 12592.1 12639.0 12704.4 13347.5 14935.3 15056.4 15264.7 15911.0 15981.9 13481.3 14152.4 14289.2 14303.7 14390.4 10706.0 13061.9 14066.6 14133.9 14599.3 11426.6 13247.4 14194.2 14380.6 14469.2 10149.7 10255.9 10354.2 10396.3 10399.6 10447.7 10926.8 11304.1 11542.1 11546.7 04:18:18 INFO - PROCESS | 1682 | -------- Summary: end -------- 04:18:18 INFO - PROCESS | 1682 | 04:18:19 INFO - TEST-INFO | 1682: exit 0 04:18:19 INFO - TEST-OK | dromaeo_css | took 1115550ms 04:18:19 INFO - TEST-START | kraken 04:18:19 INFO - operating with platform_type : mac_ 04:18:19 INFO - Initialising browser for kraken test... 04:18:19 INFO - TEST-INFO | started process 1691 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpUY8rQO/profile http://localhost:49224/getInfo.html) 04:18:21 INFO - PROCESS | 1691 | 2017-05-29 04:18:21.482 plugin-container[1693:17524] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x853b, name = 'com.apple.tsm.portname' 04:18:21 INFO - PROCESS | 1691 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:18:21 INFO - PROCESS | 1691 | 2017-05-29 04:18:21.483 plugin-container[1693:17524] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x861b, name = 'com.apple.CFPasteboardClient' 04:18:21 INFO - PROCESS | 1691 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:18:21 INFO - PROCESS | 1691 | 2017-05-29 04:18:21.483 plugin-container[1693:17524] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:18:21 INFO - PROCESS | 1691 | __metrics Screen width/height:1600/1200 04:18:21 INFO - PROCESS | 1691 | colorDepth:24 04:18:21 INFO - PROCESS | 1691 | Browser inner width/height: 1024/689 04:18:21 INFO - PROCESS | 1691 | __metrics 04:18:21 ERROR - PROCESS | 1691 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 04:18:22 INFO - TEST-INFO | 1691: exit 0 04:18:22 INFO - Browser initialized. 04:18:22 INFO - Running cycle 1/1 for kraken test... 04:18:22 INFO - TEST-INFO | started process 1694 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpUY8rQO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:18:24 INFO - PROCESS | 1694 | 2017-05-29 04:18:24.879 plugin-container[1695:17649] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 04:18:24 INFO - PROCESS | 1694 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:18:24 INFO - PROCESS | 1694 | 2017-05-29 04:18:24.880 plugin-container[1695:17649] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 04:18:24 INFO - PROCESS | 1694 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:18:24 INFO - PROCESS | 1694 | 2017-05-29 04:18:24.880 plugin-container[1695:17649] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:18:34 INFO - PROCESS | 1694 | RSS: Main: 174067712 04:18:34 INFO - PROCESS | 1694 | 04:18:37 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection) 04:18:38 INFO - PROCESS | 1694 | RSS: Main: 187645952 04:18:38 INFO - PROCESS | 1694 | 04:18:41 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-dft) 04:18:41 INFO - PROCESS | 1694 | RSS: Main: 195665920 04:18:41 INFO - PROCESS | 1694 | 04:18:45 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-fft) 04:18:45 INFO - PROCESS | 1694 | RSS: Main: 197296128 04:18:45 INFO - PROCESS | 1694 | 04:18:47 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator) 04:18:48 INFO - PROCESS | 1694 | RSS: Main: 200155136 04:18:48 INFO - PROCESS | 1694 | 04:18:50 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:18:51 INFO - PROCESS | 1694 | RSS: Main: 200466432 04:18:51 INFO - PROCESS | 1694 | 04:18:53 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom) 04:18:54 INFO - PROCESS | 1694 | RSS: Main: 204468224 04:18:54 INFO - PROCESS | 1694 | 04:18:57 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate) 04:18:58 INFO - PROCESS | 1694 | RSS: Main: 204247040 04:18:58 INFO - PROCESS | 1694 | 04:19:01 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial) 04:19:02 INFO - PROCESS | 1694 | RSS: Main: 204427264 04:19:02 INFO - PROCESS | 1694 | 04:19:03 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:19:04 INFO - PROCESS | 1694 | RSS: Main: 204136448 04:19:04 INFO - PROCESS | 1694 | 04:19:06 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:19:07 INFO - PROCESS | 1694 | RSS: Main: 204152832 04:19:07 INFO - PROCESS | 1694 | 04:19:09 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:19:09 INFO - PROCESS | 1694 | RSS: Main: 204713984 04:19:09 INFO - PROCESS | 1694 | 04:19:12 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:19:13 INFO - PROCESS | 1694 | RSS: Main: 204500992 04:19:13 INFO - PROCESS | 1694 | 04:19:16 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:19:17 INFO - PROCESS | 1694 | RSS: Main: 204222464 04:19:17 INFO - PROCESS | 1694 | 04:19:18 INFO - PROCESS | 1694 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49224/tests/kraken/driver.html?testName=ai-astar) 04:19:19 INFO - PROCESS | 1694 | RSS: Main: 206340096 04:19:19 INFO - PROCESS | 1694 | 04:19:19 INFO - PROCESS | 1694 | __start_tp_report 04:19:19 INFO - PROCESS | 1694 | _x_x_mozilla_page_load 04:19:19 INFO - PROCESS | 1694 | _x_x_mozilla_page_load_details 04:19:19 INFO - PROCESS | 1694 | |i|pagename|runs| 04:19:19 INFO - PROCESS | 1694 | |0;ai-astar;107;114;115;118;121;120;116;117;119;118 04:19:19 INFO - PROCESS | 1694 | |1;audio-beat-detection;140;140;111;158;137;143;111;111;160;139 04:19:19 INFO - PROCESS | 1694 | |2;audio-dft;198;194;195;244;290;211;195;283;280;198 04:19:19 INFO - PROCESS | 1694 | |3;audio-fft;76;57;59;87;61;60;61;63;71;60 04:19:19 INFO - PROCESS | 1694 | |4;audio-oscillator;86;70;83;72;71;69;78;98;73;82 04:19:19 INFO - PROCESS | 1694 | |5;imaging-gaussian-blur;89;90;93;91;92;97;90;93;89;90 04:19:19 INFO - PROCESS | 1694 | |6;imaging-darkroom;129;164;99;138;95;121;156;156;97;100 04:19:19 INFO - PROCESS | 1694 | |7;imaging-desaturate;90;102;93;91;91;91;111;84;144;93 04:19:19 INFO - PROCESS | 1694 | |8;json-parse-financial;65;65;65;67;68;68;67;69;68;69 04:19:19 INFO - PROCESS | 1694 | |9;json-stringify-tinderbox;59;65;56;63;63;70;85;51;85;87 04:19:19 INFO - PROCESS | 1694 | |10;stanford-crypto-aes;95;100;85;85;86;85;83;88;91;84 04:19:19 INFO - PROCESS | 1694 | |11;stanford-crypto-ccm;138;140;136;139;139;146;155;152;208;151 04:19:19 INFO - PROCESS | 1694 | |12;stanford-crypto-pbkdf2;166;171;221;159;226;154;228;216;166;171 04:19:19 INFO - PROCESS | 1694 | |13;stanford-crypto-sha256-iterative;68;63;64;66;65;64;66;65;66;63 04:19:19 INFO - PROCESS | 1694 | __end_tp_report 04:19:19 INFO - PROCESS | 1694 | __start_cc_report 04:19:19 INFO - PROCESS | 1694 | _x_x_mozilla_cycle_collect,1004 04:19:19 INFO - PROCESS | 1694 | __end_cc_report 04:19:19 INFO - PROCESS | 1694 | __startTimestamp1496056759182__endTimestamp 04:19:19 INFO - PROCESS | 1694 | 04:19:19 INFO - PROCESS | 1694 | ------- Summary: start ------- 04:19:19 INFO - PROCESS | 1694 | Number of tests: 14 04:19:19 INFO - PROCESS | 1694 | 04:19:19 INFO - PROCESS | 1694 | [#0] ai-astar Cycles:10 Average:116.50 Median:118.00 stddev:3.98 (3.4%) stddev-sans-first:2.30 04:19:19 INFO - PROCESS | 1694 | Values: 107.0 114.0 115.0 118.0 121.0 120.0 116.0 117.0 119.0 118.0 04:19:19 INFO - PROCESS | 1694 | 04:19:19 INFO - PROCESS | 1694 | [#1] audio-beat-detection Cycles:10 Average:135.00 Median:140.00 stddev:18.31 (13.1%) stddev-sans-first:19.33 04:19:19 INFO - PROCESS | 1694 | Values: 140.0 140.0 111.0 158.0 137.0 143.0 111.0 111.0 160.0 139.0 04:19:19 INFO - PROCESS | 1694 | 04:19:19 INFO - PROCESS | 1694 | [#2] audio-dft Cycles:10 Average:228.80 Median:227.50 stddev:41.16 (18.1%) stddev-sans-first:42.12 04:19:19 INFO - PROCESS | 1694 | Values: 198.0 194.0 195.0 244.0 290.0 211.0 195.0 283.0 280.0 198.0 04:19:19 INFO - PROCESS | 1694 | 04:19:19 INFO - PROCESS | 1694 | [#3] audio-fft Cycles:10 Average:65.50 Median:62.00 stddev:9.57 (15.4%) stddev-sans-first:9.37 04:19:19 INFO - PROCESS | 1694 | Values: 76.0 57.0 59.0 87.0 61.0 60.0 61.0 63.0 71.0 60.0 04:19:19 INFO - PROCESS | 1694 | 04:19:19 INFO - PROCESS | 1694 | [#4] audio-oscillator Cycles:10 Average:78.20 Median:80.00 stddev:9.19 (11.5%) stddev-sans-first:9.30 04:19:19 INFO - PROCESS | 1694 | Values: 86.0 70.0 83.0 72.0 71.0 69.0 78.0 98.0 73.0 82.0 04:19:19 INFO - PROCESS | 1694 | 04:19:19 INFO - PROCESS | 1694 | [#5] imaging-gaussian-blur Cycles:10 Average:91.40 Median:91.50 stddev:2.46 (2.7%) stddev-sans-first:2.45 04:19:19 INFO - PROCESS | 1694 | Values: 89.0 90.0 93.0 91.0 92.0 97.0 90.0 93.0 89.0 90.0 04:19:19 INFO - PROCESS | 1694 | 04:19:19 INFO - PROCESS | 1694 | [#6] imaging-darkroom Cycles:10 Average:125.50 Median:160.00 stddev:27.13 (17.0%) stddev-sans-first:28.75 04:19:19 INFO - PROCESS | 1694 | Values: 129.0 164.0 99.0 138.0 95.0 121.0 156.0 156.0 97.0 100.0 04:19:19 INFO - PROCESS | 1694 | 04:19:19 INFO - PROCESS | 1694 | [#7] imaging-desaturate Cycles:10 Average:99.00 Median:91.00 stddev:17.47 (19.2%) stddev-sans-first:18.23 04:19:19 INFO - PROCESS | 1694 | Values: 90.0 102.0 93.0 91.0 91.0 91.0 111.0 84.0 144.0 93.0 04:19:19 INFO - PROCESS | 1694 | 04:19:19 INFO - PROCESS | 1694 | [#8] json-parse-financial Cycles:10 Average:67.10 Median:68.00 stddev:1.60 (2.3%) stddev-sans-first:1.50 04:19:19 INFO - PROCESS | 1694 | Values: 65.0 65.0 65.0 67.0 68.0 68.0 67.0 69.0 68.0 69.0 04:19:19 INFO - PROCESS | 1694 | 04:19:19 INFO - PROCESS | 1694 | [#9] json-stringify-tinderbox Cycles:10 Average:68.40 Median:67.50 stddev:12.97 (19.2%) stddev-sans-first:13.31 04:19:19 INFO - PROCESS | 1694 | Values: 59.0 65.0 56.0 63.0 63.0 70.0 85.0 51.0 85.0 87.0 04:19:19 INFO - PROCESS | 1694 | 04:19:19 INFO - PROCESS | 1694 | [#10] stanford-crypto-aes Cycles:10 Average:88.20 Median:85.50 stddev:5.51 (6.4%) stddev-sans-first:5.27 04:19:19 INFO - PROCESS | 1694 | Values: 95.0 100.0 85.0 85.0 86.0 85.0 83.0 88.0 91.0 84.0 04:19:19 INFO - PROCESS | 1694 | 04:19:19 INFO - PROCESS | 1694 | [#11] stanford-crypto-ccm Cycles:10 Average:150.40 Median:148.50 stddev:21.32 (14.4%) stddev-sans-first:22.13 04:19:19 INFO - PROCESS | 1694 | Values: 138.0 140.0 136.0 139.0 139.0 146.0 155.0 152.0 208.0 151.0 04:19:19 INFO - PROCESS | 1694 | 04:19:19 INFO - PROCESS | 1694 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.80 Median:193.50 stddev:30.66 (15.8%) stddev-sans-first:31.49 04:19:19 INFO - PROCESS | 1694 | Values: 166.0 171.0 221.0 159.0 226.0 154.0 228.0 216.0 166.0 171.0 04:19:19 INFO - PROCESS | 1694 | 04:19:19 INFO - PROCESS | 1694 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.00 Median:65.50 stddev:1.56 (2.4%) stddev-sans-first:1.22 04:19:19 INFO - PROCESS | 1694 | Values: 68.0 63.0 64.0 66.0 65.0 64.0 66.0 65.0 66.0 63.0 04:19:19 INFO - PROCESS | 1694 | -------- Summary: end -------- 04:19:19 INFO - PROCESS | 1694 | 04:19:19 INFO - TEST-INFO | 1694: exit 0 04:19:20 INFO - TEST-OK | kraken | took 60926ms 04:19:20 INFO - SUITE-END | took 1176s 04:19:20 INFO - Completed test suite (00:19:36) 04:19:20 INFO - javascript benchmark 04:19:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7842.319279644941, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3179.7933134346267, 3226.241833169205, 3631.7276204284667, 3651.580068292147, 3689.0224090615993, 6236.282827474842, 6238.656873871935, 6258.468030217431, 6273.7490500380045, 6274.498040156782, 6197.1324014637985, 6239.188905442297, 6267.059941008851, 6278.874422511547, 6295.43341099301, 5552.888942221153, 5643.887122257553, 5660.886782264372, 5665.830025099253, 5723.914141287884, 5693.146028095777, 5702.515286200678, 5714.285714285715, 5716.8856622867725, 5725.169850371693, 5478.863028424282, 5499.202615620733, 5600.103983362663, 5683.090705487123, 5716.085426331788, 4629.768511574424, 5621.8594535136535, 5710.800121995751, 5729.140628905656, 5783.363829978699, 5472.398036216013, 5628.43715628438, 5633.8591535211535, 5634.605577609569, 5700.401457846908, 6285.151504546919, 6285.182926219608, 6307.369263073701, 6318.43134117927, 6336.936630633681, 6251.87496250077, 6297.811065668037, 6359.682015899187, 6379.872402551923, 6398.680065996728, 6617.7022034008105, 6645.169353830771, 6653.3346665333565, 6663.86672266552, 6686.899696504556, 5179.741012949337, 6244.438000579969, 6318.399752023551, 6354.205724284465, 6396.5202609804455, 5614.466625670555, 6413.839654008594, 6471.964485682268, 6476.773312934025, 6567.802965911031, 5283.445238250005, 6313.2424109107205, 6326.46225070865, 6399.840003999937, 6402.583832050902, 5458.235846981426, 6592.340765923369, 6624.039514270404, 6635.0, 6640.601563906181, 6200.162977998003, 6443.323451037667, 6468.256150542654, 6598.802035938929, 6603.7358505660195, 6345.111684364193, 6348.77779277723, 6500.122483464672, 6520.282768895418, 6547.279799222082, 5318.813841515605, 5334.359876814807, 5355.357357117171, 5356.330458692663, 5363.0614642438195, 4677.391939047902, 4714.952850471452, 4745.762711864462, 4752.453467851172, 4768.523147685273, 6140.995089002448, 6152.630842149485, 6158.414950579688, 6158.599691020159, 6312.810615681537, 6640.800775976729, 6709.0, 6721.630310332881, 6723.260441351447, 6744.629045402452, 6138.817583427401, 6160.322364539897, 6174.641578852646, 6241.625502469867, 6255.40573645503, 5851.268591426072, 5948.405159484104, 5953.553483488756, 5963.105534169909, 6000.33996260411, 5422.5119739223655, 6474.4496717778575, 6547.247066587213, 6743.0, 6755.3582409671, 6520.706568204348, 6530.869382612416, 6547.247066587403, 6571.704273307808, 6653.434458071119, 6043.697815109315, 6635.303293154342, 6774.42417394527, 6804.897926531007, 6823.078884350748, 6283.654399008098, 6330.208723909512, 6405.679716014088, 6422.100905873088, 6476.060971159061, 6499.967500162469, 6590.5716128451495, 6602.900956485752, 6611.669416529058, 6632.900506492503, 6341.778037768656, 6428.389303016206, 6436.485081193399, 6446.742130314735, 6454.096426500388, 5774.451427114585, 6211.81364559064, 6251.9687401562705, 6415.743370265137, 6417.935820641734, 6261.24865016201, 6300.810975670737, 6302.275238347649, 6355.745770169141, 6374.681265936591, 6422.582532135396, 6424.871502570016, 6436.420722135031, 6442.452391546771, 6462.806115816533, 6152.630842149485, 6221.595596286227, 6265.592736472115, 6333.429991300805, 6334.619922804646], "value": 6035.94061208546, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [784.9803754906127, 802.5987006496752, 824.4311425116668, 978.9902100978989, 983.6901376066543, 39390.60609393905, 40288.19423611526, 42408.57591424092, 42450.57549424505, 42476.938076548104, 40969.0, 41034.58965410345, 41286.38070428938, 41334.58665413345, 41374.0, 8311.667533298676, 8429.620667069996, 11126.49930753114, 11277.774444511106, 11557.53769849205, 11167.218294719372, 11187.105031597494, 11220.326780393161, 11252.15608829337, 11252.774944501105, 9424.81150376992, 11224.719382015433, 11260.155488338367, 11264.887351126466, 11279.210455268134, 9389.859152112724, 11172.664820055412, 11172.832407513853, 11238.887611123866, 11247.156463265246, 9298.86051709225, 11029.669109926635, 11094.389808560525, 11108.055815255713, 11126.165537584633, 34458.827705861564, 34637.653623463695, 34693.47959780605, 34755.6524434755, 34788.304233915434, 3860.942085868714, 3881.6506514413554, 3893.669038131762, 3920.3923391874305, 3946.427767973628, 26072.60891086635, 26107.73892261091, 26400.86799566009, 27598.516133548077, 27700.584491232647, 3157.494800831879, 3230.9353812923846, 3245.837708114585, 3268.869245230188, 3282.868685252587, 342353.86469202983, 346771.0, 347400.0, 347418.9999999975, 347607.0, 45134.0, 45137.09725805466, 45178.77410612926, 45310.0, 45322.093558128654, 46163.0, 46223.76888115538, 46450.0, 46530.534694653295, 46710.065798683834, 14219.36012879423, 14482.782758258423, 14595.854041459663, 14606.926965365317, 14641.828653707678, 12484.75030499385, 12594.0, 12651.0, 12669.873301267055, 12681.809772853414, 11925.22486038422, 12122.030237580972, 12176.025917926545, 12212.572559960536, 12288.262704237774, 5611.102223644198, 5660.235868157828, 5663.584103974006, 5665.745041473061, 5669.603127781098, 1119.2948442481186, 1177.8822117788152, 1194.904407647386, 1210.9878901211052, 1237.2823762217893, 23831.761682382956, 27694.5845812313, 28274.2931426716, 28365.290867728057, 28558.857205713837, 1302.1145620977827, 1307.7123032932739, 1313.258009224804, 1315.0728736240926, 1325.3969443903002, 13771.517996870061, 14340.928295358457, 14358.641033974025, 14658.0, 14674.486392976194, 445197.77401113434, 445629.0, 447156.0, 447790.0, 449530.0, 49194.0, 59957.70021149866, 60257.0, 60269.0, 60297.397026030056, 1448.7826825975976, 1487.9732984240839, 1491.843356447579, 1498.572906721601, 1501.3018946190075, 27207.047753328538, 27422.5886611697, 27988.16035518938, 28291.2927176814, 28354.85822570874, 41965.37051944284, 42091.0, 42187.0, 42227.788861055495, 42270.36594451024, 448822.75588621845, 448969.0, 451027.0, 451288.0, 451500.0, 29890.0, 30073.045252058546, 30187.547186791777, 30215.09354719362, 30241.0, 37517.4372384409, 38232.80883595564, 38559.65041223656, 38704.03239919025, 39112.21775564416, 27923.162305130878, 27986.580201297405, 28268.293292667848, 28336.433271334045, 29042.27394315159, 23794.88102559476, 25878.964841406887, 27538.724612754417, 27864.16407507778, 28494.0, 31016.480499905818, 31609.841950790098, 32057.0, 32151.196220094684, 32330.676693232766, 26876.59685104686, 27507.862460688368, 27778.166655000383, 28398.148055558366, 28449.573256401585, 21983.890080549496, 22982.770172298064, 23121.0, 23256.0, 23598.056077756697], "value": 18609.60796773053, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.34429044156184, 387.84486205517806, 434.7369841246047, 438.0013569062539, 444.4599811229356, 20430.672006319615, 20570.177192912284, 21920.45198870029, 21975.450613734665, 21998.230061947834, 22615.208467703633, 23540.529189416244, 23679.763202367973, 23828.404289892762, 24080.397990050256, 6149.800788846177, 6803.149606299213, 7021.36807687308, 7043.295670432942, 7063.258357872426, 6645.966770166143, 6971.930280697205, 7022.052022976884, 7023.859522809541, 7041.15506139264, 5965.433283838029, 6674.365935236145, 6958.304169583026, 7009.649517524103, 7031.542949708278, 6694.531382803206, 6994.4404447644065, 7056.647167641649, 7079.787606371791, 7090.822729431754, 6765.293882447011, 6801.115854938876, 6834.555753875982, 6843.0, 6863.313668633146, 27390.452190956068, 27520.0, 27546.44907101867, 27601.585976210372, 27643.723562764317, 2897.7392034716977, 3098.1789825695982, 3102.0, 3120.0795765249304, 3130.4365214261425, 16542.338306467725, 16847.241874115694, 16901.91549042247, 17374.56563585908, 17783.911080444643, 2151.0201639442375, 2161.9243326483497, 2209.856359336654, 2232.0, 2270.2735124760084, 176186.0, 177627.11186444113, 183744.0, 184174.07912960483, 184799.0, 21012.579748404947, 21200.681989770164, 22011.11955521793, 22040.77959220387, 22260.443488912584, 32136.517952230733, 32554.511682324784, 32589.511157332658, 32766.017019489453, 32819.671803281664, 8956.596953137125, 9215.63137474507, 10694.304870183569, 11028.117750579935, 11214.215004949738, 8147.696368581117, 9184.448933064037, 9224.907750922404, 9254.676086336945, 9408.764780880396, 7587.355074818703, 8168.714095006765, 8929.687460938965, 9206.125418085272, 9227.90772092284, 3368.898933032013, 3401.863925443004, 3440.8451619677176, 4155.812988415529, 4158.126793373425, 945.4374647084974, 1032.746976990642, 1054.4464156317995, 1060.3637817309552, 1096.6381094238882, 18936.431907042534, 19481.902590486956, 21534.461638459165, 21747.021384037755, 21748.782512174992, 988.3378136648319, 1026.9486525673688, 1037.2998226197437, 1043.2019505078622, 1069.2568664777905, 10110.949445252874, 10620.24765913631, 10942.234043617029, 10961.451927403758, 10983.286086404358, 641363.0, 745789.2710536412, 772873.0, 772881.0, 800309.0, 171754.0, 173480.0, 175469.0, 177428.0, 177770.0, 1284.9807252891214, 1318.7824009038402, 1345.892328613689, 1348.4875747216192, 1351.3851197705217, 13534.59396218115, 17900.194491248185, 18128.365507207185, 18397.08014599238, 18435.631287374446, 61663.691681541306, 62012.37987620066, 62599.06101408391, 63165.68417157885, 64631.353686462535, 92057.0, 92182.53908730681, 93340.13319733703, 93656.53171734097, 94636.10727785269, 9398.0, 9618.903810961801, 11534.019608333385, 11720.120990925885, 11900.107491937966, 12816.231026138461, 12846.935765321114, 13024.609261722164, 13250.138740982191, 13270.203787772765, 11266.718332041764, 11456.770864582828, 11533.82699259495, 11627.244229125079, 11635.709107272385, 10563.894361056291, 10635.989580989793, 10640.6015337713, 10866.782664346827, 11004.0, 11567.479463423997, 11799.17405781587, 11817.409129543317, 11826.586069487526, 11939.76120477603, 11544.653660390202, 11712.180147389601, 11791.35147566892, 11820.0, 11837.82243266369, 11252.606158784403, 11393.316401015965, 11481.0, 11484.94257528707, 11651.41742912868], "value": 11845.442942703743, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1575.3304845440684, 1622.5619082847631, 1624.6994306053382, 1634.6158652716613, 1635.8773092018102, 11093.999999999989, 11450.026747726453, 11756.588519401812, 11782.175247732663, 11786.94106529466, 11393.316401015923, 11779.587714429998, 11810.232334898226, 11847.289162650248, 11857.46641401137, 2352.200251914349, 2416.0, 2416.0577374823833, 2423.333583264599, 2432.4891772727747, 2302.6085565453827, 2354.0701422937927, 2385.272491889973, 2388.5819981503255, 2398.1726304424947, 2301.7007788987407, 2349.3069544484333, 2358.8230882683783, 2405.795507381875, 2407.7712617301418, 1920.9615807683838, 2251.4371407148215, 2273.5111950930545, 2284.4745708486976, 2304.308707387777, 2318.084356679119, 2329.0683726509365, 2329.871857047862, 2340.7659234076627, 2344.3318654183477, 10453.529591168415, 10473.31923424975, 10528.947355263175, 10531.26281160319, 10549.470326802648, 5490.3411590608985, 5537.723113844291, 5648.4633959773755, 5692.7153642318135, 5703.230063941356, 3350.430426827445, 3440.776349537272, 3560.5193298904574, 3561.626029266916, 3574.0528759878653, 908.8227795579865, 1124.2804605052772, 1179.1804695736466, 1194.6953526850684, 1237.993810030953, 14206.502772403019, 14531.055481393782, 14759.114453132739, 16016.75974860378, 16119.919400402923, 4162.62536371728, 4481.955180448219, 4624.12141693084, 4795.592374648124, 4806.086843499794, 13593.048486605843, 13608.251546165056, 13612.0, 13618.0, 13626.863731362757, 5816.854578635568, 5901.439363260483, 5940.851478712981, 5952.672603006789, 5971.313298970587, 5090.643654944119, 5177.119889618773, 5244.580433565306, 5248.606354523426, 5261.605379596545, 3959.821808018646, 4019.959800402017, 4060.8578699745367, 4109.753414795122, 4113.424120623115, 1023.2939271902363, 1039.9184847758247, 1040.5265604150093, 1050.6532844161409, 1051.8001579699833, 811.5495899775564, 813.4875028731861, 825.9215374539409, 829.7801082713086, 836.6444261189092, 5526.246438569348, 5845.357010728816, 5980.342162362137, 6548.967255163694, 6747.561408508432, 940.1632547033146, 956.8947415784259, 987.111599560387, 1006.6023920551488, 1016.8068067067234, 4089.407035979766, 4098.549159592442, 4137.234611596864, 4223.408722778813, 4248.065425606362, 13802.861971380158, 14309.713805723828, 14315.28423578817, 14338.0, 14347.426102955973, 13907.930460347836, 14140.222287774066, 14225.430982760574, 14274.286285685674, 14345.497907573184, 9045.231155351737, 10581.206409519316, 10589.470526473646, 10693.83959240612, 10714.571417143226, 5069.949300506948, 6303.527235457451, 6786.151731033621, 6870.45036397077, 6918.377346038881, 11980.880191197977, 12035.338056406632, 12174.695632609255, 12198.02415806751, 12214.75570488603, 10361.430121343097, 11864.754200808791, 12347.320897350732, 12350.3824808761, 12354.567590134302, 9419.481928493999, 9429.669961551313, 9540.141387275178, 9546.570404331684, 9589.376690515093, 10461.790764184825, 10489.265751397328, 10586.523606437575, 10604.946975265075, 10651.89348106509, 8862.601182946653, 8948.776280592776, 9054.230390416888, 9104.772380690536, 9255.814883702153, 8833.02836687951, 8903.243224326005, 8924.241439477717, 9001.0, 9003.729888103368, 8029.116797152192, 8094.838103237785, 8169.632366543638, 8177.632006559838, 8222.712205072794, 5847.473727364558, 5879.7060146993335, 5885.529157667462, 6036.517078633787, 6085.0, 4986.950130498793, 5968.4926781224085, 6114.694265286629, 6203.4416902479, 6225.906611400923], "value": 5141.673252326318, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.6363000699231, 385.90738222826513, 392.1686025625674, 401.0435112257267, 406.00731212186196, 4637.675362724611, 5146.279520867077, 5283.418823929364, 5325.801694618709, 5539.806106786264, 5107.923381149286, 5256.947430525694, 5320.255164277004, 5354.518093371596, 5367.865803354918, 4232.243618898154, 4332.935005974913, 4423.867283981485, 4474.798634061475, 4515.367848501212, 3924.156306394125, 3963.9009024774323, 4228.429162063128, 4456.955430445686, 4465.665075119363, 3635.745497815154, 3897.7856217908, 3989.421533877586, 4014.1570270243283, 4447.777611119447, 3653.7442379033478, 3957.6543975048335, 4118.876433706979, 4353.542877997828, 4498.52765459627, 4053.209624123297, 4056.837726490937, 4066.0, 4105.938410923838, 4115.97942010288, 4506.504284528732, 4664.160451118797, 4862.836037963108, 5218.686878787247, 5249.136556522418, 4214.262504061808, 5057.4689657585795, 5128.769205385729, 5135.589152867761, 5153.3558305211845, 5270.628217302191, 5644.899244647296, 5668.930840243323, 5694.373618901917, 5764.884702305931, 3915.314819906505, 4602.0, 4602.746848923308, 4646.256598944153, 4691.554302341272, 5101.336826212606, 5461.317335333083, 5496.203050557647, 5511.779528818843, 5820.923129221108, 4058.8782336529953, 4357.150355680612, 4471.6401508528925, 4605.953940460619, 4608.861734147981, 3950.802459876995, 4417.911641767147, 4483.968687201962, 4781.784819683123, 4797.0, 4143.461350024478, 4622.774964634307, 5002.74986250686, 5148.227765835155, 5167.379914410295, 5021.593953692899, 5580.414056524007, 5627.803026894039, 5634.67585117497, 5658.773649053992, 1434.684369438722, 1479.9112053276838, 1604.1898841085176, 1628.4951664983894, 1631.274083033039, 1016.893226211252, 1103.4882211370473, 1213.5145941623246, 1216.403962058585, 1238.7584421037811, 4565.269556870885, 4894.821804297125, 4921.458639549646, 5373.086575282211, 5404.02752995154, 5463.863403414946, 5519.199716041152, 5864.70676466175, 5999.160117583542, 6013.969930150409, 4963.45402005779, 5035.446100928895, 5280.67983004249, 5436.347638283352, 5469.808556700496, 2414.539203781722, 2995.3974623576282, 3056.264643144373, 3069.815811051344, 3141.89003384885, 5341.169180334429, 5846.118491060012, 5874.353821079678, 6052.550046473468, 6179.876402471926, 5102.489751024942, 5312.831177141023, 5801.679328268726, 5890.204822348928, 6098.761360854164, 5841.649501029951, 5890.67601281925, 5951.9107213391835, 6151.138840562415, 6173.753049878044, 4977.726225057657, 5085.46602606721, 5847.649141051551, 5857.912131318119, 6035.0, 4728.479867214535, 5184.185535062697, 5318.893861892627, 5884.880583591417, 5906.911396328973, 5197.688138711689, 5217.582593392595, 6167.475764560063, 6218.9378106218355, 6231.345708700703, 3915.691240901434, 4668.906621867612, 4671.813127474863, 4678.906421871611, 4700.106979673851, 4904.754762261801, 5067.949320506747, 5744.368119506935, 5899.734511946886, 5985.4912332450485, 5345.4654534546235, 5505.284313039279, 5697.658140511582, 5821.76712931478, 5865.618734782225, 5089.8741761532665, 5275.076861549291, 5510.476504732044, 5727.255456790591, 5734.283214598176, 4763.476017638126, 4888.9755551222015, 5696.544276457957, 5698.091139468324, 5737.9384813809575, 5159.194282001269, 5450.236966824568, 5813.6337960578385, 5829.854253643693, 5839.678817665069, 4673.719576825401, 5260.9736951315, 5314.581865251224, 5501.0098182327565, 5682.204491371294], "value": 4324.697626091787, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [739.945577551989, 748.8801791713327, 761.7143571160815, 818.9467684600504, 885.6280362247858, 15826.366945322174, 17173.91413042933, 17184.656306873854, 17213.913930430346, 17225.0, 15874.285657145427, 15876.0, 16120.274587643555, 16123.758143627825, 16147.515574532783, 958.2238386906628, 979.6326377608397, 1000.9049140331693, 1002.5889385352007, 1026.7535791410082, 975.4488713876673, 978.0708327089258, 982.5234761140841, 983.6753871222479, 984.7045886234137, 1045.8117538843005, 1054.3357684658693, 1055.7360659835042, 1057.799018186546, 1059.0204061243358, 1034.7154532503546, 1042.0725554256717, 1050.56926660069, 1052.2108418686023, 1053.7260312318815, 766.7469734987441, 777.0481160578303, 779.9883001754978, 782.3741007194221, 782.7319742017911, 14787.964842461031, 17398.0, 17499.387521436816, 17669.469915902417, 17880.55298617532, 14498.927505362512, 14555.636109097357, 14674.779878301833, 14803.481878134322, 15044.849551504454, 11841.644750657493, 12139.514419423213, 12147.574834880737, 12160.331181784999, 12288.324142172178, 3794.298054859832, 3868.323043467382, 3895.7272990890647, 3933.468981687493, 4058.269511487931, 98835.0, 121253.99999999911, 122918.0, 126857.0, 127176.0, 10501.579936802442, 10696.197785166143, 11229.55081796719, 11471.770564588662, 11611.477483513263, 23500.412489687893, 23571.64642530329, 23822.404439889142, 23825.404364891016, 23961.400964975666, 8129.065157506962, 8197.549134797524, 8220.712275070464, 8239.588020598947, 8297.0, 4578.613603746271, 4614.607758340579, 4683.953160468352, 4758.3100450434495, 4762.547557981986, 3552.946705799415, 3657.085728567858, 3663.6153203913204, 3666.7433279670167, 3674.1365779041903, 645.3997782062727, 672.8923372260513, 695.214407719284, 716.913970323554, 720.9567425954459, 572.83101485062, 595.6098755315276, 604.619089973314, 613.1936503497886, 622.7228883146956, 9367.484788336707, 10900.563977440972, 11187.272827266203, 11218.08329834249, 11220.0, 600.6546235914367, 626.8840264551072, 632.7753647455179, 649.1398896462207, 649.2047242128355, 5193.169092945186, 5271.4728527147745, 5295.179247216687, 5302.655327403706, 5366.221897824794, 110759.44620276846, 111251.0, 112702.0, 112881.0, 113297.43351283355, 63538.68230658817, 75723.62138189384, 77637.0, 90826.54586727024, 94542.52728736312, 70144.24639384056, 70256.29743702497, 72859.0, 74490.0, 76186.0, 9682.838059432914, 10590.788184236118, 10826.592542969363, 11013.559457621926, 11351.262167959387, 42887.0, 49779.0, 50286.74856625694, 50491.990160197325, 51173.0, 56965.71517142388, 61261.38738612557, 64402.0, 65114.0, 66119.66940165269, 11431.371274579978, 12592.118551701293, 12639.0, 12704.36478176069, 13347.466101356225, 14935.327910243848, 15056.397744090114, 15264.694706105594, 15910.965787223793, 15981.920090399473, 13481.2585307809, 14152.43390264378, 14289.214093224557, 14303.713925721218, 14390.424383024561, 10705.982931621484, 13061.934690326487, 14066.648333791738, 14133.858661413666, 14599.343029563483, 11426.600068997544, 13247.403866825824, 14194.219317937612, 14380.640483987983, 14469.204193769443, 10149.6609477532, 10255.897441025494, 10354.223433242687, 10396.272260941661, 10399.584016639552, 10447.746270447595, 10926.836097458385, 11304.095672346357, 11542.13433992437, 11546.711332216762], "value": 7862.010298830822, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1566.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 114.0, 115.0, 118.0, 121.0, 120.0, 116.0, 117.0, 119.0, 118.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 140.0, 111.0, 158.0, 137.0, 143.0, 111.0, 111.0, 160.0, 139.0], "value": 135.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 194.0, 195.0, 244.0, 290.0, 211.0, 195.0, 283.0, 280.0, 198.0], "value": 228.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 57.0, 59.0, 87.0, 61.0, 60.0, 61.0, 63.0, 71.0, 60.0], "value": 65.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 83.0, 72.0, 71.0, 69.0, 78.0, 98.0, 73.0, 82.0], "value": 78.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 93.0, 91.0, 92.0, 97.0, 90.0, 93.0, 89.0, 90.0], "value": 91.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 164.0, 99.0, 138.0, 95.0, 121.0, 156.0, 156.0, 97.0, 100.0], "value": 125.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 102.0, 93.0, 91.0, 91.0, 91.0, 111.0, 84.0, 144.0, 93.0], "value": 99.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 65.0, 67.0, 68.0, 68.0, 67.0, 69.0, 68.0, 69.0], "value": 67.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 65.0, 56.0, 63.0, 63.0, 70.0, 85.0, 51.0, 85.0, 87.0], "value": 68.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 100.0, 85.0, 85.0, 86.0, 85.0, 83.0, 88.0, 91.0, 84.0], "value": 88.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 140.0, 136.0, 139.0, 139.0, 146.0, 155.0, 152.0, 208.0, 151.0], "value": 150.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 171.0, 221.0, 159.0, 226.0, 154.0, 228.0, 216.0, 166.0, 171.0], "value": 187.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 63.0, 64.0, 66.0, 65.0, 64.0, 66.0, 65.0, 66.0, 63.0], "value": 65.0, "unit": "score"}]}]} 04:19:20 INFO - Return code: 0 04:19:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:19:20 ERROR - # TBPL SUCCESS # 04:19:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:19:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:19:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:19:20 INFO - 2017-05-29 04:19:20.504 screenresolution[1699:18036] starting screenresolution argv=screenresolution get 04:19:20 INFO - 2017-05-29 04:19:20.507 screenresolution[1699:18036] Display 0: 1600x1200x32@60 04:19:20 INFO - 2017-05-29 04:19:20.513 screenresolution[1700:18039] starting screenresolution argv=screenresolution list 04:19:20 INFO - Available Modes on Display 0 04:19:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:19:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:19:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:19:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:19:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:19:20 INFO - Intel Iris: 04:19:20 INFO - Chipset Model: Intel Iris 04:19:20 INFO - Type: GPU 04:19:20 INFO - Bus: Built-In 04:19:20 INFO - VRAM (Dynamic, Max): 1536 MB 04:19:20 INFO - Vendor: Intel (0x8086) 04:19:20 INFO - Device ID: 0x0a2e 04:19:20 INFO - Revision ID: 0x0009 04:19:20 INFO - Displays: 04:19:20 INFO - Display: 04:19:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:19:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:19:20 INFO - Display Serial Number: 1600x1200 60 04:19:20 INFO - Main Display: Yes 04:19:20 INFO - Mirror: Off 04:19:20 INFO - Online: Yes 04:19:20 INFO - Rotation: Supported 04:19:20 INFO - Return code: 0 04:19:20 INFO - Running post-action listener: _resource_record_post_action 04:19:20 INFO - [mozharness: 2017-05-29 11:19:20.748393Z] Finished run-tests step (success) 04:19:20 INFO - Running post-run listener: _resource_record_post_run 04:19:20 INFO - Total resource usage - Wall time: 1192s; CPU: 54.0%; Read bytes: 35852288; Write bytes: 429116416; Read time: 379; Write time: 1387 04:19:20 INFO - TinderboxPrint: CPU usage
54.5% 04:19:20 INFO - TinderboxPrint: I/O read bytes / time
35,852,288 / 379 04:19:20 INFO - TinderboxPrint: I/O write bytes / time
429,116,416 / 1,387 04:19:20 INFO - TinderboxPrint: CPU idle
2,172.1 (45.5%) 04:19:20 INFO - TinderboxPrint: CPU system
957.6 (20.1%) 04:19:20 INFO - TinderboxPrint: CPU user
1,640.0 (34.4%) 04:19:20 INFO - TinderboxPrint: Swap in / out
154,128,384 / 0 04:19:20 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 121924096; Write bytes: 114225152; Read time: 11058; Write time: 237 04:19:20 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 33619968; Write bytes: 304741376; Read time: 350; Write time: 1116 04:19:21 INFO - Running post-run listener: _upload_blobber_files 04:19:21 INFO - Blob upload gear active. 04:19:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:19:21 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 04:19:21 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'] 04:19:21 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 04:19:21 INFO - (blobuploader) - INFO - Open directory for files ... 04:19:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:19:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:19:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:19:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:19:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:19:22 INFO - (blobuploader) - INFO - Done attempting. 04:19:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:19:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:19:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:19:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:19:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:19:23 INFO - (blobuploader) - INFO - Done attempting. 04:19:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:19:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:19:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:19:24 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:19:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:19:24 INFO - (blobuploader) - INFO - Done attempting. 04:19:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:19:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:19:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:19:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:19:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:19:26 INFO - (blobuploader) - INFO - Done attempting. 04:19:26 INFO - (blobuploader) - INFO - Iteration through files over. 04:19:26 INFO - Return code: 0 04:19:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:19:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:19:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/829fb6d690d6d749a837fa4760ad5a37e9aa3118a7009d3fe178cfe8159450678857f633fea7c645e7a794ed1a141c3e8bc07162683c6eec222d2637d656f226", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8028e2d6f8e1c2a20406f70accdeec916f55ece0758d6544ea32491f2dc85dbb5df859a2a31e35c18321ecfb2c678f0347bfd449dcb5a7b2300f81e047e98455", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3e638f6be69d96f58d049d876913e84346df63c2c47578daedafee9531cd633c318173e61d9cd9f78e0a430483378f0ad27545ef358110d4ea694543d9972258", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6a1dc29dcbf2ebc2dcc67aae3dd78a5103cfc6f60ceea2d24b39f4024896499063f38b7070f2c26a4063d260331492d46bae34d98ca040b4dd6b7d3a4e32f86e"} 04:19:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:19:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:19:26 INFO - Contents: 04:19:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/829fb6d690d6d749a837fa4760ad5a37e9aa3118a7009d3fe178cfe8159450678857f633fea7c645e7a794ed1a141c3e8bc07162683c6eec222d2637d656f226", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8028e2d6f8e1c2a20406f70accdeec916f55ece0758d6544ea32491f2dc85dbb5df859a2a31e35c18321ecfb2c678f0347bfd449dcb5a7b2300f81e047e98455", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3e638f6be69d96f58d049d876913e84346df63c2c47578daedafee9531cd633c318173e61d9cd9f78e0a430483378f0ad27545ef358110d4ea694543d9972258", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6a1dc29dcbf2ebc2dcc67aae3dd78a5103cfc6f60ceea2d24b39f4024896499063f38b7070f2c26a4063d260331492d46bae34d98ca040b4dd6b7d3a4e32f86e"} 04:19:26 INFO - Running post-run listener: copy_logs_to_upload_dir 04:19:26 INFO - Copying logs to upload dir... 04:19:26 INFO - mkdir: /builds/slave/test/build/upload/logs 04:19:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.948045 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-05-29 04:19:27.035798) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-29 04:19:27.038985) ========= 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.7mecxwknr6/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.2BpeUQ0igj/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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/829fb6d690d6d749a837fa4760ad5a37e9aa3118a7009d3fe178cfe8159450678857f633fea7c645e7a794ed1a141c3e8bc07162683c6eec222d2637d656f226", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8028e2d6f8e1c2a20406f70accdeec916f55ece0758d6544ea32491f2dc85dbb5df859a2a31e35c18321ecfb2c678f0347bfd449dcb5a7b2300f81e047e98455", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3e638f6be69d96f58d049d876913e84346df63c2c47578daedafee9531cd633c318173e61d9cd9f78e0a430483378f0ad27545ef358110d4ea694543d9972258", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6a1dc29dcbf2ebc2dcc67aae3dd78a5103cfc6f60ceea2d24b39f4024896499063f38b7070f2c26a4063d260331492d46bae34d98ca040b4dd6b7d3a4e32f86e"} build_url:https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011180 build_url: 'https://queue.taskcluster.net/v1/task/b__tx7I9SzuklMr5Akz6JA/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/829fb6d690d6d749a837fa4760ad5a37e9aa3118a7009d3fe178cfe8159450678857f633fea7c645e7a794ed1a141c3e8bc07162683c6eec222d2637d656f226", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8028e2d6f8e1c2a20406f70accdeec916f55ece0758d6544ea32491f2dc85dbb5df859a2a31e35c18321ecfb2c678f0347bfd449dcb5a7b2300f81e047e98455", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3e638f6be69d96f58d049d876913e84346df63c2c47578daedafee9531cd633c318173e61d9cd9f78e0a430483378f0ad27545ef358110d4ea694543d9972258", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6a1dc29dcbf2ebc2dcc67aae3dd78a5103cfc6f60ceea2d24b39f4024896499063f38b7070f2c26a4063d260331492d46bae34d98ca040b4dd6b7d3a4e32f86e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-29 04:19:27.073009) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:19:27.073336) ========= 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.7mecxwknr6/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.2BpeUQ0igj/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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.005096 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-29 04:19:27.122411) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-29 04:19:27.122734) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-29 04:19:27.123056) ========= ========= Total master_lag: 0.22 =========