builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0236 starttime: 1495493045.7 results: success (0) buildid: 20170522144823 builduid: e6ad826b99064e628a86d6719834138e revision: 16c20bf328da5fcd6060395fd0fb320f3b8a1146 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 15:44:05.697686) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 15:44:05.698126) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 15:44:05.698401) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 15:44:05.723693) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 15:44:05.724276) ========= 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.zJb0FW9yRS/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.O5Clm7USAK/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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-22 15:44:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.3M=0.001s 2017-05-22 15:44:05 (18.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.142579 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 15:44:05.890551) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 15:44:05.890868) ========= 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.zJb0FW9yRS/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.O5Clm7USAK/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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.027090 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 15:44:05.939634) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-22 15:44:05.940010) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 16c20bf328da5fcd6060395fd0fb320f3b8a1146 --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 16c20bf328da5fcd6060395fd0fb320f3b8a1146 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zJb0FW9yRS/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.O5Clm7USAK/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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-22 15:44:06,039 truncating revision to first 12 chars 2017-05-22 15:44:06,039 Setting DEBUG logging. 2017-05-22 15:44:06,039 attempt 1/10 2017-05-22 15:44:06,039 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/16c20bf328da?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-22 15:44:07,021 unpacking tar archive at: mozilla-beta-16c20bf328da/testing/mozharness/ program finished with exit code 0 elapsedTime=1.492171 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-22 15:44:07.453828) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 15:44:07.454176) ========= script_repo_revision: 16c20bf328da5fcd6060395fd0fb320f3b8a1146 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 15:44:07.454568) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 15:44:07.454850) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 15:44:07.473315) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-05-22 15:44:07.473699) ========= /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.zJb0FW9yRS/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.O5Clm7USAK/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 15:44:07 INFO - MultiFileLogger online at 20170522 15:44:07 in /builds/slave/test 15:44:07 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 15:44:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:44:07 INFO - {'append_to_log': False, 15:44:07 INFO - 'base_work_dir': '/builds/slave/test', 15:44:07 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:44:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:44:07 INFO - 'branch': 'Mozilla-Beta', 15:44:07 INFO - 'buildbot_json_path': 'buildprops.json', 15:44:07 INFO - 'config_files': ('talos/mac_config.py',), 15:44:07 INFO - 'default_actions': ('clobber', 15:44:07 INFO - 'read-buildbot-config', 15:44:07 INFO - 'download-and-extract', 15:44:07 INFO - 'populate-webroot', 15:44:07 INFO - 'create-virtualenv', 15:44:07 INFO - 'install', 15:44:07 INFO - 'run-tests'), 15:44:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:44:07 INFO - 'download_minidump_stackwalk': True, 15:44:07 INFO - 'download_symbols': 'ondemand', 15:44:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:44:07 INFO - 'tooltool.py': '/tools/tooltool.py', 15:44:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:44:07 INFO - '/tools/misc-python/virtualenv.py')}, 15:44:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:44:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:44:07 INFO - 'gecko_profile': False, 15:44:07 INFO - 'gecko_profile_interval': 0, 15:44:07 INFO - 'installer_path': 'installer.exe', 15:44:07 INFO - 'log_level': 'info', 15:44:07 INFO - 'log_name': 'talos', 15:44:07 INFO - 'log_to_console': True, 15:44:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:44:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:44:07 INFO - 'opt_config_files': (), 15:44:07 INFO - 'pip_index': False, 15:44:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:44:07 INFO - 'cmd': ('bash', 15:44:07 INFO - '-c', 15:44:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:44:07 INFO - 'enabled': True, 15:44:07 INFO - 'halt_on_failure': False, 15:44:07 INFO - 'name': 'check_screen_resolution'},), 15:44:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:44:07 INFO - 'cmd': ('bash', 15:44:07 INFO - '-c', 15:44:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:44:07 INFO - 'enabled': True, 15:44:07 INFO - 'halt_on_failure': False, 15:44:07 INFO - 'name': 'check_screen_resolution'},), 15:44:07 INFO - 'run_cmd_checks_enabled': True, 15:44:07 INFO - 'suite': 'dromaeojs-e10s', 15:44:07 INFO - 'system_bits': '32', 15:44:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:44:07 INFO - 'title': 't-yosemite-r7-0236', 15:44:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:44:07 INFO - 'use_talos_json': True, 15:44:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:44:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:44:07 INFO - 'work_dir': 'build'} 15:44:07 INFO - [mozharness: 2017-05-22 22:44:07.682465Z] Running clobber step. 15:44:07 INFO - Running pre-action listener: _resource_record_pre_action 15:44:07 INFO - Running main action method: clobber 15:44:07 INFO - rmtree: /builds/slave/test/build 15:44:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:44:10 INFO - Running post-action listener: _resource_record_post_action 15:44:10 INFO - [mozharness: 2017-05-22 22:44:10.126600Z] Finished clobber step (success) 15:44:10 INFO - [mozharness: 2017-05-22 22:44:10.126769Z] Running read-buildbot-config step. 15:44:10 INFO - Running pre-action listener: _resource_record_pre_action 15:44:10 INFO - Running main action method: read_buildbot_config 15:44:10 INFO - Using buildbot properties: 15:44:10 INFO - { 15:44:10 INFO - "project": "", 15:44:10 INFO - "product": "firefox", 15:44:10 INFO - "script_repo_revision": "production", 15:44:10 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 15:44:10 INFO - "repository": "", 15:44:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 15:44:10 INFO - "buildid": "20170522144823", 15:44:10 INFO - "pgo_build": "False", 15:44:10 INFO - "basedir": "/builds/slave/test", 15:44:10 INFO - "buildnumber": 3, 15:44:10 INFO - "slavename": "t-yosemite-r7-0236", 15:44:10 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 15:44:10 INFO - "platform": "yosemite_r7", 15:44:10 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 15:44:10 INFO - "branch": "mozilla-beta", 15:44:10 INFO - "repo_path": "releases/mozilla-beta", 15:44:10 INFO - "revision": "16c20bf328da5fcd6060395fd0fb320f3b8a1146", 15:44:10 INFO - "stage_platform": "macosx64", 15:44:10 INFO - "builduid": "e6ad826b99064e628a86d6719834138e", 15:44:10 INFO - "slavebuilddir": "test" 15:44:10 INFO - } 15:44:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/DalBoHteQ1uFSBGVNVPngg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 15:44:10 INFO - Running post-action listener: _resource_record_post_action 15:44:10 INFO - [mozharness: 2017-05-22 22:44:10.131014Z] Finished read-buildbot-config step (success) 15:44:10 INFO - [mozharness: 2017-05-22 22:44:10.131248Z] Running download-and-extract step. 15:44:10 INFO - Running pre-action listener: _resource_record_pre_action 15:44:10 INFO - Running main action method: download_and_extract 15:44:10 INFO - mkdir: /builds/slave/test/build/tests 15:44: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')]} 15:44:10 INFO - trying https://queue.taskcluster.net/v1/task/DalBoHteQ1uFSBGVNVPngg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 15:44:10 INFO - Downloading https://queue.taskcluster.net/v1/task/DalBoHteQ1uFSBGVNVPngg/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 15:44:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DalBoHteQ1uFSBGVNVPngg/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 15:44:11 INFO - Downloaded 1287 bytes. 15:44:11 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 15:44:11 INFO - Using the following test package requirements: 15:44:11 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 15:44:11 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 15:44:11 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 15:44:11 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 15:44:11 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 15:44:11 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 15:44:11 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 15:44:11 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 15:44:11 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 15:44:11 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 15:44:11 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 15:44:11 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 15:44:11 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 15:44:11 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 15:44:11 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 15:44:11 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 15:44:11 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 15:44:11 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 15:44:11 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 15:44:11 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 15:44:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DalBoHteQ1uFSBGVNVPngg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 15:44:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DalBoHteQ1uFSBGVNVPngg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 15:44:11 INFO - Fetch https://queue.taskcluster.net/v1/task/DalBoHteQ1uFSBGVNVPngg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 15:44:12 INFO - Expected file size: 51393612 15:44:12 INFO - Obtained file size: 51393612 15:44:28 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 15:44:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DalBoHteQ1uFSBGVNVPngg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 15:44:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DalBoHteQ1uFSBGVNVPngg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 15:44:28 INFO - Fetch https://queue.taskcluster.net/v1/task/DalBoHteQ1uFSBGVNVPngg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 15:44:29 INFO - Expected file size: 51393612 15:44:29 INFO - Obtained file size: 51393612 15:44:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DalBoHteQ1uFSBGVNVPngg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 15:44:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DalBoHteQ1uFSBGVNVPngg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 15:44:45 INFO - Fetch https://queue.taskcluster.net/v1/task/DalBoHteQ1uFSBGVNVPngg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 15:44:46 INFO - Expected file size: 14404449 15:44:46 INFO - Obtained file size: 14404449 15:44: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')]} 15:44:46 INFO - trying https://queue.taskcluster.net/v1/task/DalBoHteQ1uFSBGVNVPngg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 15:44:46 INFO - Downloading https://queue.taskcluster.net/v1/task/DalBoHteQ1uFSBGVNVPngg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 15:44:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DalBoHteQ1uFSBGVNVPngg/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 15:44:48 INFO - Downloaded 59649782 bytes. 15:44:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DalBoHteQ1uFSBGVNVPngg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 15:44:48 INFO - mkdir: /builds/slave/test/properties 15:44:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:44:48 INFO - Writing to file /builds/slave/test/properties/build_url 15:44:48 INFO - Contents: 15:44:48 INFO - build_url:https://queue.taskcluster.net/v1/task/DalBoHteQ1uFSBGVNVPngg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 15:44:49 INFO - Running post-action listener: _resource_record_post_action 15:44:49 INFO - Running post-action listener: set_extra_try_arguments 15:44:49 INFO - [mozharness: 2017-05-22 22:44:49.284680Z] Finished download-and-extract step (success) 15:44:49 INFO - [mozharness: 2017-05-22 22:44:49.284840Z] Running populate-webroot step. 15:44:49 INFO - Running pre-action listener: _resource_record_pre_action 15:44:49 INFO - Running main action method: populate_webroot 15:44:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:44:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:44:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:44:49 INFO - '--tppagecycles', 15:44:49 INFO - '7'], 15:44:49 INFO - 'tests': ['tsvgm']}}, 15:44:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:44:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:44:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:44:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:44:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:44:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:44:49 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:44:49 INFO - 'talos_options': ['--disable-e10s'], 15:44:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:44:49 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:44:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:44:49 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:44:49 INFO - 'talos_options': ['--disable-e10s'], 15:44:49 INFO - 'tests': ['damp', 'tps']}, 15:44:49 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:44:49 INFO - 'tests': ['damp', 'tps']}, 15:44:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:44:49 INFO - 'tests': ['dromaeo_dom']}, 15:44:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:44:49 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:44:49 INFO - 'tests': ['basic_compositor_video']}, 15:44:49 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:44:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:44:49 INFO - 'tests': ['a11yr', 15:44:49 INFO - 'ts_paint', 15:44:49 INFO - 'tpaint', 15:44:49 INFO - 'sessionrestore', 15:44:49 INFO - 'sessionrestore_no_auto_restore', 15:44:49 INFO - 'tabpaint']}, 15:44:49 INFO - 'other-e10s': {'tests': ['a11yr', 15:44:49 INFO - 'ts_paint', 15:44:49 INFO - 'tpaint', 15:44:49 INFO - 'sessionrestore', 15:44:49 INFO - 'sessionrestore_no_auto_restore', 15:44:49 INFO - 'tabpaint']}, 15:44:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:44:49 INFO - 'ts_paint', 15:44:49 INFO - 'tpaint', 15:44:49 INFO - 'sessionrestore', 15:44:49 INFO - 'sessionrestore_no_auto_restore', 15:44:49 INFO - 'tabpaint']}, 15:44:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:44:49 INFO - 'ts_paint', 15:44:49 INFO - 'tpaint', 15:44:49 INFO - 'sessionrestore', 15:44:49 INFO - 'sessionrestore_no_auto_restore', 15:44:49 INFO - 'tabpaint']}, 15:44:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:44:49 INFO - 'tests': ['a11yr', 15:44:49 INFO - 'ts_paint', 15:44:49 INFO - 'tpaint', 15:44:49 INFO - 'sessionrestore', 15:44:49 INFO - 'sessionrestore_no_auto_restore', 15:44:49 INFO - 'tabpaint']}, 15:44:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:44:49 INFO - 'tests': ['a11yr', 15:44:49 INFO - 'ts_paint', 15:44:49 INFO - 'tpaint', 15:44:49 INFO - 'sessionrestore', 15:44:49 INFO - 'sessionrestore_no_auto_restore', 15:44:49 INFO - 'tabpaint']}, 15:44:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:44:49 INFO - 'tests': ['tsvgx', 15:44:49 INFO - 'tsvgr_opacity', 15:44:49 INFO - 'tart', 15:44:49 INFO - 'tscrollx', 15:44:49 INFO - 'cart', 15:44:49 INFO - 'tsvg_static']}, 15:44:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:44:49 INFO - 'tsvgr_opacity', 15:44:49 INFO - 'tart', 15:44:49 INFO - 'tscrollx', 15:44:49 INFO - 'cart', 15:44:49 INFO - 'tsvg_static']}, 15:44:49 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:44:49 INFO - 'talos_options': ['--disable-e10s'], 15:44:49 INFO - 'tests': ['tp5o']}, 15:44:49 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:44:49 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:44:49 INFO - 'talos_options': ['--disable-e10s', 15:44:49 INFO - '--xperf_path', 15:44:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:44:49 INFO - 'tests': ['tp5n']}, 15:44:49 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:44:49 INFO - 'talos_options': ['--xperf_path', 15:44:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:44:49 INFO - 'tests': ['tp5n']}}, 15:44:49 INFO - 'talos.zip': {'path': '', 15:44:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:44:49 INFO - Running post-action listener: _resource_record_post_action 15:44:49 INFO - [mozharness: 2017-05-22 22:44:49.303345Z] Finished populate-webroot step (success) 15:44:49 INFO - [mozharness: 2017-05-22 22:44:49.303464Z] Running create-virtualenv step. 15:44:49 INFO - Running pre-action listener: _resource_record_pre_action 15:44:49 INFO - Running main action method: create_virtualenv 15:44:49 INFO - Creating virtualenv /builds/slave/test/build/venv 15:44:49 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 15:44:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:44:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:44:49 INFO - Using real prefix '/tools/python27' 15:44:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:44:50 INFO - Installing distribute.............................................................................................................................................................................................done. 15:44:52 INFO - Installing pip.................done. 15:44:53 INFO - Return code: 0 15:44:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:44:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:44:53 INFO - Reading from file tmpfile_stdout 15:44:53 INFO - Output received: 15:44:53 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) 15:44:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:44:53 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')]} 15:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:53 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')]} 15:44:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:53 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 0x103215b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103218768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d0a5c9d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ff3d30>, '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 0x102f1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fe8be8>, '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.zJb0FW9yRS/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.O5Clm7USAK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 15:44:53 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 15:44:53 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 15:44:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zJb0FW9yRS/Render', 15:44:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:53 INFO - 'HOME': '/Users/cltbld', 15:44:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:53 INFO - 'LOGNAME': 'cltbld', 15:44:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:53 INFO - 'MOZ_NO_REMOTE': '1', 15:44:53 INFO - 'NO_EM_RESTART': '1', 15:44:53 INFO - 'PAGER': '/bin/cat', 15:44:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:53 INFO - 'PWD': '/builds/slave/test', 15:44:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:53 INFO - 'SHELL': '/bin/bash', 15:44:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O5Clm7USAK/Listeners', 15:44:53 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 15:44:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:53 INFO - 'USER': 'cltbld', 15:44:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:53 INFO - 'XPC_FLAGS': '0x0', 15:44:53 INFO - 'XPC_SERVICE_NAME': '0', 15:44:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:53 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 15:44:53 INFO - Cleaning up... 15:44:53 INFO - Return code: 0 15:44:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:44:53 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')]} 15:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:53 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')]} 15:44:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:53 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 0x103215b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103218768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d0a5c9d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ff3d30>, '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 0x102f1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fe8be8>, '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.zJb0FW9yRS/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.O5Clm7USAK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 15:44:53 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 15:44:53 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 15:44:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zJb0FW9yRS/Render', 15:44:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:53 INFO - 'HOME': '/Users/cltbld', 15:44:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:53 INFO - 'LOGNAME': 'cltbld', 15:44:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:53 INFO - 'MOZ_NO_REMOTE': '1', 15:44:53 INFO - 'NO_EM_RESTART': '1', 15:44:53 INFO - 'PAGER': '/bin/cat', 15:44:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:53 INFO - 'PWD': '/builds/slave/test', 15:44:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:53 INFO - 'SHELL': '/bin/bash', 15:44:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O5Clm7USAK/Listeners', 15:44:53 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 15:44:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:53 INFO - 'USER': 'cltbld', 15:44:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:53 INFO - 'XPC_FLAGS': '0x0', 15:44:53 INFO - 'XPC_SERVICE_NAME': '0', 15:44:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:53 INFO - Downloading/unpacking psutil>=3.1.1 15:44:53 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 15:44:53 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 15:44:53 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 15:44:53 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 15:44:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:44:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:44:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:44:55 INFO - Installing collected packages: psutil 15:44:55 INFO - Running setup.py install for psutil 15:44:55 INFO - building 'psutil._psutil_osx' extension 15:44:55 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 15:44:56 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 15:44:56 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 15:44:56 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 15:44:56 INFO - building 'psutil._psutil_posix' extension 15:44:56 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 15:44:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:44:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:44:56 INFO - ^ 15:44:56 INFO - 1 warning generated. 15:44:56 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 15:44:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:44:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:44:56 INFO - Successfully installed psutil 15:44:56 INFO - Cleaning up... 15:44:56 INFO - Return code: 0 15:44:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:44:56 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')]} 15:44:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:56 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')]} 15:44:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:56 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 0x103215b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103218768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d0a5c9d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ff3d30>, '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 0x102f1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fe8be8>, '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.zJb0FW9yRS/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.O5Clm7USAK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 15:44:56 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 15:44:56 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 15:44:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zJb0FW9yRS/Render', 15:44:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:56 INFO - 'HOME': '/Users/cltbld', 15:44:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:56 INFO - 'LOGNAME': 'cltbld', 15:44:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:56 INFO - 'MOZ_NO_REMOTE': '1', 15:44:56 INFO - 'NO_EM_RESTART': '1', 15:44:56 INFO - 'PAGER': '/bin/cat', 15:44:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:56 INFO - 'PWD': '/builds/slave/test', 15:44:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:56 INFO - 'SHELL': '/bin/bash', 15:44:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O5Clm7USAK/Listeners', 15:44:56 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 15:44:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:56 INFO - 'USER': 'cltbld', 15:44:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:56 INFO - 'XPC_FLAGS': '0x0', 15:44:56 INFO - 'XPC_SERVICE_NAME': '0', 15:44:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:44:57 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 15:44:57 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 15:44:57 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 15:44:57 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 15:44:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:44:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:44:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:44:58 INFO - Installing collected packages: mozsystemmonitor 15:44:58 INFO - Running setup.py install for mozsystemmonitor 15:44:58 INFO - Successfully installed mozsystemmonitor 15:44:58 INFO - Cleaning up... 15:44:58 INFO - Return code: 0 15:44:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:44:58 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')]} 15:44:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:44:58 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')]} 15:44:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:44:58 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 0x103215b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103218768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d0a5c9d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ff3d30>, '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 0x102f1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fe8be8>, '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.zJb0FW9yRS/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.O5Clm7USAK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 15:44:58 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 15:44:58 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 15:44:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zJb0FW9yRS/Render', 15:44:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:44:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:44:58 INFO - 'HOME': '/Users/cltbld', 15:44:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:44:58 INFO - 'LOGNAME': 'cltbld', 15:44:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:44:58 INFO - 'MOZ_NO_REMOTE': '1', 15:44:58 INFO - 'NO_EM_RESTART': '1', 15:44:58 INFO - 'PAGER': '/bin/cat', 15:44:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:44:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:44:58 INFO - 'PWD': '/builds/slave/test', 15:44:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:44:58 INFO - 'SHELL': '/bin/bash', 15:44:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O5Clm7USAK/Listeners', 15:44:58 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 15:44:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:44:58 INFO - 'USER': 'cltbld', 15:44:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:44:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:44:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:44:58 INFO - 'XPC_FLAGS': '0x0', 15:44:58 INFO - 'XPC_SERVICE_NAME': '0', 15:44:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:44:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:44:59 INFO - Downloading/unpacking jsonschema==2.5.1 15:44:59 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 15:44:59 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 15:44:59 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 15:44:59 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 15:45:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:45:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:45:00 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 15:45:00 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 15:45:00 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 15:45:00 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 15:45:00 INFO - Downloading functools32-3.2.3-2.tar.gz 15:45:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:45:00 INFO - warning: no files found matching '*.txt' 15:45:00 INFO - no previously-included directories found matching 'build' 15:45:00 INFO - no previously-included directories found matching 'dist' 15:45:00 INFO - no previously-included directories found matching '.git*' 15:45:00 INFO - Installing collected packages: jsonschema, functools32 15:45:01 INFO - Running setup.py install for functools32 15:45:01 INFO - warning: no files found matching '*.txt' 15:45:01 INFO - no previously-included directories found matching 'build' 15:45:01 INFO - no previously-included directories found matching 'dist' 15:45:01 INFO - no previously-included directories found matching '.git*' 15:45:01 INFO - Successfully installed jsonschema functools32 15:45:01 INFO - Cleaning up... 15:45:01 INFO - Return code: 0 15:45:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:45:01 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')]} 15:45:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:01 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')]} 15:45:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:01 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 0x103215b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103218768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d0a5c9d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ff3d30>, '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 0x102f1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fe8be8>, '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.zJb0FW9yRS/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.O5Clm7USAK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 15:45:01 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 15:45:01 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 15:45:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zJb0FW9yRS/Render', 15:45:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:01 INFO - 'HOME': '/Users/cltbld', 15:45:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:01 INFO - 'LOGNAME': 'cltbld', 15:45:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:01 INFO - 'MOZ_NO_REMOTE': '1', 15:45:01 INFO - 'NO_EM_RESTART': '1', 15:45:01 INFO - 'PAGER': '/bin/cat', 15:45:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:01 INFO - 'PWD': '/builds/slave/test', 15:45:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:01 INFO - 'SHELL': '/bin/bash', 15:45:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O5Clm7USAK/Listeners', 15:45:01 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 15:45:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:01 INFO - 'USER': 'cltbld', 15:45:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:01 INFO - 'XPC_FLAGS': '0x0', 15:45:01 INFO - 'XPC_SERVICE_NAME': '0', 15:45:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:45:01 INFO - Cleaning up... 15:45:01 INFO - Return code: 0 15:45:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:45:01 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')]} 15:45:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:01 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')]} 15:45:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:01 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 0x103215b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103218768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d0a5c9d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ff3d30>, '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 0x102f1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fe8be8>, '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.zJb0FW9yRS/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.O5Clm7USAK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 15:45:01 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 15:45:01 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 15:45:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zJb0FW9yRS/Render', 15:45:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:01 INFO - 'HOME': '/Users/cltbld', 15:45:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:01 INFO - 'LOGNAME': 'cltbld', 15:45:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:01 INFO - 'MOZ_NO_REMOTE': '1', 15:45:01 INFO - 'NO_EM_RESTART': '1', 15:45:01 INFO - 'PAGER': '/bin/cat', 15:45:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:01 INFO - 'PWD': '/builds/slave/test', 15:45:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:01 INFO - 'SHELL': '/bin/bash', 15:45:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O5Clm7USAK/Listeners', 15:45:01 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 15:45:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:01 INFO - 'USER': 'cltbld', 15:45:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:01 INFO - 'XPC_FLAGS': '0x0', 15:45:01 INFO - 'XPC_SERVICE_NAME': '0', 15:45:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:01 INFO - Downloading/unpacking blobuploader==1.2.4 15:45:01 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 15:45:01 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 15:45:01 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 15:45:01 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 15:45:03 INFO - Downloading blobuploader-1.2.4.tar.gz 15:45:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:45:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:45:03 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 15:45:03 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 15:45:03 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 15:45:03 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 15:45:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:45:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:45:03 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 15:45:03 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 15:45:03 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 15:45:03 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 15:45:03 INFO - Downloading docopt-0.6.1.tar.gz 15:45:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:45:04 INFO - Installing collected packages: blobuploader, requests, docopt 15:45:04 INFO - Running setup.py install for blobuploader 15:45:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:45:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:45:04 INFO - Running setup.py install for requests 15:45:04 INFO - Running setup.py install for docopt 15:45:04 INFO - Successfully installed blobuploader requests docopt 15:45:04 INFO - Cleaning up... 15:45:04 INFO - Return code: 0 15:45:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:45:04 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')]} 15:45:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45:05 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')]} 15:45:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:05 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 0x103215b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103218768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d0a5c9d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ff3d30>, '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 0x102f1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fe8be8>, '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.zJb0FW9yRS/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.O5Clm7USAK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 15:45:05 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 15:45:05 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 15:45:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zJb0FW9yRS/Render', 15:45:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:05 INFO - 'HOME': '/Users/cltbld', 15:45:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:05 INFO - 'LOGNAME': 'cltbld', 15:45:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:05 INFO - 'MOZ_NO_REMOTE': '1', 15:45:05 INFO - 'NO_EM_RESTART': '1', 15:45:05 INFO - 'PAGER': '/bin/cat', 15:45:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:05 INFO - 'PWD': '/builds/slave/test', 15:45:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:05 INFO - 'SHELL': '/bin/bash', 15:45:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O5Clm7USAK/Listeners', 15:45:05 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 15:45:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:05 INFO - 'USER': 'cltbld', 15:45:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:05 INFO - 'XPC_FLAGS': '0x0', 15:45:05 INFO - 'XPC_SERVICE_NAME': '0', 15:45:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:45:05 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-bDK3ui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:45:06 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-4YIXVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:45:06 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-TVbm79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:45:06 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-EU1RHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:45:06 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-UWzl63-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:45:06 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-TaAw7f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:45:06 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-g85kTj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:45:06 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-6MPKI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:45:06 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-w2Otmg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:45:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:45:06 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-iKx4wC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:45:07 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-ZCvW5q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:45:07 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-wpJTgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:45:07 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-O2Qu0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:45:07 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-y297eK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:45:07 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-AqGvXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:45:07 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-hFQ40V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:45:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:45:07 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-tnO1nM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:45:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:45:07 INFO - Running setup.py install for manifestparser 15:45:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:45:07 INFO - Running setup.py install for mozcrash 15:45:08 INFO - Running setup.py install for mozdebug 15:45:08 INFO - Running setup.py install for mozdevice 15:45:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:45:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:45:08 INFO - Running setup.py install for mozfile 15:45:08 INFO - Running setup.py install for mozhttpd 15:45:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:45:08 INFO - Running setup.py install for mozinfo 15:45:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:45:08 INFO - Running setup.py install for mozInstall 15:45:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:45:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:45:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:45:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:45:08 INFO - Running setup.py install for mozleak 15:45:09 INFO - Running setup.py install for mozlog 15:45:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:45:09 INFO - Running setup.py install for moznetwork 15:45:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:45:09 INFO - Running setup.py install for mozprocess 15:45:09 INFO - Running setup.py install for mozprofile 15:45:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:45:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:45:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:45:09 INFO - Running setup.py install for mozrunner 15:45:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:45:09 INFO - Running setup.py install for mozscreenshot 15:45:10 INFO - Running setup.py install for moztest 15:45:10 INFO - Running setup.py install for mozversion 15:45:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:45:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:45:10 INFO - Cleaning up... 15:45:10 INFO - Return code: 0 15:45:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:45: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')]} 15:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45: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')]} 15:45:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:10 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 0x103215b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103218768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d0a5c9d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ff3d30>, '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 0x102f1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fe8be8>, '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.zJb0FW9yRS/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.O5Clm7USAK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 15:45:10 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 15:45:10 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 15:45:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zJb0FW9yRS/Render', 15:45:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:10 INFO - 'HOME': '/Users/cltbld', 15:45:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:10 INFO - 'LOGNAME': 'cltbld', 15:45:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:10 INFO - 'MOZ_NO_REMOTE': '1', 15:45:10 INFO - 'NO_EM_RESTART': '1', 15:45:10 INFO - 'PAGER': '/bin/cat', 15:45:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:10 INFO - 'PWD': '/builds/slave/test', 15:45:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:10 INFO - 'SHELL': '/bin/bash', 15:45:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O5Clm7USAK/Listeners', 15:45:10 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 15:45:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:10 INFO - 'USER': 'cltbld', 15:45:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:10 INFO - 'XPC_FLAGS': '0x0', 15:45:10 INFO - 'XPC_SERVICE_NAME': '0', 15:45:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:45:11 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-P2JlSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:45:11 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)) 15:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:45:11 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-d5KVcb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:45:11 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)) 15:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:45:11 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-quS0p9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:45:11 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)) 15:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:45:11 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-DXxiwP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:45:11 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)) 15:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:45:11 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-ok6URI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:45:11 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)) 15:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:45:11 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-KybCJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:45:11 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)) 15:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:45:11 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-t4Vzxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:45:11 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)) 15:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:45:11 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-FQRVlE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:45:11 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)) 15:45:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:45:11 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-dyksrh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:45:12 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)) 15:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:45:12 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-vmeqij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:45:12 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)) 15:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:45:12 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-AviqXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:45:12 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)) 15:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:45:12 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-XAGxI2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:45:12 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)) 15:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:45:12 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-MmVjAS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:45:12 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)) 15:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:45:12 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-bOmerH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:45:12 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)) 15:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:45:12 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-QxucTq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:45:12 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)) 15:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:45:12 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-yp_l_P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:45:12 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)) 15:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:45:12 INFO - Running setup.py (path:/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/pip-iUue8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:45:12 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)) 15:45:12 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)) 15:45:12 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)) 15:45:12 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)) 15:45:12 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)) 15:45:12 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)) 15:45:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:45:14 INFO - Downloading blessings-1.6.tar.gz 15:45:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:45:14 INFO - Installing collected packages: blessings 15:45:14 INFO - Running setup.py install for blessings 15:45:14 INFO - Successfully installed blessings 15:45:14 INFO - Cleaning up... 15:45:15 INFO - Return code: 0 15:45:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:45:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:45:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:45:15 INFO - Reading from file tmpfile_stdout 15:45:15 INFO - Current package versions: 15:45:15 INFO - blessings == 1.6 15:45:15 INFO - blobuploader == 1.2.4 15:45:15 INFO - docopt == 0.6.1 15:45:15 INFO - functools32 == 3.2.3-2 15:45:15 INFO - jsonschema == 2.5.1 15:45:15 INFO - manifestparser == 1.1 15:45:15 INFO - mozInstall == 1.12 15:45:15 INFO - mozcrash == 1.0 15:45:15 INFO - mozdebug == 0.1 15:45:15 INFO - mozdevice == 0.49 15:45:15 INFO - mozfile == 1.2 15:45:15 INFO - mozhttpd == 0.7 15:45:15 INFO - mozinfo == 0.9 15:45:15 INFO - mozleak == 0.1 15:45:15 INFO - mozlog == 3.4 15:45:15 INFO - moznetwork == 0.27 15:45:15 INFO - mozprocess == 0.25 15:45:15 INFO - mozprofile == 0.28 15:45:15 INFO - mozrunner == 6.13 15:45:15 INFO - mozscreenshot == 0.1 15:45:15 INFO - mozsystemmonitor == 0.3 15:45:15 INFO - moztest == 0.8 15:45:15 INFO - mozversion == 1.4 15:45:15 INFO - psutil == 3.1.1 15:45:15 INFO - requests == 1.2.3 15:45:15 INFO - wsgiref == 0.1.2 15:45:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:45: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')]} 15:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45: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')]} 15:45:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45:15 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 0x103215b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103218768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d0a5c9d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ff3d30>, '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 0x102f1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fe8be8>, '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.zJb0FW9yRS/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.O5Clm7USAK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 15:45:15 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 15:45:15 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 15:45:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zJb0FW9yRS/Render', 15:45:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:15 INFO - 'HOME': '/Users/cltbld', 15:45:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:15 INFO - 'LOGNAME': 'cltbld', 15:45:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:15 INFO - 'MOZ_NO_REMOTE': '1', 15:45:15 INFO - 'NO_EM_RESTART': '1', 15:45:15 INFO - 'PAGER': '/bin/cat', 15:45:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:15 INFO - 'PWD': '/builds/slave/test', 15:45:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:15 INFO - 'SHELL': '/bin/bash', 15:45:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O5Clm7USAK/Listeners', 15:45:15 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 15:45:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:15 INFO - 'USER': 'cltbld', 15:45:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:15 INFO - 'XPC_FLAGS': '0x0', 15:45:15 INFO - 'XPC_SERVICE_NAME': '0', 15:45:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:15 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)) 15:45:15 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)) 15:45:15 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)) 15:45:15 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)) 15:45:15 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)) 15:45:15 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)) 15:45:15 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)) 15:45:15 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)) 15:45:15 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)) 15:45:15 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)) 15:45:15 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)) 15:45:15 INFO - Cleaning up... 15:45:15 INFO - Return code: 0 15:45:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:45: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')]} 15:45:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:45: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')]} 15:45:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:45: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103215b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103218768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8d0a5c9d90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102ff3d30>, '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 0x102f1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fe8be8>, '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.zJb0FW9yRS/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.O5Clm7USAK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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 15:45: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'] in /builds/slave/test/build 15:45: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 15:45:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zJb0FW9yRS/Render', 15:45:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:15 INFO - 'HOME': '/Users/cltbld', 15:45:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:15 INFO - 'LOGNAME': 'cltbld', 15:45:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:15 INFO - 'MOZ_NO_REMOTE': '1', 15:45:15 INFO - 'NO_EM_RESTART': '1', 15:45:15 INFO - 'PAGER': '/bin/cat', 15:45:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:15 INFO - 'PWD': '/builds/slave/test', 15:45:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:15 INFO - 'SHELL': '/bin/bash', 15:45:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O5Clm7USAK/Listeners', 15:45:15 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 15:45:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:15 INFO - 'USER': 'cltbld', 15:45:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:15 INFO - 'XPC_FLAGS': '0x0', 15:45:15 INFO - 'XPC_SERVICE_NAME': '0', 15:45:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:45:15 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:45:15 INFO - Cleaning up... 15:45:15 INFO - Return code: 0 15:45:15 INFO - Running post-action listener: _resource_record_post_action 15:45:15 INFO - Running post-action listener: _start_resource_monitoring 15:45:15 INFO - Starting resource monitoring. 15:45:15 INFO - [mozharness: 2017-05-22 22:45:15.850955Z] Finished create-virtualenv step (success) 15:45:15 INFO - [mozharness: 2017-05-22 22:45:15.851907Z] Running install step. 15:45:15 INFO - Running pre-action listener: _resource_record_pre_action 15:45:15 INFO - Running main action method: install 15:45:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:45:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:45:16 INFO - Reading from file tmpfile_stdout 15:45:16 INFO - Detecting whether we're running mozinstall >=1.0... 15:45:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:45:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:45:16 INFO - Reading from file tmpfile_stdout 15:45:16 INFO - Output received: 15:45:16 INFO - Usage: mozinstall [options] installer 15:45:16 INFO - Options: 15:45:16 INFO - -h, --help show this help message and exit 15:45:16 INFO - -d DEST, --destination=DEST 15:45:16 INFO - Directory to install application into. [default: 15:45:16 INFO - "/builds/slave/test"] 15:45:16 INFO - --app=APP Application being installed. [default: firefox] 15:45:16 INFO - mkdir: /builds/slave/test/build/application 15:45:16 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'] 15:45:16 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 15:45:30 INFO - Reading from file tmpfile_stdout 15:45:30 INFO - Output received: 15:45:30 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 15:45:30 INFO - Running post-action listener: _resource_record_post_action 15:45:30 INFO - [mozharness: 2017-05-22 22:45:30.986270Z] Finished install step (success) 15:45:30 INFO - [mozharness: 2017-05-22 22:45:30.986438Z] Running run-tests step. 15:45:30 INFO - Running pre-action listener: _resource_record_pre_action 15:45:30 INFO - Running main action method: run_tests 15:45:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:45:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:45:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:45:31 INFO - 2017-05-22 15:45:31.001 screenresolution[1622:7311] starting screenresolution argv=screenresolution get 15:45:31 INFO - 2017-05-22 15:45:31.003 screenresolution[1622:7311] Display 0: 1600x1200x32@60 15:45:31 INFO - 2017-05-22 15:45:31.009 screenresolution[1623:7313] starting screenresolution argv=screenresolution list 15:45:31 INFO - Available Modes on Display 0 15:45:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:45:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:45:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:45:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:45:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:45:31 INFO - Intel Iris: 15:45:31 INFO - Chipset Model: Intel Iris 15:45:31 INFO - Type: GPU 15:45:31 INFO - Bus: Built-In 15:45:31 INFO - VRAM (Dynamic, Max): 1536 MB 15:45:31 INFO - Vendor: Intel (0x8086) 15:45:31 INFO - Device ID: 0x0a2e 15:45:31 INFO - Revision ID: 0x0009 15:45:31 INFO - Displays: 15:45:31 INFO - Display: 15:45:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:45:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:45:31 INFO - Display Serial Number: 1600x1200 60 15:45:31 INFO - Main Display: Yes 15:45:31 INFO - Mirror: Off 15:45:31 INFO - Online: Yes 15:45:31 INFO - Rotation: Supported 15:45:31 INFO - Return code: 0 15:45:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:45:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:45:31 INFO - Python 2.7.3 15:45:31 INFO - Return code: 0 15:45:31 INFO - grabbing minidump binary from tooltool 15:45: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')]} 15:45:31 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 0x102ff3d30>, '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 0x102f1adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102fe8be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:45:31 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 15:45:31 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 15:45:31 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 15:45:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:45:31 INFO - Return code: 0 15:45:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:45:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:45:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:45:31 INFO - ENV: RUST_BACKTRACE is now 1 15:45:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:45:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:45:31 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/DalBoHteQ1uFSBGVNVPngg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0236', '--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 15:45:31 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/DalBoHteQ1uFSBGVNVPngg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0236 --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 15:45:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zJb0FW9yRS/Render', 15:45:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:45:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:45:31 INFO - 'HOME': '/Users/cltbld', 15:45:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:45:31 INFO - 'LOGNAME': 'cltbld', 15:45:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:45:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:45:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:45:31 INFO - 'MOZ_NO_REMOTE': '1', 15:45:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:45:31 INFO - 'NO_EM_RESTART': '1', 15:45:31 INFO - 'PAGER': '/bin/cat', 15:45:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:45:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:45:31 INFO - 'PWD': '/builds/slave/test', 15:45:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:45:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:45:31 INFO - 'RUST_BACKTRACE': '1', 15:45:31 INFO - 'SHELL': '/bin/bash', 15:45:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.O5Clm7USAK/Listeners', 15:45:31 INFO - 'TMPDIR': '/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/', 15:45:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:45:31 INFO - 'USER': 'cltbld', 15:45:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:45:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:45:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:45:31 INFO - 'XPC_FLAGS': '0x0', 15:45:31 INFO - 'XPC_SERVICE_NAME': '0', 15:45:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:45:31 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/DalBoHteQ1uFSBGVNVPngg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0236', '--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 15:45:31 INFO - mozversion application_buildid: 20170522144823 15:45:31 INFO - mozversion application_changeset: 16c20bf328da5fcd6060395fd0fb320f3b8a1146 15:45:31 INFO - mozversion application_display_name: Firefox 15:45:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:45:31 INFO - mozversion application_name: Firefox 15:45:31 INFO - mozversion application_remotingname: firefox 15:45:31 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:45:31 INFO - mozversion application_vendor: Mozilla 15:45:31 INFO - mozversion application_version: 54.0 15:45:31 INFO - mozversion platform_buildid: 20170522144823 15:45:31 INFO - mozversion platform_changeset: 16c20bf328da5fcd6060395fd0fb320f3b8a1146 15:45:31 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:45:31 INFO - mozversion platform_version: 54.0 15:45:31 INFO - using testdate: 1495493131 15:45:31 INFO - actual date: 1495493131 15:45:31 INFO - starting webserver on 'localhost:49224' 15:45:31 INFO - SUITE-START | Running 2 tests 15:45:31 INFO - TEST-START | dromaeo_css 15:45:31 INFO - operating with platform_type : mac_ 15:45:31 INFO - Initialising browser for dromaeo_css test... 15:45:31 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmp28sLWt/profile http://localhost:49224/getInfo.html) 15:45:34 INFO - PROCESS | 1633 | 2017-05-22 15:45:34.536 plugin-container[1636:7486] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3b, name = 'com.apple.tsm.portname' 15:45:34 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:45:34 INFO - PROCESS | 1633 | 2017-05-22 15:45:34.537 plugin-container[1636:7486] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9117, name = 'com.apple.CFPasteboardClient' 15:45:34 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:45:34 INFO - PROCESS | 1633 | 2017-05-22 15:45:34.537 plugin-container[1636:7486] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:45:34 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 15:45:34 INFO - PROCESS | 1633 | colorDepth:24 15:45:34 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 15:45:34 INFO - PROCESS | 1633 | __metrics 15:45:34 ERROR - PROCESS | 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 15:45:35 INFO - TEST-INFO | 1633: exit 0 15:45:35 INFO - Browser initialized. 15:45:35 INFO - Running cycle 1/1 for dromaeo_css test... 15:45:35 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmp28sLWt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:45:37 INFO - PROCESS | 1637 | 2017-05-22 15:45:37.819 plugin-container[1638:7617] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 15:45:37 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:45:37 INFO - PROCESS | 1637 | 2017-05-22 15:45:37.819 plugin-container[1638:7617] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 15:45:37 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:45:37 INFO - PROCESS | 1637 | 2017-05-22 15:45:37.819 plugin-container[1638:7617] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:45:47 INFO - PROCESS | 1637 | RSS: Main: 174333952 15:45:47 INFO - PROCESS | 1637 | 15:48:50 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49224/tests/dromaeo/cssquery-ext.html) 15:48:52 INFO - PROCESS | 1637 | RSS: Main: 190947328 15:48:52 INFO - PROCESS | 1637 | 15:51:54 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-ext.html (next: http://localhost:49224/tests/dromaeo/cssquery-jquery.html) 15:51:55 INFO - PROCESS | 1637 | RSS: Main: 202838016 15:51:55 INFO - PROCESS | 1637 | 15:54:57 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49224/tests/dromaeo/cssquery-mootools.html) 15:54:57 INFO - PROCESS | 1637 | RSS: Main: 204382208 15:54:57 INFO - PROCESS | 1637 | 15:57:59 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49224/tests/dromaeo/cssquery-prototype.html) 15:58:00 INFO - PROCESS | 1637 | RSS: Main: 207470592 15:58:00 INFO - PROCESS | 1637 | 16:01:03 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49224/tests/dromaeo/cssquery-yui.html) 16:01:04 INFO - PROCESS | 1637 | RSS: Main: 209862656 16:01:04 INFO - PROCESS | 1637 | 16:04:06 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-yui.html (next: http://localhost:49224/tests/dromaeo/cssquery-dojo.html) 16:04:07 INFO - PROCESS | 1637 | RSS: Main: 208203776 16:04:07 INFO - PROCESS | 1637 | 16:04:07 INFO - PROCESS | 1637 | __start_tp_report 16:04:07 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 16:04:07 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 16:04:07 INFO - PROCESS | 1637 | |i|pagename|runs| 16:04:07 INFO - PROCESS | 1637 | |0;dojo.html;3597.172650290435;3623.4927110204576;3646.179609587843;3650.3611867923105;3662.706983441324;6099.481544068754;6109.602875813069;6135.539834512413;6192.071189321604;6194.69026548673;4981.701097934127;6147.999999999989;6153.35389784073;6170.938290617092;6185.783497577586;5520.420355862647;5546.306711661043;5581.134924086773;5594.692291923942;5662.37713851476;5618.971905140468;5640.5487560995225;5646.576506761989;5660.462256085665;5684.8294551163535;5427.1316589345515;5463.098588732874;5611.3546942101775;5619.7471113799775;5626.606137570372;5580.944190558083;5599.776008959637;5610.102383618621;5612.102063669793;5637.3235211774845;5491.395946445888;5572.331320241557;5594.24477695514;5594.776208951637;5599.720013999284;5882.382349853289;6099.9935010723175;6131.264248290234;6247.656378899159;6252.531060170505;5400.378956420022;6347.87304253917;6363.809085727389;6373.42639162473;6408.10286559882;5420.891582168374;6598.7030583623855;6640.169978752656;6648.069270302161;6650.501212409089;6281.309056003836;6287.842803929939;6301.590396624205;6310.495160387158;6323.620582765003;6472.773452929125;6477.740890364333;6524.6737663116655;6721.596704197764;6730.528862979544;6396.200474940632;6411;6425.261094974045;6434.067060276234;6458.806235812933;6495.350464953562;6621.006848972693;6631.767888123893;6647.335266473411;6701.195856497252;5452.482014208644;6293.30773614902;6320.115183874262;6513.609183449008;6548.410643042053;6301.338359472281;6417.903731444032;6428.774992875227;6445.581037232565;6454;5251.6061295403;5283.735813209324;5316.0962636351915;5321.5476684482255;5324.121519949165;4715.7642117893965;4724.173269677793;4740.691855029412;4756.191447453941;4761.40482439695;5170.586353091744;5744;6125.724342404603;6242.75028998844;6346.9682651586445;6565.901511477332;6631.502637302222;6643.800685979429;6675.0988616537115;6685.465162787063;5925.34821169671;5942.078977758368;6261.96869015652;6272.968635156886;6334.619922804646;5688.658680479101;5713.171590119409;5909.911351329733;5919.55603329752;5920.171176035358;6788;6819.69311380999;6824.692888820115;6844.931550684629;6911.239763626098;6586.1438013058;6599.670016499252;6626.83432914181;6629.834254143685;6656.467482601283;5434.809781657623;5661.46216109469;6581.868362632625;6922.996165556067;6942.757003505055;6394.936050639434;6395.6802159890885;6424.164858568543;6456.515761318014;6464.8707025858275;6654.633995130314;6687.331266873273;6690.665466726547;6692.7992160233325;6707.429868461236;6253.874922501615;6412.647304398116;6495.545311828127;6496.610203387811;6498.057781621733;6281.12064311006;6336.239651241696;6361.90457143134;6372.458341041063;6425.839354016187;6086.6348019119;6211.378862113933;6367.395097465734;6391;6399.600149943771;6258.906116408167;6262.405071518198;6473.935260647333;6483.157189565326;6491.091247225487;6254.28075771274;6306;6314.1160237565855;6342.587731797418;6357 16:04:07 INFO - PROCESS | 1637 | |1;ext.html;759.525296689569;812.8089898873764;836.4395854777299;966.2318456826822;969.1326262994615;40855.79572102139;41721.58278417215;43256.56743432565;43303.917402064966;43325.48360807372;41219.58780412195;41301.58698413015;41444.963875903115;41570;41608.37587436191;8190.959045204766;8309.709160179395;10983.450827458595;11241.60654377097;11444.484998175101;11349.773004539946;11392.71518212043;11413.429328533583;11421.314721116718;11431.542738290458;11362.659120226406;11404.087672986183;11406.51715277017;11410.20128590999;11435.313881167156;9561;11322.320660760339;11360.977512023916;11368.54525818962;11368.545258189662;9160.8625870612;11159.051480624157;11167.385793781339;11167.83248251277;11172.720681982933;36342.63657363419;36389;36445.08887277813;36513.81743091294;36545;3904.1996390739832;3933.3509970854775;3943.3493473576827;3961.108750531136;4003.6596889264447;21111.78888211129;24303.392415189584;26555;28408.715912840813;28527.71472285292;3188.6651901550467;3244.9675503244903;3249.5613092232484;3254.446744053517;3256.9837150814096;344760.2761986199;348738.0252394938;355157;355906;512778.9999999963;42776.78611606922;44678.10643787171;44937.87655308643;45020.3246951296;45087.549124508994;46524.767376162905;46560.767196163804;46576.76711616488;46611.76694116508;46678.766606166755;14158.221297828512;14271.429142834379;14291.57125286243;14292.285385730673;14294.85705142956;12542.498300068077;12731.55439559611;12735.363231838372;12745.808812867814;12749.49002039908;11970;11980.400979950968;12085.879141208476;12115.515379384902;12192.451339689735;5802.82591522255;5832.88334233313;5848.766049357978;5859.589828711949;5882.529397648178;1095.9616413425492;1151.740858306891;1170.5434880396651;1172.976540469186;1185.2118341302937;23711.762882370957;26956;27258;27351.589726154125;27511.724882750917;1088.7060493666665;1251.9937400313122;1259.8362212912446;1313.1398933698442;1318.156379916854;14347;15222.086674799548;15369.846301537065;15406.075635461684;15568.922155389377;336508;337998;338275;339038;341354;55200.89598208094;55509.44490555043;55527.44472555303;55595;55954.72022639861;1376.9793453098212;1445.1545845680396;1498.6103613060466;1532.9156896370584;1540;27370.726292737614;28856.134315970554;29328.826846925884;29377.265568360963;29423.411531768816;47162.29256561223;48125.27812082751;48170;51281.48718512767;51313.48686513087;484678;486315.5684221698;486642;486706;487291.690624648;27803.304917377227;28339.291517712223;32788.83605581957;33954.15114622154;34219.4867076999;40159.397609036474;40451.25059741887;40947;41231.58768412397;41659.7917010413;21296.893515532323;24493.142740004016;24861.62707559352;27470.313242169104;30507.847460762554;28909.710902891544;29003.274918127216;29107.417851643273;29787.851060744557;30037.849810750806;32817.83591082029;33931.15172120717;34336.96989090331;34668.653313466544;34669.30661386809;28626.856865715537;29140.854295728386;29238.853805730836;29246;29999.4000119992;19329.13018914124;23535.940882660663;23880.597014925428;23992.160274390313;24070 16:04:07 INFO - PROCESS | 1637 | |2;jquery.html;425.3003808734634;436.36944615031274;440.4450392505444;440.6012558634435;450.61922675339326;18016.729749053742;20280.88455134969;21009.369718908438;22377.888110559466;22490.775092249074;21875.781242187575;22282.108715651353;22570.43573910653;22600.886995565;22853.77146228537;6682.866342673144;6801.591904485735;6991.370776630102;7026.156861176641;7045.18980317265;6682.131322928012;6884.483663725216;6890.414314783249;6891.965540172292;6904.240533541307;5895.35151133375;6570.178727659043;6815.761448349309;6886.51794374394;6909.48178886583;6575.835604109888;6833.04337392763;6887.173539175331;6892.586444813327;6894.655267236618;6639.402453779135;6702.396784289437;6712.160979877515;6720.361565651304;6754.763583274611;26711.599326010124;26922;27157.728422715718;27339.906403743826;27507.58738618922;3108.751299896003;3111.8132912025353;3124.609423822022;3129.233337832223;3163.1459505933267;16213.675726485404;16803.24402318576;16888.57778555546;17034;17098.829011709848;2130.286354071388;2152.838537109723;2153.6123497770395;2155.3210738617286;2212.9225477108384;114043.2893506598;131934;137759.31120344435;138851.6114838849;144181.2790936049;16863.662726745642;18135.45593632193;19920.800791992184;21085.78914210869;21293.574128517343;24231.636525452133;30851.457427128553;31412.900548480695;31437.842810785798;31509;10190.898091019144;10320.12278956297;10505.264631475877;10512.947435262775;10645.893541064645;8708.956455217683;8831.317118305456;8929.33030022751;8932.508712020772;9135.817283654422;8935.821283574292;9046.592903319366;9052.728418147466;9211;9272.629094836266;3739.420389839579;3835.290471262825;4106.507219133723;4205.474315710536;4221.915561688749;868.5396739727954;1057.7620035492107;1062.3094988257694;1086.2504871638544;1114.1755101225035;19393.224271029;20918.79081209199;21288.36134915955;21519.892400537898;21784.78215217859;977.8093271811929;1085.2403317677656;1087.3312912558877;1115.5887801930564;1129.8474705914762;9797.755056123653;11107.611233606785;11149.498272577754;11155.553777848796;11189.776204475866;653900;707569;735006;752583;771030;117484;131372;158956.82086358208;166092;168348.15825921038;1262.1921969939247;1273.4715093236389;1291.044626976031;1299.8505171905351;1300.921944683341;14723.33744981457;17540.91229543844;18938.431847044612;19006;19007.144678489225;52680.47319526909;59587;62335;62382.688086559276;62601.373986259554;86931.56534217288;88479;88861;90596.54701726449;90938;9633.807323853624;11492.425378731197;11914.225575337921;12012.339321337411;12049.156559040783;13039.348032598522;13110.541131060367;13204.60386188416;13379.732405351644;13449.260290683715;10614.150867930393;11034.944825276143;11285.774284514428;11455.942720286344;11719;10622.575096996034;10641.840372394265;10647.574097036031;11039.282446640942;11076.501557430096;11525;11583;11745.119116066158;11790.115741319263;11965.581204658143;11607.941960290145;11713.765724685629;11775.941120294343;11892.227005244631;11944.76110477803;10915.962983516552;11016.504257308601;11195.04842088399;11487.48306326234;11536.365499897585 16:04:07 INFO - PROCESS | 1637 | |3;mootools.html;1595.4575444348918;1644.8108467526238;1652.9504114876559;1656.4699296225208;1674.6566953774477;9730.318877678557;10964.013238808506;11403.828942565868;11633.534658613657;11806.409679516033;11386.60146894859;11566.07471402288;11570.47932843022;11573.768524629524;11654.359010254433;2483.2798488438375;2536.5687833068423;2539.771420572148;2540.657011303478;2545.847249165047;2089.477630592352;2454.349597356702;2521.8739063046864;2529.3423709835483;2533.733957934418;2471.7775400213977;2502.4119331956977;2510.7740303372693;2520.4329025969196;2523.6971563412326;2088.6449303618347;2468.777809997091;2492.3395300245447;2502.8248022638422;2511.9246422607353;2316.2472196536023;2341.332720174759;2473.5300292944285;2497.5379554782426;2499.5875680512877;10583.206259530489;10783.676489705322;10811.459427028696;10820;10838.40388778617;5919.260092488439;6084.30030546484;6088.634681919055;6091.390860913917;6096.939030609681;3000.9249768755735;3521.5950165731015;3523.6300188480095;3577.5706915170085;3775.0562359410146;1231.1874163052432;1242.6644805902356;1265.1586694847915;1284.081881454755;1308.3196737696344;13765.38055787492;14108.717825643533;14885.255737213096;15725.292361843745;15847.841521584867;4814.7833347499445;4898.412190537087;4936.308916751657;4945.67853089548;4974.875628109254;12199.329036902878;12200;12210.877891220975;12216.938915305367;12231.93884030592;5813.82558523245;6043.123747056652;6048.364921683248;6066.3023752269055;6079.300880398722;4292.635126014261;5525.668459892419;5535.557155427556;5535.667859928417;5573.386927437979;3978.0850404407147;4108.506979162519;4150.605692459211;4155.501339839238;4180.602842729936;1086.9728256793485;1088.1294964028746;1089.2810744908322;1097.6542389147394;1100.350793032115;787.2914377060576;956.8469044952774;965.7295957132014;973.454865275448;989.9010098990187;5898.64608123514;6769.898451523231;6835.487338449636;6865.313468653095;7284.781456556312;1034.3173505486343;1068.5992752717732;1076.6326765008396;1083.1389045708681;1099.6975831646362;4163.8959026024595;4316.589923957219;4333.739975601411;4371.453568303962;4449.599536041707;11702.590409335802;13592.184468931895;14151.646208844655;14173.220472873885;14185.077969931921;14084.788728169086;14110.85889141116;14110.929445352913;14119.223442710749;14138.858611413754;9935.701928942144;10402.167826573856;10593.523291451904;10604.416757078434;10611.31026483276;6138.846528836815;6273.121762953281;6877.3810357068105;7153.713851445884;7154.320339567733;12136.39318034077;12536.623901282976;12542.749145017231;12692.619221423372;12697.174683645533;9574;12016.218945768496;12243.938780306042;12414.255144691348;12915.935420322838;9324.393914395543;9595.47224902637;9616.95191524038;9621.90378096238;9643.662471813734;10445.791084178425;10512.474376281003;10615.0446459819;10644.787104257717;10659.520321585702;9088.500132492776;9193;9249.12133347331;9333;9400.482973436254;8843.071477495028;8854.29165666758;8896.154865287788;8908.510031948304;8975.730728078168;8144.348452123697;8235.464694794819;8289.709860154866;8298.875516867372;8303.667853286042;4953.356063711694;5960;5974.850628734316;6083.482903953037;6185.876282474235;6001.549883758823;6071;6071.392860713893;6193.721282542387;6289.459082524628 16:04:07 INFO - PROCESS | 1637 | |4;prototype.html;305.1060393048368;348.82619983754665;384.6096212344471;396.79366729300784;403.26001786721366;4591.88520286993;4604.451635778887;4751.168545504536;4818.578519336796;5478.561715062795;4628.930566041512;4638.4665763437215;5402.784373515957;5425.647332923357;5433.891322173554;4257.254980378445;4487.621560572901;4497.977510112445;4558.361829343895;4583.381243532131;4289.335153051282;4316.330968699856;4543.931841022388;4583.899864032642;4610.654200934927;4301.763403012833;4573.862784116482;4578.885527861814;4593.265077587586;4657.953420465786;3690.42798366255;3903.6291552302487;4309.758925358828;4525.298578720271;4555.157295900269;3583.7491375603718;3693.741438099334;3826.234753049401;4156.750594964312;4173.499180098408;5350.438203988564;5377.300950876361;5398.4871437213405;5409;5427.375851777057;4573.3764513597525;4713.6464765142755;4772.141014617368;4932.876678083041;5250.264962905197;4817.35125603794;5162.92255616166;5686.3744988051285;5776.8844623107725;5799.594028418012;4174.478190226222;4183.581641835823;4700.9059818803435;4763.428068737343;4771.475137734847;5745.741441635095;5936.28764548257;6012.230072788844;6126.479249263818;6206.331277550651;4446.73319600825;4742.747195082377;4774.30772537987;4778.354922085474;4868.756562171877;4788.084766093591;4804.552942938431;4834.250691142878;4842.225243960949;4939.209726443752;4237.978810105991;5112.954818072801;5191.6884986900905;5254.97372513135;5278.683279003272;4615.630749540029;4886.633502487328;5605.430479465756;5773.268019594105;5829.941700583025;1479.5413421839264;1519.270750039987;1555.2068445092857;1597.7763113164167;1649.1177220187142;1129.1418521923397;1165.685264978451;1175.3183153770792;1196.3539688568076;1254.3943751997276;4720.811167553259;5339.199120131933;5428;5534;5571.275734154615;5551.500364967092;6004.819855404345;6013.9699301503215;6108.198081565949;6121.877562448726;4221.957780422218;5112.667676601009;5270.789168433296;5276.472352764769;5419.701916394636;2621.5412302846926;2629.290960875446;3001.409253095863;3156.6527681954967;3201.9273543363233;5299.4700529946695;5652.7738890443925;5831.591788574759;6415.3961509622595;6426.389492998158;5617.775288988477;5710.314762228559;6218.5647004710145;6241.469475094577;6281.905771413432;5238;6396.2964073951825;6427.457410221607;6436.613803171825;6469.450096741735;5014.899702006012;5510.421405752339;5557.5831812613405;5963.541146135144;6038.939610604013;5325.520703136736;5877.941970445361;5889.941100589111;5996.340402555628;6030.7286172121485;5633.267675202197;5978.461938425388;6209.379062093754;6245.656488893154;6295.433410993032;4134.462519872518;4162.5421203668175;4459.643228541644;4821.325014497903;4857.611391088633;5499.775213547066;5615.0200036960505;5872.324682661549;5982.910256346071;5994.831007953581;5144.459831717616;5897.351291357862;5927.666275088243;5933.258342707161;5954.374790647093;5270.973645131749;5308.761105750174;5308.946910531;5943.970280148745;5974.880502390015;5301.416844147064;5670.347909990239;5727.852055479195;5779.364269930388;5841.678707670949;5151.356080489939;5310.548603368757;5327.709085093292;5600.299962504687;5873.267386121019;4719.858404247878;5048.974755126201;5135.794568217379;5723.7996670116345;5751.396103409249 16:04:07 INFO - PROCESS | 1637 | |5;yui.html;774.0015380159595;801.0787594266593;878.5651102704162;885.6944354197801;897.443584977314;16535.09057001866;16739.916300418496;16887.915560422196;17211;17518;15856.603584910383;16011.839881601181;16163;16377.344906203738;16406.507804765846;990.0841721407705;1009.5658866687321;1015.2081376526285;1016.6899095775785;1017.8778546574422;973.459729849933;1015.3400289811644;1022.8056669232822;1024.3853687787343;1026.7073883943078;1047.659510659035;1058.2116323339123;1060.6658902445743;1061.4533515239657;1063.9361638301689;970.9951450242739;1009.7425156585098;1050.1703654113242;1052.2791887557048;1055.5144633468615;784.8037990502374;785.1638005524155;785.3128512551517;786.8426314737018;787.5943888897165;17040.233189506376;17162.22769975354;17299.56751081233;17719.202635881415;17966.371177008743;13694.863051369459;13701;13948.790768138486;13952.651183720489;14021.859781402158;12142.392880355947;12202.511899524008;12207;12221.205621634654;12252.085540748232;3830.578636349999;3859.1509867829313;3887;3917.157811070605;4065.044714492122;101290.48064279041;109419;111353.44323278412;114294;114482;11544.595939142258;11670.183087183816;11687.649370518899;11720.120990925714;11727.296362218123;24047.158349457684;24265.878670606533;24318.87840560786;24337.878310608332;24353.878230608734;8039.437239393304;8132.471389359673;8163.959180204061;8176.427650064438;8339.374546908884;4528.366028755977;4541.091781643619;4631.768411579407;4635.119327327797;4730.88172795676;3625.3655610268097;3708.9072773180887;3731.0858839584475;3731.962680373216;3736.626337366296;656.0847617573459;663.9568428052161;679.106974328766;693.6913073682163;696.6168607266085;620.6152185644929;743.862385458681;751.4627041665235;766.9424793140537;770.1066762555521;10041.146502547364;10612.31019983714;10636.883127271525;10964.245720684812;10989.450527473595;640.6188317950812;700.6916956539106;732.6300218389679;733.8348871503869;733.9486235963539;4622.445306563234;5205.713685747244;5212.139996900545;5215.791368345223;5250.632455728139;82589;91617;107271;110734;111856;63407;65718.34281657219;72571.63714181395;86434;91506;60817.47956301128;63940.68029659822;70909;71362.64318678374;82481.17518824856;10941.070009049;11124;11453;11472.082233421139;11483.770324593628;42103;50979.49020509747;51939.480605193465;51973.7401312991;52804.73597631987;66556.3410561256;66714.66642666755;67096.99354509589;67127.66436167787;67285.66357168183;11032.889671103187;11213.495392707184;11526.847315268742;12047.337396442928;13338.3330833456;12925.612231633066;13248.735025299633;15517.37930482768;15873.365065397255;15925.920370398075;14189.716205675622;14205.147691138565;14644.04813687107;14770.718259636466;14784.186869722269;13242.735145297233;13649.38577763983;13764.518241861486;13827.79258311106;14127.717445651235;13786.379612917643;13811.654708632364;13883.652908677364;14073.577792666236;14269.500567480089;10143.442110683984;10418.218633602359;10504.789904202025;10739.677809665722;10835.566577336818;10447.94776026115;10861.696581514921;11838.289702617525;11989.520419183134;12237.449314780679 16:04:07 INFO - PROCESS | 1637 | __end_tp_report 16:04:07 INFO - PROCESS | 1637 | __start_cc_report 16:04:07 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,394 16:04:07 INFO - PROCESS | 1637 | __end_cc_report 16:04:07 INFO - PROCESS | 1637 | __startTimestamp1495494247014__endTimestamp 16:04:07 INFO - PROCESS | 1637 | 16:04:07 INFO - PROCESS | 1637 | ------- Summary: start ------- 16:04:07 INFO - PROCESS | 1637 | Number of tests: 6 16:04:07 INFO - PROCESS | 1637 | 16:04:07 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:6066.02 Median:6293.31 stddev:659.46 (10.5%) stddev-sans-first:632.41 16:04:07 INFO - PROCESS | 1637 | Values: 3597.2 3623.5 3646.2 3650.4 3662.7 6099.5 6109.6 6135.5 6192.1 6194.7 4981.7 6148.0 6153.4 6170.9 6185.8 5520.4 5546.3 5581.1 5594.7 5662.4 5619.0 5640.5 5646.6 5660.5 5684.8 5427.1 5463.1 5611.4 5619.7 5626.6 5580.9 5599.8 5610.1 5612.1 5637.3 5491.4 5572.3 5594.2 5594.8 5599.7 5882.4 6100.0 6131.3 6247.7 6252.5 5400.4 6347.9 6363.8 6373.4 6408.1 5420.9 6598.7 6640.2 6648.1 6650.5 6281.3 6287.8 6301.6 6310.5 6323.6 6472.8 6477.7 6524.7 6721.6 6730.5 6396.2 6411.0 6425.3 6434.1 6458.8 6495.4 6621.0 6631.8 6647.3 6701.2 5452.5 6293.3 6320.1 6513.6 6548.4 6301.3 6417.9 6428.8 6445.6 6454.0 5251.6 5283.7 5316.1 5321.5 5324.1 4715.8 4724.2 4740.7 4756.2 4761.4 5170.6 5744.0 6125.7 6242.8 6347.0 6565.9 6631.5 6643.8 6675.1 6685.5 5925.3 5942.1 6262.0 6273.0 6334.6 5688.7 5713.2 5909.9 5919.6 5920.2 6788.0 6819.7 6824.7 6844.9 6911.2 6586.1 6599.7 6626.8 6629.8 6656.5 5434.8 5661.5 6581.9 6923.0 6942.8 6394.9 6395.7 6424.2 6456.5 6464.9 6654.6 6687.3 6690.7 6692.8 6707.4 6253.9 6412.6 6495.5 6496.6 6498.1 6281.1 6336.2 6361.9 6372.5 6425.8 6086.6 6211.4 6367.4 6391.0 6399.6 6258.9 6262.4 6473.9 6483.2 6491.1 6254.3 6306.0 6314.1 6342.6 6357.0 16:04:07 INFO - PROCESS | 1637 | 16:04:07 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:54060.23 Median:30018.62 stddev:108585.60 (361.7%) stddev-sans-first:108816.47 16:04:07 INFO - PROCESS | 1637 | Values: 759.5 812.8 836.4 966.2 969.1 40855.8 41721.6 43256.6 43303.9 43325.5 41219.6 41301.6 41445.0 41570.0 41608.4 8191.0 8309.7 10983.5 11241.6 11444.5 11349.8 11392.7 11413.4 11421.3 11431.5 11362.7 11404.1 11406.5 11410.2 11435.3 9561.0 11322.3 11361.0 11368.5 11368.5 9160.9 11159.1 11167.4 11167.8 11172.7 36342.6 36389.0 36445.1 36513.8 36545.0 3904.2 3933.4 3943.3 3961.1 4003.7 21111.8 24303.4 26555.0 28408.7 28527.7 3188.7 3245.0 3249.6 3254.4 3257.0 344760.3 348738.0 355157.0 355906.0 512779.0 42776.8 44678.1 44937.9 45020.3 45087.5 46524.8 46560.8 46576.8 46611.8 46678.8 14158.2 14271.4 14291.6 14292.3 14294.9 12542.5 12731.6 12735.4 12745.8 12749.5 11970.0 11980.4 12085.9 12115.5 12192.5 5802.8 5832.9 5848.8 5859.6 5882.5 1096.0 1151.7 1170.5 1173.0 1185.2 23711.8 26956.0 27258.0 27351.6 27511.7 1088.7 1252.0 1259.8 1313.1 1318.2 14347.0 15222.1 15369.8 15406.1 15568.9 336508.0 337998.0 338275.0 339038.0 341354.0 55200.9 55509.4 55527.4 55595.0 55954.7 1377.0 1445.2 1498.6 1532.9 1540.0 27370.7 28856.1 29328.8 29377.3 29423.4 47162.3 48125.3 48170.0 51281.5 51313.5 484678.0 486315.6 486642.0 486706.0 487291.7 27803.3 28339.3 32788.8 33954.2 34219.5 40159.4 40451.3 40947.0 41231.6 41659.8 21296.9 24493.1 24861.6 27470.3 30507.8 28909.7 29003.3 29107.4 29787.9 30037.8 32817.8 33931.2 34337.0 34668.7 34669.3 28626.9 29140.9 29238.9 29246.0 29999.4 19329.1 23535.9 23880.6 23992.2 24070.0 16:04:07 INFO - PROCESS | 1637 | 16:04:07 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:41613.33 Median:2154.47 stddev:120640.49 (5599.6%) stddev-sans-first:120939.28 16:04:07 INFO - PROCESS | 1637 | Values: 425.3 436.4 440.4 440.6 450.6 18016.7 20280.9 21009.4 22377.9 22490.8 21875.8 22282.1 22570.4 22600.9 22853.8 6682.9 6801.6 6991.4 7026.2 7045.2 6682.1 6884.5 6890.4 6892.0 6904.2 5895.4 6570.2 6815.8 6886.5 6909.5 6575.8 6833.0 6887.2 6892.6 6894.7 6639.4 6702.4 6712.2 6720.4 6754.8 26711.6 26922.0 27157.7 27339.9 27507.6 3108.8 3111.8 3124.6 3129.2 3163.1 16213.7 16803.2 16888.6 17034.0 17098.8 2130.3 2152.8 2153.6 2155.3 2212.9 114043.3 131934.0 137759.3 138851.6 144181.3 16863.7 18135.5 19920.8 21085.8 21293.6 24231.6 30851.5 31412.9 31437.8 31509.0 10190.9 10320.1 10505.3 10512.9 10645.9 8709.0 8831.3 8929.3 8932.5 9135.8 8935.8 9046.6 9052.7 9211.0 9272.6 3739.4 3835.3 4106.5 4205.5 4221.9 868.5 1057.8 1062.3 1086.3 1114.2 19393.2 20918.8 21288.4 21519.9 21784.8 977.8 1085.2 1087.3 1115.6 1129.8 9797.8 11107.6 11149.5 11155.6 11189.8 653900.0 707569.0 735006.0 752583.0 771030.0 117484.0 131372.0 158956.8 166092.0 168348.2 1262.2 1273.5 1291.0 1299.9 1300.9 14723.3 17540.9 18938.4 19006.0 19007.1 52680.5 59587.0 62335.0 62382.7 62601.4 86931.6 88479.0 88861.0 90596.5 90938.0 9633.8 11492.4 11914.2 12012.3 12049.2 13039.3 13110.5 13204.6 13379.7 13449.3 10614.2 11034.9 11285.8 11455.9 11719.0 10622.6 10641.8 10647.6 11039.3 11076.5 11525.0 11583.0 11745.1 11790.1 11965.6 11607.9 11713.8 11775.9 11892.2 11944.8 10916.0 11016.5 11195.0 11487.5 11536.4 16:04:07 INFO - PROCESS | 1637 | 16:04:07 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:6872.54 Median:2526.52 stddev:4242.97 (167.9%) stddev-sans-first:4236.34 16:04:07 INFO - PROCESS | 1637 | Values: 1595.5 1644.8 1653.0 1656.5 1674.7 9730.3 10964.0 11403.8 11633.5 11806.4 11386.6 11566.1 11570.5 11573.8 11654.4 2483.3 2536.6 2539.8 2540.7 2545.8 2089.5 2454.3 2521.9 2529.3 2533.7 2471.8 2502.4 2510.8 2520.4 2523.7 2088.6 2468.8 2492.3 2502.8 2511.9 2316.2 2341.3 2473.5 2497.5 2499.6 10583.2 10783.7 10811.5 10820.0 10838.4 5919.3 6084.3 6088.6 6091.4 6096.9 3000.9 3521.6 3523.6 3577.6 3775.1 1231.2 1242.7 1265.2 1284.1 1308.3 13765.4 14108.7 14885.3 15725.3 15847.8 4814.8 4898.4 4936.3 4945.7 4974.9 12199.3 12200.0 12210.9 12216.9 12231.9 5813.8 6043.1 6048.4 6066.3 6079.3 4292.6 5525.7 5535.6 5535.7 5573.4 3978.1 4108.5 4150.6 4155.5 4180.6 1087.0 1088.1 1089.3 1097.7 1100.4 787.3 956.8 965.7 973.5 989.9 5898.6 6769.9 6835.5 6865.3 7284.8 1034.3 1068.6 1076.6 1083.1 1099.7 4163.9 4316.6 4333.7 4371.5 4449.6 11702.6 13592.2 14151.6 14173.2 14185.1 14084.8 14110.9 14110.9 14119.2 14138.9 9935.7 10402.2 10593.5 10604.4 10611.3 6138.8 6273.1 6877.4 7153.7 7154.3 12136.4 12536.6 12542.7 12692.6 12697.2 9574.0 12016.2 12243.9 12414.3 12915.9 9324.4 9595.5 9617.0 9621.9 9643.7 10445.8 10512.5 10615.0 10644.8 10659.5 9088.5 9193.0 9249.1 9333.0 9400.5 8843.1 8854.3 8896.2 8908.5 8975.7 8144.3 8235.5 8289.7 8298.9 8303.7 4953.4 5960.0 5974.9 6083.5 6185.9 6001.5 6071.0 6071.4 6193.7 6289.5 16:04:07 INFO - PROCESS | 1637 | 16:04:07 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4824.61 Median:5214.84 stddev:1374.47 (26.4%) stddev-sans-first:1335.81 16:04:07 INFO - PROCESS | 1637 | Values: 305.1 348.8 384.6 396.8 403.3 4591.9 4604.5 4751.2 4818.6 5478.6 4628.9 4638.5 5402.8 5425.6 5433.9 4257.3 4487.6 4498.0 4558.4 4583.4 4289.3 4316.3 4543.9 4583.9 4610.7 4301.8 4573.9 4578.9 4593.3 4658.0 3690.4 3903.6 4309.8 4525.3 4555.2 3583.7 3693.7 3826.2 4156.8 4173.5 5350.4 5377.3 5398.5 5409.0 5427.4 4573.4 4713.6 4772.1 4932.9 5250.3 4817.4 5162.9 5686.4 5776.9 5799.6 4174.5 4183.6 4700.9 4763.4 4771.5 5745.7 5936.3 6012.2 6126.5 6206.3 4446.7 4742.7 4774.3 4778.4 4868.8 4788.1 4804.6 4834.3 4842.2 4939.2 4238.0 5113.0 5191.7 5255.0 5278.7 4615.6 4886.6 5605.4 5773.3 5829.9 1479.5 1519.3 1555.2 1597.8 1649.1 1129.1 1165.7 1175.3 1196.4 1254.4 4720.8 5339.2 5428.0 5534.0 5571.3 5551.5 6004.8 6014.0 6108.2 6121.9 4222.0 5112.7 5270.8 5276.5 5419.7 2621.5 2629.3 3001.4 3156.7 3201.9 5299.5 5652.8 5831.6 6415.4 6426.4 5617.8 5710.3 6218.6 6241.5 6281.9 5238.0 6396.3 6427.5 6436.6 6469.5 5014.9 5510.4 5557.6 5963.5 6038.9 5325.5 5877.9 5889.9 5996.3 6030.7 5633.3 5978.5 6209.4 6245.7 6295.4 4134.5 4162.5 4459.6 4821.3 4857.6 5499.8 5615.0 5872.3 5982.9 5994.8 5144.5 5897.4 5927.7 5933.3 5954.4 5271.0 5308.8 5308.9 5944.0 5974.9 5301.4 5670.3 5727.9 5779.4 5841.7 5151.4 5310.5 5327.7 5600.3 5873.3 4719.9 5049.0 5135.8 5723.8 5751.4 16:04:07 INFO - PROCESS | 1637 | 16:04:07 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:20343.20 Median:16637.50 stddev:28410.09 (170.8%) stddev-sans-first:28451.79 16:04:07 INFO - PROCESS | 1637 | Values: 774.0 801.1 878.6 885.7 897.4 16535.1 16739.9 16887.9 17211.0 17518.0 15856.6 16011.8 16163.0 16377.3 16406.5 990.1 1009.6 1015.2 1016.7 1017.9 973.5 1015.3 1022.8 1024.4 1026.7 1047.7 1058.2 1060.7 1061.5 1063.9 971.0 1009.7 1050.2 1052.3 1055.5 784.8 785.2 785.3 786.8 787.6 17040.2 17162.2 17299.6 17719.2 17966.4 13694.9 13701.0 13948.8 13952.7 14021.9 12142.4 12202.5 12207.0 12221.2 12252.1 3830.6 3859.2 3887.0 3917.2 4065.0 101290.5 109419.0 111353.4 114294.0 114482.0 11544.6 11670.2 11687.6 11720.1 11727.3 24047.2 24265.9 24318.9 24337.9 24353.9 8039.4 8132.5 8164.0 8176.4 8339.4 4528.4 4541.1 4631.8 4635.1 4730.9 3625.4 3708.9 3731.1 3732.0 3736.6 656.1 664.0 679.1 693.7 696.6 620.6 743.9 751.5 766.9 770.1 10041.1 10612.3 10636.9 10964.2 10989.5 640.6 700.7 732.6 733.8 733.9 4622.4 5205.7 5212.1 5215.8 5250.6 82589.0 91617.0 107271.0 110734.0 111856.0 63407.0 65718.3 72571.6 86434.0 91506.0 60817.5 63940.7 70909.0 71362.6 82481.2 10941.1 11124.0 11453.0 11472.1 11483.8 42103.0 50979.5 51939.5 51973.7 52804.7 66556.3 66714.7 67097.0 67127.7 67285.7 11032.9 11213.5 11526.8 12047.3 13338.3 12925.6 13248.7 15517.4 15873.4 15925.9 14189.7 14205.1 14644.0 14770.7 14784.2 13242.7 13649.4 13764.5 13827.8 14127.7 13786.4 13811.7 13883.7 14073.6 14269.5 10143.4 10418.2 10504.8 10739.7 10835.6 10447.9 10861.7 11838.3 11989.5 12237.4 16:04:07 INFO - PROCESS | 1637 | -------- Summary: end -------- 16:04:07 INFO - PROCESS | 1637 | 16:04:07 INFO - TEST-INFO | 1637: exit 0 16:04:07 INFO - TEST-OK | dromaeo_css | took 1116000ms 16:04:07 INFO - TEST-START | kraken 16:04:07 INFO - operating with platform_type : mac_ 16:04:07 INFO - Initialising browser for kraken test... 16:04:07 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpHIgs1R/profile http://localhost:49224/getInfo.html) 16:04:10 INFO - PROCESS | 1648 | 2017-05-22 16:04:10.231 plugin-container[1650:10050] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8443, name = 'com.apple.tsm.portname' 16:04:10 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:04:10 INFO - PROCESS | 1648 | 2017-05-22 16:04:10.231 plugin-container[1650:10050] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x871f, name = 'com.apple.CFPasteboardClient' 16:04:10 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:04:10 INFO - PROCESS | 1648 | 2017-05-22 16:04:10.231 plugin-container[1650:10050] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:04:10 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 16:04:10 INFO - PROCESS | 1648 | colorDepth:24 16:04:10 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 16:04:10 INFO - PROCESS | 1648 | __metrics 16:04:10 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 16:04:11 INFO - TEST-INFO | 1648: exit 0 16:04:11 INFO - Browser initialized. 16:04:11 INFO - Running cycle 1/1 for kraken test... 16:04:11 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/T/tmpHIgs1R/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:04:13 INFO - PROCESS | 1651 | 2017-05-22 16:04:13.461 plugin-container[1652:10174] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x433f, name = 'com.apple.tsm.portname' 16:04:13 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:04:13 INFO - PROCESS | 1651 | 2017-05-22 16:04:13.462 plugin-container[1652:10174] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 16:04:13 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:04:13 INFO - PROCESS | 1651 | 2017-05-22 16:04:13.462 plugin-container[1652:10174] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:04:23 INFO - PROCESS | 1651 | RSS: Main: 174415872 16:04:23 INFO - PROCESS | 1651 | 16:04:26 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection) 16:04:27 INFO - PROCESS | 1651 | RSS: Main: 187953152 16:04:27 INFO - PROCESS | 1651 | 16:04:29 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-dft) 16:04:30 INFO - PROCESS | 1651 | RSS: Main: 195870720 16:04:30 INFO - PROCESS | 1651 | 16:04:33 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-fft) 16:04:34 INFO - PROCESS | 1651 | RSS: Main: 199471104 16:04:34 INFO - PROCESS | 1651 | 16:04:36 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator) 16:04:36 INFO - PROCESS | 1651 | RSS: Main: 200855552 16:04:36 INFO - PROCESS | 1651 | 16:04:38 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:04:39 INFO - PROCESS | 1651 | RSS: Main: 201674752 16:04:39 INFO - PROCESS | 1651 | 16:04:42 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom) 16:04:42 INFO - PROCESS | 1651 | RSS: Main: 205217792 16:04:42 INFO - PROCESS | 1651 | 16:04:45 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate) 16:04:46 INFO - PROCESS | 1651 | RSS: Main: 204955648 16:04:46 INFO - PROCESS | 1651 | 16:04:49 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial) 16:04:49 INFO - PROCESS | 1651 | RSS: Main: 204935168 16:04:49 INFO - PROCESS | 1651 | 16:04:51 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:04:52 INFO - PROCESS | 1651 | RSS: Main: 204333056 16:04:52 INFO - PROCESS | 1651 | 16:04:53 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:04:54 INFO - PROCESS | 1651 | RSS: Main: 204484608 16:04:54 INFO - PROCESS | 1651 | 16:04:56 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:04:57 INFO - PROCESS | 1651 | RSS: Main: 204431360 16:04:57 INFO - PROCESS | 1651 | 16:05:00 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:05:00 INFO - PROCESS | 1651 | RSS: Main: 204414976 16:05:00 INFO - PROCESS | 1651 | 16:05:03 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:05:04 INFO - PROCESS | 1651 | RSS: Main: 204886016 16:05:04 INFO - PROCESS | 1651 | 16:05:06 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49224/tests/kraken/driver.html?testName=ai-astar) 16:05:06 INFO - PROCESS | 1651 | RSS: Main: 207056896 16:05:06 INFO - PROCESS | 1651 | 16:05:06 INFO - PROCESS | 1651 | __start_tp_report 16:05:06 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 16:05:06 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 16:05:06 INFO - PROCESS | 1651 | |i|pagename|runs| 16:05:06 INFO - PROCESS | 1651 | |0;ai-astar;107;119;119;119;121;162;177;151;119;135 16:05:06 INFO - PROCESS | 1651 | |1;audio-beat-detection;138;110;112;145;110;108;111;109;130;112 16:05:06 INFO - PROCESS | 1651 | |2;audio-dft;200;196;197;204;194;196;198;190;198;197 16:05:06 INFO - PROCESS | 1651 | |3;audio-fft;84;56;60;85;68;85;83;84;71;60 16:05:06 INFO - PROCESS | 1651 | |4;audio-oscillator;82;70;83;70;72;68;85;99;71;80 16:05:06 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;89;91;93;115;89;88;89;87;80;77 16:05:06 INFO - PROCESS | 1651 | |6;imaging-darkroom;95;96;96;95;96;95;96;96;95;94 16:05:06 INFO - PROCESS | 1651 | |7;imaging-desaturate;89;92;94;91;97;117;91;90;90;90 16:05:06 INFO - PROCESS | 1651 | |8;json-parse-financial;65;66;67;66;70;67;68;69;71;68 16:05:06 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;58;64;58;62;64;58;58;59;59;59 16:05:06 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;82;74;94;100;99;98;77;82;81;84 16:05:06 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;135;134;135;134;134;138;138;138;225;137 16:05:06 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;189;203;221;215;171;164;165;163;159;161 16:05:06 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;67;63;63;63;65;65;62;63;62;65 16:05:06 INFO - PROCESS | 1651 | __end_tp_report 16:05:06 INFO - PROCESS | 1651 | __start_cc_report 16:05:06 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,977 16:05:06 INFO - PROCESS | 1651 | __end_cc_report 16:05:06 INFO - PROCESS | 1651 | __startTimestamp1495494306465__endTimestamp 16:05:06 INFO - PROCESS | 1651 | 16:05:06 INFO - PROCESS | 1651 | ------- Summary: start ------- 16:05:06 INFO - PROCESS | 1651 | Number of tests: 14 16:05:06 INFO - PROCESS | 1651 | 16:05:06 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:132.90 Median:128.00 stddev:22.87 (17.9%) stddev-sans-first:22.26 16:05:06 INFO - PROCESS | 1651 | Values: 107.0 119.0 119.0 119.0 121.0 162.0 177.0 151.0 119.0 135.0 16:05:06 INFO - PROCESS | 1651 | 16:05:06 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:118.50 Median:112.00 stddev:13.75 (12.3%) stddev-sans-first:12.64 16:05:06 INFO - PROCESS | 1651 | Values: 138.0 110.0 112.0 145.0 110.0 108.0 111.0 109.0 130.0 112.0 16:05:06 INFO - PROCESS | 1651 | 16:05:06 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:197.00 Median:197.50 stddev:3.65 (1.8%) stddev-sans-first:3.71 16:05:06 INFO - PROCESS | 1651 | Values: 200.0 196.0 197.0 204.0 194.0 196.0 198.0 190.0 198.0 197.0 16:05:06 INFO - PROCESS | 1651 | 16:05:06 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:73.60 Median:83.50 stddev:11.94 (14.3%) stddev-sans-first:12.05 16:05:06 INFO - PROCESS | 1651 | Values: 84.0 56.0 60.0 85.0 68.0 85.0 83.0 84.0 71.0 60.0 16:05:06 INFO - PROCESS | 1651 | 16:05:06 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:78.00 Median:81.00 stddev:9.71 (12.0%) stddev-sans-first:10.19 16:05:06 INFO - PROCESS | 1651 | Values: 82.0 70.0 83.0 70.0 72.0 68.0 85.0 99.0 71.0 80.0 16:05:06 INFO - PROCESS | 1651 | 16:05:06 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:89.80 Median:89.00 stddev:10.11 (11.4%) stddev-sans-first:10.72 16:05:06 INFO - PROCESS | 1651 | Values: 89.0 91.0 93.0 115.0 89.0 88.0 89.0 87.0 80.0 77.0 16:05:06 INFO - PROCESS | 1651 | 16:05:06 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:95.40 Median:96.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 16:05:06 INFO - PROCESS | 1651 | Values: 95.0 96.0 96.0 95.0 96.0 95.0 96.0 96.0 95.0 94.0 16:05:06 INFO - PROCESS | 1651 | 16:05:06 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:94.10 Median:91.00 stddev:8.39 (9.2%) stddev-sans-first:8.69 16:05:06 INFO - PROCESS | 1651 | Values: 89.0 92.0 94.0 91.0 97.0 117.0 91.0 90.0 90.0 90.0 16:05:06 INFO - PROCESS | 1651 | 16:05:06 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:67.70 Median:68.00 stddev:1.89 (2.8%) stddev-sans-first:1.73 16:05:06 INFO - PROCESS | 1651 | Values: 65.0 66.0 67.0 66.0 70.0 67.0 68.0 69.0 71.0 68.0 16:05:06 INFO - PROCESS | 1651 | 16:05:06 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:59.90 Median:59.00 stddev:2.47 (4.2%) stddev-sans-first:2.52 16:05:06 INFO - PROCESS | 1651 | Values: 58.0 64.0 58.0 62.0 64.0 58.0 58.0 59.0 59.0 59.0 16:05:06 INFO - PROCESS | 1651 | 16:05:06 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:83.00 stddev:9.70 (11.7%) stddev-sans-first:10.11 16:05:06 INFO - PROCESS | 1651 | Values: 82.0 74.0 94.0 100.0 99.0 98.0 77.0 82.0 81.0 84.0 16:05:06 INFO - PROCESS | 1651 | 16:05:06 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:144.80 Median:137.50 stddev:28.23 (20.5%) stddev-sans-first:29.72 16:05:06 INFO - PROCESS | 1651 | Values: 135.0 134.0 135.0 134.0 134.0 138.0 138.0 138.0 225.0 137.0 16:05:06 INFO - PROCESS | 1651 | 16:05:06 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.10 Median:180.00 stddev:23.94 (13.3%) stddev-sans-first:25.22 16:05:06 INFO - PROCESS | 1651 | Values: 189.0 203.0 221.0 215.0 171.0 164.0 165.0 163.0 159.0 161.0 16:05:06 INFO - PROCESS | 1651 | 16:05:06 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.80 Median:64.00 stddev:1.62 (2.5%) stddev-sans-first:1.24 16:05:06 INFO - PROCESS | 1651 | Values: 67.0 63.0 63.0 63.0 65.0 65.0 62.0 63.0 62.0 65.0 16:05:06 INFO - PROCESS | 1651 | -------- Summary: end -------- 16:05:06 INFO - PROCESS | 1651 | 16:05:07 INFO - TEST-INFO | 1651: exit 0 16:05:07 INFO - TEST-OK | kraken | took 59655ms 16:05:07 INFO - SUITE-END | took 1175s 16:05:07 INFO - Completed test suite (00:19:36) 16:05:07 INFO - javascript benchmark 16:05:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7899.9319780834, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3597.172650290435, 3623.4927110204576, 3646.179609587843, 3650.3611867923105, 3662.706983441324, 6099.481544068754, 6109.602875813069, 6135.539834512413, 6192.071189321604, 6194.69026548673, 4981.701097934127, 6147.999999999989, 6153.35389784073, 6170.938290617092, 6185.783497577586, 5520.420355862647, 5546.306711661043, 5581.134924086773, 5594.692291923942, 5662.37713851476, 5618.971905140468, 5640.5487560995225, 5646.576506761989, 5660.462256085665, 5684.8294551163535, 5427.1316589345515, 5463.098588732874, 5611.3546942101775, 5619.7471113799775, 5626.606137570372, 5580.944190558083, 5599.776008959637, 5610.102383618621, 5612.102063669793, 5637.3235211774845, 5491.395946445888, 5572.331320241557, 5594.24477695514, 5594.776208951637, 5599.720013999284, 5882.382349853289, 6099.9935010723175, 6131.264248290234, 6247.656378899159, 6252.531060170505, 5400.378956420022, 6347.87304253917, 6363.809085727389, 6373.42639162473, 6408.10286559882, 5420.891582168374, 6598.7030583623855, 6640.169978752656, 6648.069270302161, 6650.501212409089, 6281.309056003836, 6287.842803929939, 6301.590396624205, 6310.495160387158, 6323.620582765003, 6472.773452929125, 6477.740890364333, 6524.6737663116655, 6721.596704197764, 6730.528862979544, 6396.200474940632, 6411.0, 6425.261094974045, 6434.067060276234, 6458.806235812933, 6495.350464953562, 6621.006848972693, 6631.767888123893, 6647.335266473411, 6701.195856497252, 5452.482014208644, 6293.30773614902, 6320.115183874262, 6513.609183449008, 6548.410643042053, 6301.338359472281, 6417.903731444032, 6428.774992875227, 6445.581037232565, 6454.0, 5251.6061295403, 5283.735813209324, 5316.0962636351915, 5321.5476684482255, 5324.121519949165, 4715.7642117893965, 4724.173269677793, 4740.691855029412, 4756.191447453941, 4761.40482439695, 5170.586353091744, 5744.0, 6125.724342404603, 6242.75028998844, 6346.9682651586445, 6565.901511477332, 6631.502637302222, 6643.800685979429, 6675.0988616537115, 6685.465162787063, 5925.34821169671, 5942.078977758368, 6261.96869015652, 6272.968635156886, 6334.619922804646, 5688.658680479101, 5713.171590119409, 5909.911351329733, 5919.55603329752, 5920.171176035358, 6788.0, 6819.69311380999, 6824.692888820115, 6844.931550684629, 6911.239763626098, 6586.1438013058, 6599.670016499252, 6626.83432914181, 6629.834254143685, 6656.467482601283, 5434.809781657623, 5661.46216109469, 6581.868362632625, 6922.996165556067, 6942.757003505055, 6394.936050639434, 6395.6802159890885, 6424.164858568543, 6456.515761318014, 6464.8707025858275, 6654.633995130314, 6687.331266873273, 6690.665466726547, 6692.7992160233325, 6707.429868461236, 6253.874922501615, 6412.647304398116, 6495.545311828127, 6496.610203387811, 6498.057781621733, 6281.12064311006, 6336.239651241696, 6361.90457143134, 6372.458341041063, 6425.839354016187, 6086.6348019119, 6211.378862113933, 6367.395097465734, 6391.0, 6399.600149943771, 6258.906116408167, 6262.405071518198, 6473.935260647333, 6483.157189565326, 6491.091247225487, 6254.28075771274, 6306.0, 6314.1160237565855, 6342.587731797418, 6357.0], "value": 6027.952493823966, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [759.525296689569, 812.8089898873764, 836.4395854777299, 966.2318456826822, 969.1326262994615, 40855.79572102139, 41721.58278417215, 43256.56743432565, 43303.917402064966, 43325.48360807372, 41219.58780412195, 41301.58698413015, 41444.963875903115, 41570.0, 41608.37587436191, 8190.959045204766, 8309.709160179395, 10983.450827458595, 11241.60654377097, 11444.484998175101, 11349.773004539946, 11392.71518212043, 11413.429328533583, 11421.314721116718, 11431.542738290458, 11362.659120226406, 11404.087672986183, 11406.51715277017, 11410.20128590999, 11435.313881167156, 9561.0, 11322.320660760339, 11360.977512023916, 11368.54525818962, 11368.545258189662, 9160.8625870612, 11159.051480624157, 11167.385793781339, 11167.83248251277, 11172.720681982933, 36342.63657363419, 36389.0, 36445.08887277813, 36513.81743091294, 36545.0, 3904.1996390739832, 3933.3509970854775, 3943.3493473576827, 3961.108750531136, 4003.6596889264447, 21111.78888211129, 24303.392415189584, 26555.0, 28408.715912840813, 28527.71472285292, 3188.6651901550467, 3244.9675503244903, 3249.5613092232484, 3254.446744053517, 3256.9837150814096, 344760.2761986199, 348738.0252394938, 355157.0, 355906.0, 512778.9999999963, 42776.78611606922, 44678.10643787171, 44937.87655308643, 45020.3246951296, 45087.549124508994, 46524.767376162905, 46560.767196163804, 46576.76711616488, 46611.76694116508, 46678.766606166755, 14158.221297828512, 14271.429142834379, 14291.57125286243, 14292.285385730673, 14294.85705142956, 12542.498300068077, 12731.55439559611, 12735.363231838372, 12745.808812867814, 12749.49002039908, 11970.0, 11980.400979950968, 12085.879141208476, 12115.515379384902, 12192.451339689735, 5802.82591522255, 5832.88334233313, 5848.766049357978, 5859.589828711949, 5882.529397648178, 1095.9616413425492, 1151.740858306891, 1170.5434880396651, 1172.976540469186, 1185.2118341302937, 23711.762882370957, 26956.0, 27258.0, 27351.589726154125, 27511.724882750917, 1088.7060493666665, 1251.9937400313122, 1259.8362212912446, 1313.1398933698442, 1318.156379916854, 14347.0, 15222.086674799548, 15369.846301537065, 15406.075635461684, 15568.922155389377, 336508.0, 337998.0, 338275.0, 339038.0, 341354.0, 55200.89598208094, 55509.44490555043, 55527.44472555303, 55595.0, 55954.72022639861, 1376.9793453098212, 1445.1545845680396, 1498.6103613060466, 1532.9156896370584, 1540.0, 27370.726292737614, 28856.134315970554, 29328.826846925884, 29377.265568360963, 29423.411531768816, 47162.29256561223, 48125.27812082751, 48170.0, 51281.48718512767, 51313.48686513087, 484678.0, 486315.5684221698, 486642.0, 486706.0, 487291.690624648, 27803.304917377227, 28339.291517712223, 32788.83605581957, 33954.15114622154, 34219.4867076999, 40159.397609036474, 40451.25059741887, 40947.0, 41231.58768412397, 41659.7917010413, 21296.893515532323, 24493.142740004016, 24861.62707559352, 27470.313242169104, 30507.847460762554, 28909.710902891544, 29003.274918127216, 29107.417851643273, 29787.851060744557, 30037.849810750806, 32817.83591082029, 33931.15172120717, 34336.96989090331, 34668.653313466544, 34669.30661386809, 28626.856865715537, 29140.854295728386, 29238.853805730836, 29246.0, 29999.4000119992, 19329.13018914124, 23535.940882660663, 23880.597014925428, 23992.160274390313, 24070.0], "value": 18783.688308693436, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [425.3003808734634, 436.36944615031274, 440.4450392505444, 440.6012558634435, 450.61922675339326, 18016.729749053742, 20280.88455134969, 21009.369718908438, 22377.888110559466, 22490.775092249074, 21875.781242187575, 22282.108715651353, 22570.43573910653, 22600.886995565, 22853.77146228537, 6682.866342673144, 6801.591904485735, 6991.370776630102, 7026.156861176641, 7045.18980317265, 6682.131322928012, 6884.483663725216, 6890.414314783249, 6891.965540172292, 6904.240533541307, 5895.35151133375, 6570.178727659043, 6815.761448349309, 6886.51794374394, 6909.48178886583, 6575.835604109888, 6833.04337392763, 6887.173539175331, 6892.586444813327, 6894.655267236618, 6639.402453779135, 6702.396784289437, 6712.160979877515, 6720.361565651304, 6754.763583274611, 26711.599326010124, 26922.0, 27157.728422715718, 27339.906403743826, 27507.58738618922, 3108.751299896003, 3111.8132912025353, 3124.609423822022, 3129.233337832223, 3163.1459505933267, 16213.675726485404, 16803.24402318576, 16888.57778555546, 17034.0, 17098.829011709848, 2130.286354071388, 2152.838537109723, 2153.6123497770395, 2155.3210738617286, 2212.9225477108384, 114043.2893506598, 131934.0, 137759.31120344435, 138851.6114838849, 144181.2790936049, 16863.662726745642, 18135.45593632193, 19920.800791992184, 21085.78914210869, 21293.574128517343, 24231.636525452133, 30851.457427128553, 31412.900548480695, 31437.842810785798, 31509.0, 10190.898091019144, 10320.12278956297, 10505.264631475877, 10512.947435262775, 10645.893541064645, 8708.956455217683, 8831.317118305456, 8929.33030022751, 8932.508712020772, 9135.817283654422, 8935.821283574292, 9046.592903319366, 9052.728418147466, 9211.0, 9272.629094836266, 3739.420389839579, 3835.290471262825, 4106.507219133723, 4205.474315710536, 4221.915561688749, 868.5396739727954, 1057.7620035492107, 1062.3094988257694, 1086.2504871638544, 1114.1755101225035, 19393.224271029, 20918.79081209199, 21288.36134915955, 21519.892400537898, 21784.78215217859, 977.8093271811929, 1085.2403317677656, 1087.3312912558877, 1115.5887801930564, 1129.8474705914762, 9797.755056123653, 11107.611233606785, 11149.498272577754, 11155.553777848796, 11189.776204475866, 653900.0, 707569.0, 735006.0, 752583.0, 771030.0, 117484.0, 131372.0, 158956.82086358208, 166092.0, 168348.15825921038, 1262.1921969939247, 1273.4715093236389, 1291.044626976031, 1299.8505171905351, 1300.921944683341, 14723.33744981457, 17540.91229543844, 18938.431847044612, 19006.0, 19007.144678489225, 52680.47319526909, 59587.0, 62335.0, 62382.688086559276, 62601.373986259554, 86931.56534217288, 88479.0, 88861.0, 90596.54701726449, 90938.0, 9633.807323853624, 11492.425378731197, 11914.225575337921, 12012.339321337411, 12049.156559040783, 13039.348032598522, 13110.541131060367, 13204.60386188416, 13379.732405351644, 13449.260290683715, 10614.150867930393, 11034.944825276143, 11285.774284514428, 11455.942720286344, 11719.0, 10622.575096996034, 10641.840372394265, 10647.574097036031, 11039.282446640942, 11076.501557430096, 11525.0, 11583.0, 11745.119116066158, 11790.115741319263, 11965.581204658143, 11607.941960290145, 11713.765724685629, 11775.941120294343, 11892.227005244631, 11944.76110477803, 10915.962983516552, 11016.504257308601, 11195.04842088399, 11487.48306326234, 11536.365499897585], "value": 11630.37481027919, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1595.4575444348918, 1644.8108467526238, 1652.9504114876559, 1656.4699296225208, 1674.6566953774477, 9730.318877678557, 10964.013238808506, 11403.828942565868, 11633.534658613657, 11806.409679516033, 11386.60146894859, 11566.07471402288, 11570.47932843022, 11573.768524629524, 11654.359010254433, 2483.2798488438375, 2536.5687833068423, 2539.771420572148, 2540.657011303478, 2545.847249165047, 2089.477630592352, 2454.349597356702, 2521.8739063046864, 2529.3423709835483, 2533.733957934418, 2471.7775400213977, 2502.4119331956977, 2510.7740303372693, 2520.4329025969196, 2523.6971563412326, 2088.6449303618347, 2468.777809997091, 2492.3395300245447, 2502.8248022638422, 2511.9246422607353, 2316.2472196536023, 2341.332720174759, 2473.5300292944285, 2497.5379554782426, 2499.5875680512877, 10583.206259530489, 10783.676489705322, 10811.459427028696, 10820.0, 10838.40388778617, 5919.260092488439, 6084.30030546484, 6088.634681919055, 6091.390860913917, 6096.939030609681, 3000.9249768755735, 3521.5950165731015, 3523.6300188480095, 3577.5706915170085, 3775.0562359410146, 1231.1874163052432, 1242.6644805902356, 1265.1586694847915, 1284.081881454755, 1308.3196737696344, 13765.38055787492, 14108.717825643533, 14885.255737213096, 15725.292361843745, 15847.841521584867, 4814.7833347499445, 4898.412190537087, 4936.308916751657, 4945.67853089548, 4974.875628109254, 12199.329036902878, 12200.0, 12210.877891220975, 12216.938915305367, 12231.93884030592, 5813.82558523245, 6043.123747056652, 6048.364921683248, 6066.3023752269055, 6079.300880398722, 4292.635126014261, 5525.668459892419, 5535.557155427556, 5535.667859928417, 5573.386927437979, 3978.0850404407147, 4108.506979162519, 4150.605692459211, 4155.501339839238, 4180.602842729936, 1086.9728256793485, 1088.1294964028746, 1089.2810744908322, 1097.6542389147394, 1100.350793032115, 787.2914377060576, 956.8469044952774, 965.7295957132014, 973.454865275448, 989.9010098990187, 5898.64608123514, 6769.898451523231, 6835.487338449636, 6865.313468653095, 7284.781456556312, 1034.3173505486343, 1068.5992752717732, 1076.6326765008396, 1083.1389045708681, 1099.6975831646362, 4163.8959026024595, 4316.589923957219, 4333.739975601411, 4371.453568303962, 4449.599536041707, 11702.590409335802, 13592.184468931895, 14151.646208844655, 14173.220472873885, 14185.077969931921, 14084.788728169086, 14110.85889141116, 14110.929445352913, 14119.223442710749, 14138.858611413754, 9935.701928942144, 10402.167826573856, 10593.523291451904, 10604.416757078434, 10611.31026483276, 6138.846528836815, 6273.121762953281, 6877.3810357068105, 7153.713851445884, 7154.320339567733, 12136.39318034077, 12536.623901282976, 12542.749145017231, 12692.619221423372, 12697.174683645533, 9574.0, 12016.218945768496, 12243.938780306042, 12414.255144691348, 12915.935420322838, 9324.393914395543, 9595.47224902637, 9616.95191524038, 9621.90378096238, 9643.662471813734, 10445.791084178425, 10512.474376281003, 10615.0446459819, 10644.787104257717, 10659.520321585702, 9088.500132492776, 9193.0, 9249.12133347331, 9333.0, 9400.482973436254, 8843.071477495028, 8854.29165666758, 8896.154865287788, 8908.510031948304, 8975.730728078168, 8144.348452123697, 8235.464694794819, 8289.709860154866, 8298.875516867372, 8303.667853286042, 4953.356063711694, 5960.0, 5974.850628734316, 6083.482903953037, 6185.876282474235, 6001.549883758823, 6071.0, 6071.392860713893, 6193.721282542387, 6289.459082524628], "value": 5274.076649478576, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.1060393048368, 348.82619983754665, 384.6096212344471, 396.79366729300784, 403.26001786721366, 4591.88520286993, 4604.451635778887, 4751.168545504536, 4818.578519336796, 5478.561715062795, 4628.930566041512, 4638.4665763437215, 5402.784373515957, 5425.647332923357, 5433.891322173554, 4257.254980378445, 4487.621560572901, 4497.977510112445, 4558.361829343895, 4583.381243532131, 4289.335153051282, 4316.330968699856, 4543.931841022388, 4583.899864032642, 4610.654200934927, 4301.763403012833, 4573.862784116482, 4578.885527861814, 4593.265077587586, 4657.953420465786, 3690.42798366255, 3903.6291552302487, 4309.758925358828, 4525.298578720271, 4555.157295900269, 3583.7491375603718, 3693.741438099334, 3826.234753049401, 4156.750594964312, 4173.499180098408, 5350.438203988564, 5377.300950876361, 5398.4871437213405, 5409.0, 5427.375851777057, 4573.3764513597525, 4713.6464765142755, 4772.141014617368, 4932.876678083041, 5250.264962905197, 4817.35125603794, 5162.92255616166, 5686.3744988051285, 5776.8844623107725, 5799.594028418012, 4174.478190226222, 4183.581641835823, 4700.9059818803435, 4763.428068737343, 4771.475137734847, 5745.741441635095, 5936.28764548257, 6012.230072788844, 6126.479249263818, 6206.331277550651, 4446.73319600825, 4742.747195082377, 4774.30772537987, 4778.354922085474, 4868.756562171877, 4788.084766093591, 4804.552942938431, 4834.250691142878, 4842.225243960949, 4939.209726443752, 4237.978810105991, 5112.954818072801, 5191.6884986900905, 5254.97372513135, 5278.683279003272, 4615.630749540029, 4886.633502487328, 5605.430479465756, 5773.268019594105, 5829.941700583025, 1479.5413421839264, 1519.270750039987, 1555.2068445092857, 1597.7763113164167, 1649.1177220187142, 1129.1418521923397, 1165.685264978451, 1175.3183153770792, 1196.3539688568076, 1254.3943751997276, 4720.811167553259, 5339.199120131933, 5428.0, 5534.0, 5571.275734154615, 5551.500364967092, 6004.819855404345, 6013.9699301503215, 6108.198081565949, 6121.877562448726, 4221.957780422218, 5112.667676601009, 5270.789168433296, 5276.472352764769, 5419.701916394636, 2621.5412302846926, 2629.290960875446, 3001.409253095863, 3156.6527681954967, 3201.9273543363233, 5299.4700529946695, 5652.7738890443925, 5831.591788574759, 6415.3961509622595, 6426.389492998158, 5617.775288988477, 5710.314762228559, 6218.5647004710145, 6241.469475094577, 6281.905771413432, 5238.0, 6396.2964073951825, 6427.457410221607, 6436.613803171825, 6469.450096741735, 5014.899702006012, 5510.421405752339, 5557.5831812613405, 5963.541146135144, 6038.939610604013, 5325.520703136736, 5877.941970445361, 5889.941100589111, 5996.340402555628, 6030.7286172121485, 5633.267675202197, 5978.461938425388, 6209.379062093754, 6245.656488893154, 6295.433410993032, 4134.462519872518, 4162.5421203668175, 4459.643228541644, 4821.325014497903, 4857.611391088633, 5499.775213547066, 5615.0200036960505, 5872.324682661549, 5982.910256346071, 5994.831007953581, 5144.459831717616, 5897.351291357862, 5927.666275088243, 5933.258342707161, 5954.374790647093, 5270.973645131749, 5308.761105750174, 5308.946910531, 5943.970280148745, 5974.880502390015, 5301.416844147064, 5670.347909990239, 5727.852055479195, 5779.364269930388, 5841.678707670949, 5151.356080489939, 5310.548603368757, 5327.709085093292, 5600.299962504687, 5873.267386121019, 4719.858404247878, 5048.974755126201, 5135.794568217379, 5723.7996670116345, 5751.396103409249], "value": 4415.2606489535265, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [774.0015380159595, 801.0787594266593, 878.5651102704162, 885.6944354197801, 897.443584977314, 16535.09057001866, 16739.916300418496, 16887.915560422196, 17211.0, 17518.0, 15856.603584910383, 16011.839881601181, 16163.0, 16377.344906203738, 16406.507804765846, 990.0841721407705, 1009.5658866687321, 1015.2081376526285, 1016.6899095775785, 1017.8778546574422, 973.459729849933, 1015.3400289811644, 1022.8056669232822, 1024.3853687787343, 1026.7073883943078, 1047.659510659035, 1058.2116323339123, 1060.6658902445743, 1061.4533515239657, 1063.9361638301689, 970.9951450242739, 1009.7425156585098, 1050.1703654113242, 1052.2791887557048, 1055.5144633468615, 784.8037990502374, 785.1638005524155, 785.3128512551517, 786.8426314737018, 787.5943888897165, 17040.233189506376, 17162.22769975354, 17299.56751081233, 17719.202635881415, 17966.371177008743, 13694.863051369459, 13701.0, 13948.790768138486, 13952.651183720489, 14021.859781402158, 12142.392880355947, 12202.511899524008, 12207.0, 12221.205621634654, 12252.085540748232, 3830.578636349999, 3859.1509867829313, 3887.0, 3917.157811070605, 4065.044714492122, 101290.48064279041, 109419.0, 111353.44323278412, 114294.0, 114482.0, 11544.595939142258, 11670.183087183816, 11687.649370518899, 11720.120990925714, 11727.296362218123, 24047.158349457684, 24265.878670606533, 24318.87840560786, 24337.878310608332, 24353.878230608734, 8039.437239393304, 8132.471389359673, 8163.959180204061, 8176.427650064438, 8339.374546908884, 4528.366028755977, 4541.091781643619, 4631.768411579407, 4635.119327327797, 4730.88172795676, 3625.3655610268097, 3708.9072773180887, 3731.0858839584475, 3731.962680373216, 3736.626337366296, 656.0847617573459, 663.9568428052161, 679.106974328766, 693.6913073682163, 696.6168607266085, 620.6152185644929, 743.862385458681, 751.4627041665235, 766.9424793140537, 770.1066762555521, 10041.146502547364, 10612.31019983714, 10636.883127271525, 10964.245720684812, 10989.450527473595, 640.6188317950812, 700.6916956539106, 732.6300218389679, 733.8348871503869, 733.9486235963539, 4622.445306563234, 5205.713685747244, 5212.139996900545, 5215.791368345223, 5250.632455728139, 82589.0, 91617.0, 107271.0, 110734.0, 111856.0, 63407.0, 65718.34281657219, 72571.63714181395, 86434.0, 91506.0, 60817.47956301128, 63940.68029659822, 70909.0, 71362.64318678374, 82481.17518824856, 10941.070009049, 11124.0, 11453.0, 11472.082233421139, 11483.770324593628, 42103.0, 50979.49020509747, 51939.480605193465, 51973.7401312991, 52804.73597631987, 66556.3410561256, 66714.66642666755, 67096.99354509589, 67127.66436167787, 67285.66357168183, 11032.889671103187, 11213.495392707184, 11526.847315268742, 12047.337396442928, 13338.3330833456, 12925.612231633066, 13248.735025299633, 15517.37930482768, 15873.365065397255, 15925.920370398075, 14189.716205675622, 14205.147691138565, 14644.04813687107, 14770.718259636466, 14784.186869722269, 13242.735145297233, 13649.38577763983, 13764.518241861486, 13827.79258311106, 14127.717445651235, 13786.379612917643, 13811.654708632364, 13883.652908677364, 14073.577792666236, 14269.500567480089, 10143.442110683984, 10418.218633602359, 10504.789904202025, 10739.677809665722, 10835.566577336818, 10447.94776026115, 10861.696581514921, 11838.289702617525, 11989.520419183134, 12237.449314780679], "value": 7926.012790916238, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1483.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 119.0, 119.0, 119.0, 121.0, 162.0, 177.0, 151.0, 119.0, 135.0], "value": 132.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 110.0, 112.0, 145.0, 110.0, 108.0, 111.0, 109.0, 130.0, 112.0], "value": 118.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 196.0, 197.0, 204.0, 194.0, 196.0, 198.0, 190.0, 198.0, 197.0], "value": 197.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 56.0, 60.0, 85.0, 68.0, 85.0, 83.0, 84.0, 71.0, 60.0], "value": 73.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 70.0, 83.0, 70.0, 72.0, 68.0, 85.0, 99.0, 71.0, 80.0], "value": 78.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 93.0, 115.0, 89.0, 88.0, 89.0, 87.0, 80.0, 77.0], "value": 89.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 95.0, 96.0, 95.0, 96.0, 96.0, 95.0, 94.0], "value": 95.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 92.0, 94.0, 91.0, 97.0, 117.0, 91.0, 90.0, 90.0, 90.0], "value": 94.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 66.0, 70.0, 67.0, 68.0, 69.0, 71.0, 68.0], "value": 67.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 64.0, 58.0, 62.0, 64.0, 58.0, 58.0, 59.0, 59.0, 59.0], "value": 59.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 74.0, 94.0, 100.0, 99.0, 98.0, 77.0, 82.0, 81.0, 84.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 134.0, 135.0, 134.0, 134.0, 138.0, 138.0, 138.0, 225.0, 137.0], "value": 144.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 203.0, 221.0, 215.0, 171.0, 164.0, 165.0, 163.0, 159.0, 161.0], "value": 181.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 63.0, 63.0, 63.0, 65.0, 65.0, 62.0, 63.0, 62.0, 65.0], "value": 63.8, "unit": "score"}]}]} 16:05:07 INFO - Return code: 0 16:05:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:05:07 ERROR - # TBPL SUCCESS # 16:05:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:05:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:05:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:05:07 INFO - 2017-05-22 16:05:07.826 screenresolution[1656:10558] starting screenresolution argv=screenresolution get 16:05:07 INFO - 2017-05-22 16:05:07.829 screenresolution[1656:10558] Display 0: 1600x1200x32@60 16:05:07 INFO - 2017-05-22 16:05:07.835 screenresolution[1657:10561] starting screenresolution argv=screenresolution list 16:05:07 INFO - Available Modes on Display 0 16:05:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:05:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:05:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:05:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:05:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:05:08 INFO - Intel Iris: 16:05:08 INFO - Chipset Model: Intel Iris 16:05:08 INFO - Type: GPU 16:05:08 INFO - Bus: Built-In 16:05:08 INFO - VRAM (Dynamic, Max): 1536 MB 16:05:08 INFO - Vendor: Intel (0x8086) 16:05:08 INFO - Device ID: 0x0a2e 16:05:08 INFO - Revision ID: 0x0009 16:05:08 INFO - Displays: 16:05:08 INFO - Display: 16:05:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:05:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:05:08 INFO - Display Serial Number: 1600x1200 60 16:05:08 INFO - Main Display: Yes 16:05:08 INFO - Mirror: Off 16:05:08 INFO - Online: Yes 16:05:08 INFO - Rotation: Supported 16:05:08 INFO - Return code: 0 16:05:08 INFO - Running post-action listener: _resource_record_post_action 16:05:08 INFO - [mozharness: 2017-05-22 23:05:08.072489Z] Finished run-tests step (success) 16:05:08 INFO - Running post-run listener: _resource_record_post_run 16:05:08 INFO - Total resource usage - Wall time: 1191s; CPU: 55.0%; Read bytes: 40396800; Write bytes: 426070016; Read time: 260; Write time: 1433 16:05:08 INFO - TinderboxPrint: CPU usage
54.6% 16:05:08 INFO - TinderboxPrint: I/O read bytes / time
40,396,800 / 260 16:05:08 INFO - TinderboxPrint: I/O write bytes / time
426,070,016 / 1,433 16:05:08 INFO - TinderboxPrint: CPU idle
2,165.6 (45.4%) 16:05:08 INFO - TinderboxPrint: CPU system
956.0 (20.1%) 16:05:08 INFO - TinderboxPrint: CPU user
1,644.1 (34.5%) 16:05:08 INFO - TinderboxPrint: Swap in / out
162,299,904 / 0 16:05:08 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 123259392; Write bytes: 114192384; Read time: 11148; Write time: 243 16:05:08 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 36308992; Write bytes: 301654016; Read time: 234; Write time: 1154 16:05:08 INFO - Running post-run listener: _upload_blobber_files 16:05:08 INFO - Blob upload gear active. 16:05:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:05:08 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 16:05:08 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'] 16:05:08 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 16:05:08 INFO - (blobuploader) - INFO - Open directory for files ... 16:05:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:05:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:05:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:05:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:05:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:05:09 INFO - (blobuploader) - INFO - Done attempting. 16:05:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:05:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:05:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:05:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:05:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:05:10 INFO - (blobuploader) - INFO - Done attempting. 16:05:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:05:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:05:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:05:11 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:05:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:05:11 INFO - (blobuploader) - INFO - Done attempting. 16:05:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:05:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:05:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:05:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:05:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:05:13 INFO - (blobuploader) - INFO - Done attempting. 16:05:13 INFO - (blobuploader) - INFO - Iteration through files over. 16:05:13 INFO - Return code: 0 16:05:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:05:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:05:13 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c5f888a6308bcffdd7b3518e3a1e774c0ffff3a2ea9dea82971721adb802d3ed835a7c8e3392bbfe27222378e89d16a57af9f47e0a0f8f4b90fa701123d38cea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bccf2192794c74c388dc00c02019851f6f3aeb25b05b510136bd3dc00e01b1c6f3272456f85a78a2eeb31744566ee5898a6050370b63ce31dd38954fd682eaff", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0170b5cb82bcd5e228ebb079fa91cb180d3e1b93ec3b648bb8c477f6a58ca1e52e488cf6ed583ba4026a354d38f58bf7aea66604b5d05cb94a5dd8faca37d5de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3559c0fa3186d92427e621dfd7591cafdfc1f5535654b4593ff31167636a4c15cc505ba347b3a3aae623d12da623c3358f19967478fc6792a8eb25879c4dec3d"} 16:05:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:05:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:05:13 INFO - Contents: 16:05:13 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c5f888a6308bcffdd7b3518e3a1e774c0ffff3a2ea9dea82971721adb802d3ed835a7c8e3392bbfe27222378e89d16a57af9f47e0a0f8f4b90fa701123d38cea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bccf2192794c74c388dc00c02019851f6f3aeb25b05b510136bd3dc00e01b1c6f3272456f85a78a2eeb31744566ee5898a6050370b63ce31dd38954fd682eaff", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0170b5cb82bcd5e228ebb079fa91cb180d3e1b93ec3b648bb8c477f6a58ca1e52e488cf6ed583ba4026a354d38f58bf7aea66604b5d05cb94a5dd8faca37d5de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3559c0fa3186d92427e621dfd7591cafdfc1f5535654b4593ff31167636a4c15cc505ba347b3a3aae623d12da623c3358f19967478fc6792a8eb25879c4dec3d"} 16:05:13 INFO - Running post-run listener: copy_logs_to_upload_dir 16:05:13 INFO - Copying logs to upload dir... 16:05:13 INFO - mkdir: /builds/slave/test/build/upload/logs 16:05:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.094127 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-05-22 16:05:13.637992) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-05-22 16:05:13.641363) ========= 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.zJb0FW9yRS/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.O5Clm7USAK/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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/c5f888a6308bcffdd7b3518e3a1e774c0ffff3a2ea9dea82971721adb802d3ed835a7c8e3392bbfe27222378e89d16a57af9f47e0a0f8f4b90fa701123d38cea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bccf2192794c74c388dc00c02019851f6f3aeb25b05b510136bd3dc00e01b1c6f3272456f85a78a2eeb31744566ee5898a6050370b63ce31dd38954fd682eaff", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0170b5cb82bcd5e228ebb079fa91cb180d3e1b93ec3b648bb8c477f6a58ca1e52e488cf6ed583ba4026a354d38f58bf7aea66604b5d05cb94a5dd8faca37d5de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3559c0fa3186d92427e621dfd7591cafdfc1f5535654b4593ff31167636a4c15cc505ba347b3a3aae623d12da623c3358f19967478fc6792a8eb25879c4dec3d"} build_url:https://queue.taskcluster.net/v1/task/DalBoHteQ1uFSBGVNVPngg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011013 build_url: 'https://queue.taskcluster.net/v1/task/DalBoHteQ1uFSBGVNVPngg/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/c5f888a6308bcffdd7b3518e3a1e774c0ffff3a2ea9dea82971721adb802d3ed835a7c8e3392bbfe27222378e89d16a57af9f47e0a0f8f4b90fa701123d38cea", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bccf2192794c74c388dc00c02019851f6f3aeb25b05b510136bd3dc00e01b1c6f3272456f85a78a2eeb31744566ee5898a6050370b63ce31dd38954fd682eaff", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0170b5cb82bcd5e228ebb079fa91cb180d3e1b93ec3b648bb8c477f6a58ca1e52e488cf6ed583ba4026a354d38f58bf7aea66604b5d05cb94a5dd8faca37d5de", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3559c0fa3186d92427e621dfd7591cafdfc1f5535654b4593ff31167636a4c15cc505ba347b3a3aae623d12da623c3358f19967478fc6792a8eb25879c4dec3d"}' ========= master_lag: 1.45 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2017-05-22 16:05:15.104705) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 16:05:15.105055) ========= 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.zJb0FW9yRS/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.O5Clm7USAK/Listeners TMPDIR=/var/folders/w5/_34gntw956zgpct1x2hz28jh00000w/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.005270 ========= master_lag: 0.07 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 16:05:15.178067) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 16:05:15.178391) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 16:05:15.178755) ========= ========= Total master_lag: 1.66 =========