builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0111 starttime: 1495936951.27 results: success (0) buildid: 20170527181716 builduid: 757023d3878f4a088afefb471b08fc50 revision: 8856170020cf83c9cc8da98e187125ca6cdab66b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-27 19:02:31.267021) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-27 19:02:31.267466) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-27 19:02:31.267744) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-27 19:02:31.292406) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 19:02:31.292763) ========= 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.y4vduZselZ/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.f8bHyklbIQ/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-27 19:02:31-- 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% 7.31M=0.002s 2017-05-27 19:02:32 (7.31 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.693297 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 19:02:32.008649) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 19:02:32.008988) ========= 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.y4vduZselZ/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.f8bHyklbIQ/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.022192 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 19:02:32.057401) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-27 19:02:32.057708) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 8856170020cf83c9cc8da98e187125ca6cdab66b --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 8856170020cf83c9cc8da98e187125ca6cdab66b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.y4vduZselZ/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.f8bHyklbIQ/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-27 19:02:32,170 truncating revision to first 12 chars 2017-05-27 19:02:32,170 Setting DEBUG logging. 2017-05-27 19:02:32,170 attempt 1/10 2017-05-27 19:02:32,170 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/8856170020cf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-27 19:02:33,307 unpacking tar archive at: mozilla-beta-8856170020cf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.405593 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-27 19:02:33.507263) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-27 19:02:33.507562) ========= script_repo_revision: 8856170020cf83c9cc8da98e187125ca6cdab66b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-27 19:02:33.507978) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-27 19:02:33.508293) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-27 19:02:33.529417) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-05-27 19:02:33.529675) ========= /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.y4vduZselZ/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.f8bHyklbIQ/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 19:02:33 INFO - MultiFileLogger online at 20170527 19:02:33 in /builds/slave/test 19:02:33 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 19:02:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:02:33 INFO - {'append_to_log': False, 19:02:33 INFO - 'base_work_dir': '/builds/slave/test', 19:02:33 INFO - 'blob_upload_branch': 'Mozilla-Beta', 19:02:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:02:33 INFO - 'branch': 'Mozilla-Beta', 19:02:33 INFO - 'buildbot_json_path': 'buildprops.json', 19:02:33 INFO - 'config_files': ('talos/mac_config.py',), 19:02:33 INFO - 'default_actions': ('clobber', 19:02:33 INFO - 'read-buildbot-config', 19:02:33 INFO - 'download-and-extract', 19:02:33 INFO - 'populate-webroot', 19:02:33 INFO - 'create-virtualenv', 19:02:33 INFO - 'install', 19:02:33 INFO - 'run-tests'), 19:02:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:02:33 INFO - 'download_minidump_stackwalk': True, 19:02:33 INFO - 'download_symbols': 'ondemand', 19:02:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:02:33 INFO - 'tooltool.py': '/tools/tooltool.py', 19:02:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:02:33 INFO - '/tools/misc-python/virtualenv.py')}, 19:02:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:02:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:02:33 INFO - 'gecko_profile': False, 19:02:33 INFO - 'gecko_profile_interval': 0, 19:02:33 INFO - 'installer_path': 'installer.exe', 19:02:33 INFO - 'log_level': 'info', 19:02:33 INFO - 'log_name': 'talos', 19:02:33 INFO - 'log_to_console': True, 19:02:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:02:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:02:33 INFO - 'opt_config_files': (), 19:02:33 INFO - 'pip_index': False, 19:02:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:02:33 INFO - 'cmd': ('bash', 19:02:33 INFO - '-c', 19:02:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:02:33 INFO - 'enabled': True, 19:02:33 INFO - 'halt_on_failure': False, 19:02:33 INFO - 'name': 'check_screen_resolution'},), 19:02:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:02:33 INFO - 'cmd': ('bash', 19:02:33 INFO - '-c', 19:02:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:02:33 INFO - 'enabled': True, 19:02:33 INFO - 'halt_on_failure': False, 19:02:33 INFO - 'name': 'check_screen_resolution'},), 19:02:33 INFO - 'run_cmd_checks_enabled': True, 19:02:33 INFO - 'suite': 'dromaeojs-e10s', 19:02:33 INFO - 'system_bits': '32', 19:02:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:02:33 INFO - 'title': 't-yosemite-r7-0111', 19:02:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:02:33 INFO - 'use_talos_json': True, 19:02:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:02:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:02:33 INFO - 'work_dir': 'build'} 19:02:33 INFO - [mozharness: 2017-05-28 02:02:33.767214Z] Running clobber step. 19:02:33 INFO - Running pre-action listener: _resource_record_pre_action 19:02:33 INFO - Running main action method: clobber 19:02:33 INFO - rmtree: /builds/slave/test/build 19:02:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:02:34 INFO - Running post-action listener: _resource_record_post_action 19:02:34 INFO - [mozharness: 2017-05-28 02:02:34.475911Z] Finished clobber step (success) 19:02:34 INFO - [mozharness: 2017-05-28 02:02:34.476032Z] Running read-buildbot-config step. 19:02:34 INFO - Running pre-action listener: _resource_record_pre_action 19:02:34 INFO - Running main action method: read_buildbot_config 19:02:34 INFO - Using buildbot properties: 19:02:34 INFO - { 19:02:34 INFO - "project": "", 19:02:34 INFO - "product": "firefox", 19:02:34 INFO - "script_repo_revision": "production", 19:02:34 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 19:02:34 INFO - "repository": "", 19:02:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 19:02:34 INFO - "buildid": "20170527181716", 19:02:34 INFO - "pgo_build": "False", 19:02:34 INFO - "basedir": "/builds/slave/test", 19:02:34 INFO - "buildnumber": 7, 19:02:34 INFO - "slavename": "t-yosemite-r7-0111", 19:02:34 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 19:02:34 INFO - "platform": "yosemite_r7", 19:02:34 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 19:02:34 INFO - "branch": "mozilla-beta", 19:02:34 INFO - "repo_path": "releases/mozilla-beta", 19:02:34 INFO - "revision": "8856170020cf83c9cc8da98e187125ca6cdab66b", 19:02:34 INFO - "stage_platform": "macosx64", 19:02:34 INFO - "builduid": "757023d3878f4a088afefb471b08fc50", 19:02:34 INFO - "slavebuilddir": "test" 19:02:34 INFO - } 19:02:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/b3qqt0NHSWW-K-XlEAqkww/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 19:02:34 INFO - Running post-action listener: _resource_record_post_action 19:02:34 INFO - [mozharness: 2017-05-28 02:02:34.479374Z] Finished read-buildbot-config step (success) 19:02:34 INFO - [mozharness: 2017-05-28 02:02:34.479461Z] Running download-and-extract step. 19:02:34 INFO - Running pre-action listener: _resource_record_pre_action 19:02:34 INFO - Running main action method: download_and_extract 19:02:34 INFO - mkdir: /builds/slave/test/build/tests 19:02: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')]} 19:02:34 INFO - trying https://queue.taskcluster.net/v1/task/b3qqt0NHSWW-K-XlEAqkww/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 19:02:34 INFO - Downloading https://queue.taskcluster.net/v1/task/b3qqt0NHSWW-K-XlEAqkww/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 19:02:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b3qqt0NHSWW-K-XlEAqkww/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 19:02:35 INFO - Downloaded 1287 bytes. 19:02:35 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 19:02:35 INFO - Using the following test package requirements: 19:02:35 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:02:35 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 19:02:35 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 19:02:35 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:02:35 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 19:02:35 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:02:35 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 19:02:35 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 19:02:35 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:02:35 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 19:02:35 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 19:02:35 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:02:35 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 19:02:35 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:02:35 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 19:02:35 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:02:35 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 19:02:35 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:02:35 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 19:02:35 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 19:02:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b3qqt0NHSWW-K-XlEAqkww/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 19:02:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b3qqt0NHSWW-K-XlEAqkww/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 19:02:35 INFO - Fetch https://queue.taskcluster.net/v1/task/b3qqt0NHSWW-K-XlEAqkww/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 19:02:37 INFO - Expected file size: 51395413 19:02:37 INFO - Obtained file size: 51395413 19:02:53 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 19:02:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b3qqt0NHSWW-K-XlEAqkww/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 19:02:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b3qqt0NHSWW-K-XlEAqkww/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 19:02:53 INFO - Fetch https://queue.taskcluster.net/v1/task/b3qqt0NHSWW-K-XlEAqkww/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 19:02:55 INFO - Expected file size: 51395413 19:02:55 INFO - Obtained file size: 51395413 19:03:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b3qqt0NHSWW-K-XlEAqkww/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 19:03:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b3qqt0NHSWW-K-XlEAqkww/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 19:03:11 INFO - Fetch https://queue.taskcluster.net/v1/task/b3qqt0NHSWW-K-XlEAqkww/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 19:03:12 INFO - Expected file size: 14404461 19:03:12 INFO - Obtained file size: 14404461 19:03: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')]} 19:03:12 INFO - trying https://queue.taskcluster.net/v1/task/b3qqt0NHSWW-K-XlEAqkww/artifacts/public/build/firefox-54.0.en-US.mac.dmg 19:03:12 INFO - Downloading https://queue.taskcluster.net/v1/task/b3qqt0NHSWW-K-XlEAqkww/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 19:03:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b3qqt0NHSWW-K-XlEAqkww/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 19:03:14 INFO - Downloaded 59647352 bytes. 19:03:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b3qqt0NHSWW-K-XlEAqkww/artifacts/public/build/firefox-54.0.en-US.mac.dmg 19:03:14 INFO - mkdir: /builds/slave/test/properties 19:03:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:03:14 INFO - Writing to file /builds/slave/test/properties/build_url 19:03:14 INFO - Contents: 19:03:14 INFO - build_url:https://queue.taskcluster.net/v1/task/b3qqt0NHSWW-K-XlEAqkww/artifacts/public/build/firefox-54.0.en-US.mac.dmg 19:03:14 INFO - Running post-action listener: _resource_record_post_action 19:03:14 INFO - Running post-action listener: set_extra_try_arguments 19:03:14 INFO - [mozharness: 2017-05-28 02:03:14.791044Z] Finished download-and-extract step (success) 19:03:14 INFO - [mozharness: 2017-05-28 02:03:14.791191Z] Running populate-webroot step. 19:03:14 INFO - Running pre-action listener: _resource_record_pre_action 19:03:14 INFO - Running main action method: populate_webroot 19:03:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:03:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:03:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:03:14 INFO - '--tppagecycles', 19:03:14 INFO - '7'], 19:03:14 INFO - 'tests': ['tsvgm']}}, 19:03:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:03:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:03:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:03:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:03:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:03:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:03:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:03:14 INFO - 'talos_options': ['--disable-e10s'], 19:03:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:03:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:03:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:03:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:03:14 INFO - 'talos_options': ['--disable-e10s'], 19:03:14 INFO - 'tests': ['damp', 'tps']}, 19:03:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:03:14 INFO - 'tests': ['damp', 'tps']}, 19:03:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:03:14 INFO - 'tests': ['dromaeo_dom']}, 19:03:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:03:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:03:14 INFO - 'tests': ['basic_compositor_video']}, 19:03:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:03:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:03:14 INFO - 'tests': ['a11yr', 19:03:14 INFO - 'ts_paint', 19:03:14 INFO - 'tpaint', 19:03:14 INFO - 'sessionrestore', 19:03:14 INFO - 'sessionrestore_no_auto_restore', 19:03:14 INFO - 'tabpaint']}, 19:03:14 INFO - 'other-e10s': {'tests': ['a11yr', 19:03:14 INFO - 'ts_paint', 19:03:14 INFO - 'tpaint', 19:03:14 INFO - 'sessionrestore', 19:03:14 INFO - 'sessionrestore_no_auto_restore', 19:03:14 INFO - 'tabpaint']}, 19:03:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:03:14 INFO - 'ts_paint', 19:03:14 INFO - 'tpaint', 19:03:14 INFO - 'sessionrestore', 19:03:14 INFO - 'sessionrestore_no_auto_restore', 19:03:14 INFO - 'tabpaint']}, 19:03:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:03:14 INFO - 'ts_paint', 19:03:14 INFO - 'tpaint', 19:03:14 INFO - 'sessionrestore', 19:03:14 INFO - 'sessionrestore_no_auto_restore', 19:03:14 INFO - 'tabpaint']}, 19:03:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:03:14 INFO - 'tests': ['a11yr', 19:03:14 INFO - 'ts_paint', 19:03:14 INFO - 'tpaint', 19:03:14 INFO - 'sessionrestore', 19:03:14 INFO - 'sessionrestore_no_auto_restore', 19:03:14 INFO - 'tabpaint']}, 19:03:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:03:14 INFO - 'tests': ['a11yr', 19:03:14 INFO - 'ts_paint', 19:03:14 INFO - 'tpaint', 19:03:14 INFO - 'sessionrestore', 19:03:14 INFO - 'sessionrestore_no_auto_restore', 19:03:14 INFO - 'tabpaint']}, 19:03:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:03:14 INFO - 'tests': ['tsvgx', 19:03:14 INFO - 'tsvgr_opacity', 19:03:14 INFO - 'tart', 19:03:14 INFO - 'tscrollx', 19:03:14 INFO - 'cart', 19:03:14 INFO - 'tsvg_static']}, 19:03:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:03:14 INFO - 'tsvgr_opacity', 19:03:14 INFO - 'tart', 19:03:14 INFO - 'tscrollx', 19:03:14 INFO - 'cart', 19:03:14 INFO - 'tsvg_static']}, 19:03:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:03:14 INFO - 'talos_options': ['--disable-e10s'], 19:03:14 INFO - 'tests': ['tp5o']}, 19:03:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:03:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:03:14 INFO - 'talos_options': ['--disable-e10s', 19:03:14 INFO - '--xperf_path', 19:03:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:03:14 INFO - 'tests': ['tp5n']}, 19:03:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:03:14 INFO - 'talos_options': ['--xperf_path', 19:03:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:03:14 INFO - 'tests': ['tp5n']}}, 19:03:14 INFO - 'talos.zip': {'path': '', 19:03:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:03:14 INFO - Running post-action listener: _resource_record_post_action 19:03:14 INFO - [mozharness: 2017-05-28 02:03:14.809848Z] Finished populate-webroot step (success) 19:03:14 INFO - [mozharness: 2017-05-28 02:03:14.809963Z] Running create-virtualenv step. 19:03:14 INFO - Running pre-action listener: _resource_record_pre_action 19:03:14 INFO - Running main action method: create_virtualenv 19:03:14 INFO - Creating virtualenv /builds/slave/test/build/venv 19:03:14 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 19:03:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:03:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:03:14 INFO - Using real prefix '/tools/python27' 19:03:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:03:15 INFO - Installing distribute.............................................................................................................................................................................................done. 19:03:18 INFO - Installing pip.................done. 19:03:18 INFO - Return code: 0 19:03:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:03:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:03:18 INFO - Reading from file tmpfile_stdout 19:03:18 INFO - Output received: 19:03:18 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) 19:03:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:03:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:18 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 0x107796b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107799768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc648c81710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107574d30>, '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 0x10749bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107569be8>, '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.y4vduZselZ/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.f8bHyklbIQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 19:03:18 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 19:03:18 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 19:03:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y4vduZselZ/Render', 19:03:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:18 INFO - 'HOME': '/Users/cltbld', 19:03:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:18 INFO - 'LOGNAME': 'cltbld', 19:03:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:18 INFO - 'MOZ_NO_REMOTE': '1', 19:03:18 INFO - 'NO_EM_RESTART': '1', 19:03:18 INFO - 'PAGER': '/bin/cat', 19:03:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:18 INFO - 'PWD': '/builds/slave/test', 19:03:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:18 INFO - 'SHELL': '/bin/bash', 19:03:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8bHyklbIQ/Listeners', 19:03:18 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 19:03:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:18 INFO - 'USER': 'cltbld', 19:03:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:18 INFO - 'XPC_FLAGS': '0x0', 19:03:18 INFO - 'XPC_SERVICE_NAME': '0', 19:03:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:19 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 19:03:19 INFO - Cleaning up... 19:03:19 INFO - Return code: 0 19:03:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:03: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')]} 19:03:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03: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')]} 19:03:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03: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', '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 0x107796b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107799768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc648c81710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107574d30>, '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 0x10749bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107569be8>, '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.y4vduZselZ/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.f8bHyklbIQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 19:03: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', 'psutil>=3.1.1'] in /builds/slave/test/build 19:03: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 psutil>=3.1.1 19:03:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y4vduZselZ/Render', 19:03:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:19 INFO - 'HOME': '/Users/cltbld', 19:03:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:19 INFO - 'LOGNAME': 'cltbld', 19:03:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:19 INFO - 'MOZ_NO_REMOTE': '1', 19:03:19 INFO - 'NO_EM_RESTART': '1', 19:03:19 INFO - 'PAGER': '/bin/cat', 19:03:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:19 INFO - 'PWD': '/builds/slave/test', 19:03:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:19 INFO - 'SHELL': '/bin/bash', 19:03:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8bHyklbIQ/Listeners', 19:03:19 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 19:03:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:19 INFO - 'USER': 'cltbld', 19:03:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:19 INFO - 'XPC_FLAGS': '0x0', 19:03:19 INFO - 'XPC_SERVICE_NAME': '0', 19:03:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:19 INFO - Downloading/unpacking psutil>=3.1.1 19:03:19 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 19:03:19 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 19:03:19 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 19:03:19 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 19:03:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:03:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:03:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:03:21 INFO - Installing collected packages: psutil 19:03:21 INFO - Running setup.py install for psutil 19:03:21 INFO - building 'psutil._psutil_osx' extension 19:03:21 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 19:03: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 19:03: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 19:03:22 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 19:03:22 INFO - building 'psutil._psutil_posix' extension 19:03:22 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 19:03:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:03:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:03:22 INFO - ^ 19:03:22 INFO - 1 warning generated. 19:03:22 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 19:03:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:03:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:03:22 INFO - Successfully installed psutil 19:03:22 INFO - Cleaning up... 19:03:22 INFO - Return code: 0 19:03:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:03:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107796b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107799768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc648c81710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107574d30>, '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 0x10749bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107569be8>, '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.y4vduZselZ/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.f8bHyklbIQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 19:03:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:03:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:03:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y4vduZselZ/Render', 19:03:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:22 INFO - 'HOME': '/Users/cltbld', 19:03:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:22 INFO - 'LOGNAME': 'cltbld', 19:03:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:22 INFO - 'MOZ_NO_REMOTE': '1', 19:03:22 INFO - 'NO_EM_RESTART': '1', 19:03:22 INFO - 'PAGER': '/bin/cat', 19:03:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:22 INFO - 'PWD': '/builds/slave/test', 19:03:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:22 INFO - 'SHELL': '/bin/bash', 19:03:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8bHyklbIQ/Listeners', 19:03:22 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 19:03:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:22 INFO - 'USER': 'cltbld', 19:03:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:22 INFO - 'XPC_FLAGS': '0x0', 19:03:22 INFO - 'XPC_SERVICE_NAME': '0', 19:03:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:03:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:24 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:03:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:03:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:03:24 INFO - Installing collected packages: mozsystemmonitor 19:03:24 INFO - Running setup.py install for mozsystemmonitor 19:03:24 INFO - Successfully installed mozsystemmonitor 19:03:24 INFO - Cleaning up... 19:03:24 INFO - Return code: 0 19:03:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:03:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107796b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107799768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc648c81710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107574d30>, '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 0x10749bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107569be8>, '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.y4vduZselZ/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.f8bHyklbIQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 19:03:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:03:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:03:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y4vduZselZ/Render', 19:03:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:24 INFO - 'HOME': '/Users/cltbld', 19:03:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:24 INFO - 'LOGNAME': 'cltbld', 19:03:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:24 INFO - 'MOZ_NO_REMOTE': '1', 19:03:24 INFO - 'NO_EM_RESTART': '1', 19:03:24 INFO - 'PAGER': '/bin/cat', 19:03:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:24 INFO - 'PWD': '/builds/slave/test', 19:03:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:24 INFO - 'SHELL': '/bin/bash', 19:03:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8bHyklbIQ/Listeners', 19:03:24 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 19:03:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:24 INFO - 'USER': 'cltbld', 19:03:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:24 INFO - 'XPC_FLAGS': '0x0', 19:03:24 INFO - 'XPC_SERVICE_NAME': '0', 19:03:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:24 INFO - Downloading/unpacking jsonschema==2.5.1 19:03: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 19:03: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 19:03: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 19:03: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 19:03:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:03:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:03: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 19:03: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 19:03: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 19:03: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 19:03:26 INFO - Downloading functools32-3.2.3-2.tar.gz 19:03:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:03:26 INFO - warning: no files found matching '*.txt' 19:03:26 INFO - no previously-included directories found matching 'build' 19:03:26 INFO - no previously-included directories found matching 'dist' 19:03:26 INFO - no previously-included directories found matching '.git*' 19:03:26 INFO - Installing collected packages: jsonschema, functools32 19:03:26 INFO - Running setup.py install for functools32 19:03:26 INFO - warning: no files found matching '*.txt' 19:03:26 INFO - no previously-included directories found matching 'build' 19:03:26 INFO - no previously-included directories found matching 'dist' 19:03:26 INFO - no previously-included directories found matching '.git*' 19:03:26 INFO - Successfully installed jsonschema functools32 19:03:26 INFO - Cleaning up... 19:03:26 INFO - Return code: 0 19:03:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:03:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107796b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107799768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc648c81710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107574d30>, '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 0x10749bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107569be8>, '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.y4vduZselZ/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.f8bHyklbIQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 19:03:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:03:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 19:03:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y4vduZselZ/Render', 19:03:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:26 INFO - 'HOME': '/Users/cltbld', 19:03:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:26 INFO - 'LOGNAME': 'cltbld', 19:03:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:26 INFO - 'MOZ_NO_REMOTE': '1', 19:03:26 INFO - 'NO_EM_RESTART': '1', 19:03:26 INFO - 'PAGER': '/bin/cat', 19:03:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:26 INFO - 'PWD': '/builds/slave/test', 19:03:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:26 INFO - 'SHELL': '/bin/bash', 19:03:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8bHyklbIQ/Listeners', 19:03:26 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 19:03:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:26 INFO - 'USER': 'cltbld', 19:03:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:26 INFO - 'XPC_FLAGS': '0x0', 19:03:26 INFO - 'XPC_SERVICE_NAME': '0', 19:03:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:03:27 INFO - Cleaning up... 19:03:27 INFO - Return code: 0 19:03:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:03:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107796b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107799768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc648c81710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107574d30>, '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 0x10749bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107569be8>, '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.y4vduZselZ/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.f8bHyklbIQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 19:03:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:03:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 19:03:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y4vduZselZ/Render', 19:03:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:27 INFO - 'HOME': '/Users/cltbld', 19:03:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:27 INFO - 'LOGNAME': 'cltbld', 19:03:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:27 INFO - 'MOZ_NO_REMOTE': '1', 19:03:27 INFO - 'NO_EM_RESTART': '1', 19:03:27 INFO - 'PAGER': '/bin/cat', 19:03:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:27 INFO - 'PWD': '/builds/slave/test', 19:03:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:27 INFO - 'SHELL': '/bin/bash', 19:03:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8bHyklbIQ/Listeners', 19:03:27 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 19:03:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:27 INFO - 'USER': 'cltbld', 19:03:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:27 INFO - 'XPC_FLAGS': '0x0', 19:03:27 INFO - 'XPC_SERVICE_NAME': '0', 19:03:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:27 INFO - Downloading/unpacking blobuploader==1.2.4 19:03: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 19:03: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 19:03: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 19:03: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 19:03:28 INFO - Downloading blobuploader-1.2.4.tar.gz 19:03:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:03:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:03: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 19:03: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 19:03: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 19:03: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 19:03:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:03:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:03:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:03:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:03:29 INFO - Downloading docopt-0.6.1.tar.gz 19:03:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:03:29 INFO - Installing collected packages: blobuploader, requests, docopt 19:03:29 INFO - Running setup.py install for blobuploader 19:03:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:03:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:03:29 INFO - Running setup.py install for requests 19:03:30 INFO - Running setup.py install for docopt 19:03:30 INFO - Successfully installed blobuploader requests docopt 19:03:30 INFO - Cleaning up... 19:03:30 INFO - Return code: 0 19:03:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:03:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:30 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 0x107796b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107799768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc648c81710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107574d30>, '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 0x10749bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107569be8>, '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.y4vduZselZ/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.f8bHyklbIQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 19:03:30 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 19:03:30 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 19:03:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y4vduZselZ/Render', 19:03:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:30 INFO - 'HOME': '/Users/cltbld', 19:03:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:30 INFO - 'LOGNAME': 'cltbld', 19:03:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:30 INFO - 'MOZ_NO_REMOTE': '1', 19:03:30 INFO - 'NO_EM_RESTART': '1', 19:03:30 INFO - 'PAGER': '/bin/cat', 19:03:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:30 INFO - 'PWD': '/builds/slave/test', 19:03:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:30 INFO - 'SHELL': '/bin/bash', 19:03:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8bHyklbIQ/Listeners', 19:03:30 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 19:03:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:30 INFO - 'USER': 'cltbld', 19:03:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:30 INFO - 'XPC_FLAGS': '0x0', 19:03:30 INFO - 'XPC_SERVICE_NAME': '0', 19:03:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:03:30 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-iArNkX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:03:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:03:30 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-s2yjxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:03:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:03:30 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-WzO3N_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:03:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:03:31 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-untmpS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:03:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:03:31 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-feNe2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:03:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:03:31 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-7DvwLV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:03:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:03:31 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-jDFwlx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:03:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:03:31 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-J3_CzN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:03:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:03:31 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-YRg24Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:03:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:03:31 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Dy9naq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:03:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:03:31 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-AUXYD8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:03:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:03:31 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-v3fkh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:03:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:03:31 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-6kETLy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:03:32 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-_yzcxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:03:32 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-nUzsDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:03:32 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-TIMyeN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:03:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:03:32 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-FIZy4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:03:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:03:32 INFO - Running setup.py install for manifestparser 19:03:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:03:32 INFO - Running setup.py install for mozcrash 19:03:32 INFO - Running setup.py install for mozdebug 19:03:32 INFO - Running setup.py install for mozdevice 19:03:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:03:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:03:33 INFO - Running setup.py install for mozfile 19:03:33 INFO - Running setup.py install for mozhttpd 19:03:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:03:33 INFO - Running setup.py install for mozinfo 19:03:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:03:33 INFO - Running setup.py install for mozInstall 19:03:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:03:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:03:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:03:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:03:33 INFO - Running setup.py install for mozleak 19:03:33 INFO - Running setup.py install for mozlog 19:03:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:03:34 INFO - Running setup.py install for moznetwork 19:03:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:03:34 INFO - Running setup.py install for mozprocess 19:03:34 INFO - Running setup.py install for mozprofile 19:03:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:03:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:03:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:03:34 INFO - Running setup.py install for mozrunner 19:03:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:03:34 INFO - Running setup.py install for mozscreenshot 19:03:34 INFO - Running setup.py install for moztest 19:03:34 INFO - Running setup.py install for mozversion 19:03:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:03:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:03:35 INFO - Cleaning up... 19:03:35 INFO - Return code: 0 19:03:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:03:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:03:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:35 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 0x107796b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107799768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc648c81710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107574d30>, '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 0x10749bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107569be8>, '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.y4vduZselZ/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.f8bHyklbIQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 19:03:35 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 19:03:35 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 19:03:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y4vduZselZ/Render', 19:03:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:35 INFO - 'HOME': '/Users/cltbld', 19:03:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:35 INFO - 'LOGNAME': 'cltbld', 19:03:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:35 INFO - 'MOZ_NO_REMOTE': '1', 19:03:35 INFO - 'NO_EM_RESTART': '1', 19:03:35 INFO - 'PAGER': '/bin/cat', 19:03:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:35 INFO - 'PWD': '/builds/slave/test', 19:03:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:35 INFO - 'SHELL': '/bin/bash', 19:03:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8bHyklbIQ/Listeners', 19:03:35 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 19:03:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:35 INFO - 'USER': 'cltbld', 19:03:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:35 INFO - 'XPC_FLAGS': '0x0', 19:03:35 INFO - 'XPC_SERVICE_NAME': '0', 19:03:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:03:35 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-0Jxm7C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:03:35 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)) 19:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:03:35 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-9vLREo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:03:35 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)) 19:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:03:35 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-eFzQ2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:03:35 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)) 19:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:03:35 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-jcUoHA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:03:35 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)) 19:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:03:35 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-seOsYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:03:35 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)) 19:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:03:35 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-6T3enM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:03:36 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)) 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:03:36 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-ngHZex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:03:36 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)) 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:03:36 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-55bCVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:03:36 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)) 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:03:36 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-01yAR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:03:36 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)) 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:03:36 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-vbLw4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:03:36 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)) 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:03:36 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-j_u5UJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:03:36 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)) 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:03:36 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-mnkNnl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:03:36 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)) 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:03:36 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-dFE1pC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:03:36 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)) 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:03:36 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Dg3brV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:03:36 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)) 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:03:36 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-g5youM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:03:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 19:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:03:37 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-tKTVK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:03:37 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)) 19:03:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:03:37 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-ryzT_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:03:37 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)) 19:03:37 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)) 19:03:37 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)) 19:03:37 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)) 19:03:37 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)) 19:03:37 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)) 19:03:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:03:37 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 19:03:37 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 19:03:37 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 19:03:37 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 19:03:38 INFO - Downloading blessings-1.6.tar.gz 19:03:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:03:38 INFO - Installing collected packages: blessings 19:03:38 INFO - Running setup.py install for blessings 19:03:38 INFO - Successfully installed blessings 19:03:38 INFO - Cleaning up... 19:03:38 INFO - Return code: 0 19:03:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:03:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:03:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:03:39 INFO - Reading from file tmpfile_stdout 19:03:39 INFO - Current package versions: 19:03:39 INFO - blessings == 1.6 19:03:39 INFO - blobuploader == 1.2.4 19:03:39 INFO - docopt == 0.6.1 19:03:39 INFO - functools32 == 3.2.3-2 19:03:39 INFO - jsonschema == 2.5.1 19:03:39 INFO - manifestparser == 1.1 19:03:39 INFO - mozInstall == 1.12 19:03:39 INFO - mozcrash == 1.0 19:03:39 INFO - mozdebug == 0.1 19:03:39 INFO - mozdevice == 0.49 19:03:39 INFO - mozfile == 1.2 19:03:39 INFO - mozhttpd == 0.7 19:03:39 INFO - mozinfo == 0.9 19:03:39 INFO - mozleak == 0.1 19:03:39 INFO - mozlog == 3.4 19:03:39 INFO - moznetwork == 0.27 19:03:39 INFO - mozprocess == 0.25 19:03:39 INFO - mozprofile == 0.28 19:03:39 INFO - mozrunner == 6.13 19:03:39 INFO - mozscreenshot == 0.1 19:03:39 INFO - mozsystemmonitor == 0.3 19:03:39 INFO - moztest == 0.8 19:03:39 INFO - mozversion == 1.4 19:03:39 INFO - psutil == 3.1.1 19:03:39 INFO - requests == 1.2.3 19:03:39 INFO - wsgiref == 0.1.2 19:03:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:03:39 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')]} 19:03:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:39 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')]} 19:03:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:39 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 0x107796b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107799768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc648c81710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107574d30>, '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 0x10749bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107569be8>, '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.y4vduZselZ/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.f8bHyklbIQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 19:03:39 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 19:03:39 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 19:03:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y4vduZselZ/Render', 19:03:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:39 INFO - 'HOME': '/Users/cltbld', 19:03:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:39 INFO - 'LOGNAME': 'cltbld', 19:03:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:39 INFO - 'MOZ_NO_REMOTE': '1', 19:03:39 INFO - 'NO_EM_RESTART': '1', 19:03:39 INFO - 'PAGER': '/bin/cat', 19:03:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:39 INFO - 'PWD': '/builds/slave/test', 19:03:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:39 INFO - 'SHELL': '/bin/bash', 19:03:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8bHyklbIQ/Listeners', 19:03:39 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 19:03:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:39 INFO - 'USER': 'cltbld', 19:03:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:39 INFO - 'XPC_FLAGS': '0x0', 19:03:39 INFO - 'XPC_SERVICE_NAME': '0', 19:03:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:39 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)) 19:03:39 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)) 19:03:39 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)) 19:03:39 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)) 19:03:39 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)) 19:03:39 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)) 19:03:39 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)) 19:03:39 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)) 19:03:39 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)) 19:03:39 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)) 19:03:39 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)) 19:03:39 INFO - Cleaning up... 19:03:39 INFO - Return code: 0 19:03:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:03:39 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')]} 19:03:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:03:39 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')]} 19:03:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:03:39 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 0x107796b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107799768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc648c81710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107574d30>, '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 0x10749bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107569be8>, '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.y4vduZselZ/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.f8bHyklbIQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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 19:03:39 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 19:03:39 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 19:03:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y4vduZselZ/Render', 19:03:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:39 INFO - 'HOME': '/Users/cltbld', 19:03:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:39 INFO - 'LOGNAME': 'cltbld', 19:03:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:39 INFO - 'MOZ_NO_REMOTE': '1', 19:03:39 INFO - 'NO_EM_RESTART': '1', 19:03:39 INFO - 'PAGER': '/bin/cat', 19:03:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:39 INFO - 'PWD': '/builds/slave/test', 19:03:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:39 INFO - 'SHELL': '/bin/bash', 19:03:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8bHyklbIQ/Listeners', 19:03:39 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 19:03:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:39 INFO - 'USER': 'cltbld', 19:03:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:39 INFO - 'XPC_FLAGS': '0x0', 19:03:39 INFO - 'XPC_SERVICE_NAME': '0', 19:03:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:03:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:03:39 INFO - Cleaning up... 19:03:39 INFO - Return code: 0 19:03:39 INFO - Running post-action listener: _resource_record_post_action 19:03:39 INFO - Running post-action listener: _start_resource_monitoring 19:03:39 INFO - Starting resource monitoring. 19:03:39 INFO - [mozharness: 2017-05-28 02:03:39.841319Z] Finished create-virtualenv step (success) 19:03:39 INFO - [mozharness: 2017-05-28 02:03:39.842335Z] Running install step. 19:03:39 INFO - Running pre-action listener: _resource_record_pre_action 19:03:39 INFO - Running main action method: install 19:03:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:03:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:03:40 INFO - Reading from file tmpfile_stdout 19:03:40 INFO - Detecting whether we're running mozinstall >=1.0... 19:03:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:03:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:03:40 INFO - Reading from file tmpfile_stdout 19:03:40 INFO - Output received: 19:03:40 INFO - Usage: mozinstall [options] installer 19:03:40 INFO - Options: 19:03:40 INFO - -h, --help show this help message and exit 19:03:40 INFO - -d DEST, --destination=DEST 19:03:40 INFO - Directory to install application into. [default: 19:03:40 INFO - "/builds/slave/test"] 19:03:40 INFO - --app=APP Application being installed. [default: firefox] 19:03:40 INFO - mkdir: /builds/slave/test/build/application 19:03:40 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'] 19:03:40 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 19:03:54 INFO - Reading from file tmpfile_stdout 19:03:54 INFO - Output received: 19:03:54 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 19:03:54 INFO - Running post-action listener: _resource_record_post_action 19:03:54 INFO - [mozharness: 2017-05-28 02:03:54.991042Z] Finished install step (success) 19:03:54 INFO - [mozharness: 2017-05-28 02:03:54.991189Z] Running run-tests step. 19:03:54 INFO - Running pre-action listener: _resource_record_pre_action 19:03:54 INFO - Running main action method: run_tests 19:03:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:03:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:03:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:03:55 INFO - 2017-05-27 19:03:55.006 screenresolution[7248:148700] starting screenresolution argv=screenresolution get 19:03:55 INFO - 2017-05-27 19:03:55.008 screenresolution[7248:148700] Display 0: 1600x1200x32@60 19:03:55 INFO - 2017-05-27 19:03:55.015 screenresolution[7249:148702] starting screenresolution argv=screenresolution list 19:03:55 INFO - Available Modes on Display 0 19:03:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:03:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:03:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:03:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:03:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:03:55 INFO - Intel Iris: 19:03:55 INFO - Chipset Model: Intel Iris 19:03:55 INFO - Type: GPU 19:03:55 INFO - Bus: Built-In 19:03:55 INFO - VRAM (Dynamic, Max): 1536 MB 19:03:55 INFO - Vendor: Intel (0x8086) 19:03:55 INFO - Device ID: 0x0a2e 19:03:55 INFO - Revision ID: 0x0009 19:03:55 INFO - Displays: 19:03:55 INFO - Display: 19:03:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:03:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:03:55 INFO - Display Serial Number: 1600x1200 60 19:03:55 INFO - Main Display: Yes 19:03:55 INFO - Mirror: Off 19:03:55 INFO - Online: Yes 19:03:55 INFO - Rotation: Supported 19:03:55 INFO - Return code: 0 19:03:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:03:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:03:55 INFO - Python 2.7.3 19:03:55 INFO - Return code: 0 19:03:55 INFO - grabbing minidump binary from tooltool 19:03:55 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')]} 19:03:55 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 0x107574d30>, '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 0x10749bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107569be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:03:55 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 19:03:55 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 19:03:55 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 19:03:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:03:55 INFO - Return code: 0 19:03:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:03:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:03:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:03:55 INFO - ENV: RUST_BACKTRACE is now 1 19:03:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:03:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:03:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b3qqt0NHSWW-K-XlEAqkww/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--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 19:03:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/b3qqt0NHSWW-K-XlEAqkww/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0111 --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 19:03:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.y4vduZselZ/Render', 19:03:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:03:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:03:55 INFO - 'HOME': '/Users/cltbld', 19:03:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:03:55 INFO - 'LOGNAME': 'cltbld', 19:03:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:03:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:03:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:03:55 INFO - 'MOZ_NO_REMOTE': '1', 19:03:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:03:55 INFO - 'NO_EM_RESTART': '1', 19:03:55 INFO - 'PAGER': '/bin/cat', 19:03:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:03:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:03:55 INFO - 'PWD': '/builds/slave/test', 19:03:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:03:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:03:55 INFO - 'RUST_BACKTRACE': '1', 19:03:55 INFO - 'SHELL': '/bin/bash', 19:03:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.f8bHyklbIQ/Listeners', 19:03:55 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 19:03:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:03:55 INFO - 'USER': 'cltbld', 19:03:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:03:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:03:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:03:55 INFO - 'XPC_FLAGS': '0x0', 19:03:55 INFO - 'XPC_SERVICE_NAME': '0', 19:03:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:03:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/b3qqt0NHSWW-K-XlEAqkww/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--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 19:03:55 INFO - mozversion application_buildid: 20170527181716 19:03:55 INFO - mozversion application_changeset: 8856170020cf83c9cc8da98e187125ca6cdab66b 19:03:55 INFO - mozversion application_display_name: Firefox 19:03:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:03:55 INFO - mozversion application_name: Firefox 19:03:55 INFO - mozversion application_remotingname: firefox 19:03:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 19:03:55 INFO - mozversion application_vendor: Mozilla 19:03:55 INFO - mozversion application_version: 54.0 19:03:55 INFO - mozversion platform_buildid: 20170527181716 19:03:55 INFO - mozversion platform_changeset: 8856170020cf83c9cc8da98e187125ca6cdab66b 19:03:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 19:03:55 INFO - mozversion platform_version: 54.0 19:03:55 INFO - using testdate: 1495937035 19:03:55 INFO - actual date: 1495937035 19:03:55 INFO - starting webserver on 'localhost:58008' 19:03:55 INFO - SUITE-START | Running 2 tests 19:03:55 INFO - TEST-START | dromaeo_css 19:03:55 INFO - operating with platform_type : mac_ 19:03:55 INFO - Initialising browser for dromaeo_css test... 19:03:55 INFO - TEST-INFO | started process 7259 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmprz1M08/profile http://localhost:58008/getInfo.html) 19:03:58 INFO - PROCESS | 7259 | 2017-05-27 19:03:58.331 plugin-container[7262:148857] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d4f, name = 'com.apple.tsm.portname' 19:03:58 INFO - PROCESS | 7259 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:03:58 INFO - PROCESS | 7259 | 2017-05-27 19:03:58.331 plugin-container[7262:148857] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a23, name = 'com.apple.CFPasteboardClient' 19:03:58 INFO - PROCESS | 7259 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:03:58 INFO - PROCESS | 7259 | 2017-05-27 19:03:58.331 plugin-container[7262:148857] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:03:58 INFO - PROCESS | 7259 | __metrics Screen width/height:1600/1200 19:03:58 INFO - PROCESS | 7259 | colorDepth:24 19:03:58 INFO - PROCESS | 7259 | Browser inner width/height: 1024/689 19:03:58 INFO - PROCESS | 7259 | __metrics 19:03:58 ERROR - PROCESS | 7259 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 19:03:59 INFO - TEST-INFO | 7259: exit 0 19:03:59 INFO - Browser initialized. 19:03:59 INFO - Running cycle 1/1 for dromaeo_css test... 19:03:59 INFO - TEST-INFO | started process 7263 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmprz1M08/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:04:01 INFO - PROCESS | 7263 | 2017-05-27 19:04:01.350 plugin-container[7264:148997] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x842f, name = 'com.apple.tsm.portname' 19:04:01 INFO - PROCESS | 7263 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:04:01 INFO - PROCESS | 7263 | 2017-05-27 19:04:01.350 plugin-container[7264:148997] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 19:04:01 INFO - PROCESS | 7263 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:04:01 INFO - PROCESS | 7263 | 2017-05-27 19:04:01.350 plugin-container[7264:148997] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:04:11 INFO - PROCESS | 7263 | RSS: Main: 174350336 19:04:11 INFO - PROCESS | 7263 | 19:07:14 INFO - PROCESS | 7263 | Cycle 1(1): loaded http://localhost:58008/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58008/tests/dromaeo/cssquery-ext.html) 19:07:16 INFO - PROCESS | 7263 | RSS: Main: 189444096 19:07:16 INFO - PROCESS | 7263 | 19:10:18 INFO - PROCESS | 7263 | Cycle 1(1): loaded http://localhost:58008/tests/dromaeo/cssquery-ext.html (next: http://localhost:58008/tests/dromaeo/cssquery-jquery.html) 19:10:19 INFO - PROCESS | 7263 | RSS: Main: 204005376 19:10:19 INFO - PROCESS | 7263 | 19:13:21 INFO - PROCESS | 7263 | Cycle 1(1): loaded http://localhost:58008/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58008/tests/dromaeo/cssquery-mootools.html) 19:13:21 INFO - PROCESS | 7263 | RSS: Main: 205631488 19:13:21 INFO - PROCESS | 7263 | 19:16:23 INFO - PROCESS | 7263 | Cycle 1(1): loaded http://localhost:58008/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58008/tests/dromaeo/cssquery-prototype.html) 19:16:24 INFO - PROCESS | 7263 | RSS: Main: 208621568 19:16:24 INFO - PROCESS | 7263 | 19:19:27 INFO - PROCESS | 7263 | Cycle 1(1): loaded http://localhost:58008/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58008/tests/dromaeo/cssquery-yui.html) 19:19:27 INFO - PROCESS | 7263 | RSS: Main: 211206144 19:19:27 INFO - PROCESS | 7263 | 19:22:30 INFO - PROCESS | 7263 | Cycle 1(1): loaded http://localhost:58008/tests/dromaeo/cssquery-yui.html (next: http://localhost:58008/tests/dromaeo/cssquery-dojo.html) 19:22:30 INFO - PROCESS | 7263 | RSS: Main: 206860288 19:22:30 INFO - PROCESS | 7263 | 19:22:30 INFO - PROCESS | 7263 | __start_tp_report 19:22:30 INFO - PROCESS | 7263 | _x_x_mozilla_page_load 19:22:30 INFO - PROCESS | 7263 | _x_x_mozilla_page_load_details 19:22:30 INFO - PROCESS | 7263 | |i|pagename|runs| 19:22:30 INFO - PROCESS | 7263 | |0;dojo.html;2975.28593137647;3527.6119626841046;3544.1848374873794;3614.313280476709;3617.565892092949;4951.900961980758;6141.140240366347;6213.8757224855535;6217.036359364306;6275.7489700411925;6136.417040381157;6152.230971128609;6160.383961603838;6179.7837075702355;6265.655388953605;5513.448655134494;5577.776888924458;5594.720263986805;5605.775768969257;5655.802046928358;5647.915281270784;5664.773409063632;5667.4615911488345;5686.431356864301;5709.71451427429;5411.810586629469;5437.13005919053;5632.7183640818;5645.491905728483;5650.49145576898;5565.888682226353;5583.888322233533;5615.4665306796;5632.831015069554;5645.181448689917;5130.30740849988;5560.4161563036105;5562.9443705563235;5596.356419011784;5599.32808063031;4719.952800471986;5056.82301119459;6162.506999440034;6220.844478888019;6245.594036387665;6414.198225221848;6414.2302923648995;6434.163558737381;6491.578047426902;6498.675066246668;6691.799246022627;6728.39444449997;6730.0577919091365;6731.427828634572;6740.662966851687;6209.441150296449;6209.96895015522;6216.502679785606;6230.7819226326865;6250.6874656267;6486.189226346707;6569.014647802868;6711.161104861892;6729.629870357177;6806.353396427331;6495.382938620824;6527.934720652827;6547.247066587308;6550.737970481127;6555.475561955032;6624.105745724266;6638.336166383419;6683.398494135551;6696.5647232929705;6698;6317.084022816758;6378.043293506012;6606.108175396356;6615;6634.137562116894;5364;6467.870642587122;6552.737890484423;6558.901616475757;6570.080188773575;5315.04329220744;5316.521513063843;5329.893402132013;5335.146376579729;5353.411124776271;4561.543845615478;4689.460712018094;4696.530346965276;4703.976480117578;4750.61995040396;6112.260323016922;6122.2959359674205;6129.328227221949;6133.44798968095;6144.986077297285;6713.160854893138;6720.932790672128;6726.293739157318;6732.326767323327;6732.663366831639;6089.725962331706;6119.593952096689;6143.662098584621;6220.440160385587;6235.75056997715;5902.557308201902;5906.616069955439;5912.320083190402;5964.105384192406;5987.341392446828;6838.794836154923;6844.041834143224;6846.520743547904;6879.312068793281;6888.242293347828;6368.713407896748;6392.296847346881;6698.765543205965;6729.360710732472;6745.055692203194;6710.932890671226;6725.8991115134295;6784.185897692309;6786.355296246848;6860.2453730088655;6578.079068930258;6593.670316484253;6614.074029635759;6614.834629134118;6620.668966551557;5859.882802344015;6084.722208336122;6389.7763578274535;6672.566283191577;6690.765823196165;6309.7160627770945;6341.334159913327;6410.262819775786;6419.518536109717;6456.870862582628;6502.707378167899;6520.413162815369;6525.9021114684265;6525.934740652722;6536.444402225674;6185.443310102112;6345.555811093179;6369.33122022181;6388.584741991755;6389.712462939272;6254;6327.430531252209;6397.808065758035;6400.183919075592;6493.837654058686;6283.5601507895835;6374.4900407966325;6391.041343798467;6395.424411802774;6468.094466774749 19:22:30 INFO - PROCESS | 7263 | |1;ext.html;749.9400047996161;828.4904783558111;847.4322204123239;894.1371576428745;902.8871391076111;40291;41628.75113746592;41667.37498937518;41667.79166104169;41696.95757606062;41492.58507414925;41685.95785105374;41723.58276417235;41761.16477670445;41830.58169418305;8054.597270136499;9611.759706007353;9757.121859032686;9775.217982561413;9781.510924453785;8091.4740541864885;11553.075753939707;11973.401329933513;11974.7605047899;11992.400379981009;11983.281003139797;12015.098867584922;12028.158028937978;12028.578999735011;12035.939820300886;11864.7627047459;11920.165588408814;11931.164818462712;11960;11969.281843089355;11648;11760.823587646191;11770.117241206857;11772.529098836036;11785.410729463492;41251.381229281586;41522.37716434256;41523.5466758665;41550.58449415497;41671;4060.7360521566297;4074.144429669743;4084.9991752020946;4102.364133559273;4137.689673274516;29353.70646293531;30644.387112257853;31264.37471250562;31306.53040204376;31313.530297045563;3190.298134410426;3290.588676415448;3362.7814192077435;3432.0390290718638;3442.3803715331233;380651;381275;382759;383570;383742.0812895918;55361;55459.722701386236;55494.612634684454;55568.72215638896;55574;57421.71289143527;57644.135337969965;57688.42311576915;57714;58095;15722.842771572366;15775.605609859616;15876.047437153808;15886.285117169755;15912.52262432129;9630.903690963141;11998.400079995965;13364.799528007088;13365.933170334087;13393.598192054254;14551.708965820624;14755.55733328002;14946.850531494763;14968.550943471713;14974.251287435585;5627.549796016309;6060.393960603904;6378.840528986812;6415.967920160369;6491.383318584727;1003.9196864250843;1060.9151267898549;1135.8125909224884;1162.2910024884814;1194.9940250298694;25569;27534.173974780788;29988.800447982274;30233.941812036912;30385;1021.9029192226875;1112.6050252160367;1115.6876074699098;1153.9653810385703;1157.5646198713703;13865.098768580212;14228.075175113585;14289.142651440947;14427;14498.855011449961;473689;474393;476100;477196;477864;48868.266975995386;49053.509464905605;49249;49308.75345623249;49349.75325123352;1492.6865358274667;1504.1351223046793;1535.063611197169;1536.6004838742008;1552.4876790659055;25954.480910382063;27061.091192361633;27416.451670966868;27456.725432746214;27694.861525692242;42974.78512607542;43193.35209971916;43195.78402107969;43302.133957320046;43546;448956;450569;450779;451157;451760;30539.54190687186;30665.846670766503;30815.84592077115;30964.84517577398;30981.070567882998;32198.678013219567;32884.342313154084;38782.4182637255;39451.60548394479;39555.40666890056;27741.861290693418;28247.57628635531;28901.42197156087;29338.853305733333;29855.850720746257;28836.855815720788;29442.558361624164;30003.099907002827;30520.847395762878;31829.681703182672;35567.46648800218;35952.28095438129;36272;36470;36494.45258321074;24225.39436514101;27477.725222748315;28176.718232818228;28205;28373.716262837934;19040.143193556047;24048.278551643478;24294.392640184138;24435.87782061078;24660.630090548297 19:22:30 INFO - PROCESS | 7263 | |2;jquery.html;391.1238825031928;418.94972603287613;421.36794807788317;425.16030839092787;453.9841105561306;21462.785372146293;22438.88780556099;22995.195168169117;23338;23531.41171470714;23130.884345578248;23471.647925281133;23492.530149397;23765;23773.643395349085;6242.219722534684;6858.451323894089;7135.53619014765;7149.606771627558;7767.262110099531;7417.295356941109;7869.213078692113;7919;7919.287264146225;7955.766856137308;7839.059312882462;7912.248336408032;7918.722844700436;7927.96036019822;7952.721654742085;7577.128630207541;7774.572398518079;7777.727779527718;7781;7785;7139.643017849139;7492.1384040835455;7515.323620874093;7547.3962083033775;7632.389408847279;26783.73216267832;27246.318842028908;27465.45069098627;27619.86190069037;27743.583846242323;3016.9396612067635;3091.7526597872115;3105.7825952183357;3123.2348074721613;3253.0962399084074;15720.37118515258;16292.42976495817;16434.342626294932;16542.172891355385;16714.164291785484;1706.1725063344268;1964.4775299143134;2085.8852763098025;2101.8843963582;2141.882196479193;137671;138965;161816;164866;165320.52019219843;18455.984920829214;18580;21187.576248474943;21203.727776333468;21392.251271205743;32490.83754581212;32750.508742368882;33058.66941330556;33129.66870331266;33174.8341258297;10646.946765266124;10780.51487683056;10798.136149108053;10858.728531786768;11107.000369966741;7271;8162;8403.663853445916;9114;9275.489848058422;8950.597223124974;9707.805843883083;9840.392296041555;9877.160441362565;9953.552090155961;3640.890773276806;3841.5414134953653;4203.852865149705;4345;4381.67137464685;1013.8644717915834;1032.8605638238741;1045.231754660324;1062.697131317571;1062.8458873463305;17810.753247272565;20097.497562561053;21196.682049769264;21326.78673213279;21353.893230533748;1045.169090572981;1061.8354155105972;1071.7374243310283;1081.4971038466992;1085.8316960871077;10441.216908731876;11029.283096598696;11097.556097756;11120.388378639253;11124.777504450027;415024;676492;771861;797339;800374;143818.28090859478;149122;150056;152295.47704523033;164348.17825910795;1255.5668294438403;1287;1310.5609620777147;1364.9317534123452;1376.2361889151616;19021.5935804475;19289;20334;20719.8964005179;20730.585388292453;65528;66238.66880665565;66534.66732666499;66599.33400666126;69619.95570066352;87891;94107.52946235225;94486.58270126091;94780.05219947988;96887;7730.226977302224;8033.558154301336;11319.830202547133;11965.401729913296;12126.27242365461;13196.010299227404;13307.733845323233;13520.864791352355;13684.52104176349;13706.45174193061;11426.82859757121;11506.079513639057;11524.539018439169;11603.5938742147;11623;9798.80402391934;10134.847977280495;10172.08451239392;11015.724606884893;11157;9932.900670993198;11467.254628449124;11758.176927615326;11937.701557461132;12158.878411215775;9919;11731.648050558497;11745.354005529778;12080.093992950387;12113.75772484563;11688.18182727201;11695.88304116948;11894.048476121716;11895.821562676394;12069.27584344942 19:22:30 INFO - PROCESS | 7263 | |3;mootools.html;1445.833729121151;1624.764409160671;1643.9068019766848;1654.6359800843813;1659.3279721712704;9884.505774711271;10184.388936663805;11142;11822.231554948932;11929.701757456069;8841.955790221056;11277.661670149888;11286.830697539523;11290.322580645166;11290.548378064888;2459.2990997565744;2506.7618576235227;2513.082724805442;2516.6476693262957;2518.7733104020635;2443.621238707994;2503.3491292264052;2504.2362079565723;2504.2987963370197;2507.887145078467;2459.311392810016;2466.765657262566;2473.5794914864427;2475.344033831036;2490.638857365681;2432.513497300547;2453.6442215878687;2457.0785955266774;2462.940935397778;2463.963040554393;2412.9034838606435;2434.245383930987;2435.366804630791;2447.339218411019;2451.044092803808;11556.075513958864;11576.305421674642;11726.003289720384;11728.061755059574;11743.001844843198;5833.183354330397;5918.704064796786;5996.820095397145;6007.309159446676;6011.939880601226;3713.740038197327;3722.3671975764187;3751.2309976454762;3842.212346468967;3878.2825177342283;1231.7413343197939;1240.7518496300686;1256.3466997161424;1294.2040645003349;1296.0409297120157;15988.360465581363;15994.920025399799;16000.519984400604;16001.91999040009;16020.35918563256;4939.382577177853;5177.0940085485645;5217.113090774577;5278.4721527846905;5290.100682883919;13380.130291531019;13399.665008374868;13444.126131801402;13446;13448.193108413527;6119.694015299217;6182.845428864224;6186.876262474725;6209.96895015522;6231.345708700521;5283.445238250005;5556.388797232301;5581.553475721932;5596;5603.299587551556;3660.213054193361;4188.141431006637;4308;4363.665399087728;4384.4081049058605;1194.713268815478;1196.5393323570488;1199.772043311768;1210.1226610707342;1211.3337664284609;950.7147855643279;960.7982323711959;982.8083523712951;1003.1773945364877;1005.1104772276442;6550.475961923034;6669.7665581704405;6718.8320291993095;6910.896336554955;6965.407940325028;848.8879567766246;852.3564708644933;934.1405906565975;1039.7556574205055;1045.832666773328;4174.833006679699;4178.791060447026;4327.156204540147;4339.240632889232;4467.419235499429;14018.509352172625;14595.78106328406;14626.488072917396;14642.974991750476;14674.266286685623;12188.695282617828;13635.522756703467;14410.42358305677;14427;14466.27668616565;10194.490275486196;10489.895101049044;10672.89327106719;10690.94654526738;10736.94631526853;5336.332958380202;6869.656517174221;6882.931170688229;6887.276835932355;7007.089078419773;10904.291221070605;12489.37553122322;12743.044271679846;13013.349332533524;13017.284049377375;12932.353382331034;12987.480500779862;13044.934775326063;13128.803067953797;13299.66750831237;9429.387089839138;9654.131128198496;9727.85408218863;9742.025797420201;9758.414495130315;10496;10617.628383006559;10722.410267435365;10738.946305268737;10758.892411075789;9139.040400758091;9240.168384845396;9251.444913305224;9368.859467108136;9420.481873497023;8970.865437018581;9003.864942026006;9013.774655633662;9055.773605659911;9151.77120571991;8011.799705007422;8179.918200817916;8185.754427367188;8186.713465028695;8195.34437245007;4676.953230467651;5989.5507836913275;5995.520358371233;6152.815415537541;6209.441150296495;5938.732757025858;6006.519478441802;6186.1030150627;6209;6233.662774776533 19:22:30 INFO - PROCESS | 7263 | |4;prototype.html;343.2191763737497;368.3369934118586;382.39581461291164;386.49176333121943;401.8372559113559;4917.606591472682;5359.732013399329;5400.108982017972;5430.859519500905;5491.231227628125;4653.953460465403;5293.444188360225;5297.814576489814;5328.600354973373;5356.250124982505;3979.5622481527007;4169.187008533338;4260.42484264625;4413.867583972486;4417.182821178077;3717.219383929378;4167.770772607505;4382.298832186851;4405.735655860659;4485.798139083749;3743.8128093595346;4395.472543294809;4408.470983481987;4452.599266066053;4466.910661786778;3506.68439840413;3840.4047372657283;4128.174365126986;4446;4471.798769055384;3885.417187421881;3998.240334336496;4015.5945419103373;4034.006333729616;4054;4839.2983017462275;5265.842024739264;5284.524392804666;5301.436076357445;5305.920411193835;4163.7293575917465;4579.542045795427;5071.923921141186;5092.490750924915;5130.896857175725;5262.578993680497;5370.489803468664;5538.030844602178;5739.713014349308;5759.8560035999435;4132.73137246078;4143.3784932260105;4250.893727656802;4567.497575266718;4598.8620341389815;4507.684462087688;5504.339479262514;6095.573309868311;6150.7232174552255;6164;4207.936880946788;4613.930791038137;4618.330342100444;4624.098300831373;4636.884077898079;3866.9806650966566;4489.483709373464;4666.486686464486;4710.175719249118;4712.95287047132;4667.439907211156;4825.879353016203;5040.672356296829;5077.441481437113;5080.7552149722815;5213.6350455467755;5355.152472397017;5649.661020338793;5691.385969909137;5721.971390143022;1319.808627748971;1550.2481296571154;1594.886775023099;1613.467555706614;1613.5240104169297;1130.6551501792032;1164.9941750291193;1199.682084247675;1214.665966859103;1239.7210627608897;4892.755362231874;5045.915128247371;5058.519440653132;5357.678539287655;5404.378686394066;5133.409657889316;5212.50481204285;5390.622656414013;5694.658320500783;5934.240578131104;5016.523430274118;5045.318881950963;5281.419043905167;5394.676319420847;5442.482964118402;2765.4745598336253;2778.6103950602464;2938.353562216309;3080.3520116737886;3083.7070478304527;5490.533304669068;5560.026995275811;5659.547236221092;5767.740451679684;6192.907106393408;4948.490710333383;5492;5798.9420105799245;5905.734241959122;6047.7278522466595;5110.565601923804;5744;5938.168656388109;6083.908741368795;6094.969525152346;5615.18579805934;5936.506667199873;5937.732802023833;5949.791757288474;5983.995361553928;5432.436753127392;5727.4845263926445;5778.94221057784;5822.475977162076;5825.6795876225515;5463.617546771688;5708.8572785679025;6043.486303664238;6097.804790275409;6105.328413874381;4475.4489940733465;4845.7577121143095;4961.975190124026;5128.5640020794235;5175.689458632494;5304.9734751326;5588.916166257428;5860.413958604106;5872.29532456108;5881.617694849821;4637.77386854305;5031.446540880427;5578.86052848682;5702.6863522505;5728.770849165987;4922.901541969069;5020.899582008413;5124.718140502308;5321.467853214647;5733.512651424676;4648.023914977893;4713.48845110395;4932.827351042696;5435.396558015474;5644.955683198621;5048.974755126201;5196.376434827694;5709.314882214161;5736.795272992718;5757.9424205757405;4503.639708823352;5248.504176309886;5475.6440831345835;5540.390557038803;5595.972020140036 19:22:30 INFO - PROCESS | 7263 | |5;yui.html;710.4494017136718;731.3344856180876;815.408828599266;840.9530134981949;914.1955079530013;16175.352985880565;17320.56698582536;17376.652466950683;17528.21123049461;17639.294428222856;16082.276297566608;16210.918945405287;16220.351185952577;16232.350705971776;16236;972.3873959405582;977.2426369563574;1009.470028235175;1015.1827778638193;1017.9287449878511;1010.9696709098702;1012.812629663511;1022.1056575496441;1024.4314405504942;1024.6772266735954;1064.8988346107108;1073.7906108308882;1076.5908954597242;1076.6500887211648;1078.2560033576847;1062.9893701063006;1069.4278560969844;1069.5026812532171;1069.5133714160038;1069.5561342043043;786.1116937860181;791.5923299500704;793.0760663826635;793.1077537770009;794.2454668065361;16581.66836663272;17038.062906540134;17067.40264090751;17356.39252626152;17963.820361796475;14647.340869660891;14701.970862039661;14737.11577305365;14761.483348082766;14794.704105917928;12053.457594408274;12068.396580171044;12098;12227.572034978733;12291.93854030733;3945.5462621798574;3946;4033.4756481657196;4066.3697126945076;4084.2852500812537;112089.87910120958;117245;118500.65629847666;119172;119612;11099.066126962916;11418.714532136763;11551.537938482366;11746.236494627821;11798.174127811142;19673.606527869364;23651.17220897295;24335;24358.26925192247;24361.26916192517;7958.721444749522;8220.958895205606;8242.340612750966;8375.45559538628;8482.575871206414;4563.315502674625;4583.495815460296;4636.374089497942;4668.533146685372;4747.907981164351;3699.9445008324897;3758.041699366644;3785.848566057382;3792.994856363066;3795.1650636859845;664.6344510519195;668.2248591633681;669.0666520204288;676.6008055247428;678.1353773938268;640.9487241020707;664.986700265992;705.1291654806311;707.3527722134189;731.7950973727364;10497;11429.485673144729;11675.005956635705;11774.175807693378;11786.646400607995;511.4399732293133;570.1704020649944;574.115861573181;634.3941535833268;753.9623018849036;5087.465816089331;5230.8169214077325;5351.518363347317;5361.329833770778;5366.356037275552;93309.06690933139;93516.53241733884;93646.53176734073;94037.5298123505;94614.52692736492;59962.70018649879;79714.80427793588;86845;88472.5576372114;91233.6314955263;76238.23761762312;76455.61772191215;79627.8055829163;80141;80811;11123.777524449628;11271.267367621078;11313.321200727984;11424.086073114297;11576.826347604961;42966;48953.5104648949;52163;52653.683657908856;53262.52501020335;52954.735226323624;55637;60719.69640151771;64411.35588644241;64570.67714661397;13121.475140994253;13412.329383530589;13470.393832277767;13477;13867.029307948347;14859.554213373616;15464.84535154679;15684.215789210723;15844.970076944961;16163.838361616234;13728.794068089186;14551.490697825526;14565.92717036408;14596.197209153599;14631.244250689986;14066.226357550433;14499.85500144985;14568;14655.48705795292;15190.873277740904;13598.592042238748;14280.714385712436;14349.784753228918;14374.928125359307;14903.776443353576;9898.752531186778;10371.844422333821;10396.58413663445;10488.895111048792;10897.12822974176;9849.901500984899;11113.44432778374;11306.383424757874;11394.326728287178;11751.588694395656 19:22:30 INFO - PROCESS | 7263 | __end_tp_report 19:22:30 INFO - PROCESS | 7263 | __start_cc_report 19:22:30 INFO - PROCESS | 7263 | _x_x_mozilla_cycle_collect,392 19:22:30 INFO - PROCESS | 7263 | __end_cc_report 19:22:30 INFO - PROCESS | 7263 | __startTimestamp1495938150881__endTimestamp 19:22:30 INFO - PROCESS | 7263 | 19:22:30 INFO - PROCESS | 7263 | ------- Summary: start ------- 19:22:30 INFO - PROCESS | 7263 | Number of tests: 6 19:22:30 INFO - PROCESS | 7263 | 19:22:30 INFO - PROCESS | 7263 | [#0] dojo.html Cycles:165 Average:6102.92 Median:6317.08 stddev:707.27 (11.2%) stddev-sans-first:665.52 19:22:30 INFO - PROCESS | 7263 | Values: 2975.3 3527.6 3544.2 3614.3 3617.6 4951.9 6141.1 6213.9 6217.0 6275.7 6136.4 6152.2 6160.4 6179.8 6265.7 5513.4 5577.8 5594.7 5605.8 5655.8 5647.9 5664.8 5667.5 5686.4 5709.7 5411.8 5437.1 5632.7 5645.5 5650.5 5565.9 5583.9 5615.5 5632.8 5645.2 5130.3 5560.4 5562.9 5596.4 5599.3 4720.0 5056.8 6162.5 6220.8 6245.6 6414.2 6414.2 6434.2 6491.6 6498.7 6691.8 6728.4 6730.1 6731.4 6740.7 6209.4 6210.0 6216.5 6230.8 6250.7 6486.2 6569.0 6711.2 6729.6 6806.4 6495.4 6527.9 6547.2 6550.7 6555.5 6624.1 6638.3 6683.4 6696.6 6698.0 6317.1 6378.0 6606.1 6615.0 6634.1 5364.0 6467.9 6552.7 6558.9 6570.1 5315.0 5316.5 5329.9 5335.1 5353.4 4561.5 4689.5 4696.5 4704.0 4750.6 6112.3 6122.3 6129.3 6133.4 6145.0 6713.2 6720.9 6726.3 6732.3 6732.7 6089.7 6119.6 6143.7 6220.4 6235.8 5902.6 5906.6 5912.3 5964.1 5987.3 6838.8 6844.0 6846.5 6879.3 6888.2 6368.7 6392.3 6698.8 6729.4 6745.1 6710.9 6725.9 6784.2 6786.4 6860.2 6578.1 6593.7 6614.1 6614.8 6620.7 5859.9 6084.7 6389.8 6672.6 6690.8 6309.7 6341.3 6410.3 6419.5 6456.9 6502.7 6520.4 6525.9 6525.9 6536.4 6185.4 6345.6 6369.3 6388.6 6389.7 6254.0 6327.4 6397.8 6400.2 6493.8 6283.6 6374.5 6391.0 6395.4 6468.1 19:22:30 INFO - PROCESS | 7263 | 19:22:30 INFO - PROCESS | 7263 | [#1] ext.html Cycles:180 Average:57513.91 Median:31310.03 stddev:116180.79 (371.1%) stddev-sans-first:116428.54 19:22:30 INFO - PROCESS | 7263 | Values: 749.9 828.5 847.4 894.1 902.9 40291.0 41628.8 41667.4 41667.8 41697.0 41492.6 41686.0 41723.6 41761.2 41830.6 8054.6 9611.8 9757.1 9775.2 9781.5 8091.5 11553.1 11973.4 11974.8 11992.4 11983.3 12015.1 12028.2 12028.6 12035.9 11864.8 11920.2 11931.2 11960.0 11969.3 11648.0 11760.8 11770.1 11772.5 11785.4 41251.4 41522.4 41523.5 41550.6 41671.0 4060.7 4074.1 4085.0 4102.4 4137.7 29353.7 30644.4 31264.4 31306.5 31313.5 3190.3 3290.6 3362.8 3432.0 3442.4 380651.0 381275.0 382759.0 383570.0 383742.1 55361.0 55459.7 55494.6 55568.7 55574.0 57421.7 57644.1 57688.4 57714.0 58095.0 15722.8 15775.6 15876.0 15886.3 15912.5 9630.9 11998.4 13364.8 13365.9 13393.6 14551.7 14755.6 14946.9 14968.6 14974.3 5627.5 6060.4 6378.8 6416.0 6491.4 1003.9 1060.9 1135.8 1162.3 1195.0 25569.0 27534.2 29988.8 30233.9 30385.0 1021.9 1112.6 1115.7 1154.0 1157.6 13865.1 14228.1 14289.1 14427.0 14498.9 473689.0 474393.0 476100.0 477196.0 477864.0 48868.3 49053.5 49249.0 49308.8 49349.8 1492.7 1504.1 1535.1 1536.6 1552.5 25954.5 27061.1 27416.5 27456.7 27694.9 42974.8 43193.4 43195.8 43302.1 43546.0 448956.0 450569.0 450779.0 451157.0 451760.0 30539.5 30665.8 30815.8 30964.8 30981.1 32198.7 32884.3 38782.4 39451.6 39555.4 27741.9 28247.6 28901.4 29338.9 29855.9 28836.9 29442.6 30003.1 30520.8 31829.7 35567.5 35952.3 36272.0 36470.0 36494.5 24225.4 27477.7 28176.7 28205.0 28373.7 19040.1 24048.3 24294.4 24435.9 24660.6 19:22:30 INFO - PROCESS | 7263 | 19:22:30 INFO - PROCESS | 7263 | [#2] jquery.html Cycles:180 Average:42036.61 Median:21950.84 stddev:118536.70 (540.0%) stddev-sans-first:118827.99 19:22:30 INFO - PROCESS | 7263 | Values: 391.1 418.9 421.4 425.2 454.0 21462.8 22438.9 22995.2 23338.0 23531.4 23130.9 23471.6 23492.5 23765.0 23773.6 6242.2 6858.5 7135.5 7149.6 7767.3 7417.3 7869.2 7919.0 7919.3 7955.8 7839.1 7912.2 7918.7 7928.0 7952.7 7577.1 7774.6 7777.7 7781.0 7785.0 7139.6 7492.1 7515.3 7547.4 7632.4 26783.7 27246.3 27465.5 27619.9 27743.6 3016.9 3091.8 3105.8 3123.2 3253.1 15720.4 16292.4 16434.3 16542.2 16714.2 1706.2 1964.5 2085.9 2101.9 2141.9 137671.0 138965.0 161816.0 164866.0 165320.5 18456.0 18580.0 21187.6 21203.7 21392.3 32490.8 32750.5 33058.7 33129.7 33174.8 10646.9 10780.5 10798.1 10858.7 11107.0 7271.0 8162.0 8403.7 9114.0 9275.5 8950.6 9707.8 9840.4 9877.2 9953.6 3640.9 3841.5 4203.9 4345.0 4381.7 1013.9 1032.9 1045.2 1062.7 1062.8 17810.8 20097.5 21196.7 21326.8 21353.9 1045.2 1061.8 1071.7 1081.5 1085.8 10441.2 11029.3 11097.6 11120.4 11124.8 415024.0 676492.0 771861.0 797339.0 800374.0 143818.3 149122.0 150056.0 152295.5 164348.2 1255.6 1287.0 1310.6 1364.9 1376.2 19021.6 19289.0 20334.0 20719.9 20730.6 65528.0 66238.7 66534.7 66599.3 69620.0 87891.0 94107.5 94486.6 94780.1 96887.0 7730.2 8033.6 11319.8 11965.4 12126.3 13196.0 13307.7 13520.9 13684.5 13706.5 11426.8 11506.1 11524.5 11603.6 11623.0 9798.8 10134.8 10172.1 11015.7 11157.0 9932.9 11467.3 11758.2 11937.7 12158.9 9919.0 11731.6 11745.4 12080.1 12113.8 11688.2 11695.9 11894.0 11895.8 12069.3 19:22:30 INFO - PROCESS | 7263 | 19:22:30 INFO - PROCESS | 7263 | [#3] mootools.html Cycles:180 Average:7023.07 Median:2504.27 stddev:4416.25 (176.3%) stddev-sans-first:4408.75 19:22:30 INFO - PROCESS | 7263 | Values: 1445.8 1624.8 1643.9 1654.6 1659.3 9884.5 10184.4 11142.0 11822.2 11929.7 8842.0 11277.7 11286.8 11290.3 11290.5 2459.3 2506.8 2513.1 2516.6 2518.8 2443.6 2503.3 2504.2 2504.3 2507.9 2459.3 2466.8 2473.6 2475.3 2490.6 2432.5 2453.6 2457.1 2462.9 2464.0 2412.9 2434.2 2435.4 2447.3 2451.0 11556.1 11576.3 11726.0 11728.1 11743.0 5833.2 5918.7 5996.8 6007.3 6011.9 3713.7 3722.4 3751.2 3842.2 3878.3 1231.7 1240.8 1256.3 1294.2 1296.0 15988.4 15994.9 16000.5 16001.9 16020.4 4939.4 5177.1 5217.1 5278.5 5290.1 13380.1 13399.7 13444.1 13446.0 13448.2 6119.7 6182.8 6186.9 6210.0 6231.3 5283.4 5556.4 5581.6 5596.0 5603.3 3660.2 4188.1 4308.0 4363.7 4384.4 1194.7 1196.5 1199.8 1210.1 1211.3 950.7 960.8 982.8 1003.2 1005.1 6550.5 6669.8 6718.8 6910.9 6965.4 848.9 852.4 934.1 1039.8 1045.8 4174.8 4178.8 4327.2 4339.2 4467.4 14018.5 14595.8 14626.5 14643.0 14674.3 12188.7 13635.5 14410.4 14427.0 14466.3 10194.5 10489.9 10672.9 10690.9 10736.9 5336.3 6869.7 6882.9 6887.3 7007.1 10904.3 12489.4 12743.0 13013.3 13017.3 12932.4 12987.5 13044.9 13128.8 13299.7 9429.4 9654.1 9727.9 9742.0 9758.4 10496.0 10617.6 10722.4 10738.9 10758.9 9139.0 9240.2 9251.4 9368.9 9420.5 8970.9 9003.9 9013.8 9055.8 9151.8 8011.8 8179.9 8185.8 8186.7 8195.3 4677.0 5989.6 5995.5 6152.8 6209.4 5938.7 6006.5 6186.1 6209.0 6233.7 19:22:30 INFO - PROCESS | 7263 | 19:22:30 INFO - PROCESS | 7263 | [#4] prototype.html Cycles:180 Average:4721.65 Median:5101.53 stddev:1323.61 (25.9%) stddev-sans-first:1285.87 19:22:30 INFO - PROCESS | 7263 | Values: 343.2 368.3 382.4 386.5 401.8 4917.6 5359.7 5400.1 5430.9 5491.2 4654.0 5293.4 5297.8 5328.6 5356.3 3979.6 4169.2 4260.4 4413.9 4417.2 3717.2 4167.8 4382.3 4405.7 4485.8 3743.8 4395.5 4408.5 4452.6 4466.9 3506.7 3840.4 4128.2 4446.0 4471.8 3885.4 3998.2 4015.6 4034.0 4054.0 4839.3 5265.8 5284.5 5301.4 5305.9 4163.7 4579.5 5071.9 5092.5 5130.9 5262.6 5370.5 5538.0 5739.7 5759.9 4132.7 4143.4 4250.9 4567.5 4598.9 4507.7 5504.3 6095.6 6150.7 6164.0 4207.9 4613.9 4618.3 4624.1 4636.9 3867.0 4489.5 4666.5 4710.2 4713.0 4667.4 4825.9 5040.7 5077.4 5080.8 5213.6 5355.2 5649.7 5691.4 5722.0 1319.8 1550.2 1594.9 1613.5 1613.5 1130.7 1165.0 1199.7 1214.7 1239.7 4892.8 5045.9 5058.5 5357.7 5404.4 5133.4 5212.5 5390.6 5694.7 5934.2 5016.5 5045.3 5281.4 5394.7 5442.5 2765.5 2778.6 2938.4 3080.4 3083.7 5490.5 5560.0 5659.5 5767.7 6192.9 4948.5 5492.0 5798.9 5905.7 6047.7 5110.6 5744.0 5938.2 6083.9 6095.0 5615.2 5936.5 5937.7 5949.8 5984.0 5432.4 5727.5 5778.9 5822.5 5825.7 5463.6 5708.9 6043.5 6097.8 6105.3 4475.4 4845.8 4962.0 5128.6 5175.7 5305.0 5588.9 5860.4 5872.3 5881.6 4637.8 5031.4 5578.9 5702.7 5728.8 4922.9 5020.9 5124.7 5321.5 5733.5 4648.0 4713.5 4932.8 5435.4 5645.0 5049.0 5196.4 5709.3 5736.8 5757.9 4503.6 5248.5 5475.6 5540.4 5596.0 19:22:30 INFO - PROCESS | 7263 | 19:22:30 INFO - PROCESS | 7263 | [#5] yui.html Cycles:180 Average:20647.86 Median:17338.48 stddev:28869.00 (166.5%) stddev-sans-first:28911.17 19:22:30 INFO - PROCESS | 7263 | Values: 710.4 731.3 815.4 841.0 914.2 16175.4 17320.6 17376.7 17528.2 17639.3 16082.3 16210.9 16220.4 16232.4 16236.0 972.4 977.2 1009.5 1015.2 1017.9 1011.0 1012.8 1022.1 1024.4 1024.7 1064.9 1073.8 1076.6 1076.7 1078.3 1063.0 1069.4 1069.5 1069.5 1069.6 786.1 791.6 793.1 793.1 794.2 16581.7 17038.1 17067.4 17356.4 17963.8 14647.3 14702.0 14737.1 14761.5 14794.7 12053.5 12068.4 12098.0 12227.6 12291.9 3945.5 3946.0 4033.5 4066.4 4084.3 112089.9 117245.0 118500.7 119172.0 119612.0 11099.1 11418.7 11551.5 11746.2 11798.2 19673.6 23651.2 24335.0 24358.3 24361.3 7958.7 8221.0 8242.3 8375.5 8482.6 4563.3 4583.5 4636.4 4668.5 4747.9 3699.9 3758.0 3785.8 3793.0 3795.2 664.6 668.2 669.1 676.6 678.1 640.9 665.0 705.1 707.4 731.8 10497.0 11429.5 11675.0 11774.2 11786.6 511.4 570.2 574.1 634.4 754.0 5087.5 5230.8 5351.5 5361.3 5366.4 93309.1 93516.5 93646.5 94037.5 94614.5 59962.7 79714.8 86845.0 88472.6 91233.6 76238.2 76455.6 79627.8 80141.0 80811.0 11123.8 11271.3 11313.3 11424.1 11576.8 42966.0 48953.5 52163.0 52653.7 53262.5 52954.7 55637.0 60719.7 64411.4 64570.7 13121.5 13412.3 13470.4 13477.0 13867.0 14859.6 15464.8 15684.2 15845.0 16163.8 13728.8 14551.5 14565.9 14596.2 14631.2 14066.2 14499.9 14568.0 14655.5 15190.9 13598.6 14280.7 14349.8 14374.9 14903.8 9898.8 10371.8 10396.6 10488.9 10897.1 9849.9 11113.4 11306.4 11394.3 11751.6 19:22:30 INFO - PROCESS | 7263 | -------- Summary: end -------- 19:22:30 INFO - PROCESS | 7263 | 19:22:31 INFO - TEST-INFO | 7263: exit 0 19:22:31 INFO - TEST-OK | dromaeo_css | took 1115948ms 19:22:31 INFO - TEST-START | kraken 19:22:31 INFO - operating with platform_type : mac_ 19:22:31 INFO - Initialising browser for kraken test... 19:22:31 INFO - TEST-INFO | started process 7268 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpI_d4L3/profile http://localhost:58008/getInfo.html) 19:22:34 INFO - PROCESS | 7268 | 2017-05-27 19:22:34.096 plugin-container[7270:151221] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 19:22:34 INFO - PROCESS | 7268 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:22:34 INFO - PROCESS | 7268 | 2017-05-27 19:22:34.096 plugin-container[7270:151221] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0f, name = 'com.apple.CFPasteboardClient' 19:22:34 INFO - PROCESS | 7268 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:22:34 INFO - PROCESS | 7268 | 2017-05-27 19:22:34.096 plugin-container[7270:151221] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:22:34 INFO - PROCESS | 7268 | __metrics Screen width/height:1600/1200 19:22:34 INFO - PROCESS | 7268 | colorDepth:24 19:22:34 INFO - PROCESS | 7268 | Browser inner width/height: 1024/689 19:22:34 INFO - PROCESS | 7268 | __metrics 19:22:34 ERROR - PROCESS | 7268 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 19:22:34 INFO - TEST-INFO | 7268: exit 0 19:22:34 INFO - Browser initialized. 19:22:34 INFO - Running cycle 1/1 for kraken test... 19:22:34 INFO - TEST-INFO | started process 7271 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpI_d4L3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:22:37 INFO - PROCESS | 7271 | 2017-05-27 19:22:37.269 plugin-container[7272:151346] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 19:22:37 INFO - PROCESS | 7271 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:22:37 INFO - PROCESS | 7271 | 2017-05-27 19:22:37.270 plugin-container[7272:151346] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 19:22:37 INFO - PROCESS | 7271 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:22:37 INFO - PROCESS | 7271 | 2017-05-27 19:22:37.271 plugin-container[7272:151346] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:22:47 INFO - PROCESS | 7271 | RSS: Main: 174419968 19:22:47 INFO - PROCESS | 7271 | 19:22:50 INFO - PROCESS | 7271 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58008/tests/kraken/driver.html?testName=audio-beat-detection) 19:22:50 INFO - PROCESS | 7271 | RSS: Main: 187863040 19:22:50 INFO - PROCESS | 7271 | 19:22:53 INFO - PROCESS | 7271 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58008/tests/kraken/driver.html?testName=audio-dft) 19:22:54 INFO - PROCESS | 7271 | RSS: Main: 195833856 19:22:54 INFO - PROCESS | 7271 | 19:22:57 INFO - PROCESS | 7271 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58008/tests/kraken/driver.html?testName=audio-fft) 19:22:57 INFO - PROCESS | 7271 | RSS: Main: 199589888 19:22:57 INFO - PROCESS | 7271 | 19:22:59 INFO - PROCESS | 7271 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58008/tests/kraken/driver.html?testName=audio-oscillator) 19:23:00 INFO - PROCESS | 7271 | RSS: Main: 200265728 19:23:00 INFO - PROCESS | 7271 | 19:23:02 INFO - PROCESS | 7271 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58008/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:23:03 INFO - PROCESS | 7271 | RSS: Main: 200093696 19:23:03 INFO - PROCESS | 7271 | 19:23:06 INFO - PROCESS | 7271 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58008/tests/kraken/driver.html?testName=imaging-darkroom) 19:23:06 INFO - PROCESS | 7271 | RSS: Main: 205410304 19:23:06 INFO - PROCESS | 7271 | 19:23:10 INFO - PROCESS | 7271 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58008/tests/kraken/driver.html?testName=imaging-desaturate) 19:23:10 INFO - PROCESS | 7271 | RSS: Main: 204910592 19:23:10 INFO - PROCESS | 7271 | 19:23:13 INFO - PROCESS | 7271 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58008/tests/kraken/driver.html?testName=json-parse-financial) 19:23:14 INFO - PROCESS | 7271 | RSS: Main: 205291520 19:23:14 INFO - PROCESS | 7271 | 19:23:16 INFO - PROCESS | 7271 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58008/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:23:16 INFO - PROCESS | 7271 | RSS: Main: 204783616 19:23:16 INFO - PROCESS | 7271 | 19:23:18 INFO - PROCESS | 7271 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58008/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:23:19 INFO - PROCESS | 7271 | RSS: Main: 204824576 19:23:19 INFO - PROCESS | 7271 | 19:23:21 INFO - PROCESS | 7271 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58008/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:23:22 INFO - PROCESS | 7271 | RSS: Main: 204804096 19:23:22 INFO - PROCESS | 7271 | 19:23:24 INFO - PROCESS | 7271 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58008/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:23:25 INFO - PROCESS | 7271 | RSS: Main: 205246464 19:23:25 INFO - PROCESS | 7271 | 19:23:28 INFO - PROCESS | 7271 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58008/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:23:29 INFO - PROCESS | 7271 | RSS: Main: 205328384 19:23:29 INFO - PROCESS | 7271 | 19:23:31 INFO - PROCESS | 7271 | Cycle 1(1): loaded http://localhost:58008/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58008/tests/kraken/driver.html?testName=ai-astar) 19:23:31 INFO - PROCESS | 7271 | RSS: Main: 207511552 19:23:31 INFO - PROCESS | 7271 | 19:23:31 INFO - PROCESS | 7271 | __start_tp_report 19:23:31 INFO - PROCESS | 7271 | _x_x_mozilla_page_load 19:23:31 INFO - PROCESS | 7271 | _x_x_mozilla_page_load_details 19:23:31 INFO - PROCESS | 7271 | |i|pagename|runs| 19:23:31 INFO - PROCESS | 7271 | |0;ai-astar;107;124;125;130;175;163;119;120;118;119 19:23:31 INFO - PROCESS | 7271 | |1;audio-beat-detection;139;110;108;157;106;108;110;118;161;97 19:23:31 INFO - PROCESS | 7271 | |2;audio-dft;194;199;193;199;196;197;193;194;197;196 19:23:31 INFO - PROCESS | 7271 | |3;audio-fft;84;59;60;91;64;63;62;60;73;61 19:23:31 INFO - PROCESS | 7271 | |4;audio-oscillator;88;70;85;71;70;68;79;70;74;82 19:23:31 INFO - PROCESS | 7271 | |5;imaging-gaussian-blur;93;90;90;126;137;91;130;104;90;89 19:23:31 INFO - PROCESS | 7271 | |6;imaging-darkroom;91;99;92;140;152;152;98;97;98;101 19:23:31 INFO - PROCESS | 7271 | |7;imaging-desaturate;137;89;89;90;99;112;94;91;88;75 19:23:31 INFO - PROCESS | 7271 | |8;json-parse-financial;68;65;66;69;71;68;69;69;68;70 19:23:31 INFO - PROCESS | 7271 | |9;json-stringify-tinderbox;60;67;64;74;61;61;57;60;62;62 19:23:31 INFO - PROCESS | 7271 | |10;stanford-crypto-aes;83;91;105;69;90;101;76;71;96;102 19:23:31 INFO - PROCESS | 7271 | |11;stanford-crypto-ccm;134;139;165;126;137;147;154;160;202;138 19:23:31 INFO - PROCESS | 7271 | |12;stanford-crypto-pbkdf2;178;229;173;161;165;162;160;168;163;169 19:23:31 INFO - PROCESS | 7271 | |13;stanford-crypto-sha256-iterative;63;63;65;65;65;62;84;85;84;62 19:23:31 INFO - PROCESS | 7271 | __end_tp_report 19:23:31 INFO - PROCESS | 7271 | __start_cc_report 19:23:31 INFO - PROCESS | 7271 | _x_x_mozilla_cycle_collect,991 19:23:31 INFO - PROCESS | 7271 | __end_cc_report 19:23:31 INFO - PROCESS | 7271 | __startTimestamp1495938211265__endTimestamp 19:23:31 INFO - PROCESS | 7271 | 19:23:31 INFO - PROCESS | 7271 | ------- Summary: start ------- 19:23:31 INFO - PROCESS | 7271 | Number of tests: 14 19:23:31 INFO - PROCESS | 7271 | 19:23:31 INFO - PROCESS | 7271 | [#0] ai-astar Cycles:10 Average:130.00 Median:124.50 stddev:21.58 (17.3%) stddev-sans-first:21.22 19:23:31 INFO - PROCESS | 7271 | Values: 107.0 124.0 125.0 130.0 175.0 163.0 119.0 120.0 118.0 119.0 19:23:31 INFO - PROCESS | 7271 | 19:23:31 INFO - PROCESS | 7271 | [#1] audio-beat-detection Cycles:10 Average:121.40 Median:128.50 stddev:22.63 (17.6%) stddev-sans-first:23.09 19:23:31 INFO - PROCESS | 7271 | Values: 139.0 110.0 108.0 157.0 106.0 108.0 110.0 118.0 161.0 97.0 19:23:31 INFO - PROCESS | 7271 | 19:23:31 INFO - PROCESS | 7271 | [#2] audio-dft Cycles:10 Average:195.80 Median:196.50 stddev:2.25 (1.1%) stddev-sans-first:2.29 19:23:31 INFO - PROCESS | 7271 | Values: 194.0 199.0 193.0 199.0 196.0 197.0 193.0 194.0 197.0 196.0 19:23:31 INFO - PROCESS | 7271 | 19:23:31 INFO - PROCESS | 7271 | [#3] audio-fft Cycles:10 Average:67.70 Median:63.50 stddev:11.27 (17.8%) stddev-sans-first:10.30 19:23:31 INFO - PROCESS | 7271 | Values: 84.0 59.0 60.0 91.0 64.0 63.0 62.0 60.0 73.0 61.0 19:23:31 INFO - PROCESS | 7271 | 19:23:31 INFO - PROCESS | 7271 | [#4] audio-oscillator Cycles:10 Average:75.70 Median:76.50 stddev:7.23 (9.4%) stddev-sans-first:6.14 19:23:31 INFO - PROCESS | 7271 | Values: 88.0 70.0 85.0 71.0 70.0 68.0 79.0 70.0 74.0 82.0 19:23:31 INFO - PROCESS | 7271 | 19:23:31 INFO - PROCESS | 7271 | [#5] imaging-gaussian-blur Cycles:10 Average:104.00 Median:90.00 stddev:19.30 (21.4%) stddev-sans-first:20.05 19:23:31 INFO - PROCESS | 7271 | Values: 93.0 90.0 90.0 126.0 137.0 91.0 130.0 104.0 90.0 89.0 19:23:31 INFO - PROCESS | 7271 | 19:23:31 INFO - PROCESS | 7271 | [#6] imaging-darkroom Cycles:10 Average:112.00 Median:94.50 stddev:25.24 (26.7%) stddev-sans-first:25.60 19:23:31 INFO - PROCESS | 7271 | Values: 91.0 99.0 92.0 140.0 152.0 152.0 98.0 97.0 98.0 101.0 19:23:31 INFO - PROCESS | 7271 | 19:23:31 INFO - PROCESS | 7271 | [#7] imaging-desaturate Cycles:10 Average:96.40 Median:89.50 stddev:17.04 (19.0%) stddev-sans-first:9.88 19:23:31 INFO - PROCESS | 7271 | Values: 137.0 89.0 89.0 90.0 99.0 112.0 94.0 91.0 88.0 75.0 19:23:31 INFO - PROCESS | 7271 | 19:23:31 INFO - PROCESS | 7271 | [#8] json-parse-financial Cycles:10 Average:68.30 Median:69.00 stddev:1.77 (2.6%) stddev-sans-first:1.87 19:23:31 INFO - PROCESS | 7271 | Values: 68.0 65.0 66.0 69.0 71.0 68.0 69.0 69.0 68.0 70.0 19:23:31 INFO - PROCESS | 7271 | 19:23:31 INFO - PROCESS | 7271 | [#9] json-stringify-tinderbox Cycles:10 Average:62.80 Median:62.00 stddev:4.73 (7.6%) stddev-sans-first:4.91 19:23:31 INFO - PROCESS | 7271 | Values: 60.0 67.0 64.0 74.0 61.0 61.0 57.0 60.0 62.0 62.0 19:23:31 INFO - PROCESS | 7271 | 19:23:31 INFO - PROCESS | 7271 | [#10] stanford-crypto-aes Cycles:10 Average:88.40 Median:79.50 stddev:13.12 (16.5%) stddev-sans-first:13.77 19:23:31 INFO - PROCESS | 7271 | Values: 83.0 91.0 105.0 69.0 90.0 101.0 76.0 71.0 96.0 102.0 19:23:31 INFO - PROCESS | 7271 | 19:23:31 INFO - PROCESS | 7271 | [#11] stanford-crypto-ccm Cycles:10 Average:150.20 Median:150.50 stddev:21.91 (14.6%) stddev-sans-first:22.44 19:23:31 INFO - PROCESS | 7271 | Values: 134.0 139.0 165.0 126.0 137.0 147.0 154.0 160.0 202.0 138.0 19:23:31 INFO - PROCESS | 7271 | 19:23:31 INFO - PROCESS | 7271 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.80 Median:168.50 stddev:20.55 (12.2%) stddev-sans-first:21.71 19:23:31 INFO - PROCESS | 7271 | Values: 178.0 229.0 173.0 161.0 165.0 162.0 160.0 168.0 163.0 169.0 19:23:31 INFO - PROCESS | 7271 | 19:23:31 INFO - PROCESS | 7271 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:65.00 stddev:10.10 (15.5%) stddev-sans-first:10.41 19:23:31 INFO - PROCESS | 7271 | Values: 63.0 63.0 65.0 65.0 65.0 62.0 84.0 85.0 84.0 62.0 19:23:31 INFO - PROCESS | 7271 | -------- Summary: end -------- 19:23:31 INFO - PROCESS | 7271 | 19:23:32 INFO - TEST-INFO | 7271: exit 0 19:23:32 INFO - TEST-OK | kraken | took 60553ms 19:23:32 INFO - SUITE-END | took 1176s 19:23:32 INFO - Completed test suite (00:19:36) 19:23:32 INFO - javascript benchmark 19:23:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7988.86470629872, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2975.28593137647, 3527.6119626841046, 3544.1848374873794, 3614.313280476709, 3617.565892092949, 4951.900961980758, 6141.140240366347, 6213.8757224855535, 6217.036359364306, 6275.7489700411925, 6136.417040381157, 6152.230971128609, 6160.383961603838, 6179.7837075702355, 6265.655388953605, 5513.448655134494, 5577.776888924458, 5594.720263986805, 5605.775768969257, 5655.802046928358, 5647.915281270784, 5664.773409063632, 5667.4615911488345, 5686.431356864301, 5709.71451427429, 5411.810586629469, 5437.13005919053, 5632.7183640818, 5645.491905728483, 5650.49145576898, 5565.888682226353, 5583.888322233533, 5615.4665306796, 5632.831015069554, 5645.181448689917, 5130.30740849988, 5560.4161563036105, 5562.9443705563235, 5596.356419011784, 5599.32808063031, 4719.952800471986, 5056.82301119459, 6162.506999440034, 6220.844478888019, 6245.594036387665, 6414.198225221848, 6414.2302923648995, 6434.163558737381, 6491.578047426902, 6498.675066246668, 6691.799246022627, 6728.39444449997, 6730.0577919091365, 6731.427828634572, 6740.662966851687, 6209.441150296449, 6209.96895015522, 6216.502679785606, 6230.7819226326865, 6250.6874656267, 6486.189226346707, 6569.014647802868, 6711.161104861892, 6729.629870357177, 6806.353396427331, 6495.382938620824, 6527.934720652827, 6547.247066587308, 6550.737970481127, 6555.475561955032, 6624.105745724266, 6638.336166383419, 6683.398494135551, 6696.5647232929705, 6698.0, 6317.084022816758, 6378.043293506012, 6606.108175396356, 6615.0, 6634.137562116894, 5364.0, 6467.870642587122, 6552.737890484423, 6558.901616475757, 6570.080188773575, 5315.04329220744, 5316.521513063843, 5329.893402132013, 5335.146376579729, 5353.411124776271, 4561.543845615478, 4689.460712018094, 4696.530346965276, 4703.976480117578, 4750.61995040396, 6112.260323016922, 6122.2959359674205, 6129.328227221949, 6133.44798968095, 6144.986077297285, 6713.160854893138, 6720.932790672128, 6726.293739157318, 6732.326767323327, 6732.663366831639, 6089.725962331706, 6119.593952096689, 6143.662098584621, 6220.440160385587, 6235.75056997715, 5902.557308201902, 5906.616069955439, 5912.320083190402, 5964.105384192406, 5987.341392446828, 6838.794836154923, 6844.041834143224, 6846.520743547904, 6879.312068793281, 6888.242293347828, 6368.713407896748, 6392.296847346881, 6698.765543205965, 6729.360710732472, 6745.055692203194, 6710.932890671226, 6725.8991115134295, 6784.185897692309, 6786.355296246848, 6860.2453730088655, 6578.079068930258, 6593.670316484253, 6614.074029635759, 6614.834629134118, 6620.668966551557, 5859.882802344015, 6084.722208336122, 6389.7763578274535, 6672.566283191577, 6690.765823196165, 6309.7160627770945, 6341.334159913327, 6410.262819775786, 6419.518536109717, 6456.870862582628, 6502.707378167899, 6520.413162815369, 6525.9021114684265, 6525.934740652722, 6536.444402225674, 6185.443310102112, 6345.555811093179, 6369.33122022181, 6388.584741991755, 6389.712462939272, 6254.0, 6327.430531252209, 6397.808065758035, 6400.183919075592, 6493.837654058686, 6283.5601507895835, 6374.4900407966325, 6391.041343798467, 6395.424411802774, 6468.094466774749], "value": 6057.05965597574, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [749.9400047996161, 828.4904783558111, 847.4322204123239, 894.1371576428745, 902.8871391076111, 40291.0, 41628.75113746592, 41667.37498937518, 41667.79166104169, 41696.95757606062, 41492.58507414925, 41685.95785105374, 41723.58276417235, 41761.16477670445, 41830.58169418305, 8054.597270136499, 9611.759706007353, 9757.121859032686, 9775.217982561413, 9781.510924453785, 8091.4740541864885, 11553.075753939707, 11973.401329933513, 11974.7605047899, 11992.400379981009, 11983.281003139797, 12015.098867584922, 12028.158028937978, 12028.578999735011, 12035.939820300886, 11864.7627047459, 11920.165588408814, 11931.164818462712, 11960.0, 11969.281843089355, 11648.0, 11760.823587646191, 11770.117241206857, 11772.529098836036, 11785.410729463492, 41251.381229281586, 41522.37716434256, 41523.5466758665, 41550.58449415497, 41671.0, 4060.7360521566297, 4074.144429669743, 4084.9991752020946, 4102.364133559273, 4137.689673274516, 29353.70646293531, 30644.387112257853, 31264.37471250562, 31306.53040204376, 31313.530297045563, 3190.298134410426, 3290.588676415448, 3362.7814192077435, 3432.0390290718638, 3442.3803715331233, 380651.0, 381275.0, 382759.0, 383570.0, 383742.0812895918, 55361.0, 55459.722701386236, 55494.612634684454, 55568.72215638896, 55574.0, 57421.71289143527, 57644.135337969965, 57688.42311576915, 57714.0, 58095.0, 15722.842771572366, 15775.605609859616, 15876.047437153808, 15886.285117169755, 15912.52262432129, 9630.903690963141, 11998.400079995965, 13364.799528007088, 13365.933170334087, 13393.598192054254, 14551.708965820624, 14755.55733328002, 14946.850531494763, 14968.550943471713, 14974.251287435585, 5627.549796016309, 6060.393960603904, 6378.840528986812, 6415.967920160369, 6491.383318584727, 1003.9196864250843, 1060.9151267898549, 1135.8125909224884, 1162.2910024884814, 1194.9940250298694, 25569.0, 27534.173974780788, 29988.800447982274, 30233.941812036912, 30385.0, 1021.9029192226875, 1112.6050252160367, 1115.6876074699098, 1153.9653810385703, 1157.5646198713703, 13865.098768580212, 14228.075175113585, 14289.142651440947, 14427.0, 14498.855011449961, 473689.0, 474393.0, 476100.0, 477196.0, 477864.0, 48868.266975995386, 49053.509464905605, 49249.0, 49308.75345623249, 49349.75325123352, 1492.6865358274667, 1504.1351223046793, 1535.063611197169, 1536.6004838742008, 1552.4876790659055, 25954.480910382063, 27061.091192361633, 27416.451670966868, 27456.725432746214, 27694.861525692242, 42974.78512607542, 43193.35209971916, 43195.78402107969, 43302.133957320046, 43546.0, 448956.0, 450569.0, 450779.0, 451157.0, 451760.0, 30539.54190687186, 30665.846670766503, 30815.84592077115, 30964.84517577398, 30981.070567882998, 32198.678013219567, 32884.342313154084, 38782.4182637255, 39451.60548394479, 39555.40666890056, 27741.861290693418, 28247.57628635531, 28901.42197156087, 29338.853305733333, 29855.850720746257, 28836.855815720788, 29442.558361624164, 30003.099907002827, 30520.847395762878, 31829.681703182672, 35567.46648800218, 35952.28095438129, 36272.0, 36470.0, 36494.45258321074, 24225.39436514101, 27477.725222748315, 28176.718232818228, 28205.0, 28373.716262837934, 19040.143193556047, 24048.278551643478, 24294.392640184138, 24435.87782061078, 24660.630090548297], "value": 19358.658024439173, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.1238825031928, 418.94972603287613, 421.36794807788317, 425.16030839092787, 453.9841105561306, 21462.785372146293, 22438.88780556099, 22995.195168169117, 23338.0, 23531.41171470714, 23130.884345578248, 23471.647925281133, 23492.530149397, 23765.0, 23773.643395349085, 6242.219722534684, 6858.451323894089, 7135.53619014765, 7149.606771627558, 7767.262110099531, 7417.295356941109, 7869.213078692113, 7919.0, 7919.287264146225, 7955.766856137308, 7839.059312882462, 7912.248336408032, 7918.722844700436, 7927.96036019822, 7952.721654742085, 7577.128630207541, 7774.572398518079, 7777.727779527718, 7781.0, 7785.0, 7139.643017849139, 7492.1384040835455, 7515.323620874093, 7547.3962083033775, 7632.389408847279, 26783.73216267832, 27246.318842028908, 27465.45069098627, 27619.86190069037, 27743.583846242323, 3016.9396612067635, 3091.7526597872115, 3105.7825952183357, 3123.2348074721613, 3253.0962399084074, 15720.37118515258, 16292.42976495817, 16434.342626294932, 16542.172891355385, 16714.164291785484, 1706.1725063344268, 1964.4775299143134, 2085.8852763098025, 2101.8843963582, 2141.882196479193, 137671.0, 138965.0, 161816.0, 164866.0, 165320.52019219843, 18455.984920829214, 18580.0, 21187.576248474943, 21203.727776333468, 21392.251271205743, 32490.83754581212, 32750.508742368882, 33058.66941330556, 33129.66870331266, 33174.8341258297, 10646.946765266124, 10780.51487683056, 10798.136149108053, 10858.728531786768, 11107.000369966741, 7271.0, 8162.0, 8403.663853445916, 9114.0, 9275.489848058422, 8950.597223124974, 9707.805843883083, 9840.392296041555, 9877.160441362565, 9953.552090155961, 3640.890773276806, 3841.5414134953653, 4203.852865149705, 4345.0, 4381.67137464685, 1013.8644717915834, 1032.8605638238741, 1045.231754660324, 1062.697131317571, 1062.8458873463305, 17810.753247272565, 20097.497562561053, 21196.682049769264, 21326.78673213279, 21353.893230533748, 1045.169090572981, 1061.8354155105972, 1071.7374243310283, 1081.4971038466992, 1085.8316960871077, 10441.216908731876, 11029.283096598696, 11097.556097756, 11120.388378639253, 11124.777504450027, 415024.0, 676492.0, 771861.0, 797339.0, 800374.0, 143818.28090859478, 149122.0, 150056.0, 152295.47704523033, 164348.17825910795, 1255.5668294438403, 1287.0, 1310.5609620777147, 1364.9317534123452, 1376.2361889151616, 19021.5935804475, 19289.0, 20334.0, 20719.8964005179, 20730.585388292453, 65528.0, 66238.66880665565, 66534.66732666499, 66599.33400666126, 69619.95570066352, 87891.0, 94107.52946235225, 94486.58270126091, 94780.05219947988, 96887.0, 7730.226977302224, 8033.558154301336, 11319.830202547133, 11965.401729913296, 12126.27242365461, 13196.010299227404, 13307.733845323233, 13520.864791352355, 13684.52104176349, 13706.45174193061, 11426.82859757121, 11506.079513639057, 11524.539018439169, 11603.5938742147, 11623.0, 9798.80402391934, 10134.847977280495, 10172.08451239392, 11015.724606884893, 11157.0, 9932.900670993198, 11467.254628449124, 11758.176927615326, 11937.701557461132, 12158.878411215775, 9919.0, 11731.648050558497, 11745.354005529778, 12080.093992950387, 12113.75772484563, 11688.18182727201, 11695.88304116948, 11894.048476121716, 11895.821562676394, 12069.27584344942], "value": 11976.623837503448, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1445.833729121151, 1624.764409160671, 1643.9068019766848, 1654.6359800843813, 1659.3279721712704, 9884.505774711271, 10184.388936663805, 11142.0, 11822.231554948932, 11929.701757456069, 8841.955790221056, 11277.661670149888, 11286.830697539523, 11290.322580645166, 11290.548378064888, 2459.2990997565744, 2506.7618576235227, 2513.082724805442, 2516.6476693262957, 2518.7733104020635, 2443.621238707994, 2503.3491292264052, 2504.2362079565723, 2504.2987963370197, 2507.887145078467, 2459.311392810016, 2466.765657262566, 2473.5794914864427, 2475.344033831036, 2490.638857365681, 2432.513497300547, 2453.6442215878687, 2457.0785955266774, 2462.940935397778, 2463.963040554393, 2412.9034838606435, 2434.245383930987, 2435.366804630791, 2447.339218411019, 2451.044092803808, 11556.075513958864, 11576.305421674642, 11726.003289720384, 11728.061755059574, 11743.001844843198, 5833.183354330397, 5918.704064796786, 5996.820095397145, 6007.309159446676, 6011.939880601226, 3713.740038197327, 3722.3671975764187, 3751.2309976454762, 3842.212346468967, 3878.2825177342283, 1231.7413343197939, 1240.7518496300686, 1256.3466997161424, 1294.2040645003349, 1296.0409297120157, 15988.360465581363, 15994.920025399799, 16000.519984400604, 16001.91999040009, 16020.35918563256, 4939.382577177853, 5177.0940085485645, 5217.113090774577, 5278.4721527846905, 5290.100682883919, 13380.130291531019, 13399.665008374868, 13444.126131801402, 13446.0, 13448.193108413527, 6119.694015299217, 6182.845428864224, 6186.876262474725, 6209.96895015522, 6231.345708700521, 5283.445238250005, 5556.388797232301, 5581.553475721932, 5596.0, 5603.299587551556, 3660.213054193361, 4188.141431006637, 4308.0, 4363.665399087728, 4384.4081049058605, 1194.713268815478, 1196.5393323570488, 1199.772043311768, 1210.1226610707342, 1211.3337664284609, 950.7147855643279, 960.7982323711959, 982.8083523712951, 1003.1773945364877, 1005.1104772276442, 6550.475961923034, 6669.7665581704405, 6718.8320291993095, 6910.896336554955, 6965.407940325028, 848.8879567766246, 852.3564708644933, 934.1405906565975, 1039.7556574205055, 1045.832666773328, 4174.833006679699, 4178.791060447026, 4327.156204540147, 4339.240632889232, 4467.419235499429, 14018.509352172625, 14595.78106328406, 14626.488072917396, 14642.974991750476, 14674.266286685623, 12188.695282617828, 13635.522756703467, 14410.42358305677, 14427.0, 14466.27668616565, 10194.490275486196, 10489.895101049044, 10672.89327106719, 10690.94654526738, 10736.94631526853, 5336.332958380202, 6869.656517174221, 6882.931170688229, 6887.276835932355, 7007.089078419773, 10904.291221070605, 12489.37553122322, 12743.044271679846, 13013.349332533524, 13017.284049377375, 12932.353382331034, 12987.480500779862, 13044.934775326063, 13128.803067953797, 13299.66750831237, 9429.387089839138, 9654.131128198496, 9727.85408218863, 9742.025797420201, 9758.414495130315, 10496.0, 10617.628383006559, 10722.410267435365, 10738.946305268737, 10758.892411075789, 9139.040400758091, 9240.168384845396, 9251.444913305224, 9368.859467108136, 9420.481873497023, 8970.865437018581, 9003.864942026006, 9013.774655633662, 9055.773605659911, 9151.77120571991, 8011.799705007422, 8179.918200817916, 8185.754427367188, 8186.713465028695, 8195.34437245007, 4676.953230467651, 5989.5507836913275, 5995.520358371233, 6152.815415537541, 6209.441150296495, 5938.732757025858, 6006.519478441802, 6186.1030150627, 6209.0, 6233.662774776533], "value": 5354.3831625279245, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.2191763737497, 368.3369934118586, 382.39581461291164, 386.49176333121943, 401.8372559113559, 4917.606591472682, 5359.732013399329, 5400.108982017972, 5430.859519500905, 5491.231227628125, 4653.953460465403, 5293.444188360225, 5297.814576489814, 5328.600354973373, 5356.250124982505, 3979.5622481527007, 4169.187008533338, 4260.42484264625, 4413.867583972486, 4417.182821178077, 3717.219383929378, 4167.770772607505, 4382.298832186851, 4405.735655860659, 4485.798139083749, 3743.8128093595346, 4395.472543294809, 4408.470983481987, 4452.599266066053, 4466.910661786778, 3506.68439840413, 3840.4047372657283, 4128.174365126986, 4446.0, 4471.798769055384, 3885.417187421881, 3998.240334336496, 4015.5945419103373, 4034.006333729616, 4054.0, 4839.2983017462275, 5265.842024739264, 5284.524392804666, 5301.436076357445, 5305.920411193835, 4163.7293575917465, 4579.542045795427, 5071.923921141186, 5092.490750924915, 5130.896857175725, 5262.578993680497, 5370.489803468664, 5538.030844602178, 5739.713014349308, 5759.8560035999435, 4132.73137246078, 4143.3784932260105, 4250.893727656802, 4567.497575266718, 4598.8620341389815, 4507.684462087688, 5504.339479262514, 6095.573309868311, 6150.7232174552255, 6164.0, 4207.936880946788, 4613.930791038137, 4618.330342100444, 4624.098300831373, 4636.884077898079, 3866.9806650966566, 4489.483709373464, 4666.486686464486, 4710.175719249118, 4712.95287047132, 4667.439907211156, 4825.879353016203, 5040.672356296829, 5077.441481437113, 5080.7552149722815, 5213.6350455467755, 5355.152472397017, 5649.661020338793, 5691.385969909137, 5721.971390143022, 1319.808627748971, 1550.2481296571154, 1594.886775023099, 1613.467555706614, 1613.5240104169297, 1130.6551501792032, 1164.9941750291193, 1199.682084247675, 1214.665966859103, 1239.7210627608897, 4892.755362231874, 5045.915128247371, 5058.519440653132, 5357.678539287655, 5404.378686394066, 5133.409657889316, 5212.50481204285, 5390.622656414013, 5694.658320500783, 5934.240578131104, 5016.523430274118, 5045.318881950963, 5281.419043905167, 5394.676319420847, 5442.482964118402, 2765.4745598336253, 2778.6103950602464, 2938.353562216309, 3080.3520116737886, 3083.7070478304527, 5490.533304669068, 5560.026995275811, 5659.547236221092, 5767.740451679684, 6192.907106393408, 4948.490710333383, 5492.0, 5798.9420105799245, 5905.734241959122, 6047.7278522466595, 5110.565601923804, 5744.0, 5938.168656388109, 6083.908741368795, 6094.969525152346, 5615.18579805934, 5936.506667199873, 5937.732802023833, 5949.791757288474, 5983.995361553928, 5432.436753127392, 5727.4845263926445, 5778.94221057784, 5822.475977162076, 5825.6795876225515, 5463.617546771688, 5708.8572785679025, 6043.486303664238, 6097.804790275409, 6105.328413874381, 4475.4489940733465, 4845.7577121143095, 4961.975190124026, 5128.5640020794235, 5175.689458632494, 5304.9734751326, 5588.916166257428, 5860.413958604106, 5872.29532456108, 5881.617694849821, 4637.77386854305, 5031.446540880427, 5578.86052848682, 5702.6863522505, 5728.770849165987, 4922.901541969069, 5020.899582008413, 5124.718140502308, 5321.467853214647, 5733.512651424676, 4648.023914977893, 4713.48845110395, 4932.827351042696, 5435.396558015474, 5644.955683198621, 5048.974755126201, 5196.376434827694, 5709.314882214161, 5736.795272992718, 5757.9424205757405, 4503.639708823352, 5248.504176309886, 5475.6440831345835, 5540.390557038803, 5595.972020140036], "value": 4332.737790982646, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [710.4494017136718, 731.3344856180876, 815.408828599266, 840.9530134981949, 914.1955079530013, 16175.352985880565, 17320.56698582536, 17376.652466950683, 17528.21123049461, 17639.294428222856, 16082.276297566608, 16210.918945405287, 16220.351185952577, 16232.350705971776, 16236.0, 972.3873959405582, 977.2426369563574, 1009.470028235175, 1015.1827778638193, 1017.9287449878511, 1010.9696709098702, 1012.812629663511, 1022.1056575496441, 1024.4314405504942, 1024.6772266735954, 1064.8988346107108, 1073.7906108308882, 1076.5908954597242, 1076.6500887211648, 1078.2560033576847, 1062.9893701063006, 1069.4278560969844, 1069.5026812532171, 1069.5133714160038, 1069.5561342043043, 786.1116937860181, 791.5923299500704, 793.0760663826635, 793.1077537770009, 794.2454668065361, 16581.66836663272, 17038.062906540134, 17067.40264090751, 17356.39252626152, 17963.820361796475, 14647.340869660891, 14701.970862039661, 14737.11577305365, 14761.483348082766, 14794.704105917928, 12053.457594408274, 12068.396580171044, 12098.0, 12227.572034978733, 12291.93854030733, 3945.5462621798574, 3946.0, 4033.4756481657196, 4066.3697126945076, 4084.2852500812537, 112089.87910120958, 117245.0, 118500.65629847666, 119172.0, 119612.0, 11099.066126962916, 11418.714532136763, 11551.537938482366, 11746.236494627821, 11798.174127811142, 19673.606527869364, 23651.17220897295, 24335.0, 24358.26925192247, 24361.26916192517, 7958.721444749522, 8220.958895205606, 8242.340612750966, 8375.45559538628, 8482.575871206414, 4563.315502674625, 4583.495815460296, 4636.374089497942, 4668.533146685372, 4747.907981164351, 3699.9445008324897, 3758.041699366644, 3785.848566057382, 3792.994856363066, 3795.1650636859845, 664.6344510519195, 668.2248591633681, 669.0666520204288, 676.6008055247428, 678.1353773938268, 640.9487241020707, 664.986700265992, 705.1291654806311, 707.3527722134189, 731.7950973727364, 10497.0, 11429.485673144729, 11675.005956635705, 11774.175807693378, 11786.646400607995, 511.4399732293133, 570.1704020649944, 574.115861573181, 634.3941535833268, 753.9623018849036, 5087.465816089331, 5230.8169214077325, 5351.518363347317, 5361.329833770778, 5366.356037275552, 93309.06690933139, 93516.53241733884, 93646.53176734073, 94037.5298123505, 94614.52692736492, 59962.70018649879, 79714.80427793588, 86845.0, 88472.5576372114, 91233.6314955263, 76238.23761762312, 76455.61772191215, 79627.8055829163, 80141.0, 80811.0, 11123.777524449628, 11271.267367621078, 11313.321200727984, 11424.086073114297, 11576.826347604961, 42966.0, 48953.5104648949, 52163.0, 52653.683657908856, 53262.52501020335, 52954.735226323624, 55637.0, 60719.69640151771, 64411.35588644241, 64570.67714661397, 13121.475140994253, 13412.329383530589, 13470.393832277767, 13477.0, 13867.029307948347, 14859.554213373616, 15464.84535154679, 15684.215789210723, 15844.970076944961, 16163.838361616234, 13728.794068089186, 14551.490697825526, 14565.92717036408, 14596.197209153599, 14631.244250689986, 14066.226357550433, 14499.85500144985, 14568.0, 14655.48705795292, 15190.873277740904, 13598.592042238748, 14280.714385712436, 14349.784753228918, 14374.928125359307, 14903.776443353576, 9898.752531186778, 10371.844422333821, 10396.58413663445, 10488.895111048792, 10897.12822974176, 9849.901500984899, 11113.44432778374, 11306.383424757874, 11394.326728287178, 11751.588694395656], "value": 7978.60650019225, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1515.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 124.0, 125.0, 130.0, 175.0, 163.0, 119.0, 120.0, 118.0, 119.0], "value": 130.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 110.0, 108.0, 157.0, 106.0, 108.0, 110.0, 118.0, 161.0, 97.0], "value": 121.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 199.0, 193.0, 199.0, 196.0, 197.0, 193.0, 194.0, 197.0, 196.0], "value": 195.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 59.0, 60.0, 91.0, 64.0, 63.0, 62.0, 60.0, 73.0, 61.0], "value": 67.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 85.0, 71.0, 70.0, 68.0, 79.0, 70.0, 74.0, 82.0], "value": 75.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 90.0, 90.0, 126.0, 137.0, 91.0, 130.0, 104.0, 90.0, 89.0], "value": 104.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 99.0, 92.0, 140.0, 152.0, 152.0, 98.0, 97.0, 98.0, 101.0], "value": 112.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 89.0, 89.0, 90.0, 99.0, 112.0, 94.0, 91.0, 88.0, 75.0], "value": 96.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 65.0, 66.0, 69.0, 71.0, 68.0, 69.0, 69.0, 68.0, 70.0], "value": 68.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 67.0, 64.0, 74.0, 61.0, 61.0, 57.0, 60.0, 62.0, 62.0], "value": 62.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 91.0, 105.0, 69.0, 90.0, 101.0, 76.0, 71.0, 96.0, 102.0], "value": 88.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 139.0, 165.0, 126.0, 137.0, 147.0, 154.0, 160.0, 202.0, 138.0], "value": 150.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 229.0, 173.0, 161.0, 165.0, 162.0, 160.0, 168.0, 163.0, 169.0], "value": 172.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 65.0, 65.0, 65.0, 62.0, 84.0, 85.0, 84.0, 62.0], "value": 69.8, "unit": "score"}]}]} 19:23:32 INFO - Return code: 0 19:23:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:23:32 ERROR - # TBPL SUCCESS # 19:23:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:23:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:23:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:23:32 INFO - 2017-05-27 19:23:32.597 screenresolution[7276:151724] starting screenresolution argv=screenresolution get 19:23:32 INFO - 2017-05-27 19:23:32.600 screenresolution[7276:151724] Display 0: 1600x1200x32@60 19:23:32 INFO - 2017-05-27 19:23:32.606 screenresolution[7277:151727] starting screenresolution argv=screenresolution list 19:23:32 INFO - Available Modes on Display 0 19:23:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:23:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:23:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:23:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:23:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:23:32 INFO - Intel Iris: 19:23:32 INFO - Chipset Model: Intel Iris 19:23:32 INFO - Type: GPU 19:23:32 INFO - Bus: Built-In 19:23:32 INFO - VRAM (Dynamic, Max): 1536 MB 19:23:32 INFO - Vendor: Intel (0x8086) 19:23:32 INFO - Device ID: 0x0a2e 19:23:32 INFO - Revision ID: 0x0009 19:23:32 INFO - Displays: 19:23:32 INFO - Display: 19:23:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:23:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:23:32 INFO - Display Serial Number: 1600x1200 60 19:23:32 INFO - Main Display: Yes 19:23:32 INFO - Mirror: Off 19:23:32 INFO - Online: Yes 19:23:32 INFO - Rotation: Supported 19:23:32 INFO - Return code: 0 19:23:32 INFO - Running post-action listener: _resource_record_post_action 19:23:32 INFO - [mozharness: 2017-05-28 02:23:32.840468Z] Finished run-tests step (success) 19:23:32 INFO - Running post-run listener: _resource_record_post_run 19:23:32 INFO - Total resource usage - Wall time: 1192s; CPU: 54.0%; Read bytes: 979456; Write bytes: 429990912; Read time: 11; Write time: 1925 19:23:32 INFO - TinderboxPrint: CPU usage
54.4% 19:23:32 INFO - TinderboxPrint: I/O read bytes / time
979,456 / 11 19:23:32 INFO - TinderboxPrint: I/O write bytes / time
429,990,912 / 1,925 19:23:32 INFO - TinderboxPrint: CPU idle
2,174.8 (45.6%) 19:23:32 INFO - TinderboxPrint: CPU system
955.1 (20.0%) 19:23:32 INFO - TinderboxPrint: CPU user
1,639.8 (34.4%) 19:23:32 INFO - TinderboxPrint: Swap in / out
123,154,432 / 0 19:23:32 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 122978816; Write bytes: 126617088; Read time: 11092; Write time: 378 19:23:32 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 843776; Write bytes: 301850112; Read time: 9; Write time: 1539 19:23:33 INFO - Running post-run listener: _upload_blobber_files 19:23:33 INFO - Blob upload gear active. 19:23:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:23:33 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 19:23:33 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'] 19:23:33 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 19:23:33 INFO - (blobuploader) - INFO - Open directory for files ... 19:23:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:23:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:23:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:23:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:23:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:23:34 INFO - (blobuploader) - INFO - Done attempting. 19:23:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:23:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:23:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:23:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:23:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:23:36 INFO - (blobuploader) - INFO - Done attempting. 19:23:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:23:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:23:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:23:37 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:23:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:23:37 INFO - (blobuploader) - INFO - Done attempting. 19:23:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:23:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:23:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:23:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:23:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:23:39 INFO - (blobuploader) - INFO - Done attempting. 19:23:39 INFO - (blobuploader) - INFO - Iteration through files over. 19:23:39 INFO - Return code: 0 19:23:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:23:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:23:39 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a379ded90942d5d444e953a2739008cfeab1dbd1ff3266b39d54d985b832a4f44989d5263b441d5c6db31112e8562102f1c158c0ab3673589b1f3e9c738cd2d2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7db4353570736bc22e8e0e2a6bbf54db0f37ea3e08aff58310cc2b96d6d7994feeae250eb90c5601f3596172c5f8e5a9b640ea430a190ed314f666b4483b6a95", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a635fd544ec621cca4bbcaac6b8842c7cee0910dcadf280a4f353971849442dcc09c0bc37b209f60ceae61e26d1cdfbfaadbf4c4c052b253ec21f543c97f4909", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f489ab64a3a764543cf3f760db0cc00ce39440a5707d6d6cb774332b1b74f563f2c8f9d32569a4fffe463c1ea5b52fee392d704850a9441c9f1e6cf938001ca2"} 19:23:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:23:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:23:39 INFO - Contents: 19:23:39 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a379ded90942d5d444e953a2739008cfeab1dbd1ff3266b39d54d985b832a4f44989d5263b441d5c6db31112e8562102f1c158c0ab3673589b1f3e9c738cd2d2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7db4353570736bc22e8e0e2a6bbf54db0f37ea3e08aff58310cc2b96d6d7994feeae250eb90c5601f3596172c5f8e5a9b640ea430a190ed314f666b4483b6a95", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a635fd544ec621cca4bbcaac6b8842c7cee0910dcadf280a4f353971849442dcc09c0bc37b209f60ceae61e26d1cdfbfaadbf4c4c052b253ec21f543c97f4909", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f489ab64a3a764543cf3f760db0cc00ce39440a5707d6d6cb774332b1b74f563f2c8f9d32569a4fffe463c1ea5b52fee392d704850a9441c9f1e6cf938001ca2"} 19:23:39 INFO - Running post-run listener: copy_logs_to_upload_dir 19:23:39 INFO - Copying logs to upload dir... 19:23:39 INFO - mkdir: /builds/slave/test/build/upload/logs 19:23:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.926007 ========= master_lag: 0.47 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-05-27 19:23:39.923277) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-27 19:23:39.930340) ========= 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.y4vduZselZ/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.f8bHyklbIQ/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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/a379ded90942d5d444e953a2739008cfeab1dbd1ff3266b39d54d985b832a4f44989d5263b441d5c6db31112e8562102f1c158c0ab3673589b1f3e9c738cd2d2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7db4353570736bc22e8e0e2a6bbf54db0f37ea3e08aff58310cc2b96d6d7994feeae250eb90c5601f3596172c5f8e5a9b640ea430a190ed314f666b4483b6a95", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a635fd544ec621cca4bbcaac6b8842c7cee0910dcadf280a4f353971849442dcc09c0bc37b209f60ceae61e26d1cdfbfaadbf4c4c052b253ec21f543c97f4909", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f489ab64a3a764543cf3f760db0cc00ce39440a5707d6d6cb774332b1b74f563f2c8f9d32569a4fffe463c1ea5b52fee392d704850a9441c9f1e6cf938001ca2"} build_url:https://queue.taskcluster.net/v1/task/b3qqt0NHSWW-K-XlEAqkww/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011503 build_url: 'https://queue.taskcluster.net/v1/task/b3qqt0NHSWW-K-XlEAqkww/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/a379ded90942d5d444e953a2739008cfeab1dbd1ff3266b39d54d985b832a4f44989d5263b441d5c6db31112e8562102f1c158c0ab3673589b1f3e9c738cd2d2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7db4353570736bc22e8e0e2a6bbf54db0f37ea3e08aff58310cc2b96d6d7994feeae250eb90c5601f3596172c5f8e5a9b640ea430a190ed314f666b4483b6a95", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a635fd544ec621cca4bbcaac6b8842c7cee0910dcadf280a4f353971849442dcc09c0bc37b209f60ceae61e26d1cdfbfaadbf4c4c052b253ec21f543c97f4909", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f489ab64a3a764543cf3f760db0cc00ce39440a5707d6d6cb774332b1b74f563f2c8f9d32569a4fffe463c1ea5b52fee392d704850a9441c9f1e6cf938001ca2"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-27 19:23:39.972062) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 19:23:39.972426) ========= 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.y4vduZselZ/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.f8bHyklbIQ/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.005423 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-27 19:23:40.021834) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-27 19:23:40.025217) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-27 19:23:40.025578) ========= ========= Total master_lag: 0.63 =========