builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0350 starttime: 1496322330.39 results: success (0) buildid: 20170601042032 builduid: bdf49377acd84768946d64fd99e7d99d revision: 86339b41e6e98b5e0580ae19ffbe6c85a54bfc9f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:30.386857) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:30.387297) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:30.387575) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:30.437671) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:30.438054) ========= 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.Ih0wem7cKz/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.GSiFHXTYjE/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-01 06:05:30-- 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% 35.4M=0s 2017-06-01 06:05:30 (35.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.182711 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:30.643814) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:30.644153) ========= 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.Ih0wem7cKz/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.GSiFHXTYjE/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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.035705 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:30.701012) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-01 06:05:30.701356) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 86339b41e6e98b5e0580ae19ffbe6c85a54bfc9f --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 86339b41e6e98b5e0580ae19ffbe6c85a54bfc9f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ih0wem7cKz/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.GSiFHXTYjE/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-01 06:05:30,800 truncating revision to first 12 chars 2017-06-01 06:05:30,800 Setting DEBUG logging. 2017-06-01 06:05:30,800 attempt 1/10 2017-06-01 06:05:30,800 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/86339b41e6e9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-01 06:05:31,655 unpacking tar archive at: mozilla-beta-86339b41e6e9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.147488 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-01 06:05:31.871326) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:31.871646) ========= script_repo_revision: 86339b41e6e98b5e0580ae19ffbe6c85a54bfc9f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:31.872063) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:31.872326) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 06:05:31.891357) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-06-01 06:05:31.891783) ========= /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.Ih0wem7cKz/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.GSiFHXTYjE/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 06:05:32 INFO - MultiFileLogger online at 20170601 06:05:32 in /builds/slave/test 06:05:32 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 06:05:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:05:32 INFO - {'append_to_log': False, 06:05:32 INFO - 'base_work_dir': '/builds/slave/test', 06:05:32 INFO - 'blob_upload_branch': 'Mozilla-Beta', 06:05:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:05:32 INFO - 'branch': 'Mozilla-Beta', 06:05:32 INFO - 'buildbot_json_path': 'buildprops.json', 06:05:32 INFO - 'config_files': ('talos/mac_config.py',), 06:05:32 INFO - 'default_actions': ('clobber', 06:05:32 INFO - 'read-buildbot-config', 06:05:32 INFO - 'download-and-extract', 06:05:32 INFO - 'populate-webroot', 06:05:32 INFO - 'create-virtualenv', 06:05:32 INFO - 'install', 06:05:32 INFO - 'run-tests'), 06:05:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:05:32 INFO - 'download_minidump_stackwalk': True, 06:05:32 INFO - 'download_symbols': 'ondemand', 06:05:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:05:32 INFO - 'tooltool.py': '/tools/tooltool.py', 06:05:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:05:32 INFO - '/tools/misc-python/virtualenv.py')}, 06:05:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:05:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:05:32 INFO - 'gecko_profile': False, 06:05:32 INFO - 'gecko_profile_interval': 0, 06:05:32 INFO - 'installer_path': 'installer.exe', 06:05:32 INFO - 'log_level': 'info', 06:05:32 INFO - 'log_name': 'talos', 06:05:32 INFO - 'log_to_console': True, 06:05:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:05:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:05:32 INFO - 'opt_config_files': (), 06:05:32 INFO - 'pip_index': False, 06:05:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:05:32 INFO - 'cmd': ('bash', 06:05:32 INFO - '-c', 06:05:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:05:32 INFO - 'enabled': True, 06:05:32 INFO - 'halt_on_failure': False, 06:05:32 INFO - 'name': 'check_screen_resolution'},), 06:05:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:05:32 INFO - 'cmd': ('bash', 06:05:32 INFO - '-c', 06:05:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:05:32 INFO - 'enabled': True, 06:05:32 INFO - 'halt_on_failure': False, 06:05:32 INFO - 'name': 'check_screen_resolution'},), 06:05:32 INFO - 'run_cmd_checks_enabled': True, 06:05:32 INFO - 'suite': 'dromaeojs-e10s', 06:05:32 INFO - 'system_bits': '32', 06:05:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:05:32 INFO - 'title': 't-yosemite-r7-0350', 06:05:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:05:32 INFO - 'use_talos_json': True, 06:05:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:05:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:05:32 INFO - 'work_dir': 'build'} 06:05:32 INFO - [mozharness: 2017-06-01 13:05:32.095431Z] Running clobber step. 06:05:32 INFO - Running pre-action listener: _resource_record_pre_action 06:05:32 INFO - Running main action method: clobber 06:05:32 INFO - rmtree: /builds/slave/test/build 06:05:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:05:34 INFO - Running post-action listener: _resource_record_post_action 06:05:34 INFO - [mozharness: 2017-06-01 13:05:34.665614Z] Finished clobber step (success) 06:05:34 INFO - [mozharness: 2017-06-01 13:05:34.665795Z] Running read-buildbot-config step. 06:05:34 INFO - Running pre-action listener: _resource_record_pre_action 06:05:34 INFO - Running main action method: read_buildbot_config 06:05:34 INFO - Using buildbot properties: 06:05:34 INFO - { 06:05:34 INFO - "project": "", 06:05:34 INFO - "product": "firefox", 06:05:34 INFO - "script_repo_revision": "production", 06:05:34 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 06:05:34 INFO - "repository": "", 06:05:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 06:05:34 INFO - "buildid": "20170601042032", 06:05:34 INFO - "pgo_build": "False", 06:05:34 INFO - "basedir": "/builds/slave/test", 06:05:34 INFO - "buildnumber": 1, 06:05:34 INFO - "slavename": "t-yosemite-r7-0350", 06:05:34 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 06:05:34 INFO - "platform": "yosemite_r7_devedition", 06:05:34 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 06:05:34 INFO - "branch": "mozilla-beta", 06:05:34 INFO - "repo_path": "releases/mozilla-beta", 06:05:34 INFO - "revision": "86339b41e6e98b5e0580ae19ffbe6c85a54bfc9f", 06:05:34 INFO - "stage_platform": "macosx64-devedition", 06:05:34 INFO - "builduid": "bdf49377acd84768946d64fd99e7d99d", 06:05:34 INFO - "slavebuilddir": "test" 06:05:34 INFO - } 06:05:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/EiqmZYMORsiF98ub23xd2w/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 06:05:34 INFO - Running post-action listener: _resource_record_post_action 06:05:34 INFO - [mozharness: 2017-06-01 13:05:34.669119Z] Finished read-buildbot-config step (success) 06:05:34 INFO - [mozharness: 2017-06-01 13:05:34.669250Z] Running download-and-extract step. 06:05:34 INFO - Running pre-action listener: _resource_record_pre_action 06:05:34 INFO - Running main action method: download_and_extract 06:05:34 INFO - mkdir: /builds/slave/test/build/tests 06:05:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:05:34 INFO - trying https://queue.taskcluster.net/v1/task/EiqmZYMORsiF98ub23xd2w/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 06:05:34 INFO - Downloading https://queue.taskcluster.net/v1/task/EiqmZYMORsiF98ub23xd2w/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 06:05:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EiqmZYMORsiF98ub23xd2w/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 06:05:36 INFO - Downloaded 1287 bytes. 06:05:36 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 06:05:36 INFO - Using the following test package requirements: 06:05:36 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:05:36 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 06:05:36 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 06:05:36 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:05:36 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 06:05:36 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:05:36 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 06:05:36 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 06:05:36 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:05:36 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 06:05:36 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 06:05:36 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:05:36 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 06:05:36 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:05:36 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 06:05:36 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:05:36 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 06:05:36 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:05:36 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 06:05:36 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 06:05:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EiqmZYMORsiF98ub23xd2w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 06:05:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EiqmZYMORsiF98ub23xd2w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 06:05:36 INFO - Fetch https://queue.taskcluster.net/v1/task/EiqmZYMORsiF98ub23xd2w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 06:05:38 INFO - Expected file size: 51963604 06:05:38 INFO - Obtained file size: 51963604 06:05:54 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 06:05:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EiqmZYMORsiF98ub23xd2w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 06:05:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EiqmZYMORsiF98ub23xd2w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 06:05:54 INFO - Fetch https://queue.taskcluster.net/v1/task/EiqmZYMORsiF98ub23xd2w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 06:05:56 INFO - Expected file size: 51963604 06:05:56 INFO - Obtained file size: 51963604 06:06:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EiqmZYMORsiF98ub23xd2w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 06:06:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EiqmZYMORsiF98ub23xd2w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 06:06:11 INFO - Fetch https://queue.taskcluster.net/v1/task/EiqmZYMORsiF98ub23xd2w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 06:06:12 INFO - Expected file size: 14404461 06:06:12 INFO - Obtained file size: 14404461 06:06: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')]} 06:06:12 INFO - trying https://queue.taskcluster.net/v1/task/EiqmZYMORsiF98ub23xd2w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 06:06:12 INFO - Downloading https://queue.taskcluster.net/v1/task/EiqmZYMORsiF98ub23xd2w/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 06:06:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EiqmZYMORsiF98ub23xd2w/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 06:06:15 INFO - Downloaded 74892241 bytes. 06:06:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EiqmZYMORsiF98ub23xd2w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 06:06:15 INFO - mkdir: /builds/slave/test/properties 06:06:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:06:15 INFO - Writing to file /builds/slave/test/properties/build_url 06:06:15 INFO - Contents: 06:06:15 INFO - build_url:https://queue.taskcluster.net/v1/task/EiqmZYMORsiF98ub23xd2w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 06:06:15 INFO - Running post-action listener: _resource_record_post_action 06:06:15 INFO - Running post-action listener: set_extra_try_arguments 06:06:15 INFO - [mozharness: 2017-06-01 13:06:15.759849Z] Finished download-and-extract step (success) 06:06:15 INFO - [mozharness: 2017-06-01 13:06:15.759972Z] Running populate-webroot step. 06:06:15 INFO - Running pre-action listener: _resource_record_pre_action 06:06:15 INFO - Running main action method: populate_webroot 06:06:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:06:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:06:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:06:15 INFO - '--tppagecycles', 06:06:15 INFO - '7'], 06:06:15 INFO - 'tests': ['tsvgm']}}, 06:06:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:06:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:06:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:06:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:06:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:06:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:06:15 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:06:15 INFO - 'talos_options': ['--disable-e10s'], 06:06:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:06:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:06:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:06:15 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:06:15 INFO - 'talos_options': ['--disable-e10s'], 06:06:15 INFO - 'tests': ['damp', 'tps']}, 06:06:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:06:15 INFO - 'tests': ['damp', 'tps']}, 06:06:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:06:15 INFO - 'tests': ['dromaeo_dom']}, 06:06:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:06:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:06:15 INFO - 'tests': ['basic_compositor_video']}, 06:06:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:06:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:06:15 INFO - 'tests': ['a11yr', 06:06:15 INFO - 'ts_paint', 06:06:15 INFO - 'tpaint', 06:06:15 INFO - 'sessionrestore', 06:06:15 INFO - 'sessionrestore_no_auto_restore', 06:06:15 INFO - 'tabpaint']}, 06:06:15 INFO - 'other-e10s': {'tests': ['a11yr', 06:06:15 INFO - 'ts_paint', 06:06:15 INFO - 'tpaint', 06:06:15 INFO - 'sessionrestore', 06:06:15 INFO - 'sessionrestore_no_auto_restore', 06:06:15 INFO - 'tabpaint']}, 06:06:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:06:15 INFO - 'ts_paint', 06:06:15 INFO - 'tpaint', 06:06:15 INFO - 'sessionrestore', 06:06:15 INFO - 'sessionrestore_no_auto_restore', 06:06:15 INFO - 'tabpaint']}, 06:06:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:06:15 INFO - 'ts_paint', 06:06:15 INFO - 'tpaint', 06:06:15 INFO - 'sessionrestore', 06:06:15 INFO - 'sessionrestore_no_auto_restore', 06:06:15 INFO - 'tabpaint']}, 06:06:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:06:15 INFO - 'tests': ['a11yr', 06:06:15 INFO - 'ts_paint', 06:06:15 INFO - 'tpaint', 06:06:15 INFO - 'sessionrestore', 06:06:15 INFO - 'sessionrestore_no_auto_restore', 06:06:15 INFO - 'tabpaint']}, 06:06:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:06:15 INFO - 'tests': ['a11yr', 06:06:15 INFO - 'ts_paint', 06:06:15 INFO - 'tpaint', 06:06:15 INFO - 'sessionrestore', 06:06:15 INFO - 'sessionrestore_no_auto_restore', 06:06:15 INFO - 'tabpaint']}, 06:06:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:06:15 INFO - 'tests': ['tsvgx', 06:06:15 INFO - 'tsvgr_opacity', 06:06:15 INFO - 'tart', 06:06:15 INFO - 'tscrollx', 06:06:15 INFO - 'cart', 06:06:15 INFO - 'tsvg_static']}, 06:06:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:06:15 INFO - 'tsvgr_opacity', 06:06:15 INFO - 'tart', 06:06:15 INFO - 'tscrollx', 06:06:15 INFO - 'cart', 06:06:15 INFO - 'tsvg_static']}, 06:06:15 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:06:15 INFO - 'talos_options': ['--disable-e10s'], 06:06:15 INFO - 'tests': ['tp5o']}, 06:06:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:06:15 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:06:15 INFO - 'talos_options': ['--disable-e10s', 06:06:15 INFO - '--xperf_path', 06:06:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:06:15 INFO - 'tests': ['tp5n']}, 06:06:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:06:15 INFO - 'talos_options': ['--xperf_path', 06:06:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:06:15 INFO - 'tests': ['tp5n']}}, 06:06:15 INFO - 'talos.zip': {'path': '', 06:06:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:06:15 INFO - Running post-action listener: _resource_record_post_action 06:06:15 INFO - [mozharness: 2017-06-01 13:06:15.778544Z] Finished populate-webroot step (success) 06:06:15 INFO - [mozharness: 2017-06-01 13:06:15.778659Z] Running create-virtualenv step. 06:06:15 INFO - Running pre-action listener: _resource_record_pre_action 06:06:15 INFO - Running main action method: create_virtualenv 06:06:15 INFO - Creating virtualenv /builds/slave/test/build/venv 06:06:15 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 06:06:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:06:15 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 06:06:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:06:15 INFO - Using real prefix '/tools/python27' 06:06:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:06:16 INFO - Installing distribute.............................................................................................................................................................................................done. 06:06:19 INFO - Installing pip.................done. 06:06:19 INFO - Return code: 0 06:06:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:06:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:06:19 INFO - Reading from file tmpfile_stdout 06:06:19 INFO - Output received: 06:06:19 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) 06:06:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:06:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e0f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe50b57fbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10decfd30>, '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 0x10ddf6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dec4be8>, '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.Ih0wem7cKz/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.GSiFHXTYjE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 06:06:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:06:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:06:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ih0wem7cKz/Render', 06:06:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:19 INFO - 'HOME': '/Users/cltbld', 06:06:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:19 INFO - 'LOGNAME': 'cltbld', 06:06:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:19 INFO - 'MOZ_NO_REMOTE': '1', 06:06:19 INFO - 'NO_EM_RESTART': '1', 06:06:19 INFO - 'PAGER': '/bin/cat', 06:06:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:19 INFO - 'PWD': '/builds/slave/test', 06:06:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:19 INFO - 'SHELL': '/bin/bash', 06:06:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GSiFHXTYjE/Listeners', 06:06:19 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 06:06:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:19 INFO - 'USER': 'cltbld', 06:06:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:19 INFO - 'XPC_FLAGS': '0x0', 06:06:19 INFO - 'XPC_SERVICE_NAME': '0', 06:06:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:20 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 06:06:20 INFO - Cleaning up... 06:06:20 INFO - Return code: 0 06:06:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:06:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e0f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe50b57fbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10decfd30>, '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 0x10ddf6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dec4be8>, '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.Ih0wem7cKz/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.GSiFHXTYjE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 06:06:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:06:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:06:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ih0wem7cKz/Render', 06:06:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:20 INFO - 'HOME': '/Users/cltbld', 06:06:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:20 INFO - 'LOGNAME': 'cltbld', 06:06:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:20 INFO - 'MOZ_NO_REMOTE': '1', 06:06:20 INFO - 'NO_EM_RESTART': '1', 06:06:20 INFO - 'PAGER': '/bin/cat', 06:06:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:20 INFO - 'PWD': '/builds/slave/test', 06:06:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:20 INFO - 'SHELL': '/bin/bash', 06:06:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GSiFHXTYjE/Listeners', 06:06:20 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 06:06:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:20 INFO - 'USER': 'cltbld', 06:06:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:20 INFO - 'XPC_FLAGS': '0x0', 06:06:20 INFO - 'XPC_SERVICE_NAME': '0', 06:06:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:20 INFO - Downloading/unpacking psutil>=3.1.1 06:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:06:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:06:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:06:22 INFO - Installing collected packages: psutil 06:06:22 INFO - Running setup.py install for psutil 06:06:22 INFO - building 'psutil._psutil_osx' extension 06:06:22 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 06:06:23 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 06:06:23 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 06:06:23 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 06:06:23 INFO - building 'psutil._psutil_posix' extension 06:06:23 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 06:06:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:06:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:06:23 INFO - ^ 06:06:23 INFO - 1 warning generated. 06:06:23 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 06:06:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:06:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:06:23 INFO - Successfully installed psutil 06:06:23 INFO - Cleaning up... 06:06:23 INFO - Return code: 0 06:06:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:06: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')]} 06:06:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06: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')]} 06:06:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:23 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 0x10e0f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe50b57fbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10decfd30>, '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 0x10ddf6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dec4be8>, '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.Ih0wem7cKz/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.GSiFHXTYjE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 06:06:23 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 06:06:23 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 06:06:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ih0wem7cKz/Render', 06:06:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:23 INFO - 'HOME': '/Users/cltbld', 06:06:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:23 INFO - 'LOGNAME': 'cltbld', 06:06:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:23 INFO - 'MOZ_NO_REMOTE': '1', 06:06:23 INFO - 'NO_EM_RESTART': '1', 06:06:23 INFO - 'PAGER': '/bin/cat', 06:06:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:23 INFO - 'PWD': '/builds/slave/test', 06:06:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:23 INFO - 'SHELL': '/bin/bash', 06:06:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GSiFHXTYjE/Listeners', 06:06:23 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 06:06:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:23 INFO - 'USER': 'cltbld', 06:06:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:23 INFO - 'XPC_FLAGS': '0x0', 06:06:23 INFO - 'XPC_SERVICE_NAME': '0', 06:06:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:06:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:06:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:06:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:06:25 INFO - Installing collected packages: mozsystemmonitor 06:06:25 INFO - Running setup.py install for mozsystemmonitor 06:06:25 INFO - Successfully installed mozsystemmonitor 06:06:25 INFO - Cleaning up... 06:06:25 INFO - Return code: 0 06:06:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:06:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e0f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe50b57fbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10decfd30>, '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 0x10ddf6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dec4be8>, '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.Ih0wem7cKz/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.GSiFHXTYjE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 06:06:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:06:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:06:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ih0wem7cKz/Render', 06:06:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:25 INFO - 'HOME': '/Users/cltbld', 06:06:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:25 INFO - 'LOGNAME': 'cltbld', 06:06:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:25 INFO - 'MOZ_NO_REMOTE': '1', 06:06:25 INFO - 'NO_EM_RESTART': '1', 06:06:25 INFO - 'PAGER': '/bin/cat', 06:06:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:25 INFO - 'PWD': '/builds/slave/test', 06:06:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:25 INFO - 'SHELL': '/bin/bash', 06:06:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GSiFHXTYjE/Listeners', 06:06:25 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 06:06:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:25 INFO - 'USER': 'cltbld', 06:06:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:25 INFO - 'XPC_FLAGS': '0x0', 06:06:25 INFO - 'XPC_SERVICE_NAME': '0', 06:06:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:26 INFO - Downloading/unpacking jsonschema==2.5.1 06:06:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:06:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:06:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:28 INFO - Downloading functools32-3.2.3-2.tar.gz 06:06:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:06:28 INFO - warning: no files found matching '*.txt' 06:06:28 INFO - no previously-included directories found matching 'build' 06:06:28 INFO - no previously-included directories found matching 'dist' 06:06:28 INFO - no previously-included directories found matching '.git*' 06:06:28 INFO - Installing collected packages: jsonschema, functools32 06:06:28 INFO - Running setup.py install for functools32 06:06:28 INFO - warning: no files found matching '*.txt' 06:06:28 INFO - no previously-included directories found matching 'build' 06:06:28 INFO - no previously-included directories found matching 'dist' 06:06:28 INFO - no previously-included directories found matching '.git*' 06:06:28 INFO - Successfully installed jsonschema functools32 06:06:28 INFO - Cleaning up... 06:06:28 INFO - Return code: 0 06:06:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:06:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e0f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe50b57fbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10decfd30>, '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 0x10ddf6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dec4be8>, '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.Ih0wem7cKz/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.GSiFHXTYjE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 06:06:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:06:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:06:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ih0wem7cKz/Render', 06:06:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:28 INFO - 'HOME': '/Users/cltbld', 06:06:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:28 INFO - 'LOGNAME': 'cltbld', 06:06:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:28 INFO - 'MOZ_NO_REMOTE': '1', 06:06:28 INFO - 'NO_EM_RESTART': '1', 06:06:28 INFO - 'PAGER': '/bin/cat', 06:06:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:28 INFO - 'PWD': '/builds/slave/test', 06:06:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:28 INFO - 'SHELL': '/bin/bash', 06:06:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GSiFHXTYjE/Listeners', 06:06:28 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 06:06:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:28 INFO - 'USER': 'cltbld', 06:06:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:28 INFO - 'XPC_FLAGS': '0x0', 06:06:28 INFO - 'XPC_SERVICE_NAME': '0', 06:06:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:06:28 INFO - Cleaning up... 06:06:28 INFO - Return code: 0 06:06:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:06:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e0f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe50b57fbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10decfd30>, '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 0x10ddf6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dec4be8>, '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.Ih0wem7cKz/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.GSiFHXTYjE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 06:06:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:06:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:06:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ih0wem7cKz/Render', 06:06:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:28 INFO - 'HOME': '/Users/cltbld', 06:06:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:28 INFO - 'LOGNAME': 'cltbld', 06:06:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:28 INFO - 'MOZ_NO_REMOTE': '1', 06:06:28 INFO - 'NO_EM_RESTART': '1', 06:06:28 INFO - 'PAGER': '/bin/cat', 06:06:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:28 INFO - 'PWD': '/builds/slave/test', 06:06:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:28 INFO - 'SHELL': '/bin/bash', 06:06:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GSiFHXTYjE/Listeners', 06:06:28 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 06:06:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:28 INFO - 'USER': 'cltbld', 06:06:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:28 INFO - 'XPC_FLAGS': '0x0', 06:06:28 INFO - 'XPC_SERVICE_NAME': '0', 06:06:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:28 INFO - Downloading/unpacking blobuploader==1.2.4 06:06:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:30 INFO - Downloading blobuploader-1.2.4.tar.gz 06:06:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:06:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:06:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:06:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:06:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:31 INFO - Downloading docopt-0.6.1.tar.gz 06:06:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:06:31 INFO - Installing collected packages: blobuploader, requests, docopt 06:06:31 INFO - Running setup.py install for blobuploader 06:06:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:06:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:06:31 INFO - Running setup.py install for requests 06:06:31 INFO - Running setup.py install for docopt 06:06:32 INFO - Successfully installed blobuploader requests docopt 06:06:32 INFO - Cleaning up... 06:06:32 INFO - Return code: 0 06:06:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:06:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:32 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 0x10e0f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe50b57fbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10decfd30>, '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 0x10ddf6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dec4be8>, '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.Ih0wem7cKz/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.GSiFHXTYjE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 06:06:32 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 06:06:32 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 06:06:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ih0wem7cKz/Render', 06:06:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:32 INFO - 'HOME': '/Users/cltbld', 06:06:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:32 INFO - 'LOGNAME': 'cltbld', 06:06:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:32 INFO - 'MOZ_NO_REMOTE': '1', 06:06:32 INFO - 'NO_EM_RESTART': '1', 06:06:32 INFO - 'PAGER': '/bin/cat', 06:06:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:32 INFO - 'PWD': '/builds/slave/test', 06:06:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:32 INFO - 'SHELL': '/bin/bash', 06:06:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GSiFHXTYjE/Listeners', 06:06:32 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 06:06:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:32 INFO - 'USER': 'cltbld', 06:06:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:32 INFO - 'XPC_FLAGS': '0x0', 06:06:32 INFO - 'XPC_SERVICE_NAME': '0', 06:06:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:06:32 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-3m_sfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:06:32 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-iKs9m8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:06:32 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-JLmKlZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:06:32 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-QStHG7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:06:32 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-ieSE1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:06:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:06:32 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-BA6EBm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:06:33 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-hCd3tg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:06:33 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-o4ypli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:06:33 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-qvnhEe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:06:33 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-TzcjNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:06:33 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-TOnqyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:06:33 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-Vqwsxp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:06:33 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-cVeiRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:06:33 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-5uZCoz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:06:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:06:33 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-jU1Isr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:06:34 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-LdUpsC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:06:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:06:34 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-4El92u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:06:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:06:34 INFO - Running setup.py install for manifestparser 06:06:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:06:34 INFO - Running setup.py install for mozcrash 06:06:34 INFO - Running setup.py install for mozdebug 06:06:34 INFO - Running setup.py install for mozdevice 06:06:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:06:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:06:34 INFO - Running setup.py install for mozfile 06:06:34 INFO - Running setup.py install for mozhttpd 06:06:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:06:35 INFO - Running setup.py install for mozinfo 06:06:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:06:35 INFO - Running setup.py install for mozInstall 06:06:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:06:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:06:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:06:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:06:35 INFO - Running setup.py install for mozleak 06:06:35 INFO - Running setup.py install for mozlog 06:06:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:06:35 INFO - Running setup.py install for moznetwork 06:06:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:06:35 INFO - Running setup.py install for mozprocess 06:06:36 INFO - Running setup.py install for mozprofile 06:06:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:06:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:06:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:06:36 INFO - Running setup.py install for mozrunner 06:06:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:06:36 INFO - Running setup.py install for mozscreenshot 06:06:36 INFO - Running setup.py install for moztest 06:06:36 INFO - Running setup.py install for mozversion 06:06:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:06:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:06:36 INFO - Cleaning up... 06:06:36 INFO - Return code: 0 06:06:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:06:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:36 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 0x10e0f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe50b57fbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10decfd30>, '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 0x10ddf6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dec4be8>, '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.Ih0wem7cKz/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.GSiFHXTYjE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 06:06:36 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 06:06:36 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 06:06:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ih0wem7cKz/Render', 06:06:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:36 INFO - 'HOME': '/Users/cltbld', 06:06:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:36 INFO - 'LOGNAME': 'cltbld', 06:06:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:36 INFO - 'MOZ_NO_REMOTE': '1', 06:06:36 INFO - 'NO_EM_RESTART': '1', 06:06:36 INFO - 'PAGER': '/bin/cat', 06:06:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:36 INFO - 'PWD': '/builds/slave/test', 06:06:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:36 INFO - 'SHELL': '/bin/bash', 06:06:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GSiFHXTYjE/Listeners', 06:06:36 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 06:06:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:36 INFO - 'USER': 'cltbld', 06:06:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:36 INFO - 'XPC_FLAGS': '0x0', 06:06:36 INFO - 'XPC_SERVICE_NAME': '0', 06:06:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:06:37 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-_ODjwy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:06:37 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)) 06:06:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:06:37 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-SwgmAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:06:37 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)) 06:06:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:06:37 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-DVnZV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:06:37 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)) 06:06:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:06:37 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-0hBTLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:06:37 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)) 06:06:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:06:37 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-gQNwvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:06:37 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)) 06:06:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:06:37 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-PmDUNp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:06:37 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)) 06:06:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:06:37 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-aTWmm1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:06:37 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)) 06:06:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:06:37 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-yAnOea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:06:37 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)) 06:06:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:06:37 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-nEaUem-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:06:38 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)) 06:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:06:38 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-fwVnaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:06:38 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)) 06:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:06:38 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-bvgGyZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:06:38 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)) 06:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:06:38 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-dynS9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:06:38 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)) 06:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:06:38 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-sBTtn_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:06:38 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)) 06:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:06:38 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-6taEIl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:06:38 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)) 06:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:06:38 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-ZK1ihW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:06:38 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)) 06:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:06:38 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-BVgmAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:06:38 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)) 06:06:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:06:38 INFO - Running setup.py (path:/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/pip-RUb9aT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:06:38 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)) 06:06:38 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)) 06:06:38 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)) 06:06:38 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)) 06:06:38 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)) 06:06:38 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)) 06:06:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:06:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:06:40 INFO - Downloading blessings-1.6.tar.gz 06:06:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:06:40 INFO - Installing collected packages: blessings 06:06:40 INFO - Running setup.py install for blessings 06:06:40 INFO - Successfully installed blessings 06:06:40 INFO - Cleaning up... 06:06:40 INFO - Return code: 0 06:06:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:06:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:06:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:06:41 INFO - Reading from file tmpfile_stdout 06:06:41 INFO - Current package versions: 06:06:41 INFO - blessings == 1.6 06:06:41 INFO - blobuploader == 1.2.4 06:06:41 INFO - docopt == 0.6.1 06:06:41 INFO - functools32 == 3.2.3-2 06:06:41 INFO - jsonschema == 2.5.1 06:06:41 INFO - manifestparser == 1.1 06:06:41 INFO - mozInstall == 1.12 06:06:41 INFO - mozcrash == 1.0 06:06:41 INFO - mozdebug == 0.1 06:06:41 INFO - mozdevice == 0.49 06:06:41 INFO - mozfile == 1.2 06:06:41 INFO - mozhttpd == 0.7 06:06:41 INFO - mozinfo == 0.9 06:06:41 INFO - mozleak == 0.1 06:06:41 INFO - mozlog == 3.4 06:06:41 INFO - moznetwork == 0.27 06:06:41 INFO - mozprocess == 0.25 06:06:41 INFO - mozprofile == 0.28 06:06:41 INFO - mozrunner == 6.13 06:06:41 INFO - mozscreenshot == 0.1 06:06:41 INFO - mozsystemmonitor == 0.3 06:06:41 INFO - moztest == 0.8 06:06:41 INFO - mozversion == 1.4 06:06:41 INFO - psutil == 3.1.1 06:06:41 INFO - requests == 1.2.3 06:06:41 INFO - wsgiref == 0.1.2 06:06:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:06:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:41 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 0x10e0f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe50b57fbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10decfd30>, '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 0x10ddf6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dec4be8>, '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.Ih0wem7cKz/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.GSiFHXTYjE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 06:06:41 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 06:06:41 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 06:06:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ih0wem7cKz/Render', 06:06:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:41 INFO - 'HOME': '/Users/cltbld', 06:06:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:41 INFO - 'LOGNAME': 'cltbld', 06:06:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:41 INFO - 'MOZ_NO_REMOTE': '1', 06:06:41 INFO - 'NO_EM_RESTART': '1', 06:06:41 INFO - 'PAGER': '/bin/cat', 06:06:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:41 INFO - 'PWD': '/builds/slave/test', 06:06:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:41 INFO - 'SHELL': '/bin/bash', 06:06:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GSiFHXTYjE/Listeners', 06:06:41 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 06:06:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:41 INFO - 'USER': 'cltbld', 06:06:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:41 INFO - 'XPC_FLAGS': '0x0', 06:06:41 INFO - 'XPC_SERVICE_NAME': '0', 06:06:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:41 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)) 06:06:41 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)) 06:06:41 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)) 06:06:41 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)) 06:06:41 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)) 06:06:41 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)) 06:06:41 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)) 06:06:41 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)) 06:06:41 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)) 06:06:41 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)) 06:06:41 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)) 06:06:41 INFO - Cleaning up... 06:06:41 INFO - Return code: 0 06:06:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:06:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:06:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:06:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:06:41 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 0x10e0f1b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e0f4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe50b57fbd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10decfd30>, '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 0x10ddf6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dec4be8>, '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.Ih0wem7cKz/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.GSiFHXTYjE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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 06:06:41 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 06:06:41 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 06:06:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ih0wem7cKz/Render', 06:06:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:06:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:06:41 INFO - 'HOME': '/Users/cltbld', 06:06:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:06:41 INFO - 'LOGNAME': 'cltbld', 06:06:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:06:41 INFO - 'MOZ_NO_REMOTE': '1', 06:06:41 INFO - 'NO_EM_RESTART': '1', 06:06:41 INFO - 'PAGER': '/bin/cat', 06:06:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:06:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:06:41 INFO - 'PWD': '/builds/slave/test', 06:06:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:06:41 INFO - 'SHELL': '/bin/bash', 06:06:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GSiFHXTYjE/Listeners', 06:06:41 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 06:06:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:06:41 INFO - 'USER': 'cltbld', 06:06:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:06:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:06:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:06:41 INFO - 'XPC_FLAGS': '0x0', 06:06:41 INFO - 'XPC_SERVICE_NAME': '0', 06:06:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:06:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:06:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:06:41 INFO - Cleaning up... 06:06:41 INFO - Return code: 0 06:06:41 INFO - Running post-action listener: _resource_record_post_action 06:06:41 INFO - Running post-action listener: _start_resource_monitoring 06:06:41 INFO - Starting resource monitoring. 06:06:41 INFO - [mozharness: 2017-06-01 13:06:41.579042Z] Finished create-virtualenv step (success) 06:06:41 INFO - [mozharness: 2017-06-01 13:06:41.579989Z] Running install step. 06:06:41 INFO - Running pre-action listener: _resource_record_pre_action 06:06:41 INFO - Running main action method: install 06:06:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:06:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:06:41 INFO - Reading from file tmpfile_stdout 06:06:41 INFO - Detecting whether we're running mozinstall >=1.0... 06:06:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:06:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:06:41 INFO - Reading from file tmpfile_stdout 06:06:41 INFO - Output received: 06:06:41 INFO - Usage: mozinstall [options] installer 06:06:41 INFO - Options: 06:06:41 INFO - -h, --help show this help message and exit 06:06:41 INFO - -d DEST, --destination=DEST 06:06:41 INFO - Directory to install application into. [default: 06:06:41 INFO - "/builds/slave/test"] 06:06:41 INFO - --app=APP Application being installed. [default: firefox] 06:06:41 INFO - mkdir: /builds/slave/test/build/application 06:06:41 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'] 06:06:41 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 06:07:02 INFO - Reading from file tmpfile_stdout 06:07:02 INFO - Output received: 06:07:02 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 06:07:02 INFO - Running post-action listener: _resource_record_post_action 06:07:02 INFO - [mozharness: 2017-06-01 13:07:02.216266Z] Finished install step (success) 06:07:02 INFO - [mozharness: 2017-06-01 13:07:02.216473Z] Running run-tests step. 06:07:02 INFO - Running pre-action listener: _resource_record_pre_action 06:07:02 INFO - Running main action method: run_tests 06:07:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:07:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:07:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:07:02 INFO - 2017-06-01 06:07:02.231 screenresolution[1629:7808] starting screenresolution argv=screenresolution get 06:07:02 INFO - 2017-06-01 06:07:02.233 screenresolution[1629:7808] Display 0: 1600x1200x32@60 06:07:02 INFO - 2017-06-01 06:07:02.239 screenresolution[1630:7811] starting screenresolution argv=screenresolution list 06:07:02 INFO - Available Modes on Display 0 06:07:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:07:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:07:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:07:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:07:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:07:02 INFO - Intel Iris: 06:07:02 INFO - Chipset Model: Intel Iris 06:07:02 INFO - Type: GPU 06:07:02 INFO - Bus: Built-In 06:07:02 INFO - VRAM (Dynamic, Max): 1536 MB 06:07:02 INFO - Vendor: Intel (0x8086) 06:07:02 INFO - Device ID: 0x0a2e 06:07:02 INFO - Revision ID: 0x0009 06:07:02 INFO - Displays: 06:07:02 INFO - Display: 06:07:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:07:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:07:02 INFO - Display Serial Number: 1600x1200 60 06:07:02 INFO - Main Display: Yes 06:07:02 INFO - Mirror: Off 06:07:02 INFO - Online: Yes 06:07:02 INFO - Rotation: Supported 06:07:02 INFO - Return code: 0 06:07:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:07:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:07:02 INFO - Python 2.7.3 06:07:02 INFO - Return code: 0 06:07:02 INFO - grabbing minidump binary from tooltool 06:07:02 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')]} 06:07:02 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 0x10decfd30>, '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 0x10ddf6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dec4be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:07:02 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 06:07:02 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 06:07:02 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 06:07:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:07:02 INFO - Return code: 0 06:07:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:07:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:07:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:07:02 INFO - ENV: RUST_BACKTRACE is now 1 06:07:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:07:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:07:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EiqmZYMORsiF98ub23xd2w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0350', '--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 06:07:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EiqmZYMORsiF98ub23xd2w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0350 --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 06:07:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ih0wem7cKz/Render', 06:07:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:07:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:07:02 INFO - 'HOME': '/Users/cltbld', 06:07:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:07:02 INFO - 'LOGNAME': 'cltbld', 06:07:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:07:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:07:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:07:02 INFO - 'MOZ_NO_REMOTE': '1', 06:07:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:07:02 INFO - 'NO_EM_RESTART': '1', 06:07:02 INFO - 'PAGER': '/bin/cat', 06:07:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:07:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:07:02 INFO - 'PWD': '/builds/slave/test', 06:07:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:07:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:07:02 INFO - 'RUST_BACKTRACE': '1', 06:07:02 INFO - 'SHELL': '/bin/bash', 06:07:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.GSiFHXTYjE/Listeners', 06:07:02 INFO - 'TMPDIR': '/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/', 06:07:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:07:02 INFO - 'USER': 'cltbld', 06:07:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:07:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:07:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:07:02 INFO - 'XPC_FLAGS': '0x0', 06:07:02 INFO - 'XPC_SERVICE_NAME': '0', 06:07:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:07:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EiqmZYMORsiF98ub23xd2w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0350', '--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 06:07:02 INFO - mozversion application_buildid: 20170601042032 06:07:02 INFO - mozversion application_changeset: 86339b41e6e98b5e0580ae19ffbe6c85a54bfc9f 06:07:02 INFO - mozversion application_display_name: FirefoxDeveloperEdition 06:07:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:07:02 INFO - mozversion application_name: Firefox 06:07:02 INFO - mozversion application_remotingname: firefox-dev 06:07:02 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 06:07:02 INFO - mozversion application_vendor: Mozilla 06:07:02 INFO - mozversion application_version: 54.0 06:07:02 INFO - mozversion platform_buildid: 20170601042032 06:07:02 INFO - mozversion platform_changeset: 86339b41e6e98b5e0580ae19ffbe6c85a54bfc9f 06:07:02 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 06:07:02 INFO - mozversion platform_version: 54.0 06:07:02 INFO - using testdate: 1496322422 06:07:02 INFO - actual date: 1496322422 06:07:02 INFO - starting webserver on 'localhost:49228' 06:07:02 INFO - SUITE-START | Running 2 tests 06:07:02 INFO - TEST-START | dromaeo_css 06:07:02 INFO - operating with platform_type : mac_ 06:07:02 INFO - Initialising browser for dromaeo_css test... 06:07:02 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmpVc4RRa/profile http://localhost:49228/getInfo.html) 06:07:05 INFO - PROCESS | 1640 | 2017-06-01 06:07:05.801 plugin-container[1643:7993] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 06:07:05 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:07:05 INFO - PROCESS | 1640 | 2017-06-01 06:07:05.801 plugin-container[1643:7993] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e47, name = 'com.apple.CFPasteboardClient' 06:07:05 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:07:05 INFO - PROCESS | 1640 | 2017-06-01 06:07:05.802 plugin-container[1643:7993] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:07:05 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 06:07:05 INFO - PROCESS | 1640 | colorDepth:24 06:07:05 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 06:07:05 INFO - PROCESS | 1640 | __metrics 06:07:05 ERROR - PROCESS | 1640 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 06:07:06 INFO - TEST-INFO | 1640: exit 0 06:07:06 INFO - Browser initialized. 06:07:06 INFO - Running cycle 1/1 for dromaeo_css test... 06:07:06 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmpVc4RRa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:07:08 INFO - PROCESS | 1645 | 2017-06-01 06:07:08.931 plugin-container[1646:8134] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c4b, name = 'com.apple.tsm.portname' 06:07:08 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:07:08 INFO - PROCESS | 1645 | 2017-06-01 06:07:08.932 plugin-container[1646:8134] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x870f, name = 'com.apple.CFPasteboardClient' 06:07:08 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:07:08 INFO - PROCESS | 1645 | 2017-06-01 06:07:08.932 plugin-container[1646:8134] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:07:18 INFO - PROCESS | 1645 | RSS: Main: 203210752 06:07:18 INFO - PROCESS | 1645 | 06:10:22 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 06:10:23 INFO - PROCESS | 1645 | RSS: Main: 216182784 06:10:23 INFO - PROCESS | 1645 | 06:13:25 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 06:13:26 INFO - PROCESS | 1645 | RSS: Main: 229437440 06:13:26 INFO - PROCESS | 1645 | 06:16:28 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 06:16:28 INFO - PROCESS | 1645 | RSS: Main: 232361984 06:16:28 INFO - PROCESS | 1645 | 06:19:30 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 06:19:31 INFO - PROCESS | 1645 | RSS: Main: 235552768 06:19:31 INFO - PROCESS | 1645 | 06:22:34 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 06:22:35 INFO - PROCESS | 1645 | RSS: Main: 237674496 06:22:35 INFO - PROCESS | 1645 | 06:25:37 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 06:25:38 INFO - PROCESS | 1645 | RSS: Main: 236863488 06:25:38 INFO - PROCESS | 1645 | 06:25:38 INFO - PROCESS | 1645 | __start_tp_report 06:25:38 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 06:25:38 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 06:25:38 INFO - PROCESS | 1645 | |i|pagename|runs| 06:25:38 INFO - PROCESS | 1645 | |0;dojo.html;3446.9827650861744;3467.7399195060375;3481.6518348165173;3482.4079906415905;3488.738344624154;5840.737166827493;5914.438128377803;5920.259967504062;5934.1692163097205;5938.287405511339;5859.8535036624;5929.140274660172;5944.554158438113;5950.58345915785;5976.133460648214;4639.443266807988;5302.310699609065;5365.08793505105;5374.731263436832;5476.123820188771;4578.221702310615;5382.977234325485;5423.945760542383;5443.863903402407;5451.618386712932;5179.818706345279;5213.113770658978;5421.430749771276;5426.104692725696;5433.375161856397;5320.654157479771;5328.960852633738;5329;5329.600279979017;5345.65253258537;5147.48525147486;5253.212018197263;5257.421683614799;5264.368275806889;5335.573154147698;5831.825045248607;5852.531797456193;5854.912176317359;5875.559333050039;5897.203877476529;5663.7451314690525;6242.750289988395;6252.406021427957;6281.623102613858;6295.653739044351;6338.619682819046;6357.904631430532;6358.237011558643;6376.234851817765;6380.840478988062;5009.0232404681465;6015.157877897098;6046.546509011798;6065.181200537959;6074.969625151934;6259.499240060784;6268.874622507525;6287.1512345832725;6340.619562826245;6352.872942541123;6255;6274.05889116627;6300.21247344082;6301.779437719658;6324.08300796382;6328.335524769925;6334.1765570475545;6343.904841427382;6359.395857393541;6359.650219237987;5262.578993680497;6108.888502348253;6166.907496387557;6228.595141315891;6352.333005034498;6219.129321894939;6252.499800015988;6279.780207692709;6281.842953926189;6309.652969086745;4836.540528649843;4836.927446088312;4847.5394837490385;4854.344663470457;4861.9027619447415;3942.994536393259;4145.398917157056;4324.827006919688;4332.610065094157;4367.344898265285;5645.407232240637;5758.3665796762325;5763.6541807491685;5784.623999440023;5814.651120932757;6302.212223472066;6345.619262844244;6349.365063493614;6350.174477317919;6361;5820.417958204146;5826.504747096459;5844.970775146097;5846.6784326862435;5886.646801191942;5307.946920530822;5317.494837990384;5411.56707463402;5446.21029950655;5511.53151982078;6424.51816113784;6477.902831457438;6479.254885688206;6501.187351581052;6506.60960342381;6323.557350985571;6343.841403964753;6350.96824515893;6358.586691865014;6358.90461643085;6226.844328891632;6291.779787707408;6338.968305158444;6415.582987105823;6466.773662921775;6227.688615569113;6305.306416294112;6312.621242725451;6313.936860631335;6314.179156709598;5334.199870019528;6207.81376558704;6280.497560195265;6294.8111556653375;6349.904751428639;6025.698715064142;6079.756809727562;6222.439980401605;6283.3088360281245;6322.0200868865395;6122.550631083031;6135.692037377249;6147.415995480422;6163.260408750979;6218.689065546795;5262.710550919736;6155.35368786271;6163.35284795108;6172.567920245539;6177.505799535937;6017.2779266488305;6107.9083813741945;6109.95128740435;6128.295246046761;6238.594491358047;6128.228138398679;6134.938650613437;6142.402975226562;6148.139260503623;6163.476104530986 06:25:38 INFO - PROCESS | 1645 | |1;ext.html;766.6511737159592;783.4280974888331;828.515318538655;896.1128482802028;964.4502633498904;38543.036424089376;39531.209375812505;41018.58981410185;41047.973800654996;41079;38758.837234883;38939.610603893954;38961.805190974075;39156.412653810214;39249;8050.637721302526;8189.467684600511;11005.834912476279;11268.267562608404;11449.25579837312;9444.244460443148;11080.224384293102;11162.665120046371;11164.330140191614;11166;11154.10767138631;11194.216404851664;11210.27133236337;11219;11359.886401136007;11132.053775429058;11163.386013769239;11177.888221117848;11180.329180249211;11188.44057797115;10888.183386246063;10943.890561094368;10957.06864916475;10964.616238431616;11146.331220126738;34213.31573368519;34284.48573271403;34391.82804085963;34548;34610.65389346099;3787.981060094682;3812.3519001769764;3812.6377994090794;3845.750026248285;3868.613138686137;25155.62266566003;25738.356541086436;26366.209013729618;26389;26411.67941602912;2995.475791736459;3158.1315138336913;3160.959516664359;3190.1865024418626;3315.5689760331243;357818;358793;360659.9999999974;411728;422018.77981220104;43673.78163109164;43928.901777455816;43930.34104488435;43961.34057989133;43984;45061.549384505735;45276.3208551872;45437.54562454399;45447.318290225674;45873.77063114663;13471.595852124256;13545.796813047813;13611.183329000292;13657;13665.111767735065;12023.338716370683;12024.45889934955;12035.75928481443;12062.095342849321;12078.456469458895;11788.115891308185;11917.463714132884;11933.28400295985;11966.700832479257;12041.879581204252;5397.62360598045;5815.273090863642;5830.766769329264;5885.970570147122;5888.116782482662;942.2697409507687;979.9412035277907;1034.374203606822;1071.9785604288027;1101.24014430043;27786.16641500758;29065.273368165967;29185.708142918298;29505.852470737937;29743;1056.9365838049741;1075.5160177919968;1076.1498416251231;1107.883672214422;1119.9384033878052;13100.279484628247;14083.929580352033;14145.292735363191;14329.35517901697;14453.710925781426;452237;497885;498119;502317;502932;45809.7709511457;46197.30704039442;46426;46452;46656.300155497695;1390.506370238571;1438.9495668162092;1444.1407362619434;1459.4235277065711;1468.3465857693438;25344.493110137326;26199.60700589531;26507.33731656724;26515.60226596564;26532;39815.203695926495;39952;40005.99985000398;40055.39916901191;40069.59930400659;439142;440268;440903;440928;445311;22958.77041229633;38575.807120964215;39904;40007;40015.59984400119;46671.599852004496;46722.53277467318;48258.758706206245;49049.509504905924;50457.74771126121;28235.71764282331;32823.67176328302;33000.50499242562;33008.66991330056;33120.1719957003;27644.447111058067;32440.675593243766;32861.83569082235;32902.34195316128;33549.83225083859;38834.41748373833;39689.00777480586;40060;40254.59745402508;40270.79864600658;33370.165745856546;33585;33679;33868.153296167795;35392;21478.892605536872;26313.9474421021;26355.736442636095;27017.91928322845;27387 06:25:38 INFO - PROCESS | 1645 | |2;jquery.html;400.90177906412936;405.57617289932017;418.53751604477054;435.4252386849359;441.8952618453865;20193.596128077446;20694.275700350492;22042.44893877654;22273.888630556823;22341.10635574577;21972.8901355493;23043.654345184794;23936.880315598442;24040.639390409156;24064.879675601598;6513.120728701636;6634.635095069757;6819.079424277708;6869.175698916137;6905.861882762367;6648.202215734119;6805.081314022593;6856.451483881303;6872.140982377203;6873.725050997954;6490.513211509132;6726.192856857184;6796.320367963189;6814.216365118001;6871.106756121697;6519.739210431577;6530.346965303479;6863.862722745517;6878.380945714885;6880.621565813878;6531.37951894574;6590.176227971504;6596;6600.976848588476;6601.70292336846;27085.45829083427;27559.448811023867;27957.440851182862;28003.579946300822;28107.718922810716;3305.019305019305;3381.205416727067;3395.6774106459848;3397.83010849459;3439.9656003439895;16937.322507099823;16979.49061528156;17110.657786844193;17757.556061098447;17813.554661133447;1785.6339450412634;1792.865535084874;2105.357865850915;2112.3979665795173;2139.368886178581;112739;139611;142751.28624356812;143863.28068359697;146146.26926865405;21287.68068478974;22529.43676408103;22872.542549148922;22882;23270.7672923272;35907;36283.4557481638;36296.09259768527;36331.636683633355;36344.45483317752;9804.852927206097;11020.338779673246;11247.775044499227;11294.54821807135;11589.01493373056;7868.881966770503;9198.356115071882;9237.90762092384;9239.168474837297;9386;10809.35143891369;10838.507143357072;10912.236143469881;10989.670309890716;11010.834837477445;3831.582314543581;4474.865754027384;4544.228020907244;4567.81236878414;4570.360149579062;983.9409635421755;1108.872479664849;1133.6712353417581;1143.1426430177366;1175.976480470403;23425.882870585538;23749.050037998288;23755.049798008233;23873.761262387503;23901.521969560858;981.6564202529057;1025.6769117727893;1142.9942850285695;1145.8796826333282;1167.9299242045504;12020.519179232733;12611.927986121282;12725.55460558876;12829.871701283055;12836.101472896995;815405;825821;830404;832124;840878;125864.37067814785;149794;158097.209513954;160794.58808117887;172860;1244.4710997825887;1266.6136828267468;1306.405585458595;1307.1699470835897;1307.4508706343358;19151.616967660848;19818.702719458932;20870.478238044172;21996.89001554982;22028.669569956142;67886.64226715537;68158.65920670365;68310.975335371;68904.96642550266;69158.61682766417;70326;76832;90225;94465.5276723612;95361.52319238638;7648.73229436967;11052.226344156154;11503.734589195015;11520.193586448868;12054.337011464453;12134.817977730165;12529.561465349034;13130.737385252432;13702.10936289138;13718.0397372183;11501.942490287494;11557.42212889369;11604;11753.529858805552;11808.881911180779;10521.631742888962;10611.045005949502;10643.94678026605;10895.073918716998;10913.781724365626;11490.540378385105;11607.129465290305;11837.348945807718;11956.940215298868;11967.401629918644;8276.83446331082;10297.9485102574;11656.941715291368;11780.70548236301;12077.879221207675;11486.368249746343;11519.76960460803;11617.128715346551;11828.70428239301;11899.524019039141 06:25:38 INFO - PROCESS | 1645 | |3;mootools.html;1329.773938430467;1561.4222737587095;1601.0393763741756;1605.2214675882199;1612.3389410341754;7859.960700196505;10612.946935265323;11209.775804483905;11218.99029087381;11429.942850285757;11031.172662050358;11199.104071674266;11207.991280784729;11213.719657008578;11221.551137954471;2364.704411948502;2399.4121440247163;2408.759124087586;2411.252511721363;2412.3245491262387;2321.442853715105;2382.154335211001;2405.9639105413435;2409.4578719788083;2410.8071354291615;2319.5708793873105;2384.940376490584;2394.724606670229;2394.736578976311;2399.8920048597856;2299.6895419118373;2356.45801465663;2362.1850461590893;2365.1721897335965;2372.0156135203783;2341.7072865891764;2352.3178278299274;2355.599548076831;2368.336865677613;2378.774016468433;10860.728481787939;11002.174836887265;11034.834477482844;11036.779264414667;11104.777904441866;5551.861203469905;5717.228174196472;5728.656280623176;5730.713464326767;5738.8278351649515;3349.871599890304;3471.531343268677;3504.4918486819524;3523.453864650983;3566.197605538759;1185.7095011722097;1189.1093161872664;1229.8155276708565;1232.0266989078707;1256.9120161588692;13514.054016218868;13853.445862165501;14657.41370345185;14795;14802.185879776607;3687.612800655946;4418.204723149864;4563.9087218255445;4566.543345665407;4568.611668008257;11560.6531804046;11690.357030363411;11701.590444334408;11706.70733231676;11713.945744883002;5606.327240731057;5630.23991761107;5632.155176723524;5633.63381380209;5643.068893632505;4998.300237966688;5006.299118123466;5053.570446512088;5054.342935418372;5065.39215294167;3853.1523064925673;3855.8650447234213;3866.516685414323;3935.7835319057726;3957.7229593928723;996.7657600463886;1000.1248907206194;1002.6089824968268;1014.2798612984864;1014.573878970834;920.9585568649427;928.3640706116332;949.6676163342913;955.4315182466421;1007.1489591295343;6355.428011478989;6401.391867772554;6537.901931471032;6721.059051732761;6792.830179245559;1047.9476026198658;1052.5368837711383;1067.3062509368972;1086.6414083352474;1094.414488248783;4287.09970906107;4391.538888416734;4430.645548356189;4496.595306422438;4544.954550454519;13381.996350273768;13540.526081587097;13603.31983400826;13692.52076177329;13844;13706.903447724158;13741.90064794814;13860.376283067286;13876.514321998682;13923.373448194856;8132.634031468599;8765.991910930197;10157.644482443078;10246.282760206863;10361.222908281909;6483.837904052436;6693.1298931138645;6814.557053791487;6834.384905358542;6978;11922.284662920252;12096.608889977766;12177.208481448677;12209.816852747394;12288.877111228774;12351.93824030874;12450.564230251897;12505.624831255076;12589.74820503603;12711.745765084832;9046.05016473261;9081.273498120003;9107.134822191882;9139.360244782802;9208.171264586219;10109.494525273854;10109.949450252701;10190.796184076426;10214.489275536342;10221.693349199535;8688;8698.629117485118;8726.563671816511;8827.735167944971;8837.55812209374;8528.914710852812;8538.91461085406;8543.359248056297;8643.17889800468;8719.869201962103;7655.043119610049;7707.614619268902;7797.805054873674;7802.29779319864;7823.022122234721;5859.296884373903;5893.970530147321;5956.434138756811;5989.790357337472;6058.333583305921;5134.332536770196;5993;6017.338092809699;6147.508199344131;6196.349383314868 06:25:38 INFO - PROCESS | 1645 | |4;prototype.html;316.20632213145;379.77403444950244;381.207089254351;386.17147805142645;388.90329271454476;4428.800703968325;5254.658447200929;5268.631195816294;5270.710110943896;5279.736013199344;5109.64232503724;5146.0702236661255;5150.716710580917;5181.533661970422;5183.559397451221;3479.7912125272564;4162.272656847413;4270.935935960948;4301.601979356721;4304.827806887721;3683.650053244937;4302.483701955754;4314.417553630251;4326.701989403182;4347.60871521563;3831.808409579524;4294.892627684318;4298.7205831621;4321.049369138785;4322.481302243735;3679.668829805317;3921.8823435296986;3978.1049263915534;4093.9765058735316;4097.672186225095;3496.965030349689;3806.0589099559147;3858.758642740084;3867.7872717000555;3968.646493712837;4142.875713728593;4610.76946152691;4970.980948905466;4983.401991760976;5027.924581131286;4606.631469482433;4788.473267940523;4939.135651261051;4974.204127339608;4980.800767969277;5133.563647090002;5246.475352464761;5304.912492938938;5475.4524547544925;5549.805756798533;3872.0513474199;4081.3061779497557;4354.190083676783;4415.536368681306;4453.51011388747;4953.702777833306;5155.819546315898;5378.327709036371;5507.476789704971;5877.853053673693;3693.8707145250323;4088.2420559159846;4263.8081286342185;4511.436070491189;4512.2999140704305;4261.637760790368;4514.977425112919;4542.295944128666;4625.259958406706;4674.906501869944;4368.737875727466;4443.933340999887;4798.184308667535;4804.663673542888;4934.4572097069295;5417.674939503642;5471.550039239604;5483.587221622473;5496.972515137479;5550.333959924835;1353.8443079046035;1413.3781136299995;1469.7060587882465;1477.4828809916444;1568.7803707480962;1040.6409788622914;1116.2967330581844;1119.9528440907777;1193.8777549103816;1207.2394391533278;4315.6763242756915;4650.233311018086;4987.800487980513;5014.765476267787;5037.6398372439235;5106.336176297058;5202.76587553561;5433.972830135903;5696.522012924308;5707.1153971134545;4755.476897541268;4955.992822906656;4988.9501104988485;5019.598432125421;5028.74856257193;2614.281072704983;2791.748742613134;2896.739293463598;2928.8828446862312;3016.3062495626127;4990.951900101019;5692.48767610917;5864.9413505864395;5901.2821922189605;5923.289205295392;5046.940142570102;5545.72271386429;5639.548836093103;5806.732051941819;5847.356790753014;4934.679245849008;5033.65934840301;5308.513616187473;5440.945590544044;5744.368119506935;5191.941663576814;5349.732513374238;5512.80705175317;5668.319801623831;5677.290338707661;5036.143855544639;5106.591472682251;5139.871503212449;5604.85987850307;5606.439356064361;5424.593155513273;5478.233047373451;5556.388647292401;5581.888362232651;5895;4199.992001919579;4221.788910554522;4461.799219035216;4474.306482495218;4481.327800829901;4815;4819.59033482158;5189.636725429335;5324.281222034976;5587.860303492445;5026.974865125651;5105;5407.512933943197;5510.338759348904;5532.253145825262;4622.214223581932;4972.676776009548;5115.386153661584;5321.920171197513;5487.478689524489;4857.514248575114;5007.949920500749;5070.011347787146;5097.2608971698155;5417.431169727122;4551.180787458289;4653.348531205566;5288.867778305573;5327.573794096385;5526.806561770319;4801.711897286034;5061.949380506295;5349.679019258857;5360.58773551911;5376.327959005124 06:25:38 INFO - PROCESS | 1645 | |5;yui.html;725.2167658928355;751.8082888863339;768.8428914483701;817.4359691812649;837.9203975622315;15994.280257388416;16380.42668506604;16835.66328673426;17377.826221737778;17389.39137130201;15364.308606112723;15697.979631323957;15857.36570537177;16062.03627782334;16089;977.4672803322206;999.5452069308484;1007.2697294461493;1007.848822676597;1008.3193844155165;965.2374624047029;998.6404894238068;1009.7929924365524;1011.5801942193981;1014.077189757321;1059.284982636721;1064.2231171244996;1065.4353192807823;1066.4347895615335;1066.4827558634095;1048.397171626314;1057.8994995475418;1058.560697310615;1062.5006247063907;1064.138048180968;792.2157064506155;795.7493389582321;796.80079980005;798.6446031515977;799.0371602219312;16492.83507164937;16501.257443415074;16673.91619544738;17364.218610162574;17533.298668053263;14860.554183374408;15024.699506009927;15198.98222876006;15211.554902284266;15251.466198682992;9990.400575965465;11702.414879256003;11836.88163118375;11883.58407455735;11909.10681698876;3634.23681026982;3647.74465787395;3754.981225093857;3849.191669749356;3862.478565393664;108719;111314.44342778315;112772;114210;116453.41773291164;11553.711157220969;11567.305961642329;11581.957623813898;11783.823242651368;12340.444679989421;23176.53646927052;25108.24675259745;26032.479350413265;26277.08030218933;26306.605400919;8147.83704325922;8270.503769773712;8395.328373730086;8412.915870841336;8413.537255451009;4378.708776240023;4426.22541055314;4558.817647294138;4587.266037434061;4735.763211839394;3629.691476224498;3690.3910854708674;3724.1434470072036;3725.6460636239512;3742.7005839533317;653.4184575727606;657.1293036726261;659.6174218953091;668.6589839181955;671.2582596231094;676.7090151234918;679.49038221334;696.169075331874;704.2077662629541;720.3320651663122;10176.643817466353;10304.175665946706;10324.799540106917;10370.844437333446;10696.572137114428;668.9197296324472;691.9724209548816;694.4062826283455;702.8383471801412;707.2397173039004;4785.760711964388;5055.368078990126;5060.898782024413;5061.240813877947;5064.797408103635;101004.98995009955;104157;105066.42400364;106884.39673404905;107266.46366768116;76835;79789.4042119166;80330;80664.59667701623;81227;68763;73836.63081684557;74933.25066749372;77114.61442692751;77454;9624.68500081996;9743.853842192515;9753.414795112316;9754.609815607297;9764.755881103029;50352;50717.73205669888;51760.74119629528;53251.733741332595;53680;60431;60901.78196435958;61664.69167654133;64197.67901160464;67340;9638;11363.886361136283;12950.287734174712;13015.414306356426;13174.60476185716;14256.857431425968;14637.85362146365;14721;14795.556133316019;15232.466863659718;13498.52755153565;13534.93232533831;13730.45078196901;14199.929000354932;14420.42318307256;13307.933460332637;13604.115732477358;13604.727905442034;13937.651558711113;14113.506027288995;12573.308468034345;13248.830089547222;13359.398827053;13393.933030334785;13652.317384130953;9464.290178236522;9997.900020999698;10011.749706257111;10237.436940968319;10427.895721042692;11208.943955280172;11404.543818247508;11494.655160345203;11526.88473115258;11604.187706860777 06:25:38 INFO - PROCESS | 1645 | __end_tp_report 06:25:38 INFO - PROCESS | 1645 | __start_cc_report 06:25:38 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,371 06:25:38 INFO - PROCESS | 1645 | __end_cc_report 06:25:38 INFO - PROCESS | 1645 | __startTimestamp1496323538249__endTimestamp 06:25:38 INFO - PROCESS | 1645 | 06:25:38 INFO - PROCESS | 1645 | ------- Summary: start ------- 06:25:38 INFO - PROCESS | 1645 | Number of tests: 6 06:25:38 INFO - PROCESS | 1645 | 06:25:38 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5809.97 Median:6107.91 stddev:676.92 (11.1%) stddev-sans-first:653.12 06:25:38 INFO - PROCESS | 1645 | Values: 3447.0 3467.7 3481.7 3482.4 3488.7 5840.7 5914.4 5920.3 5934.2 5938.3 5859.9 5929.1 5944.6 5950.6 5976.1 4639.4 5302.3 5365.1 5374.7 5476.1 4578.2 5383.0 5423.9 5443.9 5451.6 5179.8 5213.1 5421.4 5426.1 5433.4 5320.7 5329.0 5329.0 5329.6 5345.7 5147.5 5253.2 5257.4 5264.4 5335.6 5831.8 5852.5 5854.9 5875.6 5897.2 5663.7 6242.8 6252.4 6281.6 6295.7 6338.6 6357.9 6358.2 6376.2 6380.8 5009.0 6015.2 6046.5 6065.2 6075.0 6259.5 6268.9 6287.2 6340.6 6352.9 6255.0 6274.1 6300.2 6301.8 6324.1 6328.3 6334.2 6343.9 6359.4 6359.7 5262.6 6108.9 6166.9 6228.6 6352.3 6219.1 6252.5 6279.8 6281.8 6309.7 4836.5 4836.9 4847.5 4854.3 4861.9 3943.0 4145.4 4324.8 4332.6 4367.3 5645.4 5758.4 5763.7 5784.6 5814.7 6302.2 6345.6 6349.4 6350.2 6361.0 5820.4 5826.5 5845.0 5846.7 5886.6 5307.9 5317.5 5411.6 5446.2 5511.5 6424.5 6477.9 6479.3 6501.2 6506.6 6323.6 6343.8 6351.0 6358.6 6358.9 6226.8 6291.8 6339.0 6415.6 6466.8 6227.7 6305.3 6312.6 6313.9 6314.2 5334.2 6207.8 6280.5 6294.8 6349.9 6025.7 6079.8 6222.4 6283.3 6322.0 6122.6 6135.7 6147.4 6163.3 6218.7 5262.7 6155.4 6163.4 6172.6 6177.5 6017.3 6107.9 6110.0 6128.3 6238.6 6128.2 6134.9 6142.4 6148.1 6163.5 06:25:38 INFO - PROCESS | 1645 | 06:25:38 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:57101.04 Median:33460.00 stddev:117012.48 (349.7%) stddev-sans-first:117264.29 06:25:38 INFO - PROCESS | 1645 | Values: 766.7 783.4 828.5 896.1 964.5 38543.0 39531.2 41018.6 41048.0 41079.0 38758.8 38939.6 38961.8 39156.4 39249.0 8050.6 8189.5 11005.8 11268.3 11449.3 9444.2 11080.2 11162.7 11164.3 11166.0 11154.1 11194.2 11210.3 11219.0 11359.9 11132.1 11163.4 11177.9 11180.3 11188.4 10888.2 10943.9 10957.1 10964.6 11146.3 34213.3 34284.5 34391.8 34548.0 34610.7 3788.0 3812.4 3812.6 3845.8 3868.6 25155.6 25738.4 26366.2 26389.0 26411.7 2995.5 3158.1 3161.0 3190.2 3315.6 357818.0 358793.0 360660.0 411728.0 422018.8 43673.8 43928.9 43930.3 43961.3 43984.0 45061.5 45276.3 45437.5 45447.3 45873.8 13471.6 13545.8 13611.2 13657.0 13665.1 12023.3 12024.5 12035.8 12062.1 12078.5 11788.1 11917.5 11933.3 11966.7 12041.9 5397.6 5815.3 5830.8 5886.0 5888.1 942.3 979.9 1034.4 1072.0 1101.2 27786.2 29065.3 29185.7 29505.9 29743.0 1056.9 1075.5 1076.1 1107.9 1119.9 13100.3 14083.9 14145.3 14329.4 14453.7 452237.0 497885.0 498119.0 502317.0 502932.0 45809.8 46197.3 46426.0 46452.0 46656.3 1390.5 1438.9 1444.1 1459.4 1468.3 25344.5 26199.6 26507.3 26515.6 26532.0 39815.2 39952.0 40006.0 40055.4 40069.6 439142.0 440268.0 440903.0 440928.0 445311.0 22958.8 38575.8 39904.0 40007.0 40015.6 46671.6 46722.5 48258.8 49049.5 50457.7 28235.7 32823.7 33000.5 33008.7 33120.2 27644.4 32440.7 32861.8 32902.3 33549.8 38834.4 39689.0 40060.0 40254.6 40270.8 33370.2 33585.0 33679.0 33868.2 35392.0 21478.9 26313.9 26355.7 27017.9 27387.0 06:25:38 INFO - PROCESS | 1645 | 06:25:38 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:45496.29 Median:22307.50 stddev:137218.20 (615.1%) stddev-sans-first:137561.35 06:25:38 INFO - PROCESS | 1645 | Values: 400.9 405.6 418.5 435.4 441.9 20193.6 20694.3 22042.4 22273.9 22341.1 21972.9 23043.7 23936.9 24040.6 24064.9 6513.1 6634.6 6819.1 6869.2 6905.9 6648.2 6805.1 6856.5 6872.1 6873.7 6490.5 6726.2 6796.3 6814.2 6871.1 6519.7 6530.3 6863.9 6878.4 6880.6 6531.4 6590.2 6596.0 6601.0 6601.7 27085.5 27559.4 27957.4 28003.6 28107.7 3305.0 3381.2 3395.7 3397.8 3440.0 16937.3 16979.5 17110.7 17757.6 17813.6 1785.6 1792.9 2105.4 2112.4 2139.4 112739.0 139611.0 142751.3 143863.3 146146.3 21287.7 22529.4 22872.5 22882.0 23270.8 35907.0 36283.5 36296.1 36331.6 36344.5 9804.9 11020.3 11247.8 11294.5 11589.0 7868.9 9198.4 9237.9 9239.2 9386.0 10809.4 10838.5 10912.2 10989.7 11010.8 3831.6 4474.9 4544.2 4567.8 4570.4 983.9 1108.9 1133.7 1143.1 1176.0 23425.9 23749.1 23755.0 23873.8 23901.5 981.7 1025.7 1143.0 1145.9 1167.9 12020.5 12611.9 12725.6 12829.9 12836.1 815405.0 825821.0 830404.0 832124.0 840878.0 125864.4 149794.0 158097.2 160794.6 172860.0 1244.5 1266.6 1306.4 1307.2 1307.5 19151.6 19818.7 20870.5 21996.9 22028.7 67886.6 68158.7 68311.0 68905.0 69158.6 70326.0 76832.0 90225.0 94465.5 95361.5 7648.7 11052.2 11503.7 11520.2 12054.3 12134.8 12529.6 13130.7 13702.1 13718.0 11501.9 11557.4 11604.0 11753.5 11808.9 10521.6 10611.0 10643.9 10895.1 10913.8 11490.5 11607.1 11837.3 11956.9 11967.4 8276.8 10297.9 11656.9 11780.7 12077.9 11486.4 11519.8 11617.1 11828.7 11899.5 06:25:38 INFO - PROCESS | 1645 | 06:25:38 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:6667.50 Median:2402.93 stddev:4155.22 (172.9%) stddev-sans-first:4147.52 06:25:38 INFO - PROCESS | 1645 | Values: 1329.8 1561.4 1601.0 1605.2 1612.3 7860.0 10612.9 11209.8 11219.0 11429.9 11031.2 11199.1 11208.0 11213.7 11221.6 2364.7 2399.4 2408.8 2411.3 2412.3 2321.4 2382.2 2406.0 2409.5 2410.8 2319.6 2384.9 2394.7 2394.7 2399.9 2299.7 2356.5 2362.2 2365.2 2372.0 2341.7 2352.3 2355.6 2368.3 2378.8 10860.7 11002.2 11034.8 11036.8 11104.8 5551.9 5717.2 5728.7 5730.7 5738.8 3349.9 3471.5 3504.5 3523.5 3566.2 1185.7 1189.1 1229.8 1232.0 1256.9 13514.1 13853.4 14657.4 14795.0 14802.2 3687.6 4418.2 4563.9 4566.5 4568.6 11560.7 11690.4 11701.6 11706.7 11713.9 5606.3 5630.2 5632.2 5633.6 5643.1 4998.3 5006.3 5053.6 5054.3 5065.4 3853.2 3855.9 3866.5 3935.8 3957.7 996.8 1000.1 1002.6 1014.3 1014.6 921.0 928.4 949.7 955.4 1007.1 6355.4 6401.4 6537.9 6721.1 6792.8 1047.9 1052.5 1067.3 1086.6 1094.4 4287.1 4391.5 4430.6 4496.6 4545.0 13382.0 13540.5 13603.3 13692.5 13844.0 13706.9 13741.9 13860.4 13876.5 13923.4 8132.6 8766.0 10157.6 10246.3 10361.2 6483.8 6693.1 6814.6 6834.4 6978.0 11922.3 12096.6 12177.2 12209.8 12288.9 12351.9 12450.6 12505.6 12589.7 12711.7 9046.1 9081.3 9107.1 9139.4 9208.2 10109.5 10109.9 10190.8 10214.5 10221.7 8688.0 8698.6 8726.6 8827.7 8837.6 8528.9 8538.9 8543.4 8643.2 8719.9 7655.0 7707.6 7797.8 7802.3 7823.0 5859.3 5894.0 5956.4 5989.8 6058.3 5134.3 5993.0 6017.3 6147.5 6196.3 06:25:38 INFO - PROCESS | 1645 | 06:25:38 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4532.17 Median:4973.44 stddev:1258.18 (25.3%) stddev-sans-first:1221.26 06:25:38 INFO - PROCESS | 1645 | Values: 316.2 379.8 381.2 386.2 388.9 4428.8 5254.7 5268.6 5270.7 5279.7 5109.6 5146.1 5150.7 5181.5 5183.6 3479.8 4162.3 4270.9 4301.6 4304.8 3683.7 4302.5 4314.4 4326.7 4347.6 3831.8 4294.9 4298.7 4321.0 4322.5 3679.7 3921.9 3978.1 4094.0 4097.7 3497.0 3806.1 3858.8 3867.8 3968.6 4142.9 4610.8 4971.0 4983.4 5027.9 4606.6 4788.5 4939.1 4974.2 4980.8 5133.6 5246.5 5304.9 5475.5 5549.8 3872.1 4081.3 4354.2 4415.5 4453.5 4953.7 5155.8 5378.3 5507.5 5877.9 3693.9 4088.2 4263.8 4511.4 4512.3 4261.6 4515.0 4542.3 4625.3 4674.9 4368.7 4443.9 4798.2 4804.7 4934.5 5417.7 5471.6 5483.6 5497.0 5550.3 1353.8 1413.4 1469.7 1477.5 1568.8 1040.6 1116.3 1120.0 1193.9 1207.2 4315.7 4650.2 4987.8 5014.8 5037.6 5106.3 5202.8 5434.0 5696.5 5707.1 4755.5 4956.0 4989.0 5019.6 5028.7 2614.3 2791.7 2896.7 2928.9 3016.3 4991.0 5692.5 5864.9 5901.3 5923.3 5046.9 5545.7 5639.5 5806.7 5847.4 4934.7 5033.7 5308.5 5440.9 5744.4 5191.9 5349.7 5512.8 5668.3 5677.3 5036.1 5106.6 5139.9 5604.9 5606.4 5424.6 5478.2 5556.4 5581.9 5895.0 4200.0 4221.8 4461.8 4474.3 4481.3 4815.0 4819.6 5189.6 5324.3 5587.9 5027.0 5105.0 5407.5 5510.3 5532.3 4622.2 4972.7 5115.4 5321.9 5487.5 4857.5 5007.9 5070.0 5097.3 5417.4 4551.2 4653.3 5288.9 5327.6 5526.8 4801.7 5061.9 5349.7 5360.6 5376.3 06:25:38 INFO - PROCESS | 1645 | 06:25:38 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:20614.05 Median:17461.35 stddev:29168.29 (167.0%) stddev-sans-first:29211.88 06:25:38 INFO - PROCESS | 1645 | Values: 725.2 751.8 768.8 817.4 837.9 15994.3 16380.4 16835.7 17377.8 17389.4 15364.3 15698.0 15857.4 16062.0 16089.0 977.5 999.5 1007.3 1007.8 1008.3 965.2 998.6 1009.8 1011.6 1014.1 1059.3 1064.2 1065.4 1066.4 1066.5 1048.4 1057.9 1058.6 1062.5 1064.1 792.2 795.7 796.8 798.6 799.0 16492.8 16501.3 16673.9 17364.2 17533.3 14860.6 15024.7 15199.0 15211.6 15251.5 9990.4 11702.4 11836.9 11883.6 11909.1 3634.2 3647.7 3755.0 3849.2 3862.5 108719.0 111314.4 112772.0 114210.0 116453.4 11553.7 11567.3 11582.0 11783.8 12340.4 23176.5 25108.2 26032.5 26277.1 26306.6 8147.8 8270.5 8395.3 8412.9 8413.5 4378.7 4426.2 4558.8 4587.3 4735.8 3629.7 3690.4 3724.1 3725.6 3742.7 653.4 657.1 659.6 668.7 671.3 676.7 679.5 696.2 704.2 720.3 10176.6 10304.2 10324.8 10370.8 10696.6 668.9 692.0 694.4 702.8 707.2 4785.8 5055.4 5060.9 5061.2 5064.8 101005.0 104157.0 105066.4 106884.4 107266.5 76835.0 79789.4 80330.0 80664.6 81227.0 68763.0 73836.6 74933.3 77114.6 77454.0 9624.7 9743.9 9753.4 9754.6 9764.8 50352.0 50717.7 51760.7 53251.7 53680.0 60431.0 60901.8 61664.7 64197.7 67340.0 9638.0 11363.9 12950.3 13015.4 13174.6 14256.9 14637.9 14721.0 14795.6 15232.5 13498.5 13534.9 13730.5 14199.9 14420.4 13307.9 13604.1 13604.7 13937.7 14113.5 12573.3 13248.8 13359.4 13393.9 13652.3 9464.3 9997.9 10011.7 10237.4 10427.9 11208.9 11404.5 11494.7 11526.9 11604.2 06:25:38 INFO - PROCESS | 1645 | -------- Summary: end -------- 06:25:38 INFO - PROCESS | 1645 | 06:25:38 INFO - TEST-INFO | 1645: exit 0 06:25:38 INFO - TEST-OK | dromaeo_css | took 1115881ms 06:25:38 INFO - TEST-START | kraken 06:25:38 INFO - operating with platform_type : mac_ 06:25:38 INFO - Initialising browser for kraken test... 06:25:38 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmpqH2KmP/profile http://localhost:49228/getInfo.html) 06:25:41 INFO - PROCESS | 1652 | 2017-06-01 06:25:41.218 plugin-container[1654:10301] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8643, name = 'com.apple.tsm.portname' 06:25:41 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:25:41 INFO - PROCESS | 1652 | 2017-06-01 06:25:41.219 plugin-container[1654:10301] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 06:25:41 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:25:41 INFO - PROCESS | 1652 | 2017-06-01 06:25:41.219 plugin-container[1654:10301] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:25:41 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 06:25:41 INFO - PROCESS | 1652 | colorDepth:24 06:25:41 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 06:25:41 INFO - PROCESS | 1652 | __metrics 06:25:41 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 06:25:41 INFO - TEST-INFO | 1652: exit 0 06:25:41 INFO - Browser initialized. 06:25:41 INFO - Running cycle 1/1 for kraken test... 06:25:41 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/T/tmpqH2KmP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:25:44 INFO - PROCESS | 1655 | 2017-06-01 06:25:44.254 plugin-container[1656:10433] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8647, name = 'com.apple.tsm.portname' 06:25:44 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:25:44 INFO - PROCESS | 1655 | 2017-06-01 06:25:44.254 plugin-container[1656:10433] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4613, name = 'com.apple.CFPasteboardClient' 06:25:44 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:25:44 INFO - PROCESS | 1655 | 2017-06-01 06:25:44.254 plugin-container[1656:10433] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:25:54 INFO - PROCESS | 1655 | RSS: Main: 203091968 06:25:54 INFO - PROCESS | 1655 | 06:25:57 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 06:25:58 INFO - PROCESS | 1655 | RSS: Main: 217174016 06:25:58 INFO - PROCESS | 1655 | 06:26:00 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 06:26:01 INFO - PROCESS | 1655 | RSS: Main: 224616448 06:26:01 INFO - PROCESS | 1655 | 06:26:05 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 06:26:05 INFO - PROCESS | 1655 | RSS: Main: 228126720 06:26:05 INFO - PROCESS | 1655 | 06:26:07 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 06:26:08 INFO - PROCESS | 1655 | RSS: Main: 230039552 06:26:08 INFO - PROCESS | 1655 | 06:26:10 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:26:10 INFO - PROCESS | 1655 | RSS: Main: 229478400 06:26:10 INFO - PROCESS | 1655 | 06:26:13 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 06:26:14 INFO - PROCESS | 1655 | RSS: Main: 233320448 06:26:14 INFO - PROCESS | 1655 | 06:26:17 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 06:26:18 INFO - PROCESS | 1655 | RSS: Main: 233046016 06:26:18 INFO - PROCESS | 1655 | 06:26:21 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 06:26:22 INFO - PROCESS | 1655 | RSS: Main: 233418752 06:26:22 INFO - PROCESS | 1655 | 06:26:23 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:26:24 INFO - PROCESS | 1655 | RSS: Main: 232837120 06:26:24 INFO - PROCESS | 1655 | 06:26:26 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:26:26 INFO - PROCESS | 1655 | RSS: Main: 233332736 06:26:26 INFO - PROCESS | 1655 | 06:26:29 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:26:29 INFO - PROCESS | 1655 | RSS: Main: 232951808 06:26:29 INFO - PROCESS | 1655 | 06:26:32 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:26:33 INFO - PROCESS | 1655 | RSS: Main: 233250816 06:26:33 INFO - PROCESS | 1655 | 06:26:36 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:26:37 INFO - PROCESS | 1655 | RSS: Main: 232984576 06:26:37 INFO - PROCESS | 1655 | 06:26:38 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 06:26:39 INFO - PROCESS | 1655 | RSS: Main: 234954752 06:26:39 INFO - PROCESS | 1655 | 06:26:39 INFO - PROCESS | 1655 | __start_tp_report 06:26:39 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 06:26:39 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 06:26:39 INFO - PROCESS | 1655 | |i|pagename|runs| 06:26:39 INFO - PROCESS | 1655 | |0;ai-astar;109;119;103;181;178;184;121;120;133;113 06:26:39 INFO - PROCESS | 1655 | |1;audio-beat-detection;143;99;139;178;146;113;115;115;132;112 06:26:39 INFO - PROCESS | 1655 | |2;audio-dft;258;264;199;194;200;197;237;223;198;198 06:26:39 INFO - PROCESS | 1655 | |3;audio-fft;79;62;63;86;61;58;71;61;76;63 06:26:39 INFO - PROCESS | 1655 | |4;audio-oscillator;90;71;86;72;72;70;82;71;73;84 06:26:39 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;88;97;89;90;115;89;133;136;90;90 06:26:39 INFO - PROCESS | 1655 | |6;imaging-darkroom;121;154;99;122;154;99;97;98;105;102 06:26:39 INFO - PROCESS | 1655 | |7;imaging-desaturate;129;142;91;90;90;91;114;91;88;90 06:26:39 INFO - PROCESS | 1655 | |8;json-parse-financial;63;71;64;67;71;70;69;70;70;72 06:26:39 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;56;66;59;82;62;59;58;60;59;60 06:26:39 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;87;83;84;86;85;83;88;88;90;94 06:26:39 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;138;138;136;138;148;150;153;155;183;142 06:26:39 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;166;160;221;224;176;166;166;168;168;171 06:26:39 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;64;66;65;66;67;67;65;64;67;59 06:26:39 INFO - PROCESS | 1655 | __end_tp_report 06:26:39 INFO - PROCESS | 1655 | __start_cc_report 06:26:39 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1013 06:26:39 INFO - PROCESS | 1655 | __end_cc_report 06:26:39 INFO - PROCESS | 1655 | __startTimestamp1496323599024__endTimestamp 06:26:39 INFO - PROCESS | 1655 | 06:26:39 INFO - PROCESS | 1655 | ------- Summary: start ------- 06:26:39 INFO - PROCESS | 1655 | Number of tests: 14 06:26:39 INFO - PROCESS | 1655 | 06:26:39 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:136.10 Median:127.00 stddev:32.01 (25.2%) stddev-sans-first:32.41 06:26:39 INFO - PROCESS | 1655 | Values: 109.0 119.0 103.0 181.0 178.0 184.0 121.0 120.0 133.0 113.0 06:26:39 INFO - PROCESS | 1655 | 06:26:39 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:141.00 stddev:23.17 (16.4%) stddev-sans-first:24.03 06:26:39 INFO - PROCESS | 1655 | Values: 143.0 99.0 139.0 178.0 146.0 113.0 115.0 115.0 132.0 112.0 06:26:39 INFO - PROCESS | 1655 | 06:26:39 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:216.80 Median:211.50 stddev:27.06 (12.8%) stddev-sans-first:24.25 06:26:39 INFO - PROCESS | 1655 | Values: 258.0 264.0 199.0 194.0 200.0 197.0 237.0 223.0 198.0 198.0 06:26:39 INFO - PROCESS | 1655 | 06:26:39 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:68.00 Median:67.00 stddev:9.44 (14.1%) stddev-sans-first:9.13 06:26:39 INFO - PROCESS | 1655 | Values: 79.0 62.0 63.0 86.0 61.0 58.0 71.0 61.0 76.0 63.0 06:26:39 INFO - PROCESS | 1655 | 06:26:39 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:77.50 stddev:7.53 (9.7%) stddev-sans-first:6.38 06:26:39 INFO - PROCESS | 1655 | Values: 90.0 71.0 86.0 72.0 72.0 70.0 82.0 71.0 73.0 84.0 06:26:39 INFO - PROCESS | 1655 | 06:26:39 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:101.70 Median:89.50 stddev:19.08 (21.3%) stddev-sans-first:19.58 06:26:39 INFO - PROCESS | 1655 | Values: 88.0 97.0 89.0 90.0 115.0 89.0 133.0 136.0 90.0 90.0 06:26:39 INFO - PROCESS | 1655 | 06:26:39 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:115.10 Median:125.50 stddev:22.41 (17.9%) stddev-sans-first:23.67 06:26:39 INFO - PROCESS | 1655 | Values: 121.0 154.0 99.0 122.0 154.0 99.0 97.0 98.0 105.0 102.0 06:26:39 INFO - PROCESS | 1655 | 06:26:39 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:101.60 Median:90.00 stddev:19.61 (21.8%) stddev-sans-first:18.13 06:26:39 INFO - PROCESS | 1655 | Values: 129.0 142.0 91.0 90.0 90.0 91.0 114.0 91.0 88.0 90.0 06:26:39 INFO - PROCESS | 1655 | 06:26:39 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:68.70 Median:70.00 stddev:3.06 (4.4%) stddev-sans-first:2.45 06:26:39 INFO - PROCESS | 1655 | Values: 63.0 71.0 64.0 67.0 71.0 70.0 69.0 70.0 70.0 72.0 06:26:39 INFO - PROCESS | 1655 | 06:26:39 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:62.10 Median:60.00 stddev:7.48 (12.5%) stddev-sans-first:7.60 06:26:39 INFO - PROCESS | 1655 | Values: 56.0 66.0 59.0 82.0 62.0 59.0 58.0 60.0 59.0 60.0 06:26:39 INFO - PROCESS | 1655 | 06:26:39 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:87.50 stddev:3.43 (3.9%) stddev-sans-first:3.63 06:26:39 INFO - PROCESS | 1655 | Values: 87.0 83.0 84.0 86.0 85.0 83.0 88.0 88.0 90.0 94.0 06:26:39 INFO - PROCESS | 1655 | 06:26:39 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:148.10 Median:149.00 stddev:14.07 (9.4%) stddev-sans-first:14.45 06:26:39 INFO - PROCESS | 1655 | Values: 138.0 138.0 136.0 138.0 148.0 150.0 153.0 155.0 183.0 142.0 06:26:39 INFO - PROCESS | 1655 | 06:26:39 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.60 Median:169.50 stddev:23.50 (13.9%) stddev-sans-first:24.48 06:26:39 INFO - PROCESS | 1655 | Values: 166.0 160.0 221.0 224.0 176.0 166.0 166.0 168.0 168.0 171.0 06:26:39 INFO - PROCESS | 1655 | 06:26:39 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.00 Median:66.00 stddev:2.40 (3.6%) stddev-sans-first:2.52 06:26:39 INFO - PROCESS | 1655 | Values: 64.0 66.0 65.0 66.0 67.0 67.0 65.0 64.0 67.0 59.0 06:26:39 INFO - PROCESS | 1655 | -------- Summary: end -------- 06:26:39 INFO - PROCESS | 1655 | 06:26:39 INFO - TEST-INFO | 1655: exit 0 06:26:39 INFO - TEST-OK | kraken | took 61072ms 06:26:39 INFO - SUITE-END | took 1176s 06:26:40 INFO - Completed test suite (00:19:37) 06:26:40 INFO - javascript benchmark 06:26:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7763.820598502772, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3446.9827650861744, 3467.7399195060375, 3481.6518348165173, 3482.4079906415905, 3488.738344624154, 5840.737166827493, 5914.438128377803, 5920.259967504062, 5934.1692163097205, 5938.287405511339, 5859.8535036624, 5929.140274660172, 5944.554158438113, 5950.58345915785, 5976.133460648214, 4639.443266807988, 5302.310699609065, 5365.08793505105, 5374.731263436832, 5476.123820188771, 4578.221702310615, 5382.977234325485, 5423.945760542383, 5443.863903402407, 5451.618386712932, 5179.818706345279, 5213.113770658978, 5421.430749771276, 5426.104692725696, 5433.375161856397, 5320.654157479771, 5328.960852633738, 5329.0, 5329.600279979017, 5345.65253258537, 5147.48525147486, 5253.212018197263, 5257.421683614799, 5264.368275806889, 5335.573154147698, 5831.825045248607, 5852.531797456193, 5854.912176317359, 5875.559333050039, 5897.203877476529, 5663.7451314690525, 6242.750289988395, 6252.406021427957, 6281.623102613858, 6295.653739044351, 6338.619682819046, 6357.904631430532, 6358.237011558643, 6376.234851817765, 6380.840478988062, 5009.0232404681465, 6015.157877897098, 6046.546509011798, 6065.181200537959, 6074.969625151934, 6259.499240060784, 6268.874622507525, 6287.1512345832725, 6340.619562826245, 6352.872942541123, 6255.0, 6274.05889116627, 6300.21247344082, 6301.779437719658, 6324.08300796382, 6328.335524769925, 6334.1765570475545, 6343.904841427382, 6359.395857393541, 6359.650219237987, 5262.578993680497, 6108.888502348253, 6166.907496387557, 6228.595141315891, 6352.333005034498, 6219.129321894939, 6252.499800015988, 6279.780207692709, 6281.842953926189, 6309.652969086745, 4836.540528649843, 4836.927446088312, 4847.5394837490385, 4854.344663470457, 4861.9027619447415, 3942.994536393259, 4145.398917157056, 4324.827006919688, 4332.610065094157, 4367.344898265285, 5645.407232240637, 5758.3665796762325, 5763.6541807491685, 5784.623999440023, 5814.651120932757, 6302.212223472066, 6345.619262844244, 6349.365063493614, 6350.174477317919, 6361.0, 5820.417958204146, 5826.504747096459, 5844.970775146097, 5846.6784326862435, 5886.646801191942, 5307.946920530822, 5317.494837990384, 5411.56707463402, 5446.21029950655, 5511.53151982078, 6424.51816113784, 6477.902831457438, 6479.254885688206, 6501.187351581052, 6506.60960342381, 6323.557350985571, 6343.841403964753, 6350.96824515893, 6358.586691865014, 6358.90461643085, 6226.844328891632, 6291.779787707408, 6338.968305158444, 6415.582987105823, 6466.773662921775, 6227.688615569113, 6305.306416294112, 6312.621242725451, 6313.936860631335, 6314.179156709598, 5334.199870019528, 6207.81376558704, 6280.497560195265, 6294.8111556653375, 6349.904751428639, 6025.698715064142, 6079.756809727562, 6222.439980401605, 6283.3088360281245, 6322.0200868865395, 6122.550631083031, 6135.692037377249, 6147.415995480422, 6163.260408750979, 6218.689065546795, 5262.710550919736, 6155.35368786271, 6163.35284795108, 6172.567920245539, 6177.505799535937, 6017.2779266488305, 6107.9083813741945, 6109.95128740435, 6128.295246046761, 6238.594491358047, 6128.228138398679, 6134.938650613437, 6142.402975226562, 6148.139260503623, 6163.476104530986], "value": 5766.330373435509, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [766.6511737159592, 783.4280974888331, 828.515318538655, 896.1128482802028, 964.4502633498904, 38543.036424089376, 39531.209375812505, 41018.58981410185, 41047.973800654996, 41079.0, 38758.837234883, 38939.610603893954, 38961.805190974075, 39156.412653810214, 39249.0, 8050.637721302526, 8189.467684600511, 11005.834912476279, 11268.267562608404, 11449.25579837312, 9444.244460443148, 11080.224384293102, 11162.665120046371, 11164.330140191614, 11166.0, 11154.10767138631, 11194.216404851664, 11210.27133236337, 11219.0, 11359.886401136007, 11132.053775429058, 11163.386013769239, 11177.888221117848, 11180.329180249211, 11188.44057797115, 10888.183386246063, 10943.890561094368, 10957.06864916475, 10964.616238431616, 11146.331220126738, 34213.31573368519, 34284.48573271403, 34391.82804085963, 34548.0, 34610.65389346099, 3787.981060094682, 3812.3519001769764, 3812.6377994090794, 3845.750026248285, 3868.613138686137, 25155.62266566003, 25738.356541086436, 26366.209013729618, 26389.0, 26411.67941602912, 2995.475791736459, 3158.1315138336913, 3160.959516664359, 3190.1865024418626, 3315.5689760331243, 357818.0, 358793.0, 360659.9999999974, 411728.0, 422018.77981220104, 43673.78163109164, 43928.901777455816, 43930.34104488435, 43961.34057989133, 43984.0, 45061.549384505735, 45276.3208551872, 45437.54562454399, 45447.318290225674, 45873.77063114663, 13471.595852124256, 13545.796813047813, 13611.183329000292, 13657.0, 13665.111767735065, 12023.338716370683, 12024.45889934955, 12035.75928481443, 12062.095342849321, 12078.456469458895, 11788.115891308185, 11917.463714132884, 11933.28400295985, 11966.700832479257, 12041.879581204252, 5397.62360598045, 5815.273090863642, 5830.766769329264, 5885.970570147122, 5888.116782482662, 942.2697409507687, 979.9412035277907, 1034.374203606822, 1071.9785604288027, 1101.24014430043, 27786.16641500758, 29065.273368165967, 29185.708142918298, 29505.852470737937, 29743.0, 1056.9365838049741, 1075.5160177919968, 1076.1498416251231, 1107.883672214422, 1119.9384033878052, 13100.279484628247, 14083.929580352033, 14145.292735363191, 14329.35517901697, 14453.710925781426, 452237.0, 497885.0, 498119.0, 502317.0, 502932.0, 45809.7709511457, 46197.30704039442, 46426.0, 46452.0, 46656.300155497695, 1390.506370238571, 1438.9495668162092, 1444.1407362619434, 1459.4235277065711, 1468.3465857693438, 25344.493110137326, 26199.60700589531, 26507.33731656724, 26515.60226596564, 26532.0, 39815.203695926495, 39952.0, 40005.99985000398, 40055.39916901191, 40069.59930400659, 439142.0, 440268.0, 440903.0, 440928.0, 445311.0, 22958.77041229633, 38575.807120964215, 39904.0, 40007.0, 40015.59984400119, 46671.599852004496, 46722.53277467318, 48258.758706206245, 49049.509504905924, 50457.74771126121, 28235.71764282331, 32823.67176328302, 33000.50499242562, 33008.66991330056, 33120.1719957003, 27644.447111058067, 32440.675593243766, 32861.83569082235, 32902.34195316128, 33549.83225083859, 38834.41748373833, 39689.00777480586, 40060.0, 40254.59745402508, 40270.79864600658, 33370.165745856546, 33585.0, 33679.0, 33868.153296167795, 35392.0, 21478.892605536872, 26313.9474421021, 26355.736442636095, 27017.91928322845, 27387.0], "value": 18833.553672223832, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.90177906412936, 405.57617289932017, 418.53751604477054, 435.4252386849359, 441.8952618453865, 20193.596128077446, 20694.275700350492, 22042.44893877654, 22273.888630556823, 22341.10635574577, 21972.8901355493, 23043.654345184794, 23936.880315598442, 24040.639390409156, 24064.879675601598, 6513.120728701636, 6634.635095069757, 6819.079424277708, 6869.175698916137, 6905.861882762367, 6648.202215734119, 6805.081314022593, 6856.451483881303, 6872.140982377203, 6873.725050997954, 6490.513211509132, 6726.192856857184, 6796.320367963189, 6814.216365118001, 6871.106756121697, 6519.739210431577, 6530.346965303479, 6863.862722745517, 6878.380945714885, 6880.621565813878, 6531.37951894574, 6590.176227971504, 6596.0, 6600.976848588476, 6601.70292336846, 27085.45829083427, 27559.448811023867, 27957.440851182862, 28003.579946300822, 28107.718922810716, 3305.019305019305, 3381.205416727067, 3395.6774106459848, 3397.83010849459, 3439.9656003439895, 16937.322507099823, 16979.49061528156, 17110.657786844193, 17757.556061098447, 17813.554661133447, 1785.6339450412634, 1792.865535084874, 2105.357865850915, 2112.3979665795173, 2139.368886178581, 112739.0, 139611.0, 142751.28624356812, 143863.28068359697, 146146.26926865405, 21287.68068478974, 22529.43676408103, 22872.542549148922, 22882.0, 23270.7672923272, 35907.0, 36283.4557481638, 36296.09259768527, 36331.636683633355, 36344.45483317752, 9804.852927206097, 11020.338779673246, 11247.775044499227, 11294.54821807135, 11589.01493373056, 7868.881966770503, 9198.356115071882, 9237.90762092384, 9239.168474837297, 9386.0, 10809.35143891369, 10838.507143357072, 10912.236143469881, 10989.670309890716, 11010.834837477445, 3831.582314543581, 4474.865754027384, 4544.228020907244, 4567.81236878414, 4570.360149579062, 983.9409635421755, 1108.872479664849, 1133.6712353417581, 1143.1426430177366, 1175.976480470403, 23425.882870585538, 23749.050037998288, 23755.049798008233, 23873.761262387503, 23901.521969560858, 981.6564202529057, 1025.6769117727893, 1142.9942850285695, 1145.8796826333282, 1167.9299242045504, 12020.519179232733, 12611.927986121282, 12725.55460558876, 12829.871701283055, 12836.101472896995, 815405.0, 825821.0, 830404.0, 832124.0, 840878.0, 125864.37067814785, 149794.0, 158097.209513954, 160794.58808117887, 172860.0, 1244.4710997825887, 1266.6136828267468, 1306.405585458595, 1307.1699470835897, 1307.4508706343358, 19151.616967660848, 19818.702719458932, 20870.478238044172, 21996.89001554982, 22028.669569956142, 67886.64226715537, 68158.65920670365, 68310.975335371, 68904.96642550266, 69158.61682766417, 70326.0, 76832.0, 90225.0, 94465.5276723612, 95361.52319238638, 7648.73229436967, 11052.226344156154, 11503.734589195015, 11520.193586448868, 12054.337011464453, 12134.817977730165, 12529.561465349034, 13130.737385252432, 13702.10936289138, 13718.0397372183, 11501.942490287494, 11557.42212889369, 11604.0, 11753.529858805552, 11808.881911180779, 10521.631742888962, 10611.045005949502, 10643.94678026605, 10895.073918716998, 10913.781724365626, 11490.540378385105, 11607.129465290305, 11837.348945807718, 11956.940215298868, 11967.401629918644, 8276.83446331082, 10297.9485102574, 11656.941715291368, 11780.70548236301, 12077.879221207675, 11486.368249746343, 11519.76960460803, 11617.128715346551, 11828.70428239301, 11899.524019039141], "value": 12095.130191628554, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1329.773938430467, 1561.4222737587095, 1601.0393763741756, 1605.2214675882199, 1612.3389410341754, 7859.960700196505, 10612.946935265323, 11209.775804483905, 11218.99029087381, 11429.942850285757, 11031.172662050358, 11199.104071674266, 11207.991280784729, 11213.719657008578, 11221.551137954471, 2364.704411948502, 2399.4121440247163, 2408.759124087586, 2411.252511721363, 2412.3245491262387, 2321.442853715105, 2382.154335211001, 2405.9639105413435, 2409.4578719788083, 2410.8071354291615, 2319.5708793873105, 2384.940376490584, 2394.724606670229, 2394.736578976311, 2399.8920048597856, 2299.6895419118373, 2356.45801465663, 2362.1850461590893, 2365.1721897335965, 2372.0156135203783, 2341.7072865891764, 2352.3178278299274, 2355.599548076831, 2368.336865677613, 2378.774016468433, 10860.728481787939, 11002.174836887265, 11034.834477482844, 11036.779264414667, 11104.777904441866, 5551.861203469905, 5717.228174196472, 5728.656280623176, 5730.713464326767, 5738.8278351649515, 3349.871599890304, 3471.531343268677, 3504.4918486819524, 3523.453864650983, 3566.197605538759, 1185.7095011722097, 1189.1093161872664, 1229.8155276708565, 1232.0266989078707, 1256.9120161588692, 13514.054016218868, 13853.445862165501, 14657.41370345185, 14795.0, 14802.185879776607, 3687.612800655946, 4418.204723149864, 4563.9087218255445, 4566.543345665407, 4568.611668008257, 11560.6531804046, 11690.357030363411, 11701.590444334408, 11706.70733231676, 11713.945744883002, 5606.327240731057, 5630.23991761107, 5632.155176723524, 5633.63381380209, 5643.068893632505, 4998.300237966688, 5006.299118123466, 5053.570446512088, 5054.342935418372, 5065.39215294167, 3853.1523064925673, 3855.8650447234213, 3866.516685414323, 3935.7835319057726, 3957.7229593928723, 996.7657600463886, 1000.1248907206194, 1002.6089824968268, 1014.2798612984864, 1014.573878970834, 920.9585568649427, 928.3640706116332, 949.6676163342913, 955.4315182466421, 1007.1489591295343, 6355.428011478989, 6401.391867772554, 6537.901931471032, 6721.059051732761, 6792.830179245559, 1047.9476026198658, 1052.5368837711383, 1067.3062509368972, 1086.6414083352474, 1094.414488248783, 4287.09970906107, 4391.538888416734, 4430.645548356189, 4496.595306422438, 4544.954550454519, 13381.996350273768, 13540.526081587097, 13603.31983400826, 13692.52076177329, 13844.0, 13706.903447724158, 13741.90064794814, 13860.376283067286, 13876.514321998682, 13923.373448194856, 8132.634031468599, 8765.991910930197, 10157.644482443078, 10246.282760206863, 10361.222908281909, 6483.837904052436, 6693.1298931138645, 6814.557053791487, 6834.384905358542, 6978.0, 11922.284662920252, 12096.608889977766, 12177.208481448677, 12209.816852747394, 12288.877111228774, 12351.93824030874, 12450.564230251897, 12505.624831255076, 12589.74820503603, 12711.745765084832, 9046.05016473261, 9081.273498120003, 9107.134822191882, 9139.360244782802, 9208.171264586219, 10109.494525273854, 10109.949450252701, 10190.796184076426, 10214.489275536342, 10221.693349199535, 8688.0, 8698.629117485118, 8726.563671816511, 8827.735167944971, 8837.55812209374, 8528.914710852812, 8538.91461085406, 8543.359248056297, 8643.17889800468, 8719.869201962103, 7655.043119610049, 7707.614619268902, 7797.805054873674, 7802.29779319864, 7823.022122234721, 5859.296884373903, 5893.970530147321, 5956.434138756811, 5989.790357337472, 6058.333583305921, 5134.332536770196, 5993.0, 6017.338092809699, 6147.508199344131, 6196.349383314868], "value": 5103.914056156877, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.20632213145, 379.77403444950244, 381.207089254351, 386.17147805142645, 388.90329271454476, 4428.800703968325, 5254.658447200929, 5268.631195816294, 5270.710110943896, 5279.736013199344, 5109.64232503724, 5146.0702236661255, 5150.716710580917, 5181.533661970422, 5183.559397451221, 3479.7912125272564, 4162.272656847413, 4270.935935960948, 4301.601979356721, 4304.827806887721, 3683.650053244937, 4302.483701955754, 4314.417553630251, 4326.701989403182, 4347.60871521563, 3831.808409579524, 4294.892627684318, 4298.7205831621, 4321.049369138785, 4322.481302243735, 3679.668829805317, 3921.8823435296986, 3978.1049263915534, 4093.9765058735316, 4097.672186225095, 3496.965030349689, 3806.0589099559147, 3858.758642740084, 3867.7872717000555, 3968.646493712837, 4142.875713728593, 4610.76946152691, 4970.980948905466, 4983.401991760976, 5027.924581131286, 4606.631469482433, 4788.473267940523, 4939.135651261051, 4974.204127339608, 4980.800767969277, 5133.563647090002, 5246.475352464761, 5304.912492938938, 5475.4524547544925, 5549.805756798533, 3872.0513474199, 4081.3061779497557, 4354.190083676783, 4415.536368681306, 4453.51011388747, 4953.702777833306, 5155.819546315898, 5378.327709036371, 5507.476789704971, 5877.853053673693, 3693.8707145250323, 4088.2420559159846, 4263.8081286342185, 4511.436070491189, 4512.2999140704305, 4261.637760790368, 4514.977425112919, 4542.295944128666, 4625.259958406706, 4674.906501869944, 4368.737875727466, 4443.933340999887, 4798.184308667535, 4804.663673542888, 4934.4572097069295, 5417.674939503642, 5471.550039239604, 5483.587221622473, 5496.972515137479, 5550.333959924835, 1353.8443079046035, 1413.3781136299995, 1469.7060587882465, 1477.4828809916444, 1568.7803707480962, 1040.6409788622914, 1116.2967330581844, 1119.9528440907777, 1193.8777549103816, 1207.2394391533278, 4315.6763242756915, 4650.233311018086, 4987.800487980513, 5014.765476267787, 5037.6398372439235, 5106.336176297058, 5202.76587553561, 5433.972830135903, 5696.522012924308, 5707.1153971134545, 4755.476897541268, 4955.992822906656, 4988.9501104988485, 5019.598432125421, 5028.74856257193, 2614.281072704983, 2791.748742613134, 2896.739293463598, 2928.8828446862312, 3016.3062495626127, 4990.951900101019, 5692.48767610917, 5864.9413505864395, 5901.2821922189605, 5923.289205295392, 5046.940142570102, 5545.72271386429, 5639.548836093103, 5806.732051941819, 5847.356790753014, 4934.679245849008, 5033.65934840301, 5308.513616187473, 5440.945590544044, 5744.368119506935, 5191.941663576814, 5349.732513374238, 5512.80705175317, 5668.319801623831, 5677.290338707661, 5036.143855544639, 5106.591472682251, 5139.871503212449, 5604.85987850307, 5606.439356064361, 5424.593155513273, 5478.233047373451, 5556.388647292401, 5581.888362232651, 5895.0, 4199.992001919579, 4221.788910554522, 4461.799219035216, 4474.306482495218, 4481.327800829901, 4815.0, 4819.59033482158, 5189.636725429335, 5324.281222034976, 5587.860303492445, 5026.974865125651, 5105.0, 5407.512933943197, 5510.338759348904, 5532.253145825262, 4622.214223581932, 4972.676776009548, 5115.386153661584, 5321.920171197513, 5487.478689524489, 4857.514248575114, 5007.949920500749, 5070.011347787146, 5097.2608971698155, 5417.431169727122, 4551.180787458289, 4653.348531205566, 5288.867778305573, 5327.573794096385, 5526.806561770319, 4801.711897286034, 5061.949380506295, 5349.679019258857, 5360.58773551911, 5376.327959005124], "value": 4161.7403292237295, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [725.2167658928355, 751.8082888863339, 768.8428914483701, 817.4359691812649, 837.9203975622315, 15994.280257388416, 16380.42668506604, 16835.66328673426, 17377.826221737778, 17389.39137130201, 15364.308606112723, 15697.979631323957, 15857.36570537177, 16062.03627782334, 16089.0, 977.4672803322206, 999.5452069308484, 1007.2697294461493, 1007.848822676597, 1008.3193844155165, 965.2374624047029, 998.6404894238068, 1009.7929924365524, 1011.5801942193981, 1014.077189757321, 1059.284982636721, 1064.2231171244996, 1065.4353192807823, 1066.4347895615335, 1066.4827558634095, 1048.397171626314, 1057.8994995475418, 1058.560697310615, 1062.5006247063907, 1064.138048180968, 792.2157064506155, 795.7493389582321, 796.80079980005, 798.6446031515977, 799.0371602219312, 16492.83507164937, 16501.257443415074, 16673.91619544738, 17364.218610162574, 17533.298668053263, 14860.554183374408, 15024.699506009927, 15198.98222876006, 15211.554902284266, 15251.466198682992, 9990.400575965465, 11702.414879256003, 11836.88163118375, 11883.58407455735, 11909.10681698876, 3634.23681026982, 3647.74465787395, 3754.981225093857, 3849.191669749356, 3862.478565393664, 108719.0, 111314.44342778315, 112772.0, 114210.0, 116453.41773291164, 11553.711157220969, 11567.305961642329, 11581.957623813898, 11783.823242651368, 12340.444679989421, 23176.53646927052, 25108.24675259745, 26032.479350413265, 26277.08030218933, 26306.605400919, 8147.83704325922, 8270.503769773712, 8395.328373730086, 8412.915870841336, 8413.537255451009, 4378.708776240023, 4426.22541055314, 4558.817647294138, 4587.266037434061, 4735.763211839394, 3629.691476224498, 3690.3910854708674, 3724.1434470072036, 3725.6460636239512, 3742.7005839533317, 653.4184575727606, 657.1293036726261, 659.6174218953091, 668.6589839181955, 671.2582596231094, 676.7090151234918, 679.49038221334, 696.169075331874, 704.2077662629541, 720.3320651663122, 10176.643817466353, 10304.175665946706, 10324.799540106917, 10370.844437333446, 10696.572137114428, 668.9197296324472, 691.9724209548816, 694.4062826283455, 702.8383471801412, 707.2397173039004, 4785.760711964388, 5055.368078990126, 5060.898782024413, 5061.240813877947, 5064.797408103635, 101004.98995009955, 104157.0, 105066.42400364, 106884.39673404905, 107266.46366768116, 76835.0, 79789.4042119166, 80330.0, 80664.59667701623, 81227.0, 68763.0, 73836.63081684557, 74933.25066749372, 77114.61442692751, 77454.0, 9624.68500081996, 9743.853842192515, 9753.414795112316, 9754.609815607297, 9764.755881103029, 50352.0, 50717.73205669888, 51760.74119629528, 53251.733741332595, 53680.0, 60431.0, 60901.78196435958, 61664.69167654133, 64197.67901160464, 67340.0, 9638.0, 11363.886361136283, 12950.287734174712, 13015.414306356426, 13174.60476185716, 14256.857431425968, 14637.85362146365, 14721.0, 14795.556133316019, 15232.466863659718, 13498.52755153565, 13534.93232533831, 13730.45078196901, 14199.929000354932, 14420.42318307256, 13307.933460332637, 13604.115732477358, 13604.727905442034, 13937.651558711113, 14113.506027288995, 12573.308468034345, 13248.830089547222, 13359.398827053, 13393.933030334785, 13652.317384130953, 9464.290178236522, 9997.900020999698, 10011.749706257111, 10237.436940968319, 10427.895721042692, 11208.943955280172, 11404.543818247508, 11494.655160345203, 11526.88473115258, 11604.187706860777], "value": 7848.539609465853, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1554.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 119.0, 103.0, 181.0, 178.0, 184.0, 121.0, 120.0, 133.0, 113.0], "value": 136.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 99.0, 139.0, 178.0, 146.0, 113.0, 115.0, 115.0, 132.0, 112.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [258.0, 264.0, 199.0, 194.0, 200.0, 197.0, 237.0, 223.0, 198.0, 198.0], "value": 216.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 62.0, 63.0, 86.0, 61.0, 58.0, 71.0, 61.0, 76.0, 63.0], "value": 68.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 71.0, 86.0, 72.0, 72.0, 70.0, 82.0, 71.0, 73.0, 84.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 97.0, 89.0, 90.0, 115.0, 89.0, 133.0, 136.0, 90.0, 90.0], "value": 101.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 154.0, 99.0, 122.0, 154.0, 99.0, 97.0, 98.0, 105.0, 102.0], "value": 115.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 142.0, 91.0, 90.0, 90.0, 91.0, 114.0, 91.0, 88.0, 90.0], "value": 101.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 71.0, 64.0, 67.0, 71.0, 70.0, 69.0, 70.0, 70.0, 72.0], "value": 68.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 66.0, 59.0, 82.0, 62.0, 59.0, 58.0, 60.0, 59.0, 60.0], "value": 62.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 84.0, 86.0, 85.0, 83.0, 88.0, 88.0, 90.0, 94.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 138.0, 136.0, 138.0, 148.0, 150.0, 153.0, 155.0, 183.0, 142.0], "value": 148.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 160.0, 221.0, 224.0, 176.0, 166.0, 166.0, 168.0, 168.0, 171.0], "value": 178.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 65.0, 66.0, 67.0, 67.0, 65.0, 64.0, 67.0, 59.0], "value": 65.0, "unit": "score"}]}]} 06:26:40 INFO - Return code: 0 06:26:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:26:40 ERROR - # TBPL SUCCESS # 06:26:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:26:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:26:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:26:40 INFO - 2017-06-01 06:26:40.379 screenresolution[1660:10822] starting screenresolution argv=screenresolution get 06:26:40 INFO - 2017-06-01 06:26:40.382 screenresolution[1660:10822] Display 0: 1600x1200x32@60 06:26:40 INFO - 2017-06-01 06:26:40.388 screenresolution[1661:10825] starting screenresolution argv=screenresolution list 06:26:40 INFO - Available Modes on Display 0 06:26:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:26:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:26:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:26:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:26:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:26:40 INFO - Intel Iris: 06:26:40 INFO - Chipset Model: Intel Iris 06:26:40 INFO - Type: GPU 06:26:40 INFO - Bus: Built-In 06:26:40 INFO - VRAM (Dynamic, Max): 1536 MB 06:26:40 INFO - Vendor: Intel (0x8086) 06:26:40 INFO - Device ID: 0x0a2e 06:26:40 INFO - Revision ID: 0x0009 06:26:40 INFO - Displays: 06:26:40 INFO - Display: 06:26:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:26:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:26:40 INFO - Display Serial Number: 1600x1200 60 06:26:40 INFO - Main Display: Yes 06:26:40 INFO - Mirror: Off 06:26:40 INFO - Online: Yes 06:26:40 INFO - Rotation: Supported 06:26:40 INFO - Return code: 0 06:26:40 INFO - Running post-action listener: _resource_record_post_action 06:26:40 INFO - [mozharness: 2017-06-01 13:26:40.625208Z] Finished run-tests step (success) 06:26:40 INFO - Running post-run listener: _resource_record_post_run 06:26:40 INFO - Total resource usage - Wall time: 1198s; CPU: 55.0%; Read bytes: 34336768; Write bytes: 516521984; Read time: 217; Write time: 1604 06:26:40 INFO - TinderboxPrint: CPU usage
54.7% 06:26:40 INFO - TinderboxPrint: I/O read bytes / time
34,336,768 / 217 06:26:40 INFO - TinderboxPrint: I/O write bytes / time
516,521,984 / 1,604 06:26:40 INFO - TinderboxPrint: CPU idle
2,170.5 (45.3%) 06:26:40 INFO - TinderboxPrint: CPU system
968.4 (20.2%) 06:26:40 INFO - TinderboxPrint: CPU user
1,654.9 (34.5%) 06:26:40 INFO - TinderboxPrint: Swap in / out
239,415,296 / 0 06:26:40 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 202164736; Write bytes: 204683264; Read time: 15504; Write time: 427 06:26:40 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 32497664; Write bytes: 301045760; Read time: 200; Write time: 1141 06:26:41 INFO - Running post-run listener: _upload_blobber_files 06:26:41 INFO - Blob upload gear active. 06:26:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:26:41 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:26:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:26:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:26:41 INFO - (blobuploader) - INFO - Open directory for files ... 06:26:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:26:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:26:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:26:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:26:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:26:42 INFO - (blobuploader) - INFO - Done attempting. 06:26:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:26:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:26:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:26:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:26:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:26:44 INFO - (blobuploader) - INFO - Done attempting. 06:26:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:26:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:26:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:26:45 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:26:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:26:45 INFO - (blobuploader) - INFO - Done attempting. 06:26:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:26:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:26:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:26:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:26:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:26:47 INFO - (blobuploader) - INFO - Done attempting. 06:26:47 INFO - (blobuploader) - INFO - Iteration through files over. 06:26:47 INFO - Return code: 0 06:26:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:26:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:26:47 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1ac7b77654c5a05a02953ab9d95c3ca1572a467f2aa88adeaf0bb38860367ba1de7a8ef30b6d64fe7c2fb3ff6412421c9a347999b8b07e5be61aebc0e1bff6ac", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/43224ddc3fec1c7f34e81ce3fc52514f961ed605e67b649b4d6e13df2899766108c2f3fe918fd886b21aada361a8fd587bf9142d6d7c17d1b713dc14ea5f86e1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bbfb5d46b3a588c29af8e812235dedfa98f882e0558c1325e53b664674066306d4b9d54193301d9cf1dfd350f2b0eb40f3851143c8e4a16a0a55ae96069b6057", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/adbcecb89505dda63e12b2229334519ee2ba1ad6d0e66fe773d5cf2d7e960ec3ae45a1a8d563b31a5417efe51437b608e31642c0e252e9c65b3eebc1310ff573"} 06:26:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:26:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:26:47 INFO - Contents: 06:26:47 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1ac7b77654c5a05a02953ab9d95c3ca1572a467f2aa88adeaf0bb38860367ba1de7a8ef30b6d64fe7c2fb3ff6412421c9a347999b8b07e5be61aebc0e1bff6ac", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/43224ddc3fec1c7f34e81ce3fc52514f961ed605e67b649b4d6e13df2899766108c2f3fe918fd886b21aada361a8fd587bf9142d6d7c17d1b713dc14ea5f86e1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bbfb5d46b3a588c29af8e812235dedfa98f882e0558c1325e53b664674066306d4b9d54193301d9cf1dfd350f2b0eb40f3851143c8e4a16a0a55ae96069b6057", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/adbcecb89505dda63e12b2229334519ee2ba1ad6d0e66fe773d5cf2d7e960ec3ae45a1a8d563b31a5417efe51437b608e31642c0e252e9c65b3eebc1310ff573"} 06:26:47 INFO - Running post-run listener: copy_logs_to_upload_dir 06:26:47 INFO - Copying logs to upload dir... 06:26:47 INFO - mkdir: /builds/slave/test/build/upload/logs 06:26:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.842350 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-06-01 06:26:47.780138) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 06:26:47.784363) ========= 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.Ih0wem7cKz/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.GSiFHXTYjE/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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/1ac7b77654c5a05a02953ab9d95c3ca1572a467f2aa88adeaf0bb38860367ba1de7a8ef30b6d64fe7c2fb3ff6412421c9a347999b8b07e5be61aebc0e1bff6ac", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/43224ddc3fec1c7f34e81ce3fc52514f961ed605e67b649b4d6e13df2899766108c2f3fe918fd886b21aada361a8fd587bf9142d6d7c17d1b713dc14ea5f86e1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bbfb5d46b3a588c29af8e812235dedfa98f882e0558c1325e53b664674066306d4b9d54193301d9cf1dfd350f2b0eb40f3851143c8e4a16a0a55ae96069b6057", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/adbcecb89505dda63e12b2229334519ee2ba1ad6d0e66fe773d5cf2d7e960ec3ae45a1a8d563b31a5417efe51437b608e31642c0e252e9c65b3eebc1310ff573"} build_url:https://queue.taskcluster.net/v1/task/EiqmZYMORsiF98ub23xd2w/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011566 build_url: 'https://queue.taskcluster.net/v1/task/EiqmZYMORsiF98ub23xd2w/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/1ac7b77654c5a05a02953ab9d95c3ca1572a467f2aa88adeaf0bb38860367ba1de7a8ef30b6d64fe7c2fb3ff6412421c9a347999b8b07e5be61aebc0e1bff6ac", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/43224ddc3fec1c7f34e81ce3fc52514f961ed605e67b649b4d6e13df2899766108c2f3fe918fd886b21aada361a8fd587bf9142d6d7c17d1b713dc14ea5f86e1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bbfb5d46b3a588c29af8e812235dedfa98f882e0558c1325e53b664674066306d4b9d54193301d9cf1dfd350f2b0eb40f3851143c8e4a16a0a55ae96069b6057", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/adbcecb89505dda63e12b2229334519ee2ba1ad6d0e66fe773d5cf2d7e960ec3ae45a1a8d563b31a5417efe51437b608e31642c0e252e9c65b3eebc1310ff573"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 06:26:47.818804) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 06:26:47.819187) ========= 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.Ih0wem7cKz/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.GSiFHXTYjE/Listeners TMPDIR=/var/folders/tm/bb1xmxh10h32jsf94bs0tnvh00000w/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.005153 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 06:26:47.867623) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 06:26:47.867944) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 06:26:47.868261) ========= ========= Total master_lag: 0.18 =========