builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0301 starttime: 1495798702.24 results: success (0) buildid: 20170526031616 builduid: 29b3c7d570844b68aa0a9825d3f7cf5a revision: 9675c8ebd64e924a10fb435d59d7bd7d1069b1ad ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 04:38:22.244170) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 04:38:22.244635) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 04:38:22.244914) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 04:38:22.271055) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 04:38:22.271441) ========= 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.gCps5m4rPw/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.M1EV4qVz60/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-26 04:38:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 47.8M=0s 2017-05-26 04:38:22 (47.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.132616 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 04:38:22.429661) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 04:38:22.430121) ========= 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.gCps5m4rPw/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.M1EV4qVz60/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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.027303 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 04:38:22.480607) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-26 04:38:22.480970) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9675c8ebd64e924a10fb435d59d7bd7d1069b1ad --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 9675c8ebd64e924a10fb435d59d7bd7d1069b1ad --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gCps5m4rPw/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.M1EV4qVz60/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-26 04:38:22,533 truncating revision to first 12 chars 2017-05-26 04:38:22,533 Setting DEBUG logging. 2017-05-26 04:38:22,533 attempt 1/10 2017-05-26 04:38:22,533 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/9675c8ebd64e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-26 04:38:23,725 unpacking tar archive at: mozilla-beta-9675c8ebd64e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.483171 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-26 04:38:23.986935) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 04:38:23.987270) ========= script_repo_revision: 9675c8ebd64e924a10fb435d59d7bd7d1069b1ad ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 04:38:23.987683) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 04:38:23.987949) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 04:38:24.006988) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-05-26 04:38:24.007374) ========= /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.gCps5m4rPw/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.M1EV4qVz60/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 04:38:24 INFO - MultiFileLogger online at 20170526 04:38:24 in /builds/slave/test 04:38:24 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 04:38:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:38:24 INFO - {'append_to_log': False, 04:38:24 INFO - 'base_work_dir': '/builds/slave/test', 04:38:24 INFO - 'blob_upload_branch': 'Mozilla-Beta', 04:38:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:38:24 INFO - 'branch': 'Mozilla-Beta', 04:38:24 INFO - 'buildbot_json_path': 'buildprops.json', 04:38:24 INFO - 'config_files': ('talos/mac_config.py',), 04:38:24 INFO - 'default_actions': ('clobber', 04:38:24 INFO - 'read-buildbot-config', 04:38:24 INFO - 'download-and-extract', 04:38:24 INFO - 'populate-webroot', 04:38:24 INFO - 'create-virtualenv', 04:38:24 INFO - 'install', 04:38:24 INFO - 'run-tests'), 04:38:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:38:24 INFO - 'download_minidump_stackwalk': True, 04:38:24 INFO - 'download_symbols': 'ondemand', 04:38:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:38:24 INFO - 'tooltool.py': '/tools/tooltool.py', 04:38:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:38:24 INFO - '/tools/misc-python/virtualenv.py')}, 04:38:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:38:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:38:24 INFO - 'gecko_profile': False, 04:38:24 INFO - 'gecko_profile_interval': 0, 04:38:24 INFO - 'installer_path': 'installer.exe', 04:38:24 INFO - 'log_level': 'info', 04:38:24 INFO - 'log_name': 'talos', 04:38:24 INFO - 'log_to_console': True, 04:38:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:38:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:38:24 INFO - 'opt_config_files': (), 04:38:24 INFO - 'pip_index': False, 04:38:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:38:24 INFO - 'cmd': ('bash', 04:38:24 INFO - '-c', 04:38:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:38:24 INFO - 'enabled': True, 04:38:24 INFO - 'halt_on_failure': False, 04:38:24 INFO - 'name': 'check_screen_resolution'},), 04:38:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:38:24 INFO - 'cmd': ('bash', 04:38:24 INFO - '-c', 04:38:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:38:24 INFO - 'enabled': True, 04:38:24 INFO - 'halt_on_failure': False, 04:38:24 INFO - 'name': 'check_screen_resolution'},), 04:38:24 INFO - 'run_cmd_checks_enabled': True, 04:38:24 INFO - 'suite': 'dromaeojs-e10s', 04:38:24 INFO - 'system_bits': '32', 04:38:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:38:24 INFO - 'title': 't-yosemite-r7-0301', 04:38:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:38:24 INFO - 'use_talos_json': True, 04:38:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:38:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:38:24 INFO - 'work_dir': 'build'} 04:38:24 INFO - [mozharness: 2017-05-26 11:38:24.169605Z] Running clobber step. 04:38:24 INFO - Running pre-action listener: _resource_record_pre_action 04:38:24 INFO - Running main action method: clobber 04:38:24 INFO - rmtree: /builds/slave/test/build 04:38:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:38:26 INFO - Running post-action listener: _resource_record_post_action 04:38:26 INFO - [mozharness: 2017-05-26 11:38:26.605031Z] Finished clobber step (success) 04:38:26 INFO - [mozharness: 2017-05-26 11:38:26.605162Z] Running read-buildbot-config step. 04:38:26 INFO - Running pre-action listener: _resource_record_pre_action 04:38:26 INFO - Running main action method: read_buildbot_config 04:38:26 INFO - Using buildbot properties: 04:38:26 INFO - { 04:38:26 INFO - "project": "", 04:38:26 INFO - "product": "firefox", 04:38:26 INFO - "script_repo_revision": "production", 04:38:26 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 04:38:26 INFO - "repository": "", 04:38:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 04:38:26 INFO - "buildid": "20170526031616", 04:38:26 INFO - "pgo_build": "False", 04:38:26 INFO - "basedir": "/builds/slave/test", 04:38:26 INFO - "buildnumber": 2, 04:38:26 INFO - "slavename": "t-yosemite-r7-0301", 04:38:26 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:38:26 INFO - "platform": "yosemite_r7", 04:38:26 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 04:38:26 INFO - "branch": "mozilla-beta", 04:38:26 INFO - "repo_path": "releases/mozilla-beta", 04:38:26 INFO - "revision": "9675c8ebd64e924a10fb435d59d7bd7d1069b1ad", 04:38:26 INFO - "stage_platform": "macosx64", 04:38:26 INFO - "builduid": "29b3c7d570844b68aa0a9825d3f7cf5a", 04:38:26 INFO - "slavebuilddir": "test" 04:38:26 INFO - } 04:38:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/NBBQk6gYS7q_DeDX5XfpiA/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 04:38:26 INFO - Running post-action listener: _resource_record_post_action 04:38:26 INFO - [mozharness: 2017-05-26 11:38:26.608434Z] Finished read-buildbot-config step (success) 04:38:26 INFO - [mozharness: 2017-05-26 11:38:26.608555Z] Running download-and-extract step. 04:38:26 INFO - Running pre-action listener: _resource_record_pre_action 04:38:26 INFO - Running main action method: download_and_extract 04:38:26 INFO - mkdir: /builds/slave/test/build/tests 04:38: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')]} 04:38:26 INFO - trying https://queue.taskcluster.net/v1/task/NBBQk6gYS7q_DeDX5XfpiA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 04:38:26 INFO - Downloading https://queue.taskcluster.net/v1/task/NBBQk6gYS7q_DeDX5XfpiA/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 04:38:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NBBQk6gYS7q_DeDX5XfpiA/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 04:38:27 INFO - Downloaded 1287 bytes. 04:38:27 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 04:38:27 INFO - Using the following test package requirements: 04:38:27 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:38:27 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 04:38:27 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 04:38:27 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:38:27 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 04:38:27 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:38:27 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 04:38:27 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 04:38:27 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:38:27 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 04:38:27 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 04:38:27 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:38:27 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 04:38:27 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:38:27 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 04:38:27 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:38:27 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 04:38:27 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:38:27 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 04:38:27 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 04:38:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NBBQk6gYS7q_DeDX5XfpiA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 04:38:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NBBQk6gYS7q_DeDX5XfpiA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 04:38:27 INFO - Fetch https://queue.taskcluster.net/v1/task/NBBQk6gYS7q_DeDX5XfpiA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 04:38:29 INFO - Expected file size: 51398450 04:38:29 INFO - Obtained file size: 51398450 04:38:45 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 04:38:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NBBQk6gYS7q_DeDX5XfpiA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 04:38:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NBBQk6gYS7q_DeDX5XfpiA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 04:38:45 INFO - Fetch https://queue.taskcluster.net/v1/task/NBBQk6gYS7q_DeDX5XfpiA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 04:38:47 INFO - Expected file size: 51398450 04:38:47 INFO - Obtained file size: 51398450 04:39:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NBBQk6gYS7q_DeDX5XfpiA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 04:39:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NBBQk6gYS7q_DeDX5XfpiA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 04:39:02 INFO - Fetch https://queue.taskcluster.net/v1/task/NBBQk6gYS7q_DeDX5XfpiA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 04:39:03 INFO - Expected file size: 14404449 04:39:03 INFO - Obtained file size: 14404449 04:39:03 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')]} 04:39:03 INFO - trying https://queue.taskcluster.net/v1/task/NBBQk6gYS7q_DeDX5XfpiA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 04:39:03 INFO - Downloading https://queue.taskcluster.net/v1/task/NBBQk6gYS7q_DeDX5XfpiA/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 04:39:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NBBQk6gYS7q_DeDX5XfpiA/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 04:39:05 INFO - Downloaded 59647642 bytes. 04:39:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NBBQk6gYS7q_DeDX5XfpiA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 04:39:05 INFO - mkdir: /builds/slave/test/properties 04:39:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:39:05 INFO - Writing to file /builds/slave/test/properties/build_url 04:39:05 INFO - Contents: 04:39:05 INFO - build_url:https://queue.taskcluster.net/v1/task/NBBQk6gYS7q_DeDX5XfpiA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 04:39:05 INFO - Running post-action listener: _resource_record_post_action 04:39:05 INFO - Running post-action listener: set_extra_try_arguments 04:39:05 INFO - [mozharness: 2017-05-26 11:39:05.971047Z] Finished download-and-extract step (success) 04:39:05 INFO - [mozharness: 2017-05-26 11:39:05.971164Z] Running populate-webroot step. 04:39:05 INFO - Running pre-action listener: _resource_record_pre_action 04:39:05 INFO - Running main action method: populate_webroot 04:39:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:39:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:39:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:39:05 INFO - '--tppagecycles', 04:39:05 INFO - '7'], 04:39:05 INFO - 'tests': ['tsvgm']}}, 04:39:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:39:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:39:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:39:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:39:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:39:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:39:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:39:05 INFO - 'talos_options': ['--disable-e10s'], 04:39:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:39:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:39:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:39:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:39:05 INFO - 'talos_options': ['--disable-e10s'], 04:39:05 INFO - 'tests': ['damp', 'tps']}, 04:39:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:39:05 INFO - 'tests': ['damp', 'tps']}, 04:39:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:39:05 INFO - 'tests': ['dromaeo_dom']}, 04:39:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:39:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:39:05 INFO - 'tests': ['basic_compositor_video']}, 04:39:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:39:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:39:05 INFO - 'tests': ['a11yr', 04:39:05 INFO - 'ts_paint', 04:39:05 INFO - 'tpaint', 04:39:05 INFO - 'sessionrestore', 04:39:05 INFO - 'sessionrestore_no_auto_restore', 04:39:05 INFO - 'tabpaint']}, 04:39:05 INFO - 'other-e10s': {'tests': ['a11yr', 04:39:05 INFO - 'ts_paint', 04:39:05 INFO - 'tpaint', 04:39:05 INFO - 'sessionrestore', 04:39:05 INFO - 'sessionrestore_no_auto_restore', 04:39:05 INFO - 'tabpaint']}, 04:39:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:39:05 INFO - 'ts_paint', 04:39:05 INFO - 'tpaint', 04:39:05 INFO - 'sessionrestore', 04:39:05 INFO - 'sessionrestore_no_auto_restore', 04:39:05 INFO - 'tabpaint']}, 04:39:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:39:05 INFO - 'ts_paint', 04:39:05 INFO - 'tpaint', 04:39:05 INFO - 'sessionrestore', 04:39:05 INFO - 'sessionrestore_no_auto_restore', 04:39:05 INFO - 'tabpaint']}, 04:39:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:39:05 INFO - 'tests': ['a11yr', 04:39:05 INFO - 'ts_paint', 04:39:05 INFO - 'tpaint', 04:39:05 INFO - 'sessionrestore', 04:39:05 INFO - 'sessionrestore_no_auto_restore', 04:39:05 INFO - 'tabpaint']}, 04:39:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:39:05 INFO - 'tests': ['a11yr', 04:39:05 INFO - 'ts_paint', 04:39:05 INFO - 'tpaint', 04:39:05 INFO - 'sessionrestore', 04:39:05 INFO - 'sessionrestore_no_auto_restore', 04:39:05 INFO - 'tabpaint']}, 04:39:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:39:05 INFO - 'tests': ['tsvgx', 04:39:05 INFO - 'tsvgr_opacity', 04:39:05 INFO - 'tart', 04:39:05 INFO - 'tscrollx', 04:39:05 INFO - 'cart', 04:39:05 INFO - 'tsvg_static']}, 04:39:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:39:05 INFO - 'tsvgr_opacity', 04:39:05 INFO - 'tart', 04:39:05 INFO - 'tscrollx', 04:39:05 INFO - 'cart', 04:39:05 INFO - 'tsvg_static']}, 04:39:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:39:05 INFO - 'talos_options': ['--disable-e10s'], 04:39:05 INFO - 'tests': ['tp5o']}, 04:39:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:39:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:39:05 INFO - 'talos_options': ['--disable-e10s', 04:39:05 INFO - '--xperf_path', 04:39:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:39:05 INFO - 'tests': ['tp5n']}, 04:39:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:39:05 INFO - 'talos_options': ['--xperf_path', 04:39:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:39:05 INFO - 'tests': ['tp5n']}}, 04:39:05 INFO - 'talos.zip': {'path': '', 04:39:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:39:05 INFO - Running post-action listener: _resource_record_post_action 04:39:05 INFO - [mozharness: 2017-05-26 11:39:05.991610Z] Finished populate-webroot step (success) 04:39:05 INFO - [mozharness: 2017-05-26 11:39:05.991767Z] Running create-virtualenv step. 04:39:05 INFO - Running pre-action listener: _resource_record_pre_action 04:39:05 INFO - Running main action method: create_virtualenv 04:39:05 INFO - Creating virtualenv /builds/slave/test/build/venv 04:39:05 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 04:39:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:39:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:39:06 INFO - Using real prefix '/tools/python27' 04:39:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:39:06 INFO - Installing distribute.............................................................................................................................................................................................done. 04:39:09 INFO - Installing pip.................done. 04:39:09 INFO - Return code: 0 04:39:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:39:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:39:09 INFO - Reading from file tmpfile_stdout 04:39:09 INFO - Output received: 04:39:09 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) 04:39:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:39:09 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')]} 04:39:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:09 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')]} 04:39:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:09 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 0x10424cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9e1dc43e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10402ad30>, '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 0x103f51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10401fbe8>, '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.gCps5m4rPw/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.M1EV4qVz60/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 04:39:09 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 04:39:09 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 04:39:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gCps5m4rPw/Render', 04:39:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:09 INFO - 'HOME': '/Users/cltbld', 04:39:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:09 INFO - 'LOGNAME': 'cltbld', 04:39:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:09 INFO - 'MOZ_NO_REMOTE': '1', 04:39:09 INFO - 'NO_EM_RESTART': '1', 04:39:09 INFO - 'PAGER': '/bin/cat', 04:39:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:09 INFO - 'PWD': '/builds/slave/test', 04:39:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:09 INFO - 'SHELL': '/bin/bash', 04:39:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M1EV4qVz60/Listeners', 04:39:09 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 04:39:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:09 INFO - 'USER': 'cltbld', 04:39:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:09 INFO - 'XPC_FLAGS': '0x0', 04:39:09 INFO - 'XPC_SERVICE_NAME': '0', 04:39:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:10 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 04:39:10 INFO - Cleaning up... 04:39:10 INFO - Return code: 0 04:39:10 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:39:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10424cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9e1dc43e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10402ad30>, '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 0x103f51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10401fbe8>, '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.gCps5m4rPw/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.M1EV4qVz60/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 04:39:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:39:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:39:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gCps5m4rPw/Render', 04:39:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:10 INFO - 'HOME': '/Users/cltbld', 04:39:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:10 INFO - 'LOGNAME': 'cltbld', 04:39:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:10 INFO - 'MOZ_NO_REMOTE': '1', 04:39:10 INFO - 'NO_EM_RESTART': '1', 04:39:10 INFO - 'PAGER': '/bin/cat', 04:39:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:10 INFO - 'PWD': '/builds/slave/test', 04:39:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:10 INFO - 'SHELL': '/bin/bash', 04:39:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M1EV4qVz60/Listeners', 04:39:10 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 04:39:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:10 INFO - 'USER': 'cltbld', 04:39:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:10 INFO - 'XPC_FLAGS': '0x0', 04:39:10 INFO - 'XPC_SERVICE_NAME': '0', 04:39:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:10 INFO - Downloading/unpacking psutil>=3.1.1 04:39:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:39:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:39:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:39:12 INFO - Installing collected packages: psutil 04:39:12 INFO - Running setup.py install for psutil 04:39:12 INFO - building 'psutil._psutil_osx' extension 04:39:12 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 04:39:13 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 04:39:13 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 04:39:13 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 04:39:13 INFO - building 'psutil._psutil_posix' extension 04:39:13 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 04:39:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:39:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:39:13 INFO - ^ 04:39:13 INFO - 1 warning generated. 04:39:13 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 04:39:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:39:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:39:13 INFO - Successfully installed psutil 04:39:13 INFO - Cleaning up... 04:39:13 INFO - Return code: 0 04:39:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:39:13 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')]} 04:39:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:13 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')]} 04:39:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:13 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 0x10424cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9e1dc43e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10402ad30>, '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 0x103f51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10401fbe8>, '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.gCps5m4rPw/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.M1EV4qVz60/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 04:39:13 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 04:39:13 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 04:39:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gCps5m4rPw/Render', 04:39:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:13 INFO - 'HOME': '/Users/cltbld', 04:39:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:13 INFO - 'LOGNAME': 'cltbld', 04:39:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:13 INFO - 'MOZ_NO_REMOTE': '1', 04:39:13 INFO - 'NO_EM_RESTART': '1', 04:39:13 INFO - 'PAGER': '/bin/cat', 04:39:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:13 INFO - 'PWD': '/builds/slave/test', 04:39:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:13 INFO - 'SHELL': '/bin/bash', 04:39:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M1EV4qVz60/Listeners', 04:39:13 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 04:39:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:13 INFO - 'USER': 'cltbld', 04:39:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:13 INFO - 'XPC_FLAGS': '0x0', 04:39:13 INFO - 'XPC_SERVICE_NAME': '0', 04:39:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:39:13 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 04:39:13 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 04:39:13 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 04:39:13 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 04:39:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:39:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:39:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:39:15 INFO - Installing collected packages: mozsystemmonitor 04:39:15 INFO - Running setup.py install for mozsystemmonitor 04:39:15 INFO - Successfully installed mozsystemmonitor 04:39:15 INFO - Cleaning up... 04:39:15 INFO - Return code: 0 04:39:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:39:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10424cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9e1dc43e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10402ad30>, '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 0x103f51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10401fbe8>, '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.gCps5m4rPw/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.M1EV4qVz60/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 04:39:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:39:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:39:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gCps5m4rPw/Render', 04:39:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:15 INFO - 'HOME': '/Users/cltbld', 04:39:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:15 INFO - 'LOGNAME': 'cltbld', 04:39:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:15 INFO - 'MOZ_NO_REMOTE': '1', 04:39:15 INFO - 'NO_EM_RESTART': '1', 04:39:15 INFO - 'PAGER': '/bin/cat', 04:39:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:15 INFO - 'PWD': '/builds/slave/test', 04:39:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:15 INFO - 'SHELL': '/bin/bash', 04:39:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M1EV4qVz60/Listeners', 04:39:15 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 04:39:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:15 INFO - 'USER': 'cltbld', 04:39:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:15 INFO - 'XPC_FLAGS': '0x0', 04:39:15 INFO - 'XPC_SERVICE_NAME': '0', 04:39:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:15 INFO - Downloading/unpacking jsonschema==2.5.1 04:39:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:39:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:39:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:17 INFO - Downloading functools32-3.2.3-2.tar.gz 04:39:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:39:17 INFO - warning: no files found matching '*.txt' 04:39:17 INFO - no previously-included directories found matching 'build' 04:39:17 INFO - no previously-included directories found matching 'dist' 04:39:17 INFO - no previously-included directories found matching '.git*' 04:39:17 INFO - Installing collected packages: jsonschema, functools32 04:39:17 INFO - Running setup.py install for functools32 04:39:17 INFO - warning: no files found matching '*.txt' 04:39:17 INFO - no previously-included directories found matching 'build' 04:39:17 INFO - no previously-included directories found matching 'dist' 04:39:17 INFO - no previously-included directories found matching '.git*' 04:39:17 INFO - Successfully installed jsonschema functools32 04:39:17 INFO - Cleaning up... 04:39:17 INFO - Return code: 0 04:39:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:39:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10424cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9e1dc43e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10402ad30>, '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 0x103f51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10401fbe8>, '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.gCps5m4rPw/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.M1EV4qVz60/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 04:39:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:39:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:39:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gCps5m4rPw/Render', 04:39:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:17 INFO - 'HOME': '/Users/cltbld', 04:39:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:17 INFO - 'LOGNAME': 'cltbld', 04:39:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:17 INFO - 'MOZ_NO_REMOTE': '1', 04:39:17 INFO - 'NO_EM_RESTART': '1', 04:39:17 INFO - 'PAGER': '/bin/cat', 04:39:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:17 INFO - 'PWD': '/builds/slave/test', 04:39:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:17 INFO - 'SHELL': '/bin/bash', 04:39:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M1EV4qVz60/Listeners', 04:39:17 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 04:39:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:17 INFO - 'USER': 'cltbld', 04:39:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:17 INFO - 'XPC_FLAGS': '0x0', 04:39:17 INFO - 'XPC_SERVICE_NAME': '0', 04:39:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:39:17 INFO - Cleaning up... 04:39:17 INFO - Return code: 0 04:39:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:39:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10424cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9e1dc43e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10402ad30>, '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 0x103f51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10401fbe8>, '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.gCps5m4rPw/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.M1EV4qVz60/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 04:39:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:39:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:39:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gCps5m4rPw/Render', 04:39:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:17 INFO - 'HOME': '/Users/cltbld', 04:39:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:17 INFO - 'LOGNAME': 'cltbld', 04:39:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:17 INFO - 'MOZ_NO_REMOTE': '1', 04:39:17 INFO - 'NO_EM_RESTART': '1', 04:39:17 INFO - 'PAGER': '/bin/cat', 04:39:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:17 INFO - 'PWD': '/builds/slave/test', 04:39:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:17 INFO - 'SHELL': '/bin/bash', 04:39:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M1EV4qVz60/Listeners', 04:39:17 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 04:39:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:17 INFO - 'USER': 'cltbld', 04:39:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:17 INFO - 'XPC_FLAGS': '0x0', 04:39:17 INFO - 'XPC_SERVICE_NAME': '0', 04:39:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:18 INFO - Downloading/unpacking blobuploader==1.2.4 04:39:18 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 04:39:18 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 04:39:18 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 04:39:18 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 04:39:19 INFO - Downloading blobuploader-1.2.4.tar.gz 04:39:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:39:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:39:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:39:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:39:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:39:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:39:20 INFO - Downloading docopt-0.6.1.tar.gz 04:39:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:39:20 INFO - Installing collected packages: blobuploader, requests, docopt 04:39:20 INFO - Running setup.py install for blobuploader 04:39:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:39:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:39:21 INFO - Running setup.py install for requests 04:39:21 INFO - Running setup.py install for docopt 04:39:21 INFO - Successfully installed blobuploader requests docopt 04:39:21 INFO - Cleaning up... 04:39:21 INFO - Return code: 0 04:39:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:39:21 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')]} 04:39:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39: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')]} 04:39:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:22 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 0x10424cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9e1dc43e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10402ad30>, '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 0x103f51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10401fbe8>, '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.gCps5m4rPw/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.M1EV4qVz60/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 04:39:22 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 04:39:22 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 04:39:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gCps5m4rPw/Render', 04:39:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:22 INFO - 'HOME': '/Users/cltbld', 04:39:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:22 INFO - 'LOGNAME': 'cltbld', 04:39:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:22 INFO - 'MOZ_NO_REMOTE': '1', 04:39:22 INFO - 'NO_EM_RESTART': '1', 04:39:22 INFO - 'PAGER': '/bin/cat', 04:39:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:22 INFO - 'PWD': '/builds/slave/test', 04:39:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:22 INFO - 'SHELL': '/bin/bash', 04:39:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M1EV4qVz60/Listeners', 04:39:22 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 04:39:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:22 INFO - 'USER': 'cltbld', 04:39:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:22 INFO - 'XPC_FLAGS': '0x0', 04:39:22 INFO - 'XPC_SERVICE_NAME': '0', 04:39:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:39:22 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-PIzpn4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:39:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:39:22 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-zhJw23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:39:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:39:22 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-dkXaDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:39:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:39:22 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-hiVlWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:39:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:39:22 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-msUWck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:39:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:39:22 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-2FPaW9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:39:23 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-pll0tU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:39:23 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-oroQVu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:39:23 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-zXi08f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:39:23 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-T1RICM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:39:23 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-EPJkkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:39:23 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-_7ojld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:39:23 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-X0iHBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:39:23 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-n2V5Kk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:39:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:39:23 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-bsSv5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:39:24 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-6_pk8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:39:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:39:24 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-cPbJvJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:39:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:39:24 INFO - Running setup.py install for manifestparser 04:39:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:39:24 INFO - Running setup.py install for mozcrash 04:39:24 INFO - Running setup.py install for mozdebug 04:39:24 INFO - Running setup.py install for mozdevice 04:39:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:39:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:39:24 INFO - Running setup.py install for mozfile 04:39:24 INFO - Running setup.py install for mozhttpd 04:39:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:39:25 INFO - Running setup.py install for mozinfo 04:39:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:39:25 INFO - Running setup.py install for mozInstall 04:39:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:39:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:39:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:39:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:39:25 INFO - Running setup.py install for mozleak 04:39:25 INFO - Running setup.py install for mozlog 04:39:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:39:25 INFO - Running setup.py install for moznetwork 04:39:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:39:25 INFO - Running setup.py install for mozprocess 04:39:26 INFO - Running setup.py install for mozprofile 04:39:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:39:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:39:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:39:26 INFO - Running setup.py install for mozrunner 04:39:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:39:26 INFO - Running setup.py install for mozscreenshot 04:39:26 INFO - Running setup.py install for moztest 04:39:26 INFO - Running setup.py install for mozversion 04:39:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:39:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:39:26 INFO - Cleaning up... 04:39:26 INFO - Return code: 0 04:39:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:39: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')]} 04:39:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39: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')]} 04:39:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:26 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 0x10424cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9e1dc43e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10402ad30>, '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 0x103f51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10401fbe8>, '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.gCps5m4rPw/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.M1EV4qVz60/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 04:39:26 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 04:39:26 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 04:39:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gCps5m4rPw/Render', 04:39:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:26 INFO - 'HOME': '/Users/cltbld', 04:39:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:26 INFO - 'LOGNAME': 'cltbld', 04:39:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:26 INFO - 'MOZ_NO_REMOTE': '1', 04:39:26 INFO - 'NO_EM_RESTART': '1', 04:39:26 INFO - 'PAGER': '/bin/cat', 04:39:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:26 INFO - 'PWD': '/builds/slave/test', 04:39:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:26 INFO - 'SHELL': '/bin/bash', 04:39:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M1EV4qVz60/Listeners', 04:39:26 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 04:39:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:26 INFO - 'USER': 'cltbld', 04:39:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:26 INFO - 'XPC_FLAGS': '0x0', 04:39:26 INFO - 'XPC_SERVICE_NAME': '0', 04:39:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:39:27 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-ag41lG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:39:27 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)) 04:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:39:27 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-z8iR96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:39:27 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)) 04:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:39:27 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-VUXvL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:39:27 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)) 04:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:39:27 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-kJ7b0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:39:27 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)) 04:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:39:27 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-mXHIN9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:39:27 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)) 04:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:39:27 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-cxu1fw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:39:27 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)) 04:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:39:27 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-uPHYvb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:39:27 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)) 04:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:39:27 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-2yNas1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:39:27 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)) 04:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:39:27 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-nYd18Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:39:28 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)) 04:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:39:28 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-0gjNtX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:39:28 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)) 04:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:39:28 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-Pg3zhq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:39:28 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)) 04:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:39:28 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-y0SKqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:39:28 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)) 04:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:39:28 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-3JDFhF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:39:28 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)) 04:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:39:28 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-jjWI5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:39:28 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)) 04:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:39:28 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-FSLXGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:39:28 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)) 04:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:39:28 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-IALByi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:39:28 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)) 04:39:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:39:28 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-umnlfE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:39:28 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)) 04:39:28 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)) 04:39:28 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)) 04:39:28 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)) 04:39:28 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)) 04:39:28 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)) 04:39:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:39: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 04:39: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 04:39: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 04:39: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 04:39:30 INFO - Downloading blessings-1.6.tar.gz 04:39:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:39:30 INFO - Installing collected packages: blessings 04:39:30 INFO - Running setup.py install for blessings 04:39:30 INFO - Successfully installed blessings 04:39:30 INFO - Cleaning up... 04:39:30 INFO - Return code: 0 04:39:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:39:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:39:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:39:30 INFO - Reading from file tmpfile_stdout 04:39:30 INFO - Current package versions: 04:39:30 INFO - blessings == 1.6 04:39:30 INFO - blobuploader == 1.2.4 04:39:30 INFO - docopt == 0.6.1 04:39:30 INFO - functools32 == 3.2.3-2 04:39:30 INFO - jsonschema == 2.5.1 04:39:30 INFO - manifestparser == 1.1 04:39:30 INFO - mozInstall == 1.12 04:39:30 INFO - mozcrash == 1.0 04:39:30 INFO - mozdebug == 0.1 04:39:30 INFO - mozdevice == 0.49 04:39:30 INFO - mozfile == 1.2 04:39:30 INFO - mozhttpd == 0.7 04:39:30 INFO - mozinfo == 0.9 04:39:30 INFO - mozleak == 0.1 04:39:30 INFO - mozlog == 3.4 04:39:30 INFO - moznetwork == 0.27 04:39:30 INFO - mozprocess == 0.25 04:39:30 INFO - mozprofile == 0.28 04:39:30 INFO - mozrunner == 6.13 04:39:30 INFO - mozscreenshot == 0.1 04:39:30 INFO - mozsystemmonitor == 0.3 04:39:30 INFO - moztest == 0.8 04:39:30 INFO - mozversion == 1.4 04:39:30 INFO - psutil == 3.1.1 04:39:30 INFO - requests == 1.2.3 04:39:30 INFO - wsgiref == 0.1.2 04:39:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:39: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')]} 04:39:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39: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')]} 04:39:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:30 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 0x10424cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9e1dc43e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10402ad30>, '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 0x103f51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10401fbe8>, '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.gCps5m4rPw/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.M1EV4qVz60/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 04:39:30 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 04:39:30 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 04:39:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gCps5m4rPw/Render', 04:39:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:30 INFO - 'HOME': '/Users/cltbld', 04:39:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:30 INFO - 'LOGNAME': 'cltbld', 04:39:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:30 INFO - 'MOZ_NO_REMOTE': '1', 04:39:30 INFO - 'NO_EM_RESTART': '1', 04:39:30 INFO - 'PAGER': '/bin/cat', 04:39:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:30 INFO - 'PWD': '/builds/slave/test', 04:39:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:30 INFO - 'SHELL': '/bin/bash', 04:39:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M1EV4qVz60/Listeners', 04:39:30 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 04:39:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:30 INFO - 'USER': 'cltbld', 04:39:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:30 INFO - 'XPC_FLAGS': '0x0', 04:39:30 INFO - 'XPC_SERVICE_NAME': '0', 04:39:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:31 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)) 04:39:31 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)) 04:39:31 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)) 04:39:31 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)) 04:39:31 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)) 04:39:31 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)) 04:39:31 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)) 04:39:31 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)) 04:39:31 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)) 04:39:31 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)) 04:39:31 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)) 04:39:31 INFO - Cleaning up... 04:39:31 INFO - Return code: 0 04:39:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:39:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:39:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:39:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:39:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10424cb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9e1dc43e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10402ad30>, '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 0x103f51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10401fbe8>, '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.gCps5m4rPw/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.M1EV4qVz60/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 04:39:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:39:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:39:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gCps5m4rPw/Render', 04:39:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:31 INFO - 'HOME': '/Users/cltbld', 04:39:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:31 INFO - 'LOGNAME': 'cltbld', 04:39:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:31 INFO - 'MOZ_NO_REMOTE': '1', 04:39:31 INFO - 'NO_EM_RESTART': '1', 04:39:31 INFO - 'PAGER': '/bin/cat', 04:39:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:31 INFO - 'PWD': '/builds/slave/test', 04:39:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:31 INFO - 'SHELL': '/bin/bash', 04:39:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M1EV4qVz60/Listeners', 04:39:31 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 04:39:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:31 INFO - 'USER': 'cltbld', 04:39:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:31 INFO - 'XPC_FLAGS': '0x0', 04:39:31 INFO - 'XPC_SERVICE_NAME': '0', 04:39:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:39:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:39:31 INFO - Cleaning up... 04:39:31 INFO - Return code: 0 04:39:31 INFO - Running post-action listener: _resource_record_post_action 04:39:31 INFO - Running post-action listener: _start_resource_monitoring 04:39:31 INFO - Starting resource monitoring. 04:39:31 INFO - [mozharness: 2017-05-26 11:39:31.493366Z] Finished create-virtualenv step (success) 04:39:31 INFO - [mozharness: 2017-05-26 11:39:31.494332Z] Running install step. 04:39:31 INFO - Running pre-action listener: _resource_record_pre_action 04:39:31 INFO - Running main action method: install 04:39:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:39:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:39:31 INFO - Reading from file tmpfile_stdout 04:39:31 INFO - Detecting whether we're running mozinstall >=1.0... 04:39:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:39:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:39:31 INFO - Reading from file tmpfile_stdout 04:39:31 INFO - Output received: 04:39:31 INFO - Usage: mozinstall [options] installer 04:39:31 INFO - Options: 04:39:31 INFO - -h, --help show this help message and exit 04:39:31 INFO - -d DEST, --destination=DEST 04:39:31 INFO - Directory to install application into. [default: 04:39:31 INFO - "/builds/slave/test"] 04:39:31 INFO - --app=APP Application being installed. [default: firefox] 04:39:31 INFO - mkdir: /builds/slave/test/build/application 04:39:31 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'] 04:39:31 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 04:39:46 INFO - Reading from file tmpfile_stdout 04:39:46 INFO - Output received: 04:39:46 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 04:39:46 INFO - Running post-action listener: _resource_record_post_action 04:39:46 INFO - [mozharness: 2017-05-26 11:39:46.524464Z] Finished install step (success) 04:39:46 INFO - [mozharness: 2017-05-26 11:39:46.524626Z] Running run-tests step. 04:39:46 INFO - Running pre-action listener: _resource_record_pre_action 04:39:46 INFO - Running main action method: run_tests 04:39:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:39:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:39:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:39:46 INFO - 2017-05-26 04:39:46.539 screenresolution[1623:7271] starting screenresolution argv=screenresolution get 04:39:46 INFO - 2017-05-26 04:39:46.541 screenresolution[1623:7271] Display 0: 1600x1200x32@60 04:39:46 INFO - 2017-05-26 04:39:46.547 screenresolution[1624:7273] starting screenresolution argv=screenresolution list 04:39:46 INFO - Available Modes on Display 0 04:39:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:39:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:39:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:39:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:39:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:39:46 INFO - Intel Iris: 04:39:46 INFO - Chipset Model: Intel Iris 04:39:46 INFO - Type: GPU 04:39:46 INFO - Bus: Built-In 04:39:46 INFO - VRAM (Dynamic, Max): 1536 MB 04:39:46 INFO - Vendor: Intel (0x8086) 04:39:46 INFO - Device ID: 0x0a2e 04:39:46 INFO - Revision ID: 0x0009 04:39:46 INFO - Displays: 04:39:46 INFO - Display: 04:39:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:39:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:39:46 INFO - Display Serial Number: 1600x1200 60 04:39:46 INFO - Main Display: Yes 04:39:46 INFO - Mirror: Off 04:39:46 INFO - Online: Yes 04:39:46 INFO - Rotation: Supported 04:39:46 INFO - Return code: 0 04:39:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:39:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:39:46 INFO - Python 2.7.3 04:39:46 INFO - Return code: 0 04:39:46 INFO - grabbing minidump binary from tooltool 04:39:46 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')]} 04:39:46 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 0x10402ad30>, '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 0x103f51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10401fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:39:46 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 04:39:46 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 04:39:46 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 04:39:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:39:46 INFO - Return code: 0 04:39:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:39:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:39:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:39:46 INFO - ENV: RUST_BACKTRACE is now 1 04:39:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:39:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:39:46 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/NBBQk6gYS7q_DeDX5XfpiA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0301', '--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 04:39:46 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/NBBQk6gYS7q_DeDX5XfpiA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0301 --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 04:39:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gCps5m4rPw/Render', 04:39:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:39:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:39:46 INFO - 'HOME': '/Users/cltbld', 04:39:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:39:46 INFO - 'LOGNAME': 'cltbld', 04:39:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:39:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:39:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:39:46 INFO - 'MOZ_NO_REMOTE': '1', 04:39:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:39:46 INFO - 'NO_EM_RESTART': '1', 04:39:46 INFO - 'PAGER': '/bin/cat', 04:39:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:39:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:39:46 INFO - 'PWD': '/builds/slave/test', 04:39:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:39:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:39:46 INFO - 'RUST_BACKTRACE': '1', 04:39:46 INFO - 'SHELL': '/bin/bash', 04:39:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.M1EV4qVz60/Listeners', 04:39:46 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 04:39:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:39:46 INFO - 'USER': 'cltbld', 04:39:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:39:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:39:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:39:46 INFO - 'XPC_FLAGS': '0x0', 04:39:46 INFO - 'XPC_SERVICE_NAME': '0', 04:39:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:39:46 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/NBBQk6gYS7q_DeDX5XfpiA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0301', '--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 04:39:47 INFO - mozversion application_buildid: 20170526031616 04:39:47 INFO - mozversion application_changeset: 9675c8ebd64e924a10fb435d59d7bd7d1069b1ad 04:39:47 INFO - mozversion application_display_name: Firefox 04:39:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:39:47 INFO - mozversion application_name: Firefox 04:39:47 INFO - mozversion application_remotingname: firefox 04:39:47 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:39:47 INFO - mozversion application_vendor: Mozilla 04:39:47 INFO - mozversion application_version: 54.0 04:39:47 INFO - mozversion platform_buildid: 20170526031616 04:39:47 INFO - mozversion platform_changeset: 9675c8ebd64e924a10fb435d59d7bd7d1069b1ad 04:39:47 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:39:47 INFO - mozversion platform_version: 54.0 04:39:47 INFO - using testdate: 1495798787 04:39:47 INFO - actual date: 1495798787 04:39:47 INFO - starting webserver on 'localhost:49223' 04:39:47 INFO - SUITE-START | Running 2 tests 04:39:47 INFO - TEST-START | dromaeo_css 04:39:47 INFO - operating with platform_type : mac_ 04:39:47 INFO - Initialising browser for dromaeo_css test... 04:39:47 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpd7rhzR/profile http://localhost:49223/getInfo.html) 04:39:49 INFO - PROCESS | 1634 | 2017-05-26 04:39:49.888 plugin-container[1637:7447] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a4f, name = 'com.apple.tsm.portname' 04:39:49 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:39:49 INFO - PROCESS | 1634 | 2017-05-26 04:39:49.888 plugin-container[1637:7447] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 04:39:49 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:39:49 INFO - PROCESS | 1634 | 2017-05-26 04:39:49.888 plugin-container[1637:7447] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:39:49 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 04:39:49 INFO - PROCESS | 1634 | colorDepth:24 04:39:49 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 04:39:49 INFO - PROCESS | 1634 | __metrics 04:39:50 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 04:39:50 INFO - TEST-INFO | 1634: exit 0 04:39:50 INFO - Browser initialized. 04:39:50 INFO - Running cycle 1/1 for dromaeo_css test... 04:39:50 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpd7rhzR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:39:52 INFO - PROCESS | 1638 | 2017-05-26 04:39:52.803 plugin-container[1639:7590] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8437, name = 'com.apple.tsm.portname' 04:39:52 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:39:52 INFO - PROCESS | 1638 | 2017-05-26 04:39:52.803 plugin-container[1639:7590] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x860b, name = 'com.apple.CFPasteboardClient' 04:39:52 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:39:52 INFO - PROCESS | 1638 | 2017-05-26 04:39:52.803 plugin-container[1639:7590] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:40:02 INFO - PROCESS | 1638 | RSS: Main: 174567424 04:40:02 INFO - PROCESS | 1638 | 04:43:06 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49223/tests/dromaeo/cssquery-ext.html) 04:43:07 INFO - PROCESS | 1638 | RSS: Main: 189407232 04:43:07 INFO - PROCESS | 1638 | 04:46:10 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-ext.html (next: http://localhost:49223/tests/dromaeo/cssquery-jquery.html) 04:46:10 INFO - PROCESS | 1638 | RSS: Main: 204025856 04:46:10 INFO - PROCESS | 1638 | 04:49:12 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49223/tests/dromaeo/cssquery-mootools.html) 04:49:13 INFO - PROCESS | 1638 | RSS: Main: 205578240 04:49:13 INFO - PROCESS | 1638 | 04:52:15 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49223/tests/dromaeo/cssquery-prototype.html) 04:52:15 INFO - PROCESS | 1638 | RSS: Main: 208605184 04:52:15 INFO - PROCESS | 1638 | 04:55:18 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49223/tests/dromaeo/cssquery-yui.html) 04:55:19 INFO - PROCESS | 1638 | RSS: Main: 211066880 04:55:19 INFO - PROCESS | 1638 | 04:58:22 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-yui.html (next: http://localhost:49223/tests/dromaeo/cssquery-dojo.html) 04:58:22 INFO - PROCESS | 1638 | RSS: Main: 209240064 04:58:22 INFO - PROCESS | 1638 | 04:58:22 INFO - PROCESS | 1638 | __start_tp_report 04:58:22 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 04:58:22 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 04:58:22 INFO - PROCESS | 1638 | |i|pagename|runs| 04:58:22 INFO - PROCESS | 1638 | |0;dojo.html;3630.927381452371;3654.3422184006868;3661.8901432957023;3678.92642147157;3683.1160521474853;5137.563307118895;5535.612507124503;6225.906611400827;6262.499000079994;6267;6207.5344349173765;6218.25380954285;6222.253329600443;6257.687115644222;6266.9373306266925;5503.697296648683;5559.11054231323;5564.610477266573;5576.275084239063;5647.745851436695;4721.197396442613;5616.101423772197;5626.2967129108865;5626.915596266059;5656.095024796034;5450.100733378988;5455.318085239345;5609.186667933147;5609.80365687201;5612.438756124396;5594.832155035356;5617.9719101404435;5626.381098079208;5635.3237611486675;5659.8585035374035;4653.743489257915;5537.418571050022;5625.54995600351;5642.322921249435;5642.576806739511;5986.551008674367;6268.78059267928;6287.811365659038;6290.905636415458;6298.149749783812;6346.460550853183;6371.267304260023;6393.808185754435;6437.0666253393465;6444.226692796848;6711.731530738764;6715.2277488089;6728.158980127484;6730.192376914802;6734.730610775563;6317.9684101580115;6356.269029061671;6378.075179099004;6379.681015949231;6384.201974753156;6479.254885688112;6488.708008139645;6775.3902148805855;6810.965945170243;6900.068490753784;6469.191351081115;6510.674466276667;6524.282328943812;6529.510286728514;6534.575252608565;5544;6648.401643852077;6665.700043497957;6700.966495167493;6812.829679258059;6274.278457977391;6326.335734747877;6589.604623722496;6595.043718660767;6611.471082313404;6248.593841400295;6506.60960342381;6553.049807777846;6556.541042127101;6574.079628851964;5255.921161182585;5289.629725919226;5291.3385826771655;5330.946690533045;5335.733213339395;4582.1293954148605;4722.291656251521;4750.572448479654;4755.096531659043;4780.11567859947;6125.632462052291;6154.692265386713;6246.843828904313;6333.841653958688;6352.745890164434;6667.533272670867;6715.026321183401;6718.294579069225;6745.797626071226;6755.121834161528;6324.810255692337;6351.872962540816;6358.586691865106;6364.936350636527;6373;5662.433756624387;5681.630694004876;5897.144913987448;5908.645481271137;5950.583459157818;6807.251202367636;6862.828429289108;6863.553868998499;6881.931180688129;6928.376446119873;6663.067170596023;6704.4301234395625;6713.160854893138;6721.563098398588;6741.696623651849;6501.057346684799;6899.517033807786;6941.09765730472;6961.965190174017;6977.860442791014;6355.269144048309;6450.451711604566;6470.417662410406;6485.675716214265;6533.771318003847;6726.226483954408;6731.629760363227;6755.088063111417;6756.087928129836;6763.695633696397;6427.903581446376;6443.871122577429;6492.415682588589;6498.935010649833;6502;6198.907016394667;6291.496680265659;6413.839654008687;6417.71120299597;6454.580452270588;6297.748090076529;6370.6814659267775;6390.329015453496;6417.550771445954;6420.357964203542;6300.275468321019;6300.464460520907;6496.675166241764;6541.6402097885075;6574.211094668479;6287.528435367274;6339.841503962253;6343.52423568225;6383.106365108982;6399.680015999088 04:58:22 INFO - PROCESS | 1638 | |1;ext.html;777.5878784244353;836.694606468639;849.8300339932014;974.931754777165;980.9117179453852;39069;40256.19487610246;41500.37749433761;41972.58027419725;42065.158696826045;39345.606543934555;39523.60476395235;39606.40590391139;39725.80137099318;39758;8165.63254653542;8285.29574986124;11015.953484419008;11233.10135189187;11416.486258118362;11119.888801111967;11232.887671123306;11235.83146252807;11237;11242.325460472397;9359.298052646045;11193.216474846764;11236.49435775388;11249.493772780244;11253.324800511955;11153.609623663133;11185.832212516818;11193.776124477465;11214.439278036107;11226.887731122666;10945;10991.61529346469;11011.00900918913;11017.394043327613;11024.559017639285;34337.31325373503;34623.82688086543;34674.47988280178;34705.132371690655;34861.65138348635;3948.3090459169816;3971.1065010372436;3999.480067591224;4024.617661322199;4044.817983190734;25940;25988.480230395286;26239.73760262411;27508.17475475739;27560.862195689093;3187.7131058204873;3256.202230453554;3266.4120458317493;3290.3583801158784;3389.1527118220447;347777;351448;388901;395300;452186.21720674547;43381;43547.12905741867;43556.56443435525;43652.781736091114;43805.56194438079;43831;45858.85352866205;45872;45947;46206.537934620894;14075.2962351882;14138.929305353407;14179.929100354433;14184.290785460686;14191.432342706175;10807.891921080845;12124.787521247805;12545.560905368267;12568.183068100545;12574.43415046325;11911.285322880654;11950.402479875971;11950.581729639422;11971.281723096643;12007.039436845205;4963.45402005779;5591.52472039873;5711.6001879868845;5740.684262365527;5777.209065189804;1141.646089712192;1150.9769804603861;1158.223989926751;1182.810750279951;1223.0643557678384;25482.235532934043;27199.86400067987;27295;27438.5884211737;27580.172594822187;1238.8265642810013;1277.7891647878182;1284.101129209557;1321.9008574356963;1333.0468714868916;12859.742805144031;13350.065495415422;13634.386452609657;14070.43718251279;14255.786163207353;366507;370640;370709;370773;373256;42826.28694852171;42896.356554651706;42973.14053718908;42991;43027.35458968118;1479.3047267784125;1490.5006822714295;1515.6892836968616;1517.1049081042238;1520.2550750132355;25358;26616.201513954613;27049.59425608578;27066.594001089605;27111.1866644001;46702.299465508724;46819.76590117028;46870.06259874852;46877.76561117172;47154.764226178646;371798;372371;373179;373575;374016.3897541594;26966.46067078608;27400.766965486204;31442.74229030813;31626.525602115526;31682.683173167974;44584;44624.33063503985;44656.33015504835;45354.54645453503;45869.54130458653;30910.918117865767;31108.222294441915;31372.372552549277;31376.686233137378;31635.84182079167;31138.53292200664;31423.37153256968;31529;31734;31887.20281992876;29318.267043323234;36524.45213321749;36735.816320918224;37133.44299835559;37520;30654.540181897737;30721.38557228798;31038;31571.052868413983;31997.36005279928;21626.140876471367;23082.884585576965;23334.183303584294;24198.395040124138;24933.875330623232 04:58:22 INFO - PROCESS | 1638 | |2;jquery.html;384.1721091048789;390.20982510416405;427.1371828905613;440.1439200754532;462.24423068283346;20554.794452055492;21145;22300.88495575219;22406.32781016568;22491.887540562293;22183.44541386466;23239.535209295846;24116.91473883675;24201.789910504493;24355.269341919728;6777.7966661;6880.518363714542;7101.502894797366;7103.431725461976;7141.071660684104;6617.900731489031;7068.964655176717;7073.610951397671;7084.433245340386;7091.89362159568;6820.590764554143;6919.204291506466;6985.091938048046;7045.612491312976;7052.61210633415;6734.124563806723;6929.41100006495;6976;7001.844695625241;7027.297270272983;6790.932090679079;6792.320767923217;6817.045613614048;6830.487713421513;6838.589684618889;24823.00707971679;27437.86281068602;27546;27622.585661215096;27798.444031119467;3011.3676128013144;3059.6787337329483;3075.5232938894505;3077.953830692519;3078.6767389424235;17104.828951710573;17290.394836180672;17391.73912391315;17466.563335916577;17526.38657646989;2247.4830788918475;2255.1317742669025;2265.9660105098437;2285.440067183534;2298.195631528968;129878;132329;144062;157028;177688.22311776847;18767.24931002772;19853.503662408268;20108.39674809758;20116.89941550283;20351.880646564285;29838.25404364865;29853.253668658457;30103.849480752455;30219.546706799414;30342.54486182709;10566.260361774755;10661.200409969284;10764.730881728019;10874.075703565128;11032.338059716443;9155.54222288883;9171.541422928827;9184.8622270666;9314.487703176255;9353.158215760479;9023;9209.585568649427;9309.953450232706;9310.86033709495;9338.859917101248;4412.889677758018;4432.2908334666945;4474.306482495218;4475.507694153641;4479.432198730418;992.1963209800085;1046.6493724602344;1053.1890444357803;1086.2884810449011;1109.7614012987246;20260.493487662927;21242.893785530974;21407.036683349288;21476.463088422915;21585.67621485679;1045.1168762395764;1045.926785125034;1047.586203449633;1082.9241953063367;1098.8681358236902;8187.918120818716;8277;8530.10433904431;10900.182486313559;10900.291481053706;715054;715594;716647;718485;731804;153538;155532.11169720622;157049;161800;162529.18735406484;1325.602319304205;1345.280275052842;1370.6299299188963;1373.6909195430949;1376.3531140364014;17952.820471795116;18576.7213491795;18661.440156795317;18707.064646767878;18768.62462750765;56720;58460.830783383244;60460.69769651123;61231.387686122565;61416;86734;86874;87272.69090963768;87507;89072;11398.259113157617;11606.24559403636;11692.649220523397;11718.824217636571;11781.64367126561;12701.047421443613;12883;12920.289384083966;13146.934265328995;13230.867691322963;8062;10758.045706061946;11157.832632510357;11564.595239166589;11599.07207423421;10187.43969081678;10629.043386095289;10691;10794.298370605886;11009.889901100887;9731.56207970629;10588.67641544807;10928.617498387519;11838.526458941546;12013.038956883604;11531.250468719507;11786.15731814842;11969.64091077269;12063.758724825278;12101;9837.655682051094;10334.689959301233;11453.08375329955;11504.367259800456;11842.46708898119 04:58:22 INFO - PROCESS | 1638 | |3;mootools.html;1613.8386161383871;1640.3028712797059;1669.3406104588687;1674;1675.380109359537;10961.342319460828;11275.379854108021;11606.593769218069;11622.535098596067;11660.183787134903;11447.942760286207;11663.708407289821;11697.298162110279;11700.473478693459;11728.589499367525;2420.951580968384;2463.1132792194794;2466.7163276223196;2473.5547601431736;2475.2450502596703;2407.205622144697;2437.2566367257978;2477.083479112731;2478.950420991579;2481.801455883525;2016.8588198826087;2417.697787776528;2429.9271021869286;2435.0138194031447;2460.0282888259126;2369.4668699542635;2415.7101147862195;2418.0327868852423;2429.724394692783;2439.329184474275;2442.584760590686;2446.877656117187;2467.679201703767;2469.407342237868;2472.5178590174924;11291;11501.367424791633;11514.424278786028;11546.59586914454;11550.94224528872;5921.496672782819;6048.909266361008;6098.57309988301;6104.572679912408;6132.570720049598;3025.9546106808416;3556.964430355715;3639.9808053744996;3675.467057276707;3696.667299943018;1185.1230089733535;1185.2532904270254;1218.3299185447968;1239.4608345369793;1240.4727990603958;13788.862111378858;14056.437742490289;14732.631684207874;15608.531744047697;15729.606759830982;4601.171789077931;4654.6741728078705;4784.40194975628;4792.520747925179;4808.2787581863;11927.403629818475;11963.46164422603;11976.981956533618;12007.699807504881;12021.459034343476;5864.325602555676;5896.086106653475;5901.262342207224;5962.016267315845;5972.044472884405;5310.654807437504;5408.91886621701;5456.809011684572;5470.288862447856;5501.257330260363;3867.651911327994;3904.277708623857;3958.722889397772;3988.66096381805;4035.6973227008093;1024.9743756406;1025.01598465474;1030.6186710916859;1041.7447725307347;1046.1369370269406;934.1685899549407;943.3019565521465;946.8721722567503;963.9036096390305;1005.2309982863115;6783.898241526381;6807.965960170267;6897.517173797886;7053.823654408681;7063.92936070643;992.8361820299714;1037.725002874239;1039.6153423233452;1062.8352605346183;1082.8592283003318;4186.413902053715;4242.639375653104;4273.87178384649;4287;4325.978370108192;13570.525031623845;14116.364763585663;14133.85866141346;14147.292635368396;14149.71700565983;11549.942250288696;13243.933780331037;14037.438502459787;14048.367823447968;14058.507952221624;8942.687005954891;10229.386236825814;10458.163346932228;10458.895411045793;10544.947275263574;5445.101558243004;6144.539159562962;6554.901676474952;6804.4216241620015;7077.5753454792875;12208.75582488363;12246.020318374687;12273.631791046282;12697.4286157125;12719.682007949876;12629.557965471164;12769.233845969271;12865.035122366047;12901.225926444444;12916.870831291566;9409.952950235205;9470.621175153192;9483.762905927408;9499.477528735986;9613.375130616487;10395;10422.166226701695;10526.578936842441;10574.101201397967;10652.147828173573;9004.594793234452;9012.188902998761;9037.09629037091;9093.04523025073;9095.953965293811;8644.438111522732;8749.212570868653;8786.868196977179;8798.824023519623;8945.686900958435;7597.392208623186;7905.841883162189;7909.841803163789;8092.190780921861;8222.465539739898;5027.899442011212;5142.51146141116;5932.822015339547;5988.970055149696;6010.639361638316;5361.437049109787;5592.937341905025;5901.114832775118;6073.14975903365;6360.268569114611 04:58:22 INFO - PROCESS | 1638 | |4;prototype.html;385.8553042609022;391.89222963684966;397.37810326838496;408.44451545897573;412.8761371588524;4641.698289611173;5526.309211348582;5586.469285417889;5587.832365029056;5596.4403559644015;3886.514185726784;4109.671226301896;5215.878586103987;5398.6220964532495;5431.456854314567;4216.599423054805;4424.2699954507625;4550.271956486963;4568.908621827562;4583.381243532114;4360.059773400838;4445.244308467552;4523.185826551219;4547.499775024745;4606.56237657422;4105.445764821756;4530.275155975045;4542.024219464543;4571.3143028545655;4625.658559017881;4385;4400.185965596343;4590.934079664148;4611.469680986679;4611.907761844778;3694.8706795262037;3788.1438095333365;3870.806459677005;4096.959030409687;4109.97945010273;5345.973270133624;5386.91919621206;5398.78404863805;5434.91847622286;5449;4793.952060479385;5006.5494105530315;5064.670796398223;5110.207917772714;5294.788208471695;5412.539934105605;5607.663540187561;5818.5345172386105;5827.271591051119;5953.464188223037;4697.694649847749;4731.550502702238;4737.573618374328;4755.643326750507;4760.190767569521;5746.367899531048;5963.671998040106;6306;6319.977607165709;6379.489640828722;4227.5203678712205;4473.507914129443;4634.745089020069;4791.880202994883;4834.018052598418;4240.745555266632;4552;4922.655414121049;4941.332920055818;4959.8512044638;4537.546245375436;4843.733594652257;4886.070002349053;5044;5308.885134121878;5372.495701203669;5556.971705327607;5715.799947001919;5836.9124463133085;5895.056790913433;1549.3570168380222;1577.9842201578067;1597.7203989301825;1644.1749657879743;1863.872357223887;1308.28044575485;1354.7764618837782;1424.2522675595396;1451.9328293704118;1507.6381668399506;4889.682170658896;5576.609637325349;5578.63738856973;5587.8603034923635;5606.439356064361;5374.092197269991;5588.888222235532;5637.943620563824;5851.795187168429;5992.550558708114;5244.842654720364;5337.359516857924;5527.695976721238;5563.554915606742;5571.7347106984325;2734.726527347289;3024.0645986568616;3113.5521982277896;3153.6373317068374;3169.4770362889867;5698.572607054488;6334.144890439824;6339.7781077662985;6403.64779937108;6404.935950640527;4981.90036199274;6230.130960711859;6270.467010304175;6293.213348331458;6353.396427339396;5007.874803129951;5356.7589458473685;5493.379453061357;6052.063339731244;6176.969115154396;4819.3012013258585;6037.909431358621;6116.969415152896;6158.9221886170635;6194.752209911553;5810.767569297181;5963.360075979141;6087.4521293083835;6112.8604988253755;6115.196017174855;5938.762449502145;6301.590396624205;6373.840653983502;6407.725257533695;6412.35876412355;4819.855404337875;4849.515048495122;4866;4924.212126059813;4930.112579735682;5709.771609135588;5731.914021289601;6027.336992930686;6035.849103772441;6063.454289114001;5198.116320225647;5522.558195344443;5889.028310328919;5895.764169433174;5916.792912248051;5366.382865970308;5385.784568617211;5603.80386686464;5851.741875496788;5977.641341519523;4924.63065270099;5039.899202016012;5040.798368065383;5636.928983493123;5714.285714285715;5810.738516766671;5818.33089194748;5853.682921342664;5895.410458954242;5921.289445266423;5018.4981501849525;5479.506824394811;5545.972270138624;5670.5180059695385;5811.27359080115 04:58:22 INFO - PROCESS | 1638 | |5;yui.html;766.2873527619315;808.8665370213915;817.2726273616486;831.9084900660927;905.7961958559325;14619.561413157595;16336.264868080934;17359.39242126526;17454.127293635316;17681.46955591333;15798;15944.521664350088;15956.601084972881;15965.361385544564;15969.680606387865;829.6432534010373;832.854250506162;944.0653752784763;972.0959507657875;978.1441238915949;971.7862070344548;973.0318333258418;973.8539219117118;974.3033730882453;975.463495077708;1002.5388321372178;1006.5319626373735;1012.3217444312328;1013.2501948558088;1014.8173328800813;990.0396615283164;1004.2417974429333;1004.3572113847143;1005.3615953869272;1005.3917379985149;729.8467321862415;745.4707157917885;746.0078096132131;746.5931067568189;750.1897950213756;17055;17118.40085596998;17250;18031.639367212712;18302.26790928361;14466.855331446655;14831.258437078212;14833.184174870377;14942.775858362133;15013.474528391564;12140.210886292363;12149.392530373447;12285.38573071343;12509.812352814715;12929.80605290912;3810.2189051244427;3844.673202777767;3866.709996750255;3912.158885839558;3977.8607748728946;114338.71322573502;115227;117157.41421292924;117273.82726172714;117887.8211217894;11084.72128862014;11428.142889283337;11538.88461115395;11547.191696581158;11671.474783634756;23066.308010759705;23219.419514511934;23672.28983130509;23802.880985594962;23817.642735358982;8072.757817265491;8231.218034286732;8246.216609422096;8273.007239131342;8376.288015518627;3947.940780888289;4504.48198457175;4594.081183763194;4605.263157894721;4641.955559999041;3183.4588120019653;3750.6999440044733;3892.1048158923604;4068.6338229559483;4117.07365842683;725.575538310084;728.7012324946746;729.711763853275;736.7495051682454;737.0749709115026;692.4079911675445;737.6717360774404;748.277911815101;752.7741677496729;776.7242628866671;10632.89367106319;10825.188110891557;10947;11063.225574209728;11397.658070257905;615.7260019291462;694.7429451103125;724.1021133794058;737.6938570493293;742.6695120671358;4619.445666520039;4705.5059218781535;4974.1543937530705;5309.858380448222;5342.893142137135;80351.39297214022;91693;95062;99996.5000175009;116026;68179.65910170517;84823;84878.15121848826;88317.55841220752;89229.55385223032;77256;80348.59825700951;81286;82197.58901205576;82594.58702706448;8853;10599.788004240027;11143.609973650884;11471.369074700639;11526;41480.37779433371;46020;47389.76305118452;47616.52383476121;47977.28034079422;50712.74643626758;52599.47400525946;57354.71322643527;59053.45679259258;60326.69836650789;12981.740365192454;13050.021248406218;13202.669933251746;13454.46182152742;13524.79712804289;15060.879558536582;15302.387904483696;15444.45944391941;15454.381824727334;16294.42969496062;14060.367283472473;14255.786163207767;14580.343884525433;14612.707745845237;15120.971769923915;12185.390730463265;13816.378262978391;14313.427462901367;14376.640583985065;14394.640133996734;13532.729345413234;13689.247091410069;14389.064710794184;14730.26348682583;14779.482718104815;10125.443100629242;10169.389836609827;10599.417032063311;10733.302335348177;10803.189760768131;11072.39101849406;11581.478833452345;11643.650690479299;11751.177417580688;11754.588589399329 04:58:22 INFO - PROCESS | 1638 | __end_tp_report 04:58:22 INFO - PROCESS | 1638 | __start_cc_report 04:58:22 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,390 04:58:22 INFO - PROCESS | 1638 | __end_cc_report 04:58:22 INFO - PROCESS | 1638 | __startTimestamp1495799902559__endTimestamp 04:58:22 INFO - PROCESS | 1638 | 04:58:22 INFO - PROCESS | 1638 | ------- Summary: start ------- 04:58:22 INFO - PROCESS | 1638 | Number of tests: 6 04:58:22 INFO - PROCESS | 1638 | 04:58:22 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:6137.93 Median:6356.27 stddev:692.35 (10.9%) stddev-sans-first:665.95 04:58:22 INFO - PROCESS | 1638 | Values: 3630.9 3654.3 3661.9 3678.9 3683.1 5137.6 5535.6 6225.9 6262.5 6267.0 6207.5 6218.3 6222.3 6257.7 6266.9 5503.7 5559.1 5564.6 5576.3 5647.7 4721.2 5616.1 5626.3 5626.9 5656.1 5450.1 5455.3 5609.2 5609.8 5612.4 5594.8 5618.0 5626.4 5635.3 5659.9 4653.7 5537.4 5625.5 5642.3 5642.6 5986.6 6268.8 6287.8 6290.9 6298.1 6346.5 6371.3 6393.8 6437.1 6444.2 6711.7 6715.2 6728.2 6730.2 6734.7 6318.0 6356.3 6378.1 6379.7 6384.2 6479.3 6488.7 6775.4 6811.0 6900.1 6469.2 6510.7 6524.3 6529.5 6534.6 5544.0 6648.4 6665.7 6701.0 6812.8 6274.3 6326.3 6589.6 6595.0 6611.5 6248.6 6506.6 6553.0 6556.5 6574.1 5255.9 5289.6 5291.3 5330.9 5335.7 4582.1 4722.3 4750.6 4755.1 4780.1 6125.6 6154.7 6246.8 6333.8 6352.7 6667.5 6715.0 6718.3 6745.8 6755.1 6324.8 6351.9 6358.6 6364.9 6373.0 5662.4 5681.6 5897.1 5908.6 5950.6 6807.3 6862.8 6863.6 6881.9 6928.4 6663.1 6704.4 6713.2 6721.6 6741.7 6501.1 6899.5 6941.1 6962.0 6977.9 6355.3 6450.5 6470.4 6485.7 6533.8 6726.2 6731.6 6755.1 6756.1 6763.7 6427.9 6443.9 6492.4 6498.9 6502.0 6198.9 6291.5 6413.8 6417.7 6454.6 6297.7 6370.7 6390.3 6417.6 6420.4 6300.3 6300.5 6496.7 6541.6 6574.2 6287.5 6339.8 6343.5 6383.1 6399.7 04:58:22 INFO - PROCESS | 1638 | 04:58:22 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:51570.10 Median:31550.03 stddev:99648.18 (315.8%) stddev-sans-first:99854.74 04:58:22 INFO - PROCESS | 1638 | Values: 777.6 836.7 849.8 974.9 980.9 39069.0 40256.2 41500.4 41972.6 42065.2 39345.6 39523.6 39606.4 39725.8 39758.0 8165.6 8285.3 11016.0 11233.1 11416.5 11119.9 11232.9 11235.8 11237.0 11242.3 9359.3 11193.2 11236.5 11249.5 11253.3 11153.6 11185.8 11193.8 11214.4 11226.9 10945.0 10991.6 11011.0 11017.4 11024.6 34337.3 34623.8 34674.5 34705.1 34861.7 3948.3 3971.1 3999.5 4024.6 4044.8 25940.0 25988.5 26239.7 27508.2 27560.9 3187.7 3256.2 3266.4 3290.4 3389.2 347777.0 351448.0 388901.0 395300.0 452186.2 43381.0 43547.1 43556.6 43652.8 43805.6 43831.0 45858.9 45872.0 45947.0 46206.5 14075.3 14138.9 14179.9 14184.3 14191.4 10807.9 12124.8 12545.6 12568.2 12574.4 11911.3 11950.4 11950.6 11971.3 12007.0 4963.5 5591.5 5711.6 5740.7 5777.2 1141.6 1151.0 1158.2 1182.8 1223.1 25482.2 27199.9 27295.0 27438.6 27580.2 1238.8 1277.8 1284.1 1321.9 1333.0 12859.7 13350.1 13634.4 14070.4 14255.8 366507.0 370640.0 370709.0 370773.0 373256.0 42826.3 42896.4 42973.1 42991.0 43027.4 1479.3 1490.5 1515.7 1517.1 1520.3 25358.0 26616.2 27049.6 27066.6 27111.2 46702.3 46819.8 46870.1 46877.8 47154.8 371798.0 372371.0 373179.0 373575.0 374016.4 26966.5 27400.8 31442.7 31626.5 31682.7 44584.0 44624.3 44656.3 45354.5 45869.5 30910.9 31108.2 31372.4 31376.7 31635.8 31138.5 31423.4 31529.0 31734.0 31887.2 29318.3 36524.5 36735.8 37133.4 37520.0 30654.5 30721.4 31038.0 31571.1 31997.4 21626.1 23082.9 23334.2 24198.4 24933.9 04:58:22 INFO - PROCESS | 1638 | 04:58:22 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:42200.13 Median:2260.55 stddev:120272.05 (5320.5%) stddev-sans-first:120568.46 04:58:22 INFO - PROCESS | 1638 | Values: 384.2 390.2 427.1 440.1 462.2 20554.8 21145.0 22300.9 22406.3 22491.9 22183.4 23239.5 24116.9 24201.8 24355.3 6777.8 6880.5 7101.5 7103.4 7141.1 6617.9 7069.0 7073.6 7084.4 7091.9 6820.6 6919.2 6985.1 7045.6 7052.6 6734.1 6929.4 6976.0 7001.8 7027.3 6790.9 6792.3 6817.0 6830.5 6838.6 24823.0 27437.9 27546.0 27622.6 27798.4 3011.4 3059.7 3075.5 3078.0 3078.7 17104.8 17290.4 17391.7 17466.6 17526.4 2247.5 2255.1 2266.0 2285.4 2298.2 129878.0 132329.0 144062.0 157028.0 177688.2 18767.2 19853.5 20108.4 20116.9 20351.9 29838.3 29853.3 30103.8 30219.5 30342.5 10566.3 10661.2 10764.7 10874.1 11032.3 9155.5 9171.5 9184.9 9314.5 9353.2 9023.0 9209.6 9310.0 9310.9 9338.9 4412.9 4432.3 4474.3 4475.5 4479.4 992.2 1046.6 1053.2 1086.3 1109.8 20260.5 21242.9 21407.0 21476.5 21585.7 1045.1 1045.9 1047.6 1082.9 1098.9 8187.9 8277.0 8530.1 10900.2 10900.3 715054.0 715594.0 716647.0 718485.0 731804.0 153538.0 155532.1 157049.0 161800.0 162529.2 1325.6 1345.3 1370.6 1373.7 1376.4 17952.8 18576.7 18661.4 18707.1 18768.6 56720.0 58460.8 60460.7 61231.4 61416.0 86734.0 86874.0 87272.7 87507.0 89072.0 11398.3 11606.2 11692.6 11718.8 11781.6 12701.0 12883.0 12920.3 13146.9 13230.9 8062.0 10758.0 11157.8 11564.6 11599.1 10187.4 10629.0 10691.0 10794.3 11009.9 9731.6 10588.7 10928.6 11838.5 12013.0 11531.3 11786.2 11969.6 12063.8 12101.0 9837.7 10334.7 11453.1 11504.4 11842.5 04:58:22 INFO - PROCESS | 1638 | 04:58:22 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:6850.31 Median:2467.20 stddev:4277.89 (173.4%) stddev-sans-first:4271.80 04:58:22 INFO - PROCESS | 1638 | Values: 1613.8 1640.3 1669.3 1674.0 1675.4 10961.3 11275.4 11606.6 11622.5 11660.2 11447.9 11663.7 11697.3 11700.5 11728.6 2421.0 2463.1 2466.7 2473.6 2475.2 2407.2 2437.3 2477.1 2479.0 2481.8 2016.9 2417.7 2429.9 2435.0 2460.0 2369.5 2415.7 2418.0 2429.7 2439.3 2442.6 2446.9 2467.7 2469.4 2472.5 11291.0 11501.4 11514.4 11546.6 11550.9 5921.5 6048.9 6098.6 6104.6 6132.6 3026.0 3557.0 3640.0 3675.5 3696.7 1185.1 1185.3 1218.3 1239.5 1240.5 13788.9 14056.4 14732.6 15608.5 15729.6 4601.2 4654.7 4784.4 4792.5 4808.3 11927.4 11963.5 11977.0 12007.7 12021.5 5864.3 5896.1 5901.3 5962.0 5972.0 5310.7 5408.9 5456.8 5470.3 5501.3 3867.7 3904.3 3958.7 3988.7 4035.7 1025.0 1025.0 1030.6 1041.7 1046.1 934.2 943.3 946.9 963.9 1005.2 6783.9 6808.0 6897.5 7053.8 7063.9 992.8 1037.7 1039.6 1062.8 1082.9 4186.4 4242.6 4273.9 4287.0 4326.0 13570.5 14116.4 14133.9 14147.3 14149.7 11549.9 13243.9 14037.4 14048.4 14058.5 8942.7 10229.4 10458.2 10458.9 10544.9 5445.1 6144.5 6554.9 6804.4 7077.6 12208.8 12246.0 12273.6 12697.4 12719.7 12629.6 12769.2 12865.0 12901.2 12916.9 9410.0 9470.6 9483.8 9499.5 9613.4 10395.0 10422.2 10526.6 10574.1 10652.1 9004.6 9012.2 9037.1 9093.0 9096.0 8644.4 8749.2 8786.9 8798.8 8945.7 7597.4 7905.8 7909.8 8092.2 8222.5 5027.9 5142.5 5932.8 5989.0 6010.6 5361.4 5592.9 5901.1 6073.1 6360.3 04:58:22 INFO - PROCESS | 1638 | 04:58:22 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4914.55 Median:5351.37 stddev:1363.78 (25.5%) stddev-sans-first:1324.57 04:58:22 INFO - PROCESS | 1638 | Values: 385.9 391.9 397.4 408.4 412.9 4641.7 5526.3 5586.5 5587.8 5596.4 3886.5 4109.7 5215.9 5398.6 5431.5 4216.6 4424.3 4550.3 4568.9 4583.4 4360.1 4445.2 4523.2 4547.5 4606.6 4105.4 4530.3 4542.0 4571.3 4625.7 4385.0 4400.2 4590.9 4611.5 4611.9 3694.9 3788.1 3870.8 4097.0 4110.0 5346.0 5386.9 5398.8 5434.9 5449.0 4794.0 5006.5 5064.7 5110.2 5294.8 5412.5 5607.7 5818.5 5827.3 5953.5 4697.7 4731.6 4737.6 4755.6 4760.2 5746.4 5963.7 6306.0 6320.0 6379.5 4227.5 4473.5 4634.7 4791.9 4834.0 4240.7 4552.0 4922.7 4941.3 4959.9 4537.5 4843.7 4886.1 5044.0 5308.9 5372.5 5557.0 5715.8 5836.9 5895.1 1549.4 1578.0 1597.7 1644.2 1863.9 1308.3 1354.8 1424.3 1451.9 1507.6 4889.7 5576.6 5578.6 5587.9 5606.4 5374.1 5588.9 5637.9 5851.8 5992.6 5244.8 5337.4 5527.7 5563.6 5571.7 2734.7 3024.1 3113.6 3153.6 3169.5 5698.6 6334.1 6339.8 6403.6 6404.9 4981.9 6230.1 6270.5 6293.2 6353.4 5007.9 5356.8 5493.4 6052.1 6177.0 4819.3 6037.9 6117.0 6158.9 6194.8 5810.8 5963.4 6087.5 6112.9 6115.2 5938.8 6301.6 6373.8 6407.7 6412.4 4819.9 4849.5 4866.0 4924.2 4930.1 5709.8 5731.9 6027.3 6035.8 6063.5 5198.1 5522.6 5889.0 5895.8 5916.8 5366.4 5385.8 5603.8 5851.7 5977.6 4924.6 5039.9 5040.8 5636.9 5714.3 5810.7 5818.3 5853.7 5895.4 5921.3 5018.5 5479.5 5546.0 5670.5 5811.3 04:58:22 INFO - PROCESS | 1638 | 04:58:22 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:20537.95 Median:23445.85 stddev:29019.73 (123.8%) stddev-sans-first:29063.16 04:58:22 INFO - PROCESS | 1638 | Values: 766.3 808.9 817.3 831.9 905.8 14619.6 16336.3 17359.4 17454.1 17681.5 15798.0 15944.5 15956.6 15965.4 15969.7 829.6 832.9 944.1 972.1 978.1 971.8 973.0 973.9 974.3 975.5 1002.5 1006.5 1012.3 1013.3 1014.8 990.0 1004.2 1004.4 1005.4 1005.4 729.8 745.5 746.0 746.6 750.2 17055.0 17118.4 17250.0 18031.6 18302.3 14466.9 14831.3 14833.2 14942.8 15013.5 12140.2 12149.4 12285.4 12509.8 12929.8 3810.2 3844.7 3866.7 3912.2 3977.9 114338.7 115227.0 117157.4 117273.8 117887.8 11084.7 11428.1 11538.9 11547.2 11671.5 23066.3 23219.4 23672.3 23802.9 23817.6 8072.8 8231.2 8246.2 8273.0 8376.3 3947.9 4504.5 4594.1 4605.3 4642.0 3183.5 3750.7 3892.1 4068.6 4117.1 725.6 728.7 729.7 736.7 737.1 692.4 737.7 748.3 752.8 776.7 10632.9 10825.2 10947.0 11063.2 11397.7 615.7 694.7 724.1 737.7 742.7 4619.4 4705.5 4974.2 5309.9 5342.9 80351.4 91693.0 95062.0 99996.5 116026.0 68179.7 84823.0 84878.2 88317.6 89229.6 77256.0 80348.6 81286.0 82197.6 82594.6 8853.0 10599.8 11143.6 11471.4 11526.0 41480.4 46020.0 47389.8 47616.5 47977.3 50712.7 52599.5 57354.7 59053.5 60326.7 12981.7 13050.0 13202.7 13454.5 13524.8 15060.9 15302.4 15444.5 15454.4 16294.4 14060.4 14255.8 14580.3 14612.7 15121.0 12185.4 13816.4 14313.4 14376.6 14394.6 13532.7 13689.2 14389.1 14730.3 14779.5 10125.4 10169.4 10599.4 10733.3 10803.2 11072.4 11581.5 11643.7 11751.2 11754.6 04:58:22 INFO - PROCESS | 1638 | -------- Summary: end -------- 04:58:22 INFO - PROCESS | 1638 | 04:58:23 INFO - TEST-INFO | 1638: exit 0 04:58:23 INFO - TEST-OK | dromaeo_css | took 1115991ms 04:58:23 INFO - TEST-START | kraken 04:58:23 INFO - operating with platform_type : mac_ 04:58:23 INFO - Initialising browser for kraken test... 04:58:23 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpVNAfQk/profile http://localhost:49223/getInfo.html) 04:58:25 INFO - PROCESS | 1650 | 2017-05-26 04:58:25.639 plugin-container[1652:10035] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8537, name = 'com.apple.tsm.portname' 04:58:25 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:58:25 INFO - PROCESS | 1650 | 2017-05-26 04:58:25.640 plugin-container[1652:10035] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 04:58:25 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:58:25 INFO - PROCESS | 1650 | 2017-05-26 04:58:25.640 plugin-container[1652:10035] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:58:25 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 04:58:25 INFO - PROCESS | 1650 | colorDepth:24 04:58:25 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 04:58:25 INFO - PROCESS | 1650 | __metrics 04:58:25 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 04:58:26 INFO - TEST-INFO | 1650: exit 0 04:58:26 INFO - Browser initialized. 04:58:26 INFO - Running cycle 1/1 for kraken test... 04:58:26 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpVNAfQk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:58:28 INFO - PROCESS | 1653 | 2017-05-26 04:58:28.868 plugin-container[1654:10164] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 04:58:28 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:58:28 INFO - PROCESS | 1653 | 2017-05-26 04:58:28.868 plugin-container[1654:10164] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.CFPasteboardClient' 04:58:28 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:58:28 INFO - PROCESS | 1653 | 2017-05-26 04:58:28.868 plugin-container[1654:10164] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:58:38 INFO - PROCESS | 1653 | RSS: Main: 174518272 04:58:38 INFO - PROCESS | 1653 | 04:58:41 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-beat-detection) 04:58:42 INFO - PROCESS | 1653 | RSS: Main: 188141568 04:58:42 INFO - PROCESS | 1653 | 04:58:45 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-dft) 04:58:45 INFO - PROCESS | 1653 | RSS: Main: 196112384 04:58:45 INFO - PROCESS | 1653 | 04:58:49 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-fft) 04:58:49 INFO - PROCESS | 1653 | RSS: Main: 199823360 04:58:49 INFO - PROCESS | 1653 | 04:58:51 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-oscillator) 04:58:52 INFO - PROCESS | 1653 | RSS: Main: 200208384 04:58:52 INFO - PROCESS | 1653 | 04:58:54 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49223/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:58:54 INFO - PROCESS | 1653 | RSS: Main: 200146944 04:58:54 INFO - PROCESS | 1653 | 04:58:57 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49223/tests/kraken/driver.html?testName=imaging-darkroom) 04:58:58 INFO - PROCESS | 1653 | RSS: Main: 205946880 04:58:58 INFO - PROCESS | 1653 | 04:59:01 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49223/tests/kraken/driver.html?testName=imaging-desaturate) 04:59:01 INFO - PROCESS | 1653 | RSS: Main: 205799424 04:59:01 INFO - PROCESS | 1653 | 04:59:04 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49223/tests/kraken/driver.html?testName=json-parse-financial) 04:59:05 INFO - PROCESS | 1653 | RSS: Main: 205287424 04:59:05 INFO - PROCESS | 1653 | 04:59:07 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49223/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:59:07 INFO - PROCESS | 1653 | RSS: Main: 204546048 04:59:07 INFO - PROCESS | 1653 | 04:59:09 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:59:10 INFO - PROCESS | 1653 | RSS: Main: 204615680 04:59:10 INFO - PROCESS | 1653 | 04:59:12 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:59:12 INFO - PROCESS | 1653 | RSS: Main: 204574720 04:59:12 INFO - PROCESS | 1653 | 04:59:15 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:59:16 INFO - PROCESS | 1653 | RSS: Main: 204611584 04:59:16 INFO - PROCESS | 1653 | 04:59:19 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:59:20 INFO - PROCESS | 1653 | RSS: Main: 205029376 04:59:20 INFO - PROCESS | 1653 | 04:59:21 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49223/tests/kraken/driver.html?testName=ai-astar) 04:59:22 INFO - PROCESS | 1653 | RSS: Main: 206684160 04:59:22 INFO - PROCESS | 1653 | 04:59:22 INFO - PROCESS | 1653 | __start_tp_report 04:59:22 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 04:59:22 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 04:59:22 INFO - PROCESS | 1653 | |i|pagename|runs| 04:59:22 INFO - PROCESS | 1653 | |0;ai-astar;106;120;121;139;161;119;119;118;119;121 04:59:22 INFO - PROCESS | 1653 | |1;audio-beat-detection;146;99;121;178;117;109;111;133;131;98 04:59:22 INFO - PROCESS | 1653 | |2;audio-dft;198;196;197;196;193;197;198;195;203;196 04:59:22 INFO - PROCESS | 1653 | |3;audio-fft;83;57;58;86;60;60;61;60;70;58 04:59:22 INFO - PROCESS | 1653 | |4;audio-oscillator;91;71;70;91;95;102;114;86;70;83 04:59:22 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;88;89;88;93;90;88;94;88;93;95 04:59:22 INFO - PROCESS | 1653 | |6;imaging-darkroom;95;97;95;96;96;95;96;95;106;118 04:59:22 INFO - PROCESS | 1653 | |7;imaging-desaturate;90;95;92;96;91;90;91;91;92;91 04:59:22 INFO - PROCESS | 1653 | |8;json-parse-financial;66;65;65;67;68;69;69;68;70;67 04:59:22 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;56;64;68;71;67;64;59;58;58;58 04:59:22 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;86;82;84;85;83;81;84;89;102;105 04:59:22 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;135;161;131;135;163;136;129;135;196;180 04:59:22 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;165;166;163;170;225;201;219;219;166;164 04:59:22 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;64;65;66;63;65;67;62;60;62;81 04:59:22 INFO - PROCESS | 1653 | __end_tp_report 04:59:22 INFO - PROCESS | 1653 | __start_cc_report 04:59:22 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,987 04:59:22 INFO - PROCESS | 1653 | __end_cc_report 04:59:22 INFO - PROCESS | 1653 | __startTimestamp1495799962157__endTimestamp 04:59:22 INFO - PROCESS | 1653 | 04:59:22 INFO - PROCESS | 1653 | ------- Summary: start ------- 04:59:22 INFO - PROCESS | 1653 | Number of tests: 14 04:59:22 INFO - PROCESS | 1653 | 04:59:22 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:124.30 Median:120.50 stddev:15.14 (12.6%) stddev-sans-first:14.53 04:59:22 INFO - PROCESS | 1653 | Values: 106.0 120.0 121.0 139.0 161.0 119.0 119.0 118.0 119.0 121.0 04:59:22 INFO - PROCESS | 1653 | 04:59:22 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:124.30 Median:139.50 stddev:24.23 (17.4%) stddev-sans-first:24.39 04:59:22 INFO - PROCESS | 1653 | Values: 146.0 99.0 121.0 178.0 117.0 109.0 111.0 133.0 131.0 98.0 04:59:22 INFO - PROCESS | 1653 | 04:59:22 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:196.90 Median:197.00 stddev:2.60 (1.3%) stddev-sans-first:2.73 04:59:22 INFO - PROCESS | 1653 | Values: 198.0 196.0 197.0 196.0 193.0 197.0 198.0 195.0 203.0 196.0 04:59:22 INFO - PROCESS | 1653 | 04:59:22 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:65.30 Median:60.50 stddev:10.76 (17.8%) stddev-sans-first:9.31 04:59:22 INFO - PROCESS | 1653 | Values: 83.0 57.0 58.0 86.0 60.0 60.0 61.0 60.0 70.0 58.0 04:59:22 INFO - PROCESS | 1653 | 04:59:22 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:87.30 Median:84.50 stddev:14.53 (17.2%) stddev-sans-first:15.35 04:59:22 INFO - PROCESS | 1653 | Values: 91.0 71.0 70.0 91.0 95.0 102.0 114.0 86.0 70.0 83.0 04:59:22 INFO - PROCESS | 1653 | 04:59:22 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:90.60 Median:91.50 stddev:2.84 (3.1%) stddev-sans-first:2.85 04:59:22 INFO - PROCESS | 1653 | Values: 88.0 89.0 88.0 93.0 90.0 88.0 94.0 88.0 93.0 95.0 04:59:22 INFO - PROCESS | 1653 | 04:59:22 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:98.90 Median:95.50 stddev:7.49 (7.8%) stddev-sans-first:7.81 04:59:22 INFO - PROCESS | 1653 | Values: 95.0 97.0 95.0 96.0 96.0 95.0 96.0 95.0 106.0 118.0 04:59:22 INFO - PROCESS | 1653 | 04:59:22 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:91.90 Median:91.50 stddev:2.02 (2.2%) stddev-sans-first:2.03 04:59:22 INFO - PROCESS | 1653 | Values: 90.0 95.0 92.0 96.0 91.0 90.0 91.0 91.0 92.0 91.0 04:59:22 INFO - PROCESS | 1653 | 04:59:22 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:67.40 Median:68.00 stddev:1.71 (2.5%) stddev-sans-first:1.74 04:59:22 INFO - PROCESS | 1653 | Values: 66.0 65.0 65.0 67.0 68.0 69.0 69.0 68.0 70.0 67.0 04:59:22 INFO - PROCESS | 1653 | 04:59:22 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:62.30 Median:64.00 stddev:5.19 (8.1%) stddev-sans-first:4.97 04:59:22 INFO - PROCESS | 1653 | Values: 56.0 64.0 68.0 71.0 67.0 64.0 59.0 58.0 58.0 58.0 04:59:22 INFO - PROCESS | 1653 | 04:59:22 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:88.10 Median:84.00 stddev:8.44 (10.0%) stddev-sans-first:8.92 04:59:22 INFO - PROCESS | 1653 | Values: 86.0 82.0 84.0 85.0 83.0 81.0 84.0 89.0 102.0 105.0 04:59:22 INFO - PROCESS | 1653 | 04:59:22 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:150.10 Median:148.50 stddev:23.52 (15.8%) stddev-sans-first:24.30 04:59:22 INFO - PROCESS | 1653 | Values: 135.0 161.0 131.0 135.0 163.0 136.0 129.0 135.0 196.0 180.0 04:59:22 INFO - PROCESS | 1653 | 04:59:22 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.80 Median:185.50 stddev:26.74 (14.4%) stddev-sans-first:27.28 04:59:22 INFO - PROCESS | 1653 | Values: 165.0 166.0 163.0 170.0 225.0 201.0 219.0 219.0 166.0 164.0 04:59:22 INFO - PROCESS | 1653 | 04:59:22 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:65.00 stddev:5.84 (9.0%) stddev-sans-first:6.16 04:59:22 INFO - PROCESS | 1653 | Values: 64.0 65.0 66.0 63.0 65.0 67.0 62.0 60.0 62.0 81.0 04:59:22 INFO - PROCESS | 1653 | -------- Summary: end -------- 04:59:22 INFO - PROCESS | 1653 | 04:59:23 INFO - TEST-INFO | 1653: exit 0 04:59:23 INFO - TEST-OK | kraken | took 59982ms 04:59:23 INFO - SUITE-END | took 1175s 04:59:23 INFO - Completed test suite (00:19:36) 04:59:23 INFO - javascript benchmark 04:59:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7932.690224390122, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3630.927381452371, 3654.3422184006868, 3661.8901432957023, 3678.92642147157, 3683.1160521474853, 5137.563307118895, 5535.612507124503, 6225.906611400827, 6262.499000079994, 6267.0, 6207.5344349173765, 6218.25380954285, 6222.253329600443, 6257.687115644222, 6266.9373306266925, 5503.697296648683, 5559.11054231323, 5564.610477266573, 5576.275084239063, 5647.745851436695, 4721.197396442613, 5616.101423772197, 5626.2967129108865, 5626.915596266059, 5656.095024796034, 5450.100733378988, 5455.318085239345, 5609.186667933147, 5609.80365687201, 5612.438756124396, 5594.832155035356, 5617.9719101404435, 5626.381098079208, 5635.3237611486675, 5659.8585035374035, 4653.743489257915, 5537.418571050022, 5625.54995600351, 5642.322921249435, 5642.576806739511, 5986.551008674367, 6268.78059267928, 6287.811365659038, 6290.905636415458, 6298.149749783812, 6346.460550853183, 6371.267304260023, 6393.808185754435, 6437.0666253393465, 6444.226692796848, 6711.731530738764, 6715.2277488089, 6728.158980127484, 6730.192376914802, 6734.730610775563, 6317.9684101580115, 6356.269029061671, 6378.075179099004, 6379.681015949231, 6384.201974753156, 6479.254885688112, 6488.708008139645, 6775.3902148805855, 6810.965945170243, 6900.068490753784, 6469.191351081115, 6510.674466276667, 6524.282328943812, 6529.510286728514, 6534.575252608565, 5544.0, 6648.401643852077, 6665.700043497957, 6700.966495167493, 6812.829679258059, 6274.278457977391, 6326.335734747877, 6589.604623722496, 6595.043718660767, 6611.471082313404, 6248.593841400295, 6506.60960342381, 6553.049807777846, 6556.541042127101, 6574.079628851964, 5255.921161182585, 5289.629725919226, 5291.3385826771655, 5330.946690533045, 5335.733213339395, 4582.1293954148605, 4722.291656251521, 4750.572448479654, 4755.096531659043, 4780.11567859947, 6125.632462052291, 6154.692265386713, 6246.843828904313, 6333.841653958688, 6352.745890164434, 6667.533272670867, 6715.026321183401, 6718.294579069225, 6745.797626071226, 6755.121834161528, 6324.810255692337, 6351.872962540816, 6358.586691865106, 6364.936350636527, 6373.0, 5662.433756624387, 5681.630694004876, 5897.144913987448, 5908.645481271137, 5950.583459157818, 6807.251202367636, 6862.828429289108, 6863.553868998499, 6881.931180688129, 6928.376446119873, 6663.067170596023, 6704.4301234395625, 6713.160854893138, 6721.563098398588, 6741.696623651849, 6501.057346684799, 6899.517033807786, 6941.09765730472, 6961.965190174017, 6977.860442791014, 6355.269144048309, 6450.451711604566, 6470.417662410406, 6485.675716214265, 6533.771318003847, 6726.226483954408, 6731.629760363227, 6755.088063111417, 6756.087928129836, 6763.695633696397, 6427.903581446376, 6443.871122577429, 6492.415682588589, 6498.935010649833, 6502.0, 6198.907016394667, 6291.496680265659, 6413.839654008687, 6417.71120299597, 6454.580452270588, 6297.748090076529, 6370.6814659267775, 6390.329015453496, 6417.550771445954, 6420.357964203542, 6300.275468321019, 6300.464460520907, 6496.675166241764, 6541.6402097885075, 6574.211094668479, 6287.528435367274, 6339.841503962253, 6343.52423568225, 6383.106365108982, 6399.680015999088], "value": 6094.665043548672, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [777.5878784244353, 836.694606468639, 849.8300339932014, 974.931754777165, 980.9117179453852, 39069.0, 40256.19487610246, 41500.37749433761, 41972.58027419725, 42065.158696826045, 39345.606543934555, 39523.60476395235, 39606.40590391139, 39725.80137099318, 39758.0, 8165.63254653542, 8285.29574986124, 11015.953484419008, 11233.10135189187, 11416.486258118362, 11119.888801111967, 11232.887671123306, 11235.83146252807, 11237.0, 11242.325460472397, 9359.298052646045, 11193.216474846764, 11236.49435775388, 11249.493772780244, 11253.324800511955, 11153.609623663133, 11185.832212516818, 11193.776124477465, 11214.439278036107, 11226.887731122666, 10945.0, 10991.61529346469, 11011.00900918913, 11017.394043327613, 11024.559017639285, 34337.31325373503, 34623.82688086543, 34674.47988280178, 34705.132371690655, 34861.65138348635, 3948.3090459169816, 3971.1065010372436, 3999.480067591224, 4024.617661322199, 4044.817983190734, 25940.0, 25988.480230395286, 26239.73760262411, 27508.17475475739, 27560.862195689093, 3187.7131058204873, 3256.202230453554, 3266.4120458317493, 3290.3583801158784, 3389.1527118220447, 347777.0, 351448.0, 388901.0, 395300.0, 452186.21720674547, 43381.0, 43547.12905741867, 43556.56443435525, 43652.781736091114, 43805.56194438079, 43831.0, 45858.85352866205, 45872.0, 45947.0, 46206.537934620894, 14075.2962351882, 14138.929305353407, 14179.929100354433, 14184.290785460686, 14191.432342706175, 10807.891921080845, 12124.787521247805, 12545.560905368267, 12568.183068100545, 12574.43415046325, 11911.285322880654, 11950.402479875971, 11950.581729639422, 11971.281723096643, 12007.039436845205, 4963.45402005779, 5591.52472039873, 5711.6001879868845, 5740.684262365527, 5777.209065189804, 1141.646089712192, 1150.9769804603861, 1158.223989926751, 1182.810750279951, 1223.0643557678384, 25482.235532934043, 27199.86400067987, 27295.0, 27438.5884211737, 27580.172594822187, 1238.8265642810013, 1277.7891647878182, 1284.101129209557, 1321.9008574356963, 1333.0468714868916, 12859.742805144031, 13350.065495415422, 13634.386452609657, 14070.43718251279, 14255.786163207353, 366507.0, 370640.0, 370709.0, 370773.0, 373256.0, 42826.28694852171, 42896.356554651706, 42973.14053718908, 42991.0, 43027.35458968118, 1479.3047267784125, 1490.5006822714295, 1515.6892836968616, 1517.1049081042238, 1520.2550750132355, 25358.0, 26616.201513954613, 27049.59425608578, 27066.594001089605, 27111.1866644001, 46702.299465508724, 46819.76590117028, 46870.06259874852, 46877.76561117172, 47154.764226178646, 371798.0, 372371.0, 373179.0, 373575.0, 374016.3897541594, 26966.46067078608, 27400.766965486204, 31442.74229030813, 31626.525602115526, 31682.683173167974, 44584.0, 44624.33063503985, 44656.33015504835, 45354.54645453503, 45869.54130458653, 30910.918117865767, 31108.222294441915, 31372.372552549277, 31376.686233137378, 31635.84182079167, 31138.53292200664, 31423.37153256968, 31529.0, 31734.0, 31887.20281992876, 29318.267043323234, 36524.45213321749, 36735.816320918224, 37133.44299835559, 37520.0, 30654.540181897737, 30721.38557228798, 31038.0, 31571.052868413983, 31997.36005279928, 21626.140876471367, 23082.884585576965, 23334.183303584294, 24198.395040124138, 24933.875330623232], "value": 18583.69077029035, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.1721091048789, 390.20982510416405, 427.1371828905613, 440.1439200754532, 462.24423068283346, 20554.794452055492, 21145.0, 22300.88495575219, 22406.32781016568, 22491.887540562293, 22183.44541386466, 23239.535209295846, 24116.91473883675, 24201.789910504493, 24355.269341919728, 6777.7966661, 6880.518363714542, 7101.502894797366, 7103.431725461976, 7141.071660684104, 6617.900731489031, 7068.964655176717, 7073.610951397671, 7084.433245340386, 7091.89362159568, 6820.590764554143, 6919.204291506466, 6985.091938048046, 7045.612491312976, 7052.61210633415, 6734.124563806723, 6929.41100006495, 6976.0, 7001.844695625241, 7027.297270272983, 6790.932090679079, 6792.320767923217, 6817.045613614048, 6830.487713421513, 6838.589684618889, 24823.00707971679, 27437.86281068602, 27546.0, 27622.585661215096, 27798.444031119467, 3011.3676128013144, 3059.6787337329483, 3075.5232938894505, 3077.953830692519, 3078.6767389424235, 17104.828951710573, 17290.394836180672, 17391.73912391315, 17466.563335916577, 17526.38657646989, 2247.4830788918475, 2255.1317742669025, 2265.9660105098437, 2285.440067183534, 2298.195631528968, 129878.0, 132329.0, 144062.0, 157028.0, 177688.22311776847, 18767.24931002772, 19853.503662408268, 20108.39674809758, 20116.89941550283, 20351.880646564285, 29838.25404364865, 29853.253668658457, 30103.849480752455, 30219.546706799414, 30342.54486182709, 10566.260361774755, 10661.200409969284, 10764.730881728019, 10874.075703565128, 11032.338059716443, 9155.54222288883, 9171.541422928827, 9184.8622270666, 9314.487703176255, 9353.158215760479, 9023.0, 9209.585568649427, 9309.953450232706, 9310.86033709495, 9338.859917101248, 4412.889677758018, 4432.2908334666945, 4474.306482495218, 4475.507694153641, 4479.432198730418, 992.1963209800085, 1046.6493724602344, 1053.1890444357803, 1086.2884810449011, 1109.7614012987246, 20260.493487662927, 21242.893785530974, 21407.036683349288, 21476.463088422915, 21585.67621485679, 1045.1168762395764, 1045.926785125034, 1047.586203449633, 1082.9241953063367, 1098.8681358236902, 8187.918120818716, 8277.0, 8530.10433904431, 10900.182486313559, 10900.291481053706, 715054.0, 715594.0, 716647.0, 718485.0, 731804.0, 153538.0, 155532.11169720622, 157049.0, 161800.0, 162529.18735406484, 1325.602319304205, 1345.280275052842, 1370.6299299188963, 1373.6909195430949, 1376.3531140364014, 17952.820471795116, 18576.7213491795, 18661.440156795317, 18707.064646767878, 18768.62462750765, 56720.0, 58460.830783383244, 60460.69769651123, 61231.387686122565, 61416.0, 86734.0, 86874.0, 87272.69090963768, 87507.0, 89072.0, 11398.259113157617, 11606.24559403636, 11692.649220523397, 11718.824217636571, 11781.64367126561, 12701.047421443613, 12883.0, 12920.289384083966, 13146.934265328995, 13230.867691322963, 8062.0, 10758.045706061946, 11157.832632510357, 11564.595239166589, 11599.07207423421, 10187.43969081678, 10629.043386095289, 10691.0, 10794.298370605886, 11009.889901100887, 9731.56207970629, 10588.67641544807, 10928.617498387519, 11838.526458941546, 12013.038956883604, 11531.250468719507, 11786.15731814842, 11969.64091077269, 12063.758724825278, 12101.0, 9837.655682051094, 10334.689959301233, 11453.08375329955, 11504.367259800456, 11842.46708898119], "value": 11758.06115462102, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1613.8386161383871, 1640.3028712797059, 1669.3406104588687, 1674.0, 1675.380109359537, 10961.342319460828, 11275.379854108021, 11606.593769218069, 11622.535098596067, 11660.183787134903, 11447.942760286207, 11663.708407289821, 11697.298162110279, 11700.473478693459, 11728.589499367525, 2420.951580968384, 2463.1132792194794, 2466.7163276223196, 2473.5547601431736, 2475.2450502596703, 2407.205622144697, 2437.2566367257978, 2477.083479112731, 2478.950420991579, 2481.801455883525, 2016.8588198826087, 2417.697787776528, 2429.9271021869286, 2435.0138194031447, 2460.0282888259126, 2369.4668699542635, 2415.7101147862195, 2418.0327868852423, 2429.724394692783, 2439.329184474275, 2442.584760590686, 2446.877656117187, 2467.679201703767, 2469.407342237868, 2472.5178590174924, 11291.0, 11501.367424791633, 11514.424278786028, 11546.59586914454, 11550.94224528872, 5921.496672782819, 6048.909266361008, 6098.57309988301, 6104.572679912408, 6132.570720049598, 3025.9546106808416, 3556.964430355715, 3639.9808053744996, 3675.467057276707, 3696.667299943018, 1185.1230089733535, 1185.2532904270254, 1218.3299185447968, 1239.4608345369793, 1240.4727990603958, 13788.862111378858, 14056.437742490289, 14732.631684207874, 15608.531744047697, 15729.606759830982, 4601.171789077931, 4654.6741728078705, 4784.40194975628, 4792.520747925179, 4808.2787581863, 11927.403629818475, 11963.46164422603, 11976.981956533618, 12007.699807504881, 12021.459034343476, 5864.325602555676, 5896.086106653475, 5901.262342207224, 5962.016267315845, 5972.044472884405, 5310.654807437504, 5408.91886621701, 5456.809011684572, 5470.288862447856, 5501.257330260363, 3867.651911327994, 3904.277708623857, 3958.722889397772, 3988.66096381805, 4035.6973227008093, 1024.9743756406, 1025.01598465474, 1030.6186710916859, 1041.7447725307347, 1046.1369370269406, 934.1685899549407, 943.3019565521465, 946.8721722567503, 963.9036096390305, 1005.2309982863115, 6783.898241526381, 6807.965960170267, 6897.517173797886, 7053.823654408681, 7063.92936070643, 992.8361820299714, 1037.725002874239, 1039.6153423233452, 1062.8352605346183, 1082.8592283003318, 4186.413902053715, 4242.639375653104, 4273.87178384649, 4287.0, 4325.978370108192, 13570.525031623845, 14116.364763585663, 14133.85866141346, 14147.292635368396, 14149.71700565983, 11549.942250288696, 13243.933780331037, 14037.438502459787, 14048.367823447968, 14058.507952221624, 8942.687005954891, 10229.386236825814, 10458.163346932228, 10458.895411045793, 10544.947275263574, 5445.101558243004, 6144.539159562962, 6554.901676474952, 6804.4216241620015, 7077.5753454792875, 12208.75582488363, 12246.020318374687, 12273.631791046282, 12697.4286157125, 12719.682007949876, 12629.557965471164, 12769.233845969271, 12865.035122366047, 12901.225926444444, 12916.870831291566, 9409.952950235205, 9470.621175153192, 9483.762905927408, 9499.477528735986, 9613.375130616487, 10395.0, 10422.166226701695, 10526.578936842441, 10574.101201397967, 10652.147828173573, 9004.594793234452, 9012.188902998761, 9037.09629037091, 9093.04523025073, 9095.953965293811, 8644.438111522732, 8749.212570868653, 8786.868196977179, 8798.824023519623, 8945.686900958435, 7597.392208623186, 7905.841883162189, 7909.841803163789, 8092.190780921861, 8222.465539739898, 5027.899442011212, 5142.51146141116, 5932.822015339547, 5988.970055149696, 6010.639361638316, 5361.437049109787, 5592.937341905025, 5901.114832775118, 6073.14975903365, 6360.268569114611], "value": 5227.835943910618, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.8553042609022, 391.89222963684966, 397.37810326838496, 408.44451545897573, 412.8761371588524, 4641.698289611173, 5526.309211348582, 5586.469285417889, 5587.832365029056, 5596.4403559644015, 3886.514185726784, 4109.671226301896, 5215.878586103987, 5398.6220964532495, 5431.456854314567, 4216.599423054805, 4424.2699954507625, 4550.271956486963, 4568.908621827562, 4583.381243532114, 4360.059773400838, 4445.244308467552, 4523.185826551219, 4547.499775024745, 4606.56237657422, 4105.445764821756, 4530.275155975045, 4542.024219464543, 4571.3143028545655, 4625.658559017881, 4385.0, 4400.185965596343, 4590.934079664148, 4611.469680986679, 4611.907761844778, 3694.8706795262037, 3788.1438095333365, 3870.806459677005, 4096.959030409687, 4109.97945010273, 5345.973270133624, 5386.91919621206, 5398.78404863805, 5434.91847622286, 5449.0, 4793.952060479385, 5006.5494105530315, 5064.670796398223, 5110.207917772714, 5294.788208471695, 5412.539934105605, 5607.663540187561, 5818.5345172386105, 5827.271591051119, 5953.464188223037, 4697.694649847749, 4731.550502702238, 4737.573618374328, 4755.643326750507, 4760.190767569521, 5746.367899531048, 5963.671998040106, 6306.0, 6319.977607165709, 6379.489640828722, 4227.5203678712205, 4473.507914129443, 4634.745089020069, 4791.880202994883, 4834.018052598418, 4240.745555266632, 4552.0, 4922.655414121049, 4941.332920055818, 4959.8512044638, 4537.546245375436, 4843.733594652257, 4886.070002349053, 5044.0, 5308.885134121878, 5372.495701203669, 5556.971705327607, 5715.799947001919, 5836.9124463133085, 5895.056790913433, 1549.3570168380222, 1577.9842201578067, 1597.7203989301825, 1644.1749657879743, 1863.872357223887, 1308.28044575485, 1354.7764618837782, 1424.2522675595396, 1451.9328293704118, 1507.6381668399506, 4889.682170658896, 5576.609637325349, 5578.63738856973, 5587.8603034923635, 5606.439356064361, 5374.092197269991, 5588.888222235532, 5637.943620563824, 5851.795187168429, 5992.550558708114, 5244.842654720364, 5337.359516857924, 5527.695976721238, 5563.554915606742, 5571.7347106984325, 2734.726527347289, 3024.0645986568616, 3113.5521982277896, 3153.6373317068374, 3169.4770362889867, 5698.572607054488, 6334.144890439824, 6339.7781077662985, 6403.64779937108, 6404.935950640527, 4981.90036199274, 6230.130960711859, 6270.467010304175, 6293.213348331458, 6353.396427339396, 5007.874803129951, 5356.7589458473685, 5493.379453061357, 6052.063339731244, 6176.969115154396, 4819.3012013258585, 6037.909431358621, 6116.969415152896, 6158.9221886170635, 6194.752209911553, 5810.767569297181, 5963.360075979141, 6087.4521293083835, 6112.8604988253755, 6115.196017174855, 5938.762449502145, 6301.590396624205, 6373.840653983502, 6407.725257533695, 6412.35876412355, 4819.855404337875, 4849.515048495122, 4866.0, 4924.212126059813, 4930.112579735682, 5709.771609135588, 5731.914021289601, 6027.336992930686, 6035.849103772441, 6063.454289114001, 5198.116320225647, 5522.558195344443, 5889.028310328919, 5895.764169433174, 5916.792912248051, 5366.382865970308, 5385.784568617211, 5603.80386686464, 5851.741875496788, 5977.641341519523, 4924.63065270099, 5039.899202016012, 5040.798368065383, 5636.928983493123, 5714.285714285715, 5810.738516766671, 5818.33089194748, 5853.682921342664, 5895.410458954242, 5921.289445266423, 5018.4981501849525, 5479.506824394811, 5545.972270138624, 5670.5180059695385, 5811.27359080115], "value": 4524.496257931448, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [766.2873527619315, 808.8665370213915, 817.2726273616486, 831.9084900660927, 905.7961958559325, 14619.561413157595, 16336.264868080934, 17359.39242126526, 17454.127293635316, 17681.46955591333, 15798.0, 15944.521664350088, 15956.601084972881, 15965.361385544564, 15969.680606387865, 829.6432534010373, 832.854250506162, 944.0653752784763, 972.0959507657875, 978.1441238915949, 971.7862070344548, 973.0318333258418, 973.8539219117118, 974.3033730882453, 975.463495077708, 1002.5388321372178, 1006.5319626373735, 1012.3217444312328, 1013.2501948558088, 1014.8173328800813, 990.0396615283164, 1004.2417974429333, 1004.3572113847143, 1005.3615953869272, 1005.3917379985149, 729.8467321862415, 745.4707157917885, 746.0078096132131, 746.5931067568189, 750.1897950213756, 17055.0, 17118.40085596998, 17250.0, 18031.639367212712, 18302.26790928361, 14466.855331446655, 14831.258437078212, 14833.184174870377, 14942.775858362133, 15013.474528391564, 12140.210886292363, 12149.392530373447, 12285.38573071343, 12509.812352814715, 12929.80605290912, 3810.2189051244427, 3844.673202777767, 3866.709996750255, 3912.158885839558, 3977.8607748728946, 114338.71322573502, 115227.0, 117157.41421292924, 117273.82726172714, 117887.8211217894, 11084.72128862014, 11428.142889283337, 11538.88461115395, 11547.191696581158, 11671.474783634756, 23066.308010759705, 23219.419514511934, 23672.28983130509, 23802.880985594962, 23817.642735358982, 8072.757817265491, 8231.218034286732, 8246.216609422096, 8273.007239131342, 8376.288015518627, 3947.940780888289, 4504.48198457175, 4594.081183763194, 4605.263157894721, 4641.955559999041, 3183.4588120019653, 3750.6999440044733, 3892.1048158923604, 4068.6338229559483, 4117.07365842683, 725.575538310084, 728.7012324946746, 729.711763853275, 736.7495051682454, 737.0749709115026, 692.4079911675445, 737.6717360774404, 748.277911815101, 752.7741677496729, 776.7242628866671, 10632.89367106319, 10825.188110891557, 10947.0, 11063.225574209728, 11397.658070257905, 615.7260019291462, 694.7429451103125, 724.1021133794058, 737.6938570493293, 742.6695120671358, 4619.445666520039, 4705.5059218781535, 4974.1543937530705, 5309.858380448222, 5342.893142137135, 80351.39297214022, 91693.0, 95062.0, 99996.5000175009, 116026.0, 68179.65910170517, 84823.0, 84878.15121848826, 88317.55841220752, 89229.55385223032, 77256.0, 80348.59825700951, 81286.0, 82197.58901205576, 82594.58702706448, 8853.0, 10599.788004240027, 11143.609973650884, 11471.369074700639, 11526.0, 41480.37779433371, 46020.0, 47389.76305118452, 47616.52383476121, 47977.28034079422, 50712.74643626758, 52599.47400525946, 57354.71322643527, 59053.45679259258, 60326.69836650789, 12981.740365192454, 13050.021248406218, 13202.669933251746, 13454.46182152742, 13524.79712804289, 15060.879558536582, 15302.387904483696, 15444.45944391941, 15454.381824727334, 16294.42969496062, 14060.367283472473, 14255.786163207767, 14580.343884525433, 14612.707745845237, 15120.971769923915, 12185.390730463265, 13816.378262978391, 14313.427462901367, 14376.640583985065, 14394.640133996734, 13532.729345413234, 13689.247091410069, 14389.064710794184, 14730.26348682583, 14779.482718104815, 10125.443100629242, 10169.389836609827, 10599.417032063311, 10733.302335348177, 10803.189760768131, 11072.39101849406, 11581.478833452345, 11643.650690479299, 11751.177417580688, 11754.588589399329], "value": 7909.99529558019, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1498.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 120.0, 121.0, 139.0, 161.0, 119.0, 119.0, 118.0, 119.0, 121.0], "value": 124.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 99.0, 121.0, 178.0, 117.0, 109.0, 111.0, 133.0, 131.0, 98.0], "value": 124.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 196.0, 197.0, 196.0, 193.0, 197.0, 198.0, 195.0, 203.0, 196.0], "value": 196.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 57.0, 58.0, 86.0, 60.0, 60.0, 61.0, 60.0, 70.0, 58.0], "value": 65.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 71.0, 70.0, 91.0, 95.0, 102.0, 114.0, 86.0, 70.0, 83.0], "value": 87.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 88.0, 93.0, 90.0, 88.0, 94.0, 88.0, 93.0, 95.0], "value": 90.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 95.0, 96.0, 96.0, 95.0, 96.0, 95.0, 106.0, 118.0], "value": 98.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 95.0, 92.0, 96.0, 91.0, 90.0, 91.0, 91.0, 92.0, 91.0], "value": 91.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 65.0, 67.0, 68.0, 69.0, 69.0, 68.0, 70.0, 67.0], "value": 67.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 64.0, 68.0, 71.0, 67.0, 64.0, 59.0, 58.0, 58.0, 58.0], "value": 62.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 82.0, 84.0, 85.0, 83.0, 81.0, 84.0, 89.0, 102.0, 105.0], "value": 88.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 161.0, 131.0, 135.0, 163.0, 136.0, 129.0, 135.0, 196.0, 180.0], "value": 150.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 163.0, 170.0, 225.0, 201.0, 219.0, 219.0, 166.0, 164.0], "value": 185.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 66.0, 63.0, 65.0, 67.0, 62.0, 60.0, 62.0, 81.0], "value": 65.5, "unit": "score"}]}]} 04:59:23 INFO - Return code: 0 04:59:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:59:23 ERROR - # TBPL SUCCESS # 04:59:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:59:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:59:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:59:23 INFO - 2017-05-26 04:59:23.496 screenresolution[1658:10544] starting screenresolution argv=screenresolution get 04:59:23 INFO - 2017-05-26 04:59:23.500 screenresolution[1658:10544] Display 0: 1600x1200x32@60 04:59:23 INFO - 2017-05-26 04:59:23.506 screenresolution[1659:10547] starting screenresolution argv=screenresolution list 04:59:23 INFO - Available Modes on Display 0 04:59:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:59:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:59:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:59:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:59:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:59:23 INFO - Intel Iris: 04:59:23 INFO - Chipset Model: Intel Iris 04:59:23 INFO - Type: GPU 04:59:23 INFO - Bus: Built-In 04:59:23 INFO - VRAM (Dynamic, Max): 1536 MB 04:59:23 INFO - Vendor: Intel (0x8086) 04:59:23 INFO - Device ID: 0x0a2e 04:59:23 INFO - Revision ID: 0x0009 04:59:23 INFO - Displays: 04:59:23 INFO - Display: 04:59:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:59:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:59:23 INFO - Display Serial Number: 1600x1200 60 04:59:23 INFO - Main Display: Yes 04:59:23 INFO - Mirror: Off 04:59:23 INFO - Online: Yes 04:59:23 INFO - Rotation: Supported 04:59:23 INFO - Return code: 0 04:59:23 INFO - Running post-action listener: _resource_record_post_action 04:59:23 INFO - [mozharness: 2017-05-26 11:59:23.744629Z] Finished run-tests step (success) 04:59:23 INFO - Running post-run listener: _resource_record_post_run 04:59:23 INFO - Total resource usage - Wall time: 1191s; CPU: 55.0%; Read bytes: 40331264; Write bytes: 427179008; Read time: 257; Write time: 1419 04:59:23 INFO - TinderboxPrint: CPU usage
54.5% 04:59:23 INFO - TinderboxPrint: I/O read bytes / time
40,331,264 / 257 04:59:23 INFO - TinderboxPrint: I/O write bytes / time
427,179,008 / 1,419 04:59:23 INFO - TinderboxPrint: CPU idle
2,167.0 (45.5%) 04:59:23 INFO - TinderboxPrint: CPU system
953.5 (20.0%) 04:59:23 INFO - TinderboxPrint: CPU user
1,645.2 (34.5%) 04:59:23 INFO - TinderboxPrint: Swap in / out
162,586,624 / 0 04:59:23 INFO - install - Wall time: 15s; CPU: 53.0%; Read bytes: 123378176; Write bytes: 124847616; Read time: 11078; Write time: 280 04:59:23 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 36177920; Write bytes: 300946944; Read time: 230; Write time: 1135 04:59:24 INFO - Running post-run listener: _upload_blobber_files 04:59:24 INFO - Blob upload gear active. 04:59:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:59:24 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:59:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:59:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:59:24 INFO - (blobuploader) - INFO - Open directory for files ... 04:59:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:59:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:59:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:59:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:59:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:59:25 INFO - (blobuploader) - INFO - Done attempting. 04:59:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:59:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:59:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:59:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:59:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:59:26 INFO - (blobuploader) - INFO - Done attempting. 04:59:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:59:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:59:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:59:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:59:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:59:27 INFO - (blobuploader) - INFO - Done attempting. 04:59:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:59:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:59:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:59:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:59:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:59:28 INFO - (blobuploader) - INFO - Done attempting. 04:59:28 INFO - (blobuploader) - INFO - Iteration through files over. 04:59:28 INFO - Return code: 0 04:59:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:59:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:59:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/caa583940f0a75bcff9cf5d005e9a3a1b8c22abbdf755c2b4d6ea104281bff2c1184089a759703a87abbaf13087dad33a7bc135b123b05a041dcc70802119ad0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5a72b83d19fcb6f4bbfef6e1abc96ea734b3e12924c2c063736f47fda7f1e92da65b6c0b1a4b4c6b13c4ea06e8d1a88c2854859a1cd676875721d238adbb830f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88bf391935036b5db9a493d7bf36cee66dd8a8295f87b93ff7e6b5839c34191c9d24a1e55075d964363727b063800d12c43362f48762e8bd4f9954b1b9b505b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b2aef42a3d8fc7738eb07e15123e79a979249c5a0bfcb911028c04eaa269ef4f56bda437703eaceef8f3d56dd6225302f93c9de2ccef61dcc05aba7f1e22e849"} 04:59:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:59:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:59:28 INFO - Contents: 04:59:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/caa583940f0a75bcff9cf5d005e9a3a1b8c22abbdf755c2b4d6ea104281bff2c1184089a759703a87abbaf13087dad33a7bc135b123b05a041dcc70802119ad0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5a72b83d19fcb6f4bbfef6e1abc96ea734b3e12924c2c063736f47fda7f1e92da65b6c0b1a4b4c6b13c4ea06e8d1a88c2854859a1cd676875721d238adbb830f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88bf391935036b5db9a493d7bf36cee66dd8a8295f87b93ff7e6b5839c34191c9d24a1e55075d964363727b063800d12c43362f48762e8bd4f9954b1b9b505b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b2aef42a3d8fc7738eb07e15123e79a979249c5a0bfcb911028c04eaa269ef4f56bda437703eaceef8f3d56dd6225302f93c9de2ccef61dcc05aba7f1e22e849"} 04:59:28 INFO - Running post-run listener: copy_logs_to_upload_dir 04:59:28 INFO - Copying logs to upload dir... 04:59:28 INFO - mkdir: /builds/slave/test/build/upload/logs 04:59:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.002228 ========= master_lag: 1.57 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-05-26 04:59:30.583092) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 04:59:30.586529) ========= 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.gCps5m4rPw/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.M1EV4qVz60/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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/caa583940f0a75bcff9cf5d005e9a3a1b8c22abbdf755c2b4d6ea104281bff2c1184089a759703a87abbaf13087dad33a7bc135b123b05a041dcc70802119ad0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5a72b83d19fcb6f4bbfef6e1abc96ea734b3e12924c2c063736f47fda7f1e92da65b6c0b1a4b4c6b13c4ea06e8d1a88c2854859a1cd676875721d238adbb830f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88bf391935036b5db9a493d7bf36cee66dd8a8295f87b93ff7e6b5839c34191c9d24a1e55075d964363727b063800d12c43362f48762e8bd4f9954b1b9b505b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b2aef42a3d8fc7738eb07e15123e79a979249c5a0bfcb911028c04eaa269ef4f56bda437703eaceef8f3d56dd6225302f93c9de2ccef61dcc05aba7f1e22e849"} build_url:https://queue.taskcluster.net/v1/task/NBBQk6gYS7q_DeDX5XfpiA/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011204 build_url: 'https://queue.taskcluster.net/v1/task/NBBQk6gYS7q_DeDX5XfpiA/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/caa583940f0a75bcff9cf5d005e9a3a1b8c22abbdf755c2b4d6ea104281bff2c1184089a759703a87abbaf13087dad33a7bc135b123b05a041dcc70802119ad0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5a72b83d19fcb6f4bbfef6e1abc96ea734b3e12924c2c063736f47fda7f1e92da65b6c0b1a4b4c6b13c4ea06e8d1a88c2854859a1cd676875721d238adbb830f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88bf391935036b5db9a493d7bf36cee66dd8a8295f87b93ff7e6b5839c34191c9d24a1e55075d964363727b063800d12c43362f48762e8bd4f9954b1b9b505b8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b2aef42a3d8fc7738eb07e15123e79a979249c5a0bfcb911028c04eaa269ef4f56bda437703eaceef8f3d56dd6225302f93c9de2ccef61dcc05aba7f1e22e849"}' ========= master_lag: 0.42 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 04:59:31.013982) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 04:59:31.014322) ========= 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.gCps5m4rPw/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.M1EV4qVz60/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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.005275 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 04:59:31.040282) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 04:59:31.040602) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 04:59:31.040956) ========= ========= Total master_lag: 2.08 =========