builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0252 starttime: 1495554215.59 results: success (0) buildid: 20170523074624 builduid: 7048be335ba440a8b20ef3333c0f1328 revision: 279acccff1c12f047565b465286fcbcc180c5ab0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:35.589587) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:35.590131) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:35.590445) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:35.616335) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:35.616730) ========= 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.HLOyCfiZXC/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.fvL13g7au9/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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-23 08:43:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.3M=0.001s 2017-05-23 08:43:35 (18.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.150865 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:35.790110) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:35.790447) ========= 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.HLOyCfiZXC/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.fvL13g7au9/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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.027048 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:35.838968) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:35.839366) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 279acccff1c12f047565b465286fcbcc180c5ab0 --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 279acccff1c12f047565b465286fcbcc180c5ab0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.HLOyCfiZXC/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.fvL13g7au9/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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-23 08:43:35,933 truncating revision to first 12 chars 2017-05-23 08:43:35,934 Setting DEBUG logging. 2017-05-23 08:43:35,934 attempt 1/10 2017-05-23 08:43:35,934 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/279acccff1c1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-23 08:43:36,569 unpacking tar archive at: mozilla-beta-279acccff1c1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.913356 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:36.776175) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:36.776614) ========= script_repo_revision: 279acccff1c12f047565b465286fcbcc180c5ab0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:36.777127) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:36.777399) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:36.796385) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-23 08:43:36.796799) ========= /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.HLOyCfiZXC/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.fvL13g7au9/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 08:43:36 INFO - MultiFileLogger online at 20170523 08:43:36 in /builds/slave/test 08:43:36 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 08:43:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:43:36 INFO - {'append_to_log': False, 08:43:36 INFO - 'base_work_dir': '/builds/slave/test', 08:43:36 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:43:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:43:36 INFO - 'branch': 'Mozilla-Beta', 08:43:36 INFO - 'buildbot_json_path': 'buildprops.json', 08:43:36 INFO - 'config_files': ('talos/mac_config.py',), 08:43:36 INFO - 'default_actions': ('clobber', 08:43:36 INFO - 'read-buildbot-config', 08:43:36 INFO - 'download-and-extract', 08:43:36 INFO - 'populate-webroot', 08:43:36 INFO - 'create-virtualenv', 08:43:36 INFO - 'install', 08:43:36 INFO - 'run-tests'), 08:43:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:43:36 INFO - 'download_minidump_stackwalk': True, 08:43:36 INFO - 'download_symbols': 'ondemand', 08:43:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:43:36 INFO - 'tooltool.py': '/tools/tooltool.py', 08:43:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:43:36 INFO - '/tools/misc-python/virtualenv.py')}, 08:43:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:43:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:43:36 INFO - 'gecko_profile': False, 08:43:36 INFO - 'gecko_profile_interval': 0, 08:43:36 INFO - 'installer_path': 'installer.exe', 08:43:36 INFO - 'log_level': 'info', 08:43:36 INFO - 'log_name': 'talos', 08:43:36 INFO - 'log_to_console': True, 08:43:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:43:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:43:36 INFO - 'opt_config_files': (), 08:43:36 INFO - 'pip_index': False, 08:43:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:43:36 INFO - 'cmd': ('bash', 08:43:36 INFO - '-c', 08:43:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:43:36 INFO - 'enabled': True, 08:43:36 INFO - 'halt_on_failure': False, 08:43:36 INFO - 'name': 'check_screen_resolution'},), 08:43:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:43:36 INFO - 'cmd': ('bash', 08:43:36 INFO - '-c', 08:43:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:43:36 INFO - 'enabled': True, 08:43:36 INFO - 'halt_on_failure': False, 08:43:36 INFO - 'name': 'check_screen_resolution'},), 08:43:36 INFO - 'run_cmd_checks_enabled': True, 08:43:36 INFO - 'suite': 'dromaeojs-e10s', 08:43:36 INFO - 'system_bits': '32', 08:43:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:43:36 INFO - 'title': 't-yosemite-r7-0252', 08:43:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:43:36 INFO - 'use_talos_json': True, 08:43:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:43:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:43:36 INFO - 'work_dir': 'build'} 08:43:36 INFO - [mozharness: 2017-05-23 15:43:36.997428Z] Running clobber step. 08:43:36 INFO - Running pre-action listener: _resource_record_pre_action 08:43:36 INFO - Running main action method: clobber 08:43:36 INFO - rmtree: /builds/slave/test/build 08:43:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:43:39 INFO - Running post-action listener: _resource_record_post_action 08:43:39 INFO - [mozharness: 2017-05-23 15:43:39.366487Z] Finished clobber step (success) 08:43:39 INFO - [mozharness: 2017-05-23 15:43:39.366662Z] Running read-buildbot-config step. 08:43:39 INFO - Running pre-action listener: _resource_record_pre_action 08:43:39 INFO - Running main action method: read_buildbot_config 08:43:39 INFO - Using buildbot properties: 08:43:39 INFO - { 08:43:39 INFO - "project": "", 08:43:39 INFO - "product": "firefox", 08:43:39 INFO - "script_repo_revision": "production", 08:43:39 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 08:43:39 INFO - "repository": "", 08:43:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 08:43:39 INFO - "buildid": "20170523074624", 08:43:39 INFO - "pgo_build": "False", 08:43:39 INFO - "basedir": "/builds/slave/test", 08:43:39 INFO - "buildnumber": 5, 08:43:39 INFO - "slavename": "t-yosemite-r7-0252", 08:43:39 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 08:43:39 INFO - "platform": "yosemite_r7", 08:43:39 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 08:43:39 INFO - "branch": "mozilla-beta", 08:43:39 INFO - "repo_path": "releases/mozilla-beta", 08:43:39 INFO - "revision": "279acccff1c12f047565b465286fcbcc180c5ab0", 08:43:39 INFO - "stage_platform": "macosx64", 08:43:39 INFO - "builduid": "7048be335ba440a8b20ef3333c0f1328", 08:43:39 INFO - "slavebuilddir": "test" 08:43:39 INFO - } 08:43:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 08:43:39 INFO - Running post-action listener: _resource_record_post_action 08:43:39 INFO - [mozharness: 2017-05-23 15:43:39.369961Z] Finished read-buildbot-config step (success) 08:43:39 INFO - [mozharness: 2017-05-23 15:43:39.370095Z] Running download-and-extract step. 08:43:39 INFO - Running pre-action listener: _resource_record_pre_action 08:43:39 INFO - Running main action method: download_and_extract 08:43:39 INFO - mkdir: /builds/slave/test/build/tests 08:43:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:43:39 INFO - trying https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 08:43:39 INFO - Downloading https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/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 08:43:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/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 08:43:40 INFO - Downloaded 1287 bytes. 08:43:40 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 08:43:40 INFO - Using the following test package requirements: 08:43:40 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:43:40 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 08:43:40 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 08:43:40 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:43:40 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 08:43:40 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:43:40 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 08:43:40 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 08:43:40 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:43:40 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 08:43:40 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 08:43:40 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:43:40 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 08:43:40 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:43:40 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 08:43:40 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:43:40 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 08:43:40 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:43:40 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 08:43:40 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 08:43:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 08:43:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 08:43:40 INFO - Fetch https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 08:43:44 INFO - Expected file size: 51393535 08:43:44 INFO - Obtained file size: 51393535 08:43:59 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 08:43:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 08:43:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 08:43:59 INFO - Fetch https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 08:44:03 INFO - Expected file size: 51393535 08:44:03 INFO - Obtained file size: 51393535 08:44:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 08:44:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 08:44:18 INFO - Fetch https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 08:44:21 INFO - Expected file size: 14404449 08:44:21 INFO - Obtained file size: 14404449 08:44:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:22 INFO - trying https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 08:44:22 INFO - Downloading https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 08:44:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/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 08:44:26 INFO - Downloaded 59649381 bytes. 08:44:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 08:44:26 INFO - mkdir: /builds/slave/test/properties 08:44:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:44:26 INFO - Writing to file /builds/slave/test/properties/build_url 08:44:26 INFO - Contents: 08:44:26 INFO - build_url:https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 08:44:27 INFO - Running post-action listener: _resource_record_post_action 08:44:27 INFO - Running post-action listener: set_extra_try_arguments 08:44:27 INFO - [mozharness: 2017-05-23 15:44:27.192588Z] Finished download-and-extract step (success) 08:44:27 INFO - [mozharness: 2017-05-23 15:44:27.192747Z] Running populate-webroot step. 08:44:27 INFO - Running pre-action listener: _resource_record_pre_action 08:44:27 INFO - Running main action method: populate_webroot 08:44:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:44:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:44:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:44:27 INFO - '--tppagecycles', 08:44:27 INFO - '7'], 08:44:27 INFO - 'tests': ['tsvgm']}}, 08:44:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:44:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:44:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:44:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:44:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:44:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:44:27 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:44:27 INFO - 'talos_options': ['--disable-e10s'], 08:44:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:44:27 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:44:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:44:27 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:44:27 INFO - 'talos_options': ['--disable-e10s'], 08:44:27 INFO - 'tests': ['damp', 'tps']}, 08:44:27 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:44:27 INFO - 'tests': ['damp', 'tps']}, 08:44:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:44:27 INFO - 'tests': ['dromaeo_dom']}, 08:44:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:44:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:44:27 INFO - 'tests': ['basic_compositor_video']}, 08:44:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:44:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:44:27 INFO - 'tests': ['a11yr', 08:44:27 INFO - 'ts_paint', 08:44:27 INFO - 'tpaint', 08:44:27 INFO - 'sessionrestore', 08:44:27 INFO - 'sessionrestore_no_auto_restore', 08:44:27 INFO - 'tabpaint']}, 08:44:27 INFO - 'other-e10s': {'tests': ['a11yr', 08:44:27 INFO - 'ts_paint', 08:44:27 INFO - 'tpaint', 08:44:27 INFO - 'sessionrestore', 08:44:27 INFO - 'sessionrestore_no_auto_restore', 08:44:27 INFO - 'tabpaint']}, 08:44:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:44:27 INFO - 'ts_paint', 08:44:27 INFO - 'tpaint', 08:44:27 INFO - 'sessionrestore', 08:44:27 INFO - 'sessionrestore_no_auto_restore', 08:44:27 INFO - 'tabpaint']}, 08:44:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:44:27 INFO - 'ts_paint', 08:44:27 INFO - 'tpaint', 08:44:27 INFO - 'sessionrestore', 08:44:27 INFO - 'sessionrestore_no_auto_restore', 08:44:27 INFO - 'tabpaint']}, 08:44:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:44:27 INFO - 'tests': ['a11yr', 08:44:27 INFO - 'ts_paint', 08:44:27 INFO - 'tpaint', 08:44:27 INFO - 'sessionrestore', 08:44:27 INFO - 'sessionrestore_no_auto_restore', 08:44:27 INFO - 'tabpaint']}, 08:44:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:44:27 INFO - 'tests': ['a11yr', 08:44:27 INFO - 'ts_paint', 08:44:27 INFO - 'tpaint', 08:44:27 INFO - 'sessionrestore', 08:44:27 INFO - 'sessionrestore_no_auto_restore', 08:44:27 INFO - 'tabpaint']}, 08:44:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:44:27 INFO - 'tests': ['tsvgx', 08:44:27 INFO - 'tsvgr_opacity', 08:44:27 INFO - 'tart', 08:44:27 INFO - 'tscrollx', 08:44:27 INFO - 'cart', 08:44:27 INFO - 'tsvg_static']}, 08:44:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:44:27 INFO - 'tsvgr_opacity', 08:44:27 INFO - 'tart', 08:44:27 INFO - 'tscrollx', 08:44:27 INFO - 'cart', 08:44:27 INFO - 'tsvg_static']}, 08:44:27 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:44:27 INFO - 'talos_options': ['--disable-e10s'], 08:44:27 INFO - 'tests': ['tp5o']}, 08:44:27 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:44:27 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:44:27 INFO - 'talos_options': ['--disable-e10s', 08:44:27 INFO - '--xperf_path', 08:44:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:44:27 INFO - 'tests': ['tp5n']}, 08:44:27 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:44:27 INFO - 'talos_options': ['--xperf_path', 08:44:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:44:27 INFO - 'tests': ['tp5n']}}, 08:44:27 INFO - 'talos.zip': {'path': '', 08:44:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:44:27 INFO - Running post-action listener: _resource_record_post_action 08:44:27 INFO - [mozharness: 2017-05-23 15:44:27.211583Z] Finished populate-webroot step (success) 08:44:27 INFO - [mozharness: 2017-05-23 15:44:27.211726Z] Running create-virtualenv step. 08:44:27 INFO - Running pre-action listener: _resource_record_pre_action 08:44:27 INFO - Running main action method: create_virtualenv 08:44:27 INFO - Creating virtualenv /builds/slave/test/build/venv 08:44:27 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 08:44:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:44:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:44:27 INFO - Using real prefix '/tools/python27' 08:44:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:44:28 INFO - Installing distribute.............................................................................................................................................................................................done. 08:44:31 INFO - Installing pip.................done. 08:44:31 INFO - Return code: 0 08:44:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:44:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:44:31 INFO - Reading from file tmpfile_stdout 08:44:31 INFO - Output received: 08:44:31 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) 08:44:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:44: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')]} 08:44:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44: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')]} 08:44:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:32 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 0x10aeeab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeed768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4f3c87dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acc8d30>, '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 0x10abefdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acbdbe8>, '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.HLOyCfiZXC/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.fvL13g7au9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 08:44:32 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 08:44:32 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 08:44:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLOyCfiZXC/Render', 08:44:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:32 INFO - 'HOME': '/Users/cltbld', 08:44:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:32 INFO - 'LOGNAME': 'cltbld', 08:44:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:32 INFO - 'MOZ_NO_REMOTE': '1', 08:44:32 INFO - 'NO_EM_RESTART': '1', 08:44:32 INFO - 'PAGER': '/bin/cat', 08:44:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:32 INFO - 'PWD': '/builds/slave/test', 08:44:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:32 INFO - 'SHELL': '/bin/bash', 08:44:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fvL13g7au9/Listeners', 08:44:32 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 08:44:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:32 INFO - 'USER': 'cltbld', 08:44:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:32 INFO - 'XPC_FLAGS': '0x0', 08:44:32 INFO - 'XPC_SERVICE_NAME': '0', 08:44:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:32 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 08:44:32 INFO - Cleaning up... 08:44:32 INFO - Return code: 0 08:44:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:44:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:32 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 0x10aeeab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeed768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4f3c87dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acc8d30>, '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 0x10abefdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acbdbe8>, '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.HLOyCfiZXC/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.fvL13g7au9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 08:44:32 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 08:44:32 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 08:44:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLOyCfiZXC/Render', 08:44:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:32 INFO - 'HOME': '/Users/cltbld', 08:44:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:32 INFO - 'LOGNAME': 'cltbld', 08:44:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:32 INFO - 'MOZ_NO_REMOTE': '1', 08:44:32 INFO - 'NO_EM_RESTART': '1', 08:44:32 INFO - 'PAGER': '/bin/cat', 08:44:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:32 INFO - 'PWD': '/builds/slave/test', 08:44:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:32 INFO - 'SHELL': '/bin/bash', 08:44:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fvL13g7au9/Listeners', 08:44:32 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 08:44:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:32 INFO - 'USER': 'cltbld', 08:44:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:32 INFO - 'XPC_FLAGS': '0x0', 08:44:32 INFO - 'XPC_SERVICE_NAME': '0', 08:44:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:32 INFO - Downloading/unpacking psutil>=3.1.1 08:44:32 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 08:44:32 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 08:44:32 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 08:44:32 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 08:44:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:44:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:44:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:44:35 INFO - Installing collected packages: psutil 08:44:35 INFO - Running setup.py install for psutil 08:44:35 INFO - building 'psutil._psutil_osx' extension 08:44:35 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 08:44:35 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 08:44:35 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 08:44:36 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 08:44:36 INFO - building 'psutil._psutil_posix' extension 08:44:36 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 08:44:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:44:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:44:36 INFO - ^ 08:44:36 INFO - 1 warning generated. 08:44:36 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 08:44:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:44:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:44:36 INFO - Successfully installed psutil 08:44:36 INFO - Cleaning up... 08:44:36 INFO - Return code: 0 08:44:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:44:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:36 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 0x10aeeab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeed768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4f3c87dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acc8d30>, '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 0x10abefdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acbdbe8>, '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.HLOyCfiZXC/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.fvL13g7au9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 08:44:36 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 08:44:36 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 08:44:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLOyCfiZXC/Render', 08:44:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:36 INFO - 'HOME': '/Users/cltbld', 08:44:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:36 INFO - 'LOGNAME': 'cltbld', 08:44:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:36 INFO - 'MOZ_NO_REMOTE': '1', 08:44:36 INFO - 'NO_EM_RESTART': '1', 08:44:36 INFO - 'PAGER': '/bin/cat', 08:44:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:36 INFO - 'PWD': '/builds/slave/test', 08:44:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:36 INFO - 'SHELL': '/bin/bash', 08:44:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fvL13g7au9/Listeners', 08:44:36 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 08:44:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:36 INFO - 'USER': 'cltbld', 08:44:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:36 INFO - 'XPC_FLAGS': '0x0', 08:44:36 INFO - 'XPC_SERVICE_NAME': '0', 08:44:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:44:36 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 08:44:36 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 08:44:36 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 08:44:36 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 08:44:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:44:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:44:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:44:38 INFO - Installing collected packages: mozsystemmonitor 08:44:38 INFO - Running setup.py install for mozsystemmonitor 08:44:38 INFO - Successfully installed mozsystemmonitor 08:44:38 INFO - Cleaning up... 08:44:38 INFO - Return code: 0 08:44:38 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:44:38 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')]} 08:44:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:38 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')]} 08:44:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:38 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 0x10aeeab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeed768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4f3c87dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acc8d30>, '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 0x10abefdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acbdbe8>, '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.HLOyCfiZXC/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.fvL13g7au9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 08:44:38 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 08:44:38 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 08:44:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLOyCfiZXC/Render', 08:44:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:38 INFO - 'HOME': '/Users/cltbld', 08:44:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:38 INFO - 'LOGNAME': 'cltbld', 08:44:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:38 INFO - 'MOZ_NO_REMOTE': '1', 08:44:38 INFO - 'NO_EM_RESTART': '1', 08:44:38 INFO - 'PAGER': '/bin/cat', 08:44:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:38 INFO - 'PWD': '/builds/slave/test', 08:44:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:38 INFO - 'SHELL': '/bin/bash', 08:44:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fvL13g7au9/Listeners', 08:44:38 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 08:44:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:38 INFO - 'USER': 'cltbld', 08:44:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:38 INFO - 'XPC_FLAGS': '0x0', 08:44:38 INFO - 'XPC_SERVICE_NAME': '0', 08:44:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:38 INFO - Downloading/unpacking jsonschema==2.5.1 08:44:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:44:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:44:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:44:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:44:40 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 08:44:40 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 08:44:40 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 08:44:40 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 08:44:40 INFO - Downloading functools32-3.2.3-2.tar.gz 08:44:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:44:40 INFO - warning: no files found matching '*.txt' 08:44:40 INFO - no previously-included directories found matching 'build' 08:44:40 INFO - no previously-included directories found matching 'dist' 08:44:40 INFO - no previously-included directories found matching '.git*' 08:44:40 INFO - Installing collected packages: jsonschema, functools32 08:44:40 INFO - Running setup.py install for functools32 08:44:40 INFO - warning: no files found matching '*.txt' 08:44:40 INFO - no previously-included directories found matching 'build' 08:44:40 INFO - no previously-included directories found matching 'dist' 08:44:40 INFO - no previously-included directories found matching '.git*' 08:44:40 INFO - Successfully installed jsonschema functools32 08:44:40 INFO - Cleaning up... 08:44:40 INFO - Return code: 0 08:44:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:44:40 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')]} 08:44:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:40 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')]} 08:44:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:40 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 0x10aeeab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeed768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4f3c87dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acc8d30>, '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 0x10abefdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acbdbe8>, '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.HLOyCfiZXC/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.fvL13g7au9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 08:44:40 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 08:44:40 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 08:44:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLOyCfiZXC/Render', 08:44:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:40 INFO - 'HOME': '/Users/cltbld', 08:44:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:40 INFO - 'LOGNAME': 'cltbld', 08:44:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:40 INFO - 'MOZ_NO_REMOTE': '1', 08:44:40 INFO - 'NO_EM_RESTART': '1', 08:44:40 INFO - 'PAGER': '/bin/cat', 08:44:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:40 INFO - 'PWD': '/builds/slave/test', 08:44:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:40 INFO - 'SHELL': '/bin/bash', 08:44:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fvL13g7au9/Listeners', 08:44:40 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 08:44:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:40 INFO - 'USER': 'cltbld', 08:44:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:40 INFO - 'XPC_FLAGS': '0x0', 08:44:40 INFO - 'XPC_SERVICE_NAME': '0', 08:44:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:44:40 INFO - Cleaning up... 08:44:40 INFO - Return code: 0 08:44:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:44:40 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')]} 08:44:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:40 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')]} 08:44:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:40 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 0x10aeeab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeed768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4f3c87dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acc8d30>, '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 0x10abefdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acbdbe8>, '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.HLOyCfiZXC/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.fvL13g7au9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 08:44:40 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 08:44:40 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 08:44:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLOyCfiZXC/Render', 08:44:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:40 INFO - 'HOME': '/Users/cltbld', 08:44:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:40 INFO - 'LOGNAME': 'cltbld', 08:44:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:40 INFO - 'MOZ_NO_REMOTE': '1', 08:44:40 INFO - 'NO_EM_RESTART': '1', 08:44:40 INFO - 'PAGER': '/bin/cat', 08:44:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:40 INFO - 'PWD': '/builds/slave/test', 08:44:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:40 INFO - 'SHELL': '/bin/bash', 08:44:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fvL13g7au9/Listeners', 08:44:40 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 08:44:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:40 INFO - 'USER': 'cltbld', 08:44:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:40 INFO - 'XPC_FLAGS': '0x0', 08:44:40 INFO - 'XPC_SERVICE_NAME': '0', 08:44:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:41 INFO - Downloading/unpacking blobuploader==1.2.4 08:44:41 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 08:44:41 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 08:44:41 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 08:44:41 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 08:44:42 INFO - Downloading blobuploader-1.2.4.tar.gz 08:44:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:44:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:44:42 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 08:44:42 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 08:44:42 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 08:44:42 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 08:44:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:44:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:44:43 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 08:44:43 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 08:44:43 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 08:44:43 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 08:44:43 INFO - Downloading docopt-0.6.1.tar.gz 08:44:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:44:43 INFO - Installing collected packages: blobuploader, requests, docopt 08:44:43 INFO - Running setup.py install for blobuploader 08:44:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:44:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:44:43 INFO - Running setup.py install for requests 08:44:44 INFO - Running setup.py install for docopt 08:44:44 INFO - Successfully installed blobuploader requests docopt 08:44:44 INFO - Cleaning up... 08:44:44 INFO - Return code: 0 08:44:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:44:44 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')]} 08:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:44 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')]} 08:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:44 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 0x10aeeab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeed768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4f3c87dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acc8d30>, '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 0x10abefdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acbdbe8>, '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.HLOyCfiZXC/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.fvL13g7au9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 08:44:44 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 08:44:44 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 08:44:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLOyCfiZXC/Render', 08:44:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:44 INFO - 'HOME': '/Users/cltbld', 08:44:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:44 INFO - 'LOGNAME': 'cltbld', 08:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:44 INFO - 'MOZ_NO_REMOTE': '1', 08:44:44 INFO - 'NO_EM_RESTART': '1', 08:44:44 INFO - 'PAGER': '/bin/cat', 08:44:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:44 INFO - 'PWD': '/builds/slave/test', 08:44:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:44 INFO - 'SHELL': '/bin/bash', 08:44:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fvL13g7au9/Listeners', 08:44:44 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 08:44:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:44 INFO - 'USER': 'cltbld', 08:44:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:44 INFO - 'XPC_FLAGS': '0x0', 08:44:44 INFO - 'XPC_SERVICE_NAME': '0', 08:44:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:44:44 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-fPOLSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:44:44 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-8tr10a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:44:44 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-P_WiLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:44:44 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-oDTLbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:44:44 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-BuR6Hy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:44:45 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-IvJQGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:44:45 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-qqHVQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:44:45 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-i0Bwj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:44:45 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-U2964V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:44:45 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-J406aJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:44:45 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-VoVDjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:44:45 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-sPkhbZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:44:45 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-DzcxWT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:44:45 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-DKw89O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:46 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-Eik3AB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:44:46 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-eJgoAF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:44:46 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-QFdFxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:44:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:44:46 INFO - Running setup.py install for manifestparser 08:44:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:44:46 INFO - Running setup.py install for mozcrash 08:44:46 INFO - Running setup.py install for mozdebug 08:44:46 INFO - Running setup.py install for mozdevice 08:44:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:44:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:44:46 INFO - Running setup.py install for mozfile 08:44:47 INFO - Running setup.py install for mozhttpd 08:44:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:44:47 INFO - Running setup.py install for mozinfo 08:44:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:44:47 INFO - Running setup.py install for mozInstall 08:44:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:44:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:44:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:44:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:44:47 INFO - Running setup.py install for mozleak 08:44:47 INFO - Running setup.py install for mozlog 08:44:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:44:47 INFO - Running setup.py install for moznetwork 08:44:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:44:47 INFO - Running setup.py install for mozprocess 08:44:48 INFO - Running setup.py install for mozprofile 08:44:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:44:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:44:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:44:48 INFO - Running setup.py install for mozrunner 08:44:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:44:48 INFO - Running setup.py install for mozscreenshot 08:44:48 INFO - Running setup.py install for moztest 08:44:48 INFO - Running setup.py install for mozversion 08:44:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:44:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:44:48 INFO - Cleaning up... 08:44:48 INFO - Return code: 0 08:44:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:44:48 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')]} 08:44:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:48 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')]} 08:44:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:48 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 0x10aeeab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeed768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4f3c87dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acc8d30>, '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 0x10abefdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acbdbe8>, '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.HLOyCfiZXC/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.fvL13g7au9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 08:44:48 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 08:44:48 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 08:44:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLOyCfiZXC/Render', 08:44:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:48 INFO - 'HOME': '/Users/cltbld', 08:44:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:48 INFO - 'LOGNAME': 'cltbld', 08:44:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:48 INFO - 'MOZ_NO_REMOTE': '1', 08:44:48 INFO - 'NO_EM_RESTART': '1', 08:44:48 INFO - 'PAGER': '/bin/cat', 08:44:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:48 INFO - 'PWD': '/builds/slave/test', 08:44:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:48 INFO - 'SHELL': '/bin/bash', 08:44:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fvL13g7au9/Listeners', 08:44:48 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 08:44:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:48 INFO - 'USER': 'cltbld', 08:44:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:48 INFO - 'XPC_FLAGS': '0x0', 08:44:48 INFO - 'XPC_SERVICE_NAME': '0', 08:44:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:44:49 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-RWZR4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:44:49 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)) 08:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:44:49 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-sxfZSp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:44:49 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)) 08:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:44:49 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-F4pAny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:44:49 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)) 08:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:44:49 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-uVGXNY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:44:49 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)) 08:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:44:49 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-7jwD6T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:44:49 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)) 08:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:44:49 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-oFAQ4M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:44:49 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)) 08:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:44:49 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-Q9SA1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:44:49 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)) 08:44:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:44:49 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-FPUSKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:44:50 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)) 08:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:44:50 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-T3fJZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:44:50 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)) 08:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:44:50 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-1PbtT6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:44:50 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)) 08:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:44:50 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-A4CRcI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:44:50 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)) 08:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:44:50 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-e5ZS9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:44:50 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)) 08:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:44:50 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-IfIVIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:44:50 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)) 08:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:44:50 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-2Y6BYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:44:50 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)) 08:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:50 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-6Av192-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:44:50 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)) 08:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:44:50 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-xH54vf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:44:50 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)) 08:44:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:44:50 INFO - Running setup.py (path:/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/pip-1CFFUO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:44:51 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)) 08:44:51 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)) 08:44:51 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)) 08:44:51 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)) 08:44:51 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)) 08:44:51 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)) 08:44:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:44:51 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 08:44:51 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 08:44:51 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 08:44:51 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 08:44:52 INFO - Downloading blessings-1.6.tar.gz 08:44:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:44:52 INFO - Installing collected packages: blessings 08:44:52 INFO - Running setup.py install for blessings 08:44:52 INFO - Successfully installed blessings 08:44:52 INFO - Cleaning up... 08:44:52 INFO - Return code: 0 08:44:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:44:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:44:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:44:53 INFO - Reading from file tmpfile_stdout 08:44:53 INFO - Current package versions: 08:44:53 INFO - blessings == 1.6 08:44:53 INFO - blobuploader == 1.2.4 08:44:53 INFO - docopt == 0.6.1 08:44:53 INFO - functools32 == 3.2.3-2 08:44:53 INFO - jsonschema == 2.5.1 08:44:53 INFO - manifestparser == 1.1 08:44:53 INFO - mozInstall == 1.12 08:44:53 INFO - mozcrash == 1.0 08:44:53 INFO - mozdebug == 0.1 08:44:53 INFO - mozdevice == 0.49 08:44:53 INFO - mozfile == 1.2 08:44:53 INFO - mozhttpd == 0.7 08:44:53 INFO - mozinfo == 0.9 08:44:53 INFO - mozleak == 0.1 08:44:53 INFO - mozlog == 3.4 08:44:53 INFO - moznetwork == 0.27 08:44:53 INFO - mozprocess == 0.25 08:44:53 INFO - mozprofile == 0.28 08:44:53 INFO - mozrunner == 6.13 08:44:53 INFO - mozscreenshot == 0.1 08:44:53 INFO - mozsystemmonitor == 0.3 08:44:53 INFO - moztest == 0.8 08:44:53 INFO - mozversion == 1.4 08:44:53 INFO - psutil == 3.1.1 08:44:53 INFO - requests == 1.2.3 08:44:53 INFO - wsgiref == 0.1.2 08:44:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08: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')]} 08:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08: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')]} 08:44:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:53 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 0x10aeeab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeed768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4f3c87dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acc8d30>, '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 0x10abefdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acbdbe8>, '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.HLOyCfiZXC/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.fvL13g7au9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 08:44:53 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 08:44:53 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 08:44:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLOyCfiZXC/Render', 08:44:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:53 INFO - 'HOME': '/Users/cltbld', 08:44:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:53 INFO - 'LOGNAME': 'cltbld', 08:44:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:53 INFO - 'MOZ_NO_REMOTE': '1', 08:44:53 INFO - 'NO_EM_RESTART': '1', 08:44:53 INFO - 'PAGER': '/bin/cat', 08:44:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:53 INFO - 'PWD': '/builds/slave/test', 08:44:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:53 INFO - 'SHELL': '/bin/bash', 08:44:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fvL13g7au9/Listeners', 08:44:53 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 08:44:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:53 INFO - 'USER': 'cltbld', 08:44:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:53 INFO - 'XPC_FLAGS': '0x0', 08:44:53 INFO - 'XPC_SERVICE_NAME': '0', 08:44:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:53 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)) 08:44:53 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)) 08:44:53 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)) 08:44:53 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)) 08:44:53 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)) 08:44:53 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)) 08:44:53 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)) 08:44:53 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)) 08:44:53 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)) 08:44:53 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)) 08:44:53 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)) 08:44:53 INFO - Cleaning up... 08:44:53 INFO - Return code: 0 08:44:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08: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')]} 08:44:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08: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')]} 08:44:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10aeeab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aeed768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc4f3c87dd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10acc8d30>, '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 0x10abefdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acbdbe8>, '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.HLOyCfiZXC/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.fvL13g7au9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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 08: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', 'jsonschema'] in /builds/slave/test/build 08: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 jsonschema 08:44:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLOyCfiZXC/Render', 08:44:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:53 INFO - 'HOME': '/Users/cltbld', 08:44:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:53 INFO - 'LOGNAME': 'cltbld', 08:44:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:53 INFO - 'MOZ_NO_REMOTE': '1', 08:44:53 INFO - 'NO_EM_RESTART': '1', 08:44:53 INFO - 'PAGER': '/bin/cat', 08:44:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:53 INFO - 'PWD': '/builds/slave/test', 08:44:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:53 INFO - 'SHELL': '/bin/bash', 08:44:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fvL13g7au9/Listeners', 08:44:53 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 08:44:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:53 INFO - 'USER': 'cltbld', 08:44:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:53 INFO - 'XPC_FLAGS': '0x0', 08:44:53 INFO - 'XPC_SERVICE_NAME': '0', 08:44:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:44:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:44:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:44:53 INFO - Cleaning up... 08:44:53 INFO - Return code: 0 08:44:53 INFO - Running post-action listener: _resource_record_post_action 08:44:53 INFO - Running post-action listener: _start_resource_monitoring 08:44:53 INFO - Starting resource monitoring. 08:44:53 INFO - [mozharness: 2017-05-23 15:44:53.596289Z] Finished create-virtualenv step (success) 08:44:53 INFO - [mozharness: 2017-05-23 15:44:53.597321Z] Running install step. 08:44:53 INFO - Running pre-action listener: _resource_record_pre_action 08:44:53 INFO - Running main action method: install 08:44:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:44:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:44:53 INFO - Reading from file tmpfile_stdout 08:44:53 INFO - Detecting whether we're running mozinstall >=1.0... 08:44:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:44:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:44:53 INFO - Reading from file tmpfile_stdout 08:44:53 INFO - Output received: 08:44:53 INFO - Usage: mozinstall [options] installer 08:44:53 INFO - Options: 08:44:53 INFO - -h, --help show this help message and exit 08:44:53 INFO - -d DEST, --destination=DEST 08:44:53 INFO - Directory to install application into. [default: 08:44:53 INFO - "/builds/slave/test"] 08:44:53 INFO - --app=APP Application being installed. [default: firefox] 08:44:53 INFO - mkdir: /builds/slave/test/build/application 08:44:53 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'] 08:44:53 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 08:45:09 INFO - Reading from file tmpfile_stdout 08:45:09 INFO - Output received: 08:45:09 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 08:45:09 INFO - Running post-action listener: _resource_record_post_action 08:45:09 INFO - [mozharness: 2017-05-23 15:45:09.171001Z] Finished install step (success) 08:45:09 INFO - [mozharness: 2017-05-23 15:45:09.171131Z] Running run-tests step. 08:45:09 INFO - Running pre-action listener: _resource_record_pre_action 08:45:09 INFO - Running main action method: run_tests 08:45:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:45:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:45:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:45:09 INFO - 2017-05-23 08:45:09.186 screenresolution[1623:7295] starting screenresolution argv=screenresolution get 08:45:09 INFO - 2017-05-23 08:45:09.188 screenresolution[1623:7295] Display 0: 1600x1200x32@60 08:45:09 INFO - 2017-05-23 08:45:09.194 screenresolution[1624:7297] starting screenresolution argv=screenresolution list 08:45:09 INFO - Available Modes on Display 0 08:45:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:45:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:45:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:45:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:45:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:45:09 INFO - Intel Iris: 08:45:09 INFO - Chipset Model: Intel Iris 08:45:09 INFO - Type: GPU 08:45:09 INFO - Bus: Built-In 08:45:09 INFO - VRAM (Dynamic, Max): 1536 MB 08:45:09 INFO - Vendor: Intel (0x8086) 08:45:09 INFO - Device ID: 0x0a2e 08:45:09 INFO - Revision ID: 0x0009 08:45:09 INFO - Displays: 08:45:09 INFO - Display: 08:45:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:45:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:45:09 INFO - Display Serial Number: 1600x1200 60 08:45:09 INFO - Main Display: Yes 08:45:09 INFO - Mirror: Off 08:45:09 INFO - Online: Yes 08:45:09 INFO - Rotation: Supported 08:45:09 INFO - Return code: 0 08:45:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:45:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:45:09 INFO - Python 2.7.3 08:45:09 INFO - Return code: 0 08:45:09 INFO - grabbing minidump binary from tooltool 08:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:45:09 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 0x10acc8d30>, '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 0x10abefdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10acbdbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:45:09 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 08:45:09 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 08:45:09 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 08:45:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:45:09 INFO - Return code: 0 08:45:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:45:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:45:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:45:09 INFO - ENV: RUST_BACKTRACE is now 1 08:45:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:45:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:45:09 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/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0252', '--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 08:45:09 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/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0252 --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 08:45:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.HLOyCfiZXC/Render', 08:45:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:45:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:45:09 INFO - 'HOME': '/Users/cltbld', 08:45:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:45:09 INFO - 'LOGNAME': 'cltbld', 08:45:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:45:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:45:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:09 INFO - 'MOZ_NO_REMOTE': '1', 08:45:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:45:09 INFO - 'NO_EM_RESTART': '1', 08:45:09 INFO - 'PAGER': '/bin/cat', 08:45:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:45:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:09 INFO - 'PWD': '/builds/slave/test', 08:45:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:45:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:45:09 INFO - 'RUST_BACKTRACE': '1', 08:45:09 INFO - 'SHELL': '/bin/bash', 08:45:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fvL13g7au9/Listeners', 08:45:09 INFO - 'TMPDIR': '/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/', 08:45:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:45:09 INFO - 'USER': 'cltbld', 08:45:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:45:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:45:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:09 INFO - 'XPC_FLAGS': '0x0', 08:45:09 INFO - 'XPC_SERVICE_NAME': '0', 08:45:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:45:09 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/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0252', '--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 08:45:09 INFO - mozversion application_buildid: 20170523074624 08:45:09 INFO - mozversion application_changeset: 279acccff1c12f047565b465286fcbcc180c5ab0 08:45:09 INFO - mozversion application_display_name: Firefox 08:45:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:45:09 INFO - mozversion application_name: Firefox 08:45:09 INFO - mozversion application_remotingname: firefox 08:45:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:45:09 INFO - mozversion application_vendor: Mozilla 08:45:09 INFO - mozversion application_version: 54.0 08:45:09 INFO - mozversion platform_buildid: 20170523074624 08:45:09 INFO - mozversion platform_changeset: 279acccff1c12f047565b465286fcbcc180c5ab0 08:45:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:45:09 INFO - mozversion platform_version: 54.0 08:45:09 INFO - using testdate: 1495554309 08:45:09 INFO - actual date: 1495554309 08:45:09 INFO - starting webserver on 'localhost:49224' 08:45:09 INFO - SUITE-START | Running 2 tests 08:45:09 INFO - TEST-START | dromaeo_css 08:45:09 INFO - operating with platform_type : mac_ 08:45:09 INFO - Initialising browser for dromaeo_css test... 08:45:09 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/tmpJX2fsw/profile http://localhost:49224/getInfo.html) 08:45:12 INFO - PROCESS | 1634 | 2017-05-23 08:45:12.835 plugin-container[1638:7473] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d43, name = 'com.apple.tsm.portname' 08:45:12 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:45:12 INFO - PROCESS | 1634 | 2017-05-23 08:45:12.836 plugin-container[1638:7473] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 08:45:12 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:45:12 INFO - PROCESS | 1634 | 2017-05-23 08:45:12.836 plugin-container[1638:7473] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:45:12 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 08:45:12 INFO - PROCESS | 1634 | colorDepth:24 08:45:12 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 08:45:12 INFO - PROCESS | 1634 | __metrics 08:45:12 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 08:45:13 INFO - TEST-INFO | 1634: exit 0 08:45:13 INFO - Browser initialized. 08:45:13 INFO - Running cycle 1/1 for dromaeo_css test... 08:45:13 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/tmpJX2fsw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:45:15 INFO - PROCESS | 1639 | 2017-05-23 08:45:15.928 plugin-container[1640:7608] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x854b, name = 'com.apple.tsm.portname' 08:45:15 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:45:15 INFO - PROCESS | 1639 | 2017-05-23 08:45:15.928 plugin-container[1640:7608] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4513, name = 'com.apple.CFPasteboardClient' 08:45:15 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:45:15 INFO - PROCESS | 1639 | 2017-05-23 08:45:15.928 plugin-container[1640:7608] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:45:25 INFO - PROCESS | 1639 | RSS: Main: 174284800 08:45:25 INFO - PROCESS | 1639 | 08:48:28 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49224/tests/dromaeo/cssquery-ext.html) 08:48:30 INFO - PROCESS | 1639 | RSS: Main: 189259776 08:48:30 INFO - PROCESS | 1639 | 08:51:32 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-ext.html (next: http://localhost:49224/tests/dromaeo/cssquery-jquery.html) 08:51:33 INFO - PROCESS | 1639 | RSS: Main: 203706368 08:51:33 INFO - PROCESS | 1639 | 08:54:35 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49224/tests/dromaeo/cssquery-mootools.html) 08:54:35 INFO - PROCESS | 1639 | RSS: Main: 205783040 08:54:35 INFO - PROCESS | 1639 | 08:57:37 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49224/tests/dromaeo/cssquery-prototype.html) 08:57:38 INFO - PROCESS | 1639 | RSS: Main: 208900096 08:57:38 INFO - PROCESS | 1639 | 09:00:41 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49224/tests/dromaeo/cssquery-yui.html) 09:00:41 INFO - PROCESS | 1639 | RSS: Main: 211296256 09:00:41 INFO - PROCESS | 1639 | 09:03:44 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-yui.html (next: http://localhost:49224/tests/dromaeo/cssquery-dojo.html) 09:03:44 INFO - PROCESS | 1639 | RSS: Main: 209006592 09:03:44 INFO - PROCESS | 1639 | 09:03:44 INFO - PROCESS | 1639 | __start_tp_report 09:03:44 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 09:03:44 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 09:03:44 INFO - PROCESS | 1639 | |i|pagename|runs| 09:03:44 INFO - PROCESS | 1639 | |0;dojo.html;3399.881004164855;3674.3202507536107;3703;3703.462997865311;3707.4068149096142;6222.719977601013;6225.813225603228;6237.5009999200065;6255.311915689271;6263.248410190783;6219.813405597839;6223.472461059817;6226.782062627809;6234.438900498954;6256.217972753406;5659.292588426457;5673.319201695802;5673.744681489343;5717.142428635696;5755.654660720349;4579.885502862438;4868.026394721052;5739.454751798572;5740.196372507831;5761.711914404284;5526.447355264481;5539.307586551681;5742.2247996520355;5746.511546518551;5801.216835727186;5662.037453632893;5666.744996475169;5669.688167150805;5693.373728889819;5745.195672605839;4884.8032232103005;5522.3649280332875;5566.638168519034;5650.152477128464;5685.630434021776;6101.877962440771;6269.028300613367;6271.435570798604;6275.6548389838545;6290.62256264621;5922.555808314393;6120.296165940885;6476.5466417350335;6501.772437964696;6573.441257493071;6743.696533655997;6782.559133656297;6792.150981127359;6792.626405547693;6801.693923773442;6261.8747625047245;6285.905711414287;6288.8113356598915;6314.71583778731;6333.778317758856;6630.104935833697;6698.062271282024;6820.897686534706;6947.305269473113;6990.231074581792;6469.029645553204;6508.349165083454;6538.934610653832;6542.476601871839;6552.180977377828;6693.698783554752;6711.899321510181;6742.123523941857;6751.864962700718;6764.0530325754435;6318.557700960889;6618.735250590019;6643.302453242437;6645.3022432643875;6663.100481434944;5298.602604804655;6456.999165129412;6491.448226900767;6493.707783149675;6506.089147519351;5345.732713364317;5369.731513424391;5378.462153784591;5381.973090134525;5405.189221616789;4730.574248317668;4754.215554433549;4778.259369797687;4778.4982576829625;4785.856424307276;6164.537659675452;6234.314225435199;6241.126242326078;6248.094026366151;6282.623042617366;6697.296783837724;6767.35710107539;6767.35710107539;6772.52592318533;6787.490938179656;6316.021016742412;6327.303996560375;6334.334894836068;6338.7464501420345;6348.936510634834;5707.086866101404;5787.479126878601;5790.392008839011;5857.355690874;5928.703564821828;6770.45836333102;6866.279040700654;6894.103766510522;6955.617441040791;6981.3367730065565;6775.390214880685;6778.423833974168;6787.219469760843;6789.1174147360525;6794.3545363190415;6893.482988775761;6934.063901373452;6942.548734332252;6987.196472405535;6995.75514856998;6323.114763933145;6369.7452101915405;6494.870102597827;6512.706928188148;6529.738810447719;6724.966375168093;6751.932480675327;6755.324467553205;6774.085498457842;6776.254611992578;6166.969165154145;6341.239051313872;6410.551261411656;6443.09796628463;6554.377334153248;6561.507886908596;6572.539922205399;6587.835304117435;6594.340565943367;6630.204375474974;6169.444749972524;6257.843553911189;6285.088662144055;6316.494680425463;6390.4248617624635;6494.967525162344;6519.967400162969;6577.3422657733845;6594.043863639649;6600.900986485302;5299.417064122867;6323.905141422791;6441.291457939717;6456.967715161394;6470.450011749054 09:03:44 INFO - PROCESS | 1639 | |1;ext.html;821.8315245374698;845.3786466946794;975.1126474907835;978.7553111722069;988.4662282367517;39237.60762392375;41467.37798933018;41485;41513;41575.79212103939;40092.799536002276;40331.395029074585;40333.79833100831;40369.79815100928;40447.59552404475;8272.131426200238;11265.493052812642;11503.712407189803;11567.826482602768;11711.12166587505;9412.247020238365;11408.20142590019;11412.029977451884;11414.486348114355;11455.828162577567;11303.152263580223;11386.772264554704;11390.886091139108;11393.48729307179;11440.313581185113;11306.265092769027;11372.829407558893;11402.2588531746;11403;11543.826842597367;11221.38282394468;11266.943665281704;11275.492602832892;11293.774124517464;11294.378809165493;35345.823270883484;35457.46813797795;35738.463923040916;35852.282954341026;36000.819995900114;3983.008495752124;4005.399190121505;4034.6368826805433;4035.59644035597;4061.4923134608175;26118.608220876704;26164;26241.606375904375;27237.591436128474;27665;3187;3262.559554460141;3266.2324353776844;3320.5517255170485;3406.148462884279;296141;364040;365656.3434365649;365775;370299.9999999973;37838;38671;44668.55331446709;45612.543874561496;45690;46291.537084629395;46678.29982550265;46786.532134678215;46818.5318146821;46871;13770.449182032808;13905.304734763424;13970.720585588231;13980.58058258254;13985.090969086978;11974.940125299318;12018.87981120195;12072;12104.213226140273;12231.571894983634;11982.34097124649;12004;12038.578649747393;12061.457234424472;12082.697932551757;5893.5579831512805;5904.291485021825;5918.7928422505;5921.6743079131065;5933.199018132583;1160.5589875847293;1176.9529218831321;1191.2018947305153;1201.3692811274152;1215.1068964311223;27069;27296.727032729417;27530.311742206206;27627.86186069057;27808.721912780613;1099.5931505343074;1100.372787511111;1272.8790764877322;1310.3055380648273;1315.967100822487;12596.81104783429;16575.088370139758;16726.74909876353;17321.653566928842;17441.47675569735;514587;532744;538444;538789;543371;65102.023469648;65197.348026519125;65866.34133658698;66026.00960985589;66031.00953485702;1556.0741358891441;1558.2208895552224;1592.076595574564;1597.209381356236;1606.3654856331684;26934.730652694005;28367.574486383135;29315;29899.402011960075;29947.850260748557;47876;48773.512264876896;49691.75154124351;50844;50895.982080358925;517499;518429;520016;520557;522455;27285.86357068202;27937;32102.357952840346;33463.16342091467;33806.49290260599;34654.65345346615;36951.52193912213;39566;40233.99415014648;40477.79761101176;28294.717052829208;28882.711172888;29148.27129321784;29228.26929326699;29828;29091.7090829089;29176.7082329174;29200;29736;29796;33679.32641347208;33964.660353396146;34024.65975340215;34090.488642670876;34125.48811767875;22794.51835630745;28510.002149924654;29415.705842941297;29627.40745185127;29678;16901.74647380315;23291;23737.52524949526;23782.643260351455;24165.51668966646 09:03:44 INFO - PROCESS | 1639 | |2;jquery.html;425.83818149103337;434.6023388599432;436.6855863778081;437.4772147283995;447.6262320961999;20659.690104648424;21203.681944770822;22870.428239293986;22883.771162288394;23115.768842311612;18171;23210.303690889257;23817.761822381814;23925.04299828005;24023.039078436843;6426.80719578411;6959.338862808037;7065.622203670287;7179.784606461814;7589.924100759004;7871.330936870344;7986.600669966508;7988.880166797503;7990.560519171443;8022.478538894982;7731.922680773205;8081.919180808205;8153.8776918346275;8182.4681395709085;8195.877061844078;7386.704531818694;7700.614969251571;7817.101033381177;7984.520928744294;8048.637811298477;7452.627368631547;7638.350740187091;7643.732469363601;7643.770686879402;7796.220377962215;22921.083156673714;27235;27370.45259094827;27496.862515687495;27738;3001.714837090473;3014.8794048238046;3068.6777888321626;3086.90739277822;3092.6443459002053;17141.142942852806;17334.566635834206;17444;17495;17564.560885977826;2189.6606026065983;2242.6523888797265;2290.9656355154684;2312.90748370065;2529.8988040478357;155255.2237238818;164549.94975276475;165615;166179;166258;16847.57881052959;18757.842848072647;19314.841109533474;21778.455538611663;22128.44678883041;32500;32707.345853082807;33425;33432.3313533728;33447.33105337928;10728.249022568501;10854.62008829703;11148.581826812731;11196.496157672924;11358.375289358999;7698.307152356203;9197.448153110701;9235.630574776933;9296.814063718688;9454.385464944757;7914.841703165905;9925.454100024568;10020.799584008279;10123.544440500194;10165.034321739422;4129.194807012664;4154.646855017358;4170.47869016373;4198.6431153352305;4222.831086756495;953.3850666320185;1040.843873418978;1062.53370348918;1070.1866581398037;1114.053054903342;21746.56506869854;22039.779602204093;22058.88970555137;22079;22147.778522214892;1046.874375074996;1047.1361127070197;1052.221356196409;1084.1164450972433;1086.5002099034532;11093.889061109447;11390.316581005001;11391.943040284745;11410.372429516456;11486.42567871603;550109;658683.4131658622;682082.5895870489;728063;768578;147706.3073423173;173727.13136434238;175411;175881;182364;1289.7549465601505;1322.418136020148;1346.5017943360824;1350.8581598931971;1352.783554631274;19848.40454786359;20030;20431;20471.795282047584;20492.795072049685;43523.78238108789;43564.78217608892;54043.72978135084;57814.132788009054;59548.40451595429;61772;96097.30311976527;96448;96914.51542742523;98369.52445713463;11650.951414372747;11729.882701172879;11968.281903085843;12001.099917506397;12029.518819247482;13039.608811735663;13457.663558410726;13501.932490337485;13503.45986160582;13827.101238419471;11450.370229637118;11478.82781758291;11751.412429378668;11879.930806227481;11988.280703157838;10894.67315980522;10914.617988370368;11009.339439633648;11040;11189.888101118884;10135.442550659785;11651.88348116508;11695.356755378536;11897.405129743305;12001.339926304137;11714.941425292818;11780.352080635304;11938.403079845799;11970.281783093042;12052.397380130784;11414.48634811419;11474.196806223485;11540.24988375753;11697.883021169679;11705.590304339306 09:03:44 INFO - PROCESS | 1639 | |3;mootools.html;1614.7981502312211;1677.312301956198;1685.384834535395;1696.0247857481943;1704.3864208884802;11142.442877856116;11562.53749850005;11895.881041189585;11939.880601193985;11994.520219191221;11766.764664706701;11783.646490605295;11991.820122698167;12004.159708820363;12022.51909923602;2456.533258680854;2478.603423452248;2491.9377015574573;2498.750124987496;2499.212747984388;2429.8056155507516;2435.5007223519137;2469.1358024691303;2474.146419485284;2474.232987773787;2434.573949558829;2445.9877700611473;2454.0797201049604;2465.9876700616473;2470.888810003554;2406.518696260755;2424.212131057405;2430.5746494363502;2437;2437.305367970129;2019.8788072715683;2314.143766806276;2424.381782645414;2440.0849681369486;2442.023190723707;11511.769764604745;11703;11714.589989350417;11716.53133874644;11760.764784704343;5858.443447872445;5979.820605381846;5983.282006159246;6054.878902421971;6063.9090413643835;3558.1638314995957;3612.259486805199;3676.6323367663285;3678.1540245743627;3702.8703995360033;1260.2627462934195;1261.9432125554372;1299.7660421124194;1308.319673769644;1310.6461255461068;13184;13429.194248345131;14982.400703971827;15894.52316430509;16037.83962160375;4647.0938167057775;4740.265258884879;4772.618190544748;4778.116048531033;4783;12078.818817717565;12174.57388991381;12203.45084471201;12215.877841221474;12241.326727030098;5415.9438909413075;5447.264619276426;5827.038538641077;5851.590388672752;5901.7639294427745;4945.738836596717;5235.58115350771;5345;5473.781048758085;5477.260569823103;3092.6134233220846;3232.9030129096163;3991.4212439196735;3994.08136128692;3994.500687414073;883.1962913748458;1031.8142734307746;1032.1381646325253;1043.3166276089173;1045.6967479431032;830.767385132164;833.3250067445389;906.71891713569;971.0920289529313;976.1135191225969;5607.299087614048;5900.940990590039;6396.5202609804455;6763.628000460022;6792.966035169891;875.2472873328932;1005.8491226315965;1018.6332920148741;1044.5717255925035;1082.1667316166508;4125.50493940729;4132.359484279941;4194.811233494439;4318.935215971763;4344.695871288979;10862.619808306672;13759.312034398241;14002.369893354824;14221.502247421498;14325.997180197495;11774.293542387484;14026.509072182425;14142.434302627986;14242.359093840802;14285.357158927873;10284.742881428025;10377.481125943672;10432.06111449958;10482.318649287772;10486.636737224264;6469.320721324381;6492.577982431127;6575.736970521126;6699.229588597374;6709.329067093252;9889.258305626963;12198.268103913793;12425.19236249641;12604.243745375306;12665.81001284963;11479.713007174554;11548.884511155116;12601.936990314989;12965.22208667483;13071.019673524714;9237.722868313962;9521.333506654744;9532.952335238557;9634.084761947604;9640.276979226446;10490.475476226311;10493.2130090242;10599.735006624896;10601.734956626146;10659.25385223027;9139.040400757825;9145.885100140738;9201.079892010745;9365.719028429157;9396.530173491436;8868.423552469068;8885.289176865965;8931.866022009805;8977.506237157018;9073.500957447404;8256.422050456411;8282;8314.87527687073;8387.83224335522;8401.579921003804;5391.568674506108;5453.400125986059;5982.760689572542;6066.211392518944;6106.267247930276;6043.8186854394435;6056.273247210363;6084.695765211633;6148.600340977822;6292.244930608356 09:03:44 INFO - PROCESS | 1639 | |4;prototype.html;341.726618705036;355.47744815121763;367.42131143449075;371.90144611677925;384.799904049894;4680.625549956004;5093.567046801017;5418.9458105418935;5428.45715428457;5594.615257769052;4661.976690116553;4962.602991760659;5292.497212764791;5372.194170874371;5438.972805135979;4055.3308704063793;4494.943688233263;4521.480029796583;4521.706089104214;4526.52471490492;3815.7789507357656;4127.988642782522;4234.9153016939645;4243.151369726052;4460.79926403311;3790.7915064671433;4065.8170382332864;4263.467066616673;4401;4495.43807024122;4100.384942258685;4480.574345437179;4497.955020449786;4518.819247230123;4624.8150073997;4024.3561030235023;4093.3246014407587;4141.399497072907;4149.33610622299;4165;3987.8604248851443;4627.490975992638;5191.44386095522;5206.921896171561;5232.607554433433;4567.885802854922;4817.169475599285;5089.706705026768;5115.18157094863;5195.766190521435;4924.6060315175055;5071.594272458231;5808.448197421239;5853.85365365865;5908;3855.151866589346;4153.231652144363;4220.725652832587;4323.286657701476;4558.202314594966;5255.973720131413;5296.92054619181;5934.317553481362;5936.584439089309;6186.979148440502;4348.695591308578;4890.070886531547;5036.496350364934;5086.618503612244;5148.742562871841;4472.284434490466;4703.95296047042;5071.996561358243;5254.658447200996;5318.308619879468;4760.285957106462;5110.310108135354;5275.076861549213;5361.284388995562;5515;5167.457416971164;5219.791208351623;5420.864478388072;5577.470140336662;5847.024023663865;1611.8729664451162;1676.488670955346;1700.681971472119;1813.7279408088891;1839.117223732616;1307.4770091963292;1349.9730005399838;1431.4918204037417;1452.1359790924382;1480.8000919875894;4724.622030237572;5034.370703662043;5181.341970569381;5351.491608297206;5379.273798037215;5070.391553013662;5291.756437237246;5454.59090568209;5596.692181930033;5893.631043917647;4689.437267527918;4823.252395878644;4946.752662366867;5431.293931788842;5464.836054918359;2753.650711151521;2996.2060054085687;3088.9536656950163;3088.984555077255;3102.674219206951;5418.593605479605;5703.600747947687;6014.293567894378;6155.384461553809;6336.746530138743;5745.770169193269;5809.68046757424;6114.38856114385;6239.719212635351;6251.187345645128;5498.642588231752;6096.969515152396;6246.906296405557;6292.968535157295;6299.370062993756;5672.886542269214;5680.2794169818835;5877.324107727666;5905.055191169564;6072.939270607238;5056.60943240597;5398.922614110077;5538.307711536058;5567.800718716166;5691.288588926384;5210.843674689765;5629.986602411605;5890.263717035371;6009.669468179292;6309.842253943688;4284.335927931175;4321.2005778931;5026.346574945233;5077.045337544998;5114;4807.086653535956;5480.095784195563;5662.292213473316;5713.20034189229;5972.940270597239;4977.726225057657;5578.52169175177;5820.970895145497;5866.530677545871;5867.274280831617;5405.432414606341;5566.693831839126;5602.551795856403;5639.067684803961;5873.618214816023;4720.575148236675;5116.948830511647;5285.260063591023;5379.310344827586;5531.197976293495;4744.952550474451;5477;5861.779518746495;5868.735906884286;5932.4279066046665;4768.546988036131;5246.662101164179;5386.7037312946595;5644.520215781705;5690.971545142248 09:03:44 INFO - PROCESS | 1639 | |5;yui.html;768.9384849212064;779.4543819326473;816.6774124220933;838.3460900497611;900.4597241655006;15469.84530154698;17858.910705446488;17891.64216715665;17904.194311255993;17913.820861791395;12840.871591284083;13800.723985520284;15645.530634080968;15937.804664650168;16264.512064638051;990.2721499697734;1023.6570748799124;1028.7068185567116;1031.6440827914357;1032.6179313653959;982.287841315048;1016.623849175806;1019.0777346501428;1022.2639699416409;1035.4874337203057;1050.721558786922;1066.072516910289;1067.3915867955268;1070.4701172919376;1071.8928107189258;1053.6944286157004;1063.7127975446585;1063.7925604507125;1064;1064.4943651765427;782.7690831204808;789.5617932047711;793.274154148953;793.436659971421;795.9602019898982;17523.912380438145;17769.822301777076;17809.46571602854;18221.0889455528;18390.908045459822;12445.813312800316;12955.89874860638;14080.92959535206;15038.774418383733;15113.697726045417;12062;12147.574834880737;12238.755224895453;12406.56577019809;12421.00631949442;3847.4036524338494;3944.582550776915;3976.5824588418377;4001.1797581495725;4104.979475102636;112100;113449.2223238791;115265.27102093476;115312.84687153104;115642;11509.194356394972;11546.249493782878;11717.355545445082;11827.822582661267;11828.763424731456;23887.283381498582;24260;24294.635580466307;24324.513509729706;24363.878180608983;8134.430589858653;8196.5491897945;8220.876686849702;8250.38122140842;8323.791905202297;3903.4535165076913;4442.866713998521;4589.816407343736;4638.582527572467;4668.743219122913;3329;3558.3061303045656;3619.60184379718;3683.097641077953;3703.9444408333898;661.5567569728294;668.7759600533876;678.2114651449851;687.1753895325629;696.310652454077;612.146537546148;731.9853602927911;737.9889301660479;757.2011527838183;774.8101715079841;10224.744381390525;10271.794564108675;10715.624953126604;11146.052585530158;11218.214724969173;540.7566595032251;597.3966294043048;599.9820005399757;744.6835095084567;760.9239076092457;4232.195882782262;5011.924821127686;5147.621461010204;5230.555402790806;5279.41926388097;85935.14064859397;89082.55458722665;91818;97202.51398743103;103840.48079759553;87053;88736.13326067684;89202.55398723095;91509;92294.53852730828;57149.71425142848;77053;78545.21454785494;81001;81254.37491250141;9959.751006224902;11089.500972456097;11126.388048657402;11188.776224475301;11269.267497612629;48934;50492.49507504878;50540.483785486496;51091;52224.738876305375;58181.70909145427;66719.99920001302;69487.65256173686;69727.65136174287;69785;12529.561465349034;12834.229946203257;13121.803172952605;13178.60464186076;13264.403101860633;14493.492727754478;14658.853411465749;15129;15170.31733572014;15195.316210770321;10725.249232553959;11631.6510504685;12857;13444.260565668983;13515.121517101357;13127.474901003852;13851.445942162201;13879.583612491642;13949.093308934887;14001.089929154572;13390.26353550564;13837.238951857744;14033.719325613227;14081.718365632834;14266.286685665882;9943.30396872212;10249;10378.755503433722;10499.842502362319;10669.733256668645;10347.689569312932;10505.264631475724;11129.944350278196;11367.772644547229;11636.592719254786 09:03:44 INFO - PROCESS | 1639 | __end_tp_report 09:03:44 INFO - PROCESS | 1639 | __start_cc_report 09:03:44 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,398 09:03:44 INFO - PROCESS | 1639 | __end_cc_report 09:03:44 INFO - PROCESS | 1639 | __startTimestamp1495555424851__endTimestamp 09:03:44 INFO - PROCESS | 1639 | 09:03:44 INFO - PROCESS | 1639 | ------- Summary: start ------- 09:03:44 INFO - PROCESS | 1639 | Number of tests: 6 09:03:44 INFO - PROCESS | 1639 | 09:03:44 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:6170.60 Median:6327.30 stddev:701.87 (11.1%) stddev-sans-first:669.53 09:03:44 INFO - PROCESS | 1639 | Values: 3399.9 3674.3 3703.0 3703.5 3707.4 6222.7 6225.8 6237.5 6255.3 6263.2 6219.8 6223.5 6226.8 6234.4 6256.2 5659.3 5673.3 5673.7 5717.1 5755.7 4579.9 4868.0 5739.5 5740.2 5761.7 5526.4 5539.3 5742.2 5746.5 5801.2 5662.0 5666.7 5669.7 5693.4 5745.2 4884.8 5522.4 5566.6 5650.2 5685.6 6101.9 6269.0 6271.4 6275.7 6290.6 5922.6 6120.3 6476.5 6501.8 6573.4 6743.7 6782.6 6792.2 6792.6 6801.7 6261.9 6285.9 6288.8 6314.7 6333.8 6630.1 6698.1 6820.9 6947.3 6990.2 6469.0 6508.3 6538.9 6542.5 6552.2 6693.7 6711.9 6742.1 6751.9 6764.1 6318.6 6618.7 6643.3 6645.3 6663.1 5298.6 6457.0 6491.4 6493.7 6506.1 5345.7 5369.7 5378.5 5382.0 5405.2 4730.6 4754.2 4778.3 4778.5 4785.9 6164.5 6234.3 6241.1 6248.1 6282.6 6697.3 6767.4 6767.4 6772.5 6787.5 6316.0 6327.3 6334.3 6338.7 6348.9 5707.1 5787.5 5790.4 5857.4 5928.7 6770.5 6866.3 6894.1 6955.6 6981.3 6775.4 6778.4 6787.2 6789.1 6794.4 6893.5 6934.1 6942.5 6987.2 6995.8 6323.1 6369.7 6494.9 6512.7 6529.7 6725.0 6751.9 6755.3 6774.1 6776.3 6167.0 6341.2 6410.6 6443.1 6554.4 6561.5 6572.5 6587.8 6594.3 6630.2 6169.4 6257.8 6285.1 6316.5 6390.4 6495.0 6520.0 6577.3 6594.0 6600.9 5299.4 6323.9 6441.3 6457.0 6470.5 09:03:44 INFO - PROCESS | 1639 | 09:03:44 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:59741.20 Median:29707.00 stddev:126975.51 (427.4%) stddev-sans-first:127254.64 09:03:44 INFO - PROCESS | 1639 | Values: 821.8 845.4 975.1 978.8 988.5 39237.6 41467.4 41485.0 41513.0 41575.8 40092.8 40331.4 40333.8 40369.8 40447.6 8272.1 11265.5 11503.7 11567.8 11711.1 9412.2 11408.2 11412.0 11414.5 11455.8 11303.2 11386.8 11390.9 11393.5 11440.3 11306.3 11372.8 11402.3 11403.0 11543.8 11221.4 11266.9 11275.5 11293.8 11294.4 35345.8 35457.5 35738.5 35852.3 36000.8 3983.0 4005.4 4034.6 4035.6 4061.5 26118.6 26164.0 26241.6 27237.6 27665.0 3187.0 3262.6 3266.2 3320.6 3406.1 296141.0 364040.0 365656.3 365775.0 370300.0 37838.0 38671.0 44668.6 45612.5 45690.0 46291.5 46678.3 46786.5 46818.5 46871.0 13770.4 13905.3 13970.7 13980.6 13985.1 11974.9 12018.9 12072.0 12104.2 12231.6 11982.3 12004.0 12038.6 12061.5 12082.7 5893.6 5904.3 5918.8 5921.7 5933.2 1160.6 1177.0 1191.2 1201.4 1215.1 27069.0 27296.7 27530.3 27627.9 27808.7 1099.6 1100.4 1272.9 1310.3 1316.0 12596.8 16575.1 16726.7 17321.7 17441.5 514587.0 532744.0 538444.0 538789.0 543371.0 65102.0 65197.3 65866.3 66026.0 66031.0 1556.1 1558.2 1592.1 1597.2 1606.4 26934.7 28367.6 29315.0 29899.4 29947.9 47876.0 48773.5 49691.8 50844.0 50896.0 517499.0 518429.0 520016.0 520557.0 522455.0 27285.9 27937.0 32102.4 33463.2 33806.5 34654.7 36951.5 39566.0 40234.0 40477.8 28294.7 28882.7 29148.3 29228.3 29828.0 29091.7 29176.7 29200.0 29736.0 29796.0 33679.3 33964.7 34024.7 34090.5 34125.5 22794.5 28510.0 29415.7 29627.4 29678.0 16901.7 23291.0 23737.5 23782.6 24165.5 09:03:44 INFO - PROCESS | 1639 | 09:03:44 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:42042.12 Median:12114.72 stddev:115089.54 (950.0%) stddev-sans-first:115369.98 09:03:44 INFO - PROCESS | 1639 | Values: 425.8 434.6 436.7 437.5 447.6 20659.7 21203.7 22870.4 22883.8 23115.8 18171.0 23210.3 23817.8 23925.0 24023.0 6426.8 6959.3 7065.6 7179.8 7589.9 7871.3 7986.6 7988.9 7990.6 8022.5 7731.9 8081.9 8153.9 8182.5 8195.9 7386.7 7700.6 7817.1 7984.5 8048.6 7452.6 7638.4 7643.7 7643.8 7796.2 22921.1 27235.0 27370.5 27496.9 27738.0 3001.7 3014.9 3068.7 3086.9 3092.6 17141.1 17334.6 17444.0 17495.0 17564.6 2189.7 2242.7 2291.0 2312.9 2529.9 155255.2 164549.9 165615.0 166179.0 166258.0 16847.6 18757.8 19314.8 21778.5 22128.4 32500.0 32707.3 33425.0 33432.3 33447.3 10728.2 10854.6 11148.6 11196.5 11358.4 7698.3 9197.4 9235.6 9296.8 9454.4 7914.8 9925.5 10020.8 10123.5 10165.0 4129.2 4154.6 4170.5 4198.6 4222.8 953.4 1040.8 1062.5 1070.2 1114.1 21746.6 22039.8 22058.9 22079.0 22147.8 1046.9 1047.1 1052.2 1084.1 1086.5 11093.9 11390.3 11391.9 11410.4 11486.4 550109.0 658683.4 682082.6 728063.0 768578.0 147706.3 173727.1 175411.0 175881.0 182364.0 1289.8 1322.4 1346.5 1350.9 1352.8 19848.4 20030.0 20431.0 20471.8 20492.8 43523.8 43564.8 54043.7 57814.1 59548.4 61772.0 96097.3 96448.0 96914.5 98369.5 11651.0 11729.9 11968.3 12001.1 12029.5 13039.6 13457.7 13501.9 13503.5 13827.1 11450.4 11478.8 11751.4 11879.9 11988.3 10894.7 10914.6 11009.3 11040.0 11189.9 10135.4 11651.9 11695.4 11897.4 12001.3 11714.9 11780.4 11938.4 11970.3 12052.4 11414.5 11474.2 11540.2 11697.9 11705.6 09:03:44 INFO - PROCESS | 1639 | 09:03:44 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:6853.15 Median:2472.52 stddev:4289.84 (173.5%) stddev-sans-first:4283.82 09:03:44 INFO - PROCESS | 1639 | Values: 1614.8 1677.3 1685.4 1696.0 1704.4 11142.4 11562.5 11895.9 11939.9 11994.5 11766.8 11783.6 11991.8 12004.2 12022.5 2456.5 2478.6 2491.9 2498.8 2499.2 2429.8 2435.5 2469.1 2474.1 2474.2 2434.6 2446.0 2454.1 2466.0 2470.9 2406.5 2424.2 2430.6 2437.0 2437.3 2019.9 2314.1 2424.4 2440.1 2442.0 11511.8 11703.0 11714.6 11716.5 11760.8 5858.4 5979.8 5983.3 6054.9 6063.9 3558.2 3612.3 3676.6 3678.2 3702.9 1260.3 1261.9 1299.8 1308.3 1310.6 13184.0 13429.2 14982.4 15894.5 16037.8 4647.1 4740.3 4772.6 4778.1 4783.0 12078.8 12174.6 12203.5 12215.9 12241.3 5415.9 5447.3 5827.0 5851.6 5901.8 4945.7 5235.6 5345.0 5473.8 5477.3 3092.6 3232.9 3991.4 3994.1 3994.5 883.2 1031.8 1032.1 1043.3 1045.7 830.8 833.3 906.7 971.1 976.1 5607.3 5900.9 6396.5 6763.6 6793.0 875.2 1005.8 1018.6 1044.6 1082.2 4125.5 4132.4 4194.8 4318.9 4344.7 10862.6 13759.3 14002.4 14221.5 14326.0 11774.3 14026.5 14142.4 14242.4 14285.4 10284.7 10377.5 10432.1 10482.3 10486.6 6469.3 6492.6 6575.7 6699.2 6709.3 9889.3 12198.3 12425.2 12604.2 12665.8 11479.7 11548.9 12601.9 12965.2 13071.0 9237.7 9521.3 9533.0 9634.1 9640.3 10490.5 10493.2 10599.7 10601.7 10659.3 9139.0 9145.9 9201.1 9365.7 9396.5 8868.4 8885.3 8931.9 8977.5 9073.5 8256.4 8282.0 8314.9 8387.8 8401.6 5391.6 5453.4 5982.8 6066.2 6106.3 6043.8 6056.3 6084.7 6148.6 6292.2 09:03:44 INFO - PROCESS | 1639 | 09:03:44 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4792.02 Median:5174.40 stddev:1309.83 (25.3%) stddev-sans-first:1270.20 09:03:44 INFO - PROCESS | 1639 | Values: 341.7 355.5 367.4 371.9 384.8 4680.6 5093.6 5418.9 5428.5 5594.6 4662.0 4962.6 5292.5 5372.2 5439.0 4055.3 4494.9 4521.5 4521.7 4526.5 3815.8 4128.0 4234.9 4243.2 4460.8 3790.8 4065.8 4263.5 4401.0 4495.4 4100.4 4480.6 4498.0 4518.8 4624.8 4024.4 4093.3 4141.4 4149.3 4165.0 3987.9 4627.5 5191.4 5206.9 5232.6 4567.9 4817.2 5089.7 5115.2 5195.8 4924.6 5071.6 5808.4 5853.9 5908.0 3855.2 4153.2 4220.7 4323.3 4558.2 5256.0 5296.9 5934.3 5936.6 6187.0 4348.7 4890.1 5036.5 5086.6 5148.7 4472.3 4704.0 5072.0 5254.7 5318.3 4760.3 5110.3 5275.1 5361.3 5515.0 5167.5 5219.8 5420.9 5577.5 5847.0 1611.9 1676.5 1700.7 1813.7 1839.1 1307.5 1350.0 1431.5 1452.1 1480.8 4724.6 5034.4 5181.3 5351.5 5379.3 5070.4 5291.8 5454.6 5596.7 5893.6 4689.4 4823.3 4946.8 5431.3 5464.8 2753.7 2996.2 3089.0 3089.0 3102.7 5418.6 5703.6 6014.3 6155.4 6336.7 5745.8 5809.7 6114.4 6239.7 6251.2 5498.6 6097.0 6246.9 6293.0 6299.4 5672.9 5680.3 5877.3 5905.1 6072.9 5056.6 5398.9 5538.3 5567.8 5691.3 5210.8 5630.0 5890.3 6009.7 6309.8 4284.3 4321.2 5026.3 5077.0 5114.0 4807.1 5480.1 5662.3 5713.2 5972.9 4977.7 5578.5 5821.0 5866.5 5867.3 5405.4 5566.7 5602.6 5639.1 5873.6 4720.6 5116.9 5285.3 5379.3 5531.2 4745.0 5477.0 5861.8 5868.7 5932.4 4768.5 5246.7 5386.7 5644.5 5691.0 09:03:44 INFO - PROCESS | 1639 | 09:03:44 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:20736.76 Median:17834.19 stddev:29269.22 (164.1%) stddev-sans-first:29312.93 09:03:44 INFO - PROCESS | 1639 | Values: 768.9 779.5 816.7 838.3 900.5 15469.8 17858.9 17891.6 17904.2 17913.8 12840.9 13800.7 15645.5 15937.8 16264.5 990.3 1023.7 1028.7 1031.6 1032.6 982.3 1016.6 1019.1 1022.3 1035.5 1050.7 1066.1 1067.4 1070.5 1071.9 1053.7 1063.7 1063.8 1064.0 1064.5 782.8 789.6 793.3 793.4 796.0 17523.9 17769.8 17809.5 18221.1 18390.9 12445.8 12955.9 14080.9 15038.8 15113.7 12062.0 12147.6 12238.8 12406.6 12421.0 3847.4 3944.6 3976.6 4001.2 4105.0 112100.0 113449.2 115265.3 115312.8 115642.0 11509.2 11546.2 11717.4 11827.8 11828.8 23887.3 24260.0 24294.6 24324.5 24363.9 8134.4 8196.5 8220.9 8250.4 8323.8 3903.5 4442.9 4589.8 4638.6 4668.7 3329.0 3558.3 3619.6 3683.1 3703.9 661.6 668.8 678.2 687.2 696.3 612.1 732.0 738.0 757.2 774.8 10224.7 10271.8 10715.6 11146.1 11218.2 540.8 597.4 600.0 744.7 760.9 4232.2 5011.9 5147.6 5230.6 5279.4 85935.1 89082.6 91818.0 97202.5 103840.5 87053.0 88736.1 89202.6 91509.0 92294.5 57149.7 77053.0 78545.2 81001.0 81254.4 9959.8 11089.5 11126.4 11188.8 11269.3 48934.0 50492.5 50540.5 51091.0 52224.7 58181.7 66720.0 69487.7 69727.7 69785.0 12529.6 12834.2 13121.8 13178.6 13264.4 14493.5 14658.9 15129.0 15170.3 15195.3 10725.2 11631.7 12857.0 13444.3 13515.1 13127.5 13851.4 13879.6 13949.1 14001.1 13390.3 13837.2 14033.7 14081.7 14266.3 9943.3 10249.0 10378.8 10499.8 10669.7 10347.7 10505.3 11129.9 11367.8 11636.6 09:03:44 INFO - PROCESS | 1639 | -------- Summary: end -------- 09:03:44 INFO - PROCESS | 1639 | 09:03:45 INFO - TEST-INFO | 1639: exit 0 09:03:45 INFO - TEST-OK | dromaeo_css | took 1115626ms 09:03:45 INFO - TEST-START | kraken 09:03:45 INFO - operating with platform_type : mac_ 09:03:45 INFO - Initialising browser for kraken test... 09:03:45 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/tmpMZRKWz/profile http://localhost:49224/getInfo.html) 09:03:47 INFO - PROCESS | 1651 | 2017-05-23 09:03:47.948 plugin-container[1653:10124] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833f, name = 'com.apple.tsm.portname' 09:03:47 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:47 INFO - PROCESS | 1651 | 2017-05-23 09:03:47.949 plugin-container[1653:10124] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient' 09:03:47 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:47 INFO - PROCESS | 1651 | 2017-05-23 09:03:47.949 plugin-container[1653:10124] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:03:48 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 09:03:48 INFO - PROCESS | 1651 | colorDepth:24 09:03:48 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 09:03:48 INFO - PROCESS | 1651 | __metrics 09:03:48 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 09:03:48 INFO - TEST-INFO | 1651: exit 0 09:03:48 INFO - Browser initialized. 09:03:48 INFO - Running cycle 1/1 for kraken test... 09:03:48 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/T/tmpMZRKWz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:03:51 INFO - PROCESS | 1654 | 2017-05-23 09:03:51.192 plugin-container[1655:10248] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8443, name = 'com.apple.tsm.portname' 09:03:51 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:51 INFO - PROCESS | 1654 | 2017-05-23 09:03:51.193 plugin-container[1655:10248] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4507, name = 'com.apple.CFPasteboardClient' 09:03:51 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:51 INFO - PROCESS | 1654 | 2017-05-23 09:03:51.193 plugin-container[1655:10248] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:04:01 INFO - PROCESS | 1654 | RSS: Main: 174161920 09:04:01 INFO - PROCESS | 1654 | 09:04:03 INFO - PROCESS | 1654 | 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) 09:04:04 INFO - PROCESS | 1654 | RSS: Main: 187764736 09:04:04 INFO - PROCESS | 1654 | 09:04:07 INFO - PROCESS | 1654 | 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) 09:04:07 INFO - PROCESS | 1654 | RSS: Main: 195723264 09:04:07 INFO - PROCESS | 1654 | 09:04:11 INFO - PROCESS | 1654 | 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) 09:04:11 INFO - PROCESS | 1654 | RSS: Main: 199589888 09:04:11 INFO - PROCESS | 1654 | 09:04:13 INFO - PROCESS | 1654 | 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) 09:04:14 INFO - PROCESS | 1654 | RSS: Main: 201089024 09:04:14 INFO - PROCESS | 1654 | 09:04:16 INFO - PROCESS | 1654 | 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) 09:04:16 INFO - PROCESS | 1654 | RSS: Main: 201142272 09:04:16 INFO - PROCESS | 1654 | 09:04:19 INFO - PROCESS | 1654 | 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) 09:04:20 INFO - PROCESS | 1654 | RSS: Main: 205668352 09:04:20 INFO - PROCESS | 1654 | 09:04:23 INFO - PROCESS | 1654 | 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) 09:04:24 INFO - PROCESS | 1654 | RSS: Main: 205291520 09:04:24 INFO - PROCESS | 1654 | 09:04:27 INFO - PROCESS | 1654 | 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) 09:04:27 INFO - PROCESS | 1654 | RSS: Main: 204783616 09:04:27 INFO - PROCESS | 1654 | 09:04:29 INFO - PROCESS | 1654 | 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) 09:04:30 INFO - PROCESS | 1654 | RSS: Main: 204304384 09:04:30 INFO - PROCESS | 1654 | 09:04:31 INFO - PROCESS | 1654 | 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) 09:04:32 INFO - PROCESS | 1654 | RSS: Main: 204361728 09:04:32 INFO - PROCESS | 1654 | 09:04:34 INFO - PROCESS | 1654 | 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) 09:04:35 INFO - PROCESS | 1654 | RSS: Main: 204337152 09:04:35 INFO - PROCESS | 1654 | 09:04:38 INFO - PROCESS | 1654 | 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) 09:04:39 INFO - PROCESS | 1654 | RSS: Main: 204419072 09:04:39 INFO - PROCESS | 1654 | 09:04:41 INFO - PROCESS | 1654 | 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) 09:04:42 INFO - PROCESS | 1654 | RSS: Main: 204738560 09:04:42 INFO - PROCESS | 1654 | 09:04:44 INFO - PROCESS | 1654 | 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) 09:04:44 INFO - PROCESS | 1654 | RSS: Main: 206524416 09:04:44 INFO - PROCESS | 1654 | 09:04:44 INFO - PROCESS | 1654 | __start_tp_report 09:04:44 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 09:04:44 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 09:04:44 INFO - PROCESS | 1654 | |i|pagename|runs| 09:04:44 INFO - PROCESS | 1654 | |0;ai-astar;109;120;118;119;120;119;120;121;118;176 09:04:44 INFO - PROCESS | 1654 | |1;audio-beat-detection;142;116;120;145;111;114;110;109;128;109 09:04:44 INFO - PROCESS | 1654 | |2;audio-dft;198;203;197;193;199;194;195;193;200;199 09:04:44 INFO - PROCESS | 1654 | |3;audio-fft;81;58;61;87;62;76;56;100;64;70 09:04:44 INFO - PROCESS | 1654 | |4;audio-oscillator;86;69;84;74;72;70;82;71;69;78 09:04:44 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;90;90;93;125;90;89;89;104;88;91 09:04:44 INFO - PROCESS | 1654 | |6;imaging-darkroom;96;95;97;149;97;96;98;97;95;108 09:04:44 INFO - PROCESS | 1654 | |7;imaging-desaturate;93;95;91;126;105;144;141;92;91;91 09:04:44 INFO - PROCESS | 1654 | |8;json-parse-financial;66;65;66;66;71;70;70;71;71;69 09:04:44 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;57;61;62;63;56;62;86;84;88;68 09:04:44 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;83;82;83;81;85;87;83;87;89;100 09:04:44 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;129;145;136;161;153;150;147;138;136;139 09:04:44 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;164;165;161;162;164;163;165;183;221;198 09:04:44 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;62;66;66;63;64;62;64;65;63;62 09:04:44 INFO - PROCESS | 1654 | __end_tp_report 09:04:44 INFO - PROCESS | 1654 | __start_cc_report 09:04:44 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,996 09:04:44 INFO - PROCESS | 1654 | __end_cc_report 09:04:44 INFO - PROCESS | 1654 | __startTimestamp1495555484386__endTimestamp 09:04:44 INFO - PROCESS | 1654 | 09:04:44 INFO - PROCESS | 1654 | ------- Summary: start ------- 09:04:44 INFO - PROCESS | 1654 | Number of tests: 14 09:04:44 INFO - PROCESS | 1654 | 09:04:44 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:124.00 Median:120.00 stddev:18.58 (15.5%) stddev-sans-first:18.90 09:04:44 INFO - PROCESS | 1654 | Values: 109.0 120.0 118.0 119.0 120.0 119.0 120.0 121.0 118.0 176.0 09:04:44 INFO - PROCESS | 1654 | 09:04:44 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:120.40 Median:118.00 stddev:13.53 (11.5%) stddev-sans-first:11.87 09:04:44 INFO - PROCESS | 1654 | Values: 142.0 116.0 120.0 145.0 111.0 114.0 110.0 109.0 128.0 109.0 09:04:44 INFO - PROCESS | 1654 | 09:04:44 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:197.10 Median:198.50 stddev:3.31 (1.7%) stddev-sans-first:3.50 09:04:44 INFO - PROCESS | 1654 | Values: 198.0 203.0 197.0 193.0 199.0 194.0 195.0 193.0 200.0 199.0 09:04:44 INFO - PROCESS | 1654 | 09:04:44 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:71.50 Median:67.00 stddev:14.32 (21.4%) stddev-sans-first:14.77 09:04:44 INFO - PROCESS | 1654 | Values: 81.0 58.0 61.0 87.0 62.0 76.0 56.0 100.0 64.0 70.0 09:04:44 INFO - PROCESS | 1654 | 09:04:44 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:75.50 Median:76.00 stddev:6.50 (8.6%) stddev-sans-first:5.68 09:04:44 INFO - PROCESS | 1654 | Values: 86.0 69.0 84.0 74.0 72.0 70.0 82.0 71.0 69.0 78.0 09:04:44 INFO - PROCESS | 1654 | 09:04:44 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:94.90 Median:90.00 stddev:11.53 (12.8%) stddev-sans-first:12.09 09:04:44 INFO - PROCESS | 1654 | Values: 90.0 90.0 93.0 125.0 90.0 89.0 89.0 104.0 88.0 91.0 09:04:44 INFO - PROCESS | 1654 | 09:04:44 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:102.80 Median:96.50 stddev:16.67 (17.3%) stddev-sans-first:17.49 09:04:44 INFO - PROCESS | 1654 | Values: 96.0 95.0 97.0 149.0 97.0 96.0 98.0 97.0 95.0 108.0 09:04:44 INFO - PROCESS | 1654 | 09:04:44 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:106.90 Median:91.00 stddev:21.66 (23.8%) stddev-sans-first:22.38 09:04:44 INFO - PROCESS | 1654 | Values: 93.0 95.0 91.0 126.0 105.0 144.0 141.0 92.0 91.0 91.0 09:04:44 INFO - PROCESS | 1654 | 09:04:44 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:68.50 Median:70.00 stddev:2.46 (3.5%) stddev-sans-first:2.44 09:04:44 INFO - PROCESS | 1654 | Values: 66.0 65.0 66.0 66.0 71.0 70.0 70.0 71.0 71.0 69.0 09:04:44 INFO - PROCESS | 1654 | 09:04:44 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:65.50 stddev:12.41 (18.9%) stddev-sans-first:12.42 09:04:44 INFO - PROCESS | 1654 | Values: 57.0 61.0 62.0 63.0 56.0 62.0 86.0 84.0 88.0 68.0 09:04:44 INFO - PROCESS | 1654 | 09:04:44 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:84.00 stddev:5.54 (6.6%) stddev-sans-first:5.77 09:04:44 INFO - PROCESS | 1654 | Values: 83.0 82.0 83.0 81.0 85.0 87.0 83.0 87.0 89.0 100.0 09:04:44 INFO - PROCESS | 1654 | 09:04:44 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:143.40 Median:146.00 stddev:9.58 (6.6%) stddev-sans-first:8.63 09:04:44 INFO - PROCESS | 1654 | Values: 129.0 145.0 136.0 161.0 153.0 150.0 147.0 138.0 136.0 139.0 09:04:44 INFO - PROCESS | 1654 | 09:04:44 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.60 Median:165.00 stddev:20.16 (12.2%) stddev-sans-first:21.02 09:04:44 INFO - PROCESS | 1654 | Values: 164.0 165.0 161.0 162.0 164.0 163.0 165.0 183.0 221.0 198.0 09:04:44 INFO - PROCESS | 1654 | 09:04:44 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.70 Median:64.00 stddev:1.57 (2.4%) stddev-sans-first:1.54 09:04:44 INFO - PROCESS | 1654 | Values: 62.0 66.0 66.0 63.0 64.0 62.0 64.0 65.0 63.0 62.0 09:04:44 INFO - PROCESS | 1654 | -------- Summary: end -------- 09:04:44 INFO - PROCESS | 1654 | 09:04:45 INFO - TEST-INFO | 1654: exit 0 09:04:45 INFO - TEST-OK | kraken | took 59788ms 09:04:45 INFO - SUITE-END | took 1175s 09:04:45 INFO - Completed test suite (00:19:35) 09:04:45 INFO - javascript benchmark 09:04:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7991.82930410299, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3399.881004164855, 3674.3202507536107, 3703.0, 3703.462997865311, 3707.4068149096142, 6222.719977601013, 6225.813225603228, 6237.5009999200065, 6255.311915689271, 6263.248410190783, 6219.813405597839, 6223.472461059817, 6226.782062627809, 6234.438900498954, 6256.217972753406, 5659.292588426457, 5673.319201695802, 5673.744681489343, 5717.142428635696, 5755.654660720349, 4579.885502862438, 4868.026394721052, 5739.454751798572, 5740.196372507831, 5761.711914404284, 5526.447355264481, 5539.307586551681, 5742.2247996520355, 5746.511546518551, 5801.216835727186, 5662.037453632893, 5666.744996475169, 5669.688167150805, 5693.373728889819, 5745.195672605839, 4884.8032232103005, 5522.3649280332875, 5566.638168519034, 5650.152477128464, 5685.630434021776, 6101.877962440771, 6269.028300613367, 6271.435570798604, 6275.6548389838545, 6290.62256264621, 5922.555808314393, 6120.296165940885, 6476.5466417350335, 6501.772437964696, 6573.441257493071, 6743.696533655997, 6782.559133656297, 6792.150981127359, 6792.626405547693, 6801.693923773442, 6261.8747625047245, 6285.905711414287, 6288.8113356598915, 6314.71583778731, 6333.778317758856, 6630.104935833697, 6698.062271282024, 6820.897686534706, 6947.305269473113, 6990.231074581792, 6469.029645553204, 6508.349165083454, 6538.934610653832, 6542.476601871839, 6552.180977377828, 6693.698783554752, 6711.899321510181, 6742.123523941857, 6751.864962700718, 6764.0530325754435, 6318.557700960889, 6618.735250590019, 6643.302453242437, 6645.3022432643875, 6663.100481434944, 5298.602604804655, 6456.999165129412, 6491.448226900767, 6493.707783149675, 6506.089147519351, 5345.732713364317, 5369.731513424391, 5378.462153784591, 5381.973090134525, 5405.189221616789, 4730.574248317668, 4754.215554433549, 4778.259369797687, 4778.4982576829625, 4785.856424307276, 6164.537659675452, 6234.314225435199, 6241.126242326078, 6248.094026366151, 6282.623042617366, 6697.296783837724, 6767.35710107539, 6767.35710107539, 6772.52592318533, 6787.490938179656, 6316.021016742412, 6327.303996560375, 6334.334894836068, 6338.7464501420345, 6348.936510634834, 5707.086866101404, 5787.479126878601, 5790.392008839011, 5857.355690874, 5928.703564821828, 6770.45836333102, 6866.279040700654, 6894.103766510522, 6955.617441040791, 6981.3367730065565, 6775.390214880685, 6778.423833974168, 6787.219469760843, 6789.1174147360525, 6794.3545363190415, 6893.482988775761, 6934.063901373452, 6942.548734332252, 6987.196472405535, 6995.75514856998, 6323.114763933145, 6369.7452101915405, 6494.870102597827, 6512.706928188148, 6529.738810447719, 6724.966375168093, 6751.932480675327, 6755.324467553205, 6774.085498457842, 6776.254611992578, 6166.969165154145, 6341.239051313872, 6410.551261411656, 6443.09796628463, 6554.377334153248, 6561.507886908596, 6572.539922205399, 6587.835304117435, 6594.340565943367, 6630.204375474974, 6169.444749972524, 6257.843553911189, 6285.088662144055, 6316.494680425463, 6390.4248617624635, 6494.967525162344, 6519.967400162969, 6577.3422657733845, 6594.043863639649, 6600.900986485302, 5299.417064122867, 6323.905141422791, 6441.291457939717, 6456.967715161394, 6470.450011749054], "value": 6126.3342334469235, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [821.8315245374698, 845.3786466946794, 975.1126474907835, 978.7553111722069, 988.4662282367517, 39237.60762392375, 41467.37798933018, 41485.0, 41513.0, 41575.79212103939, 40092.799536002276, 40331.395029074585, 40333.79833100831, 40369.79815100928, 40447.59552404475, 8272.131426200238, 11265.493052812642, 11503.712407189803, 11567.826482602768, 11711.12166587505, 9412.247020238365, 11408.20142590019, 11412.029977451884, 11414.486348114355, 11455.828162577567, 11303.152263580223, 11386.772264554704, 11390.886091139108, 11393.48729307179, 11440.313581185113, 11306.265092769027, 11372.829407558893, 11402.2588531746, 11403.0, 11543.826842597367, 11221.38282394468, 11266.943665281704, 11275.492602832892, 11293.774124517464, 11294.378809165493, 35345.823270883484, 35457.46813797795, 35738.463923040916, 35852.282954341026, 36000.819995900114, 3983.008495752124, 4005.399190121505, 4034.6368826805433, 4035.59644035597, 4061.4923134608175, 26118.608220876704, 26164.0, 26241.606375904375, 27237.591436128474, 27665.0, 3187.0, 3262.559554460141, 3266.2324353776844, 3320.5517255170485, 3406.148462884279, 296141.0, 364040.0, 365656.3434365649, 365775.0, 370299.9999999973, 37838.0, 38671.0, 44668.55331446709, 45612.543874561496, 45690.0, 46291.537084629395, 46678.29982550265, 46786.532134678215, 46818.5318146821, 46871.0, 13770.449182032808, 13905.304734763424, 13970.720585588231, 13980.58058258254, 13985.090969086978, 11974.940125299318, 12018.87981120195, 12072.0, 12104.213226140273, 12231.571894983634, 11982.34097124649, 12004.0, 12038.578649747393, 12061.457234424472, 12082.697932551757, 5893.5579831512805, 5904.291485021825, 5918.7928422505, 5921.6743079131065, 5933.199018132583, 1160.5589875847293, 1176.9529218831321, 1191.2018947305153, 1201.3692811274152, 1215.1068964311223, 27069.0, 27296.727032729417, 27530.311742206206, 27627.86186069057, 27808.721912780613, 1099.5931505343074, 1100.372787511111, 1272.8790764877322, 1310.3055380648273, 1315.967100822487, 12596.81104783429, 16575.088370139758, 16726.74909876353, 17321.653566928842, 17441.47675569735, 514587.0, 532744.0, 538444.0, 538789.0, 543371.0, 65102.023469648, 65197.348026519125, 65866.34133658698, 66026.00960985589, 66031.00953485702, 1556.0741358891441, 1558.2208895552224, 1592.076595574564, 1597.209381356236, 1606.3654856331684, 26934.730652694005, 28367.574486383135, 29315.0, 29899.402011960075, 29947.850260748557, 47876.0, 48773.512264876896, 49691.75154124351, 50844.0, 50895.982080358925, 517499.0, 518429.0, 520016.0, 520557.0, 522455.0, 27285.86357068202, 27937.0, 32102.357952840346, 33463.16342091467, 33806.49290260599, 34654.65345346615, 36951.52193912213, 39566.0, 40233.99415014648, 40477.79761101176, 28294.717052829208, 28882.711172888, 29148.27129321784, 29228.26929326699, 29828.0, 29091.7090829089, 29176.7082329174, 29200.0, 29736.0, 29796.0, 33679.32641347208, 33964.660353396146, 34024.65975340215, 34090.488642670876, 34125.48811767875, 22794.51835630745, 28510.002149924654, 29415.705842941297, 29627.40745185127, 29678.0, 16901.74647380315, 23291.0, 23737.52524949526, 23782.643260351455, 24165.51668966646], "value": 19213.457362430632, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [425.83818149103337, 434.6023388599432, 436.6855863778081, 437.4772147283995, 447.6262320961999, 20659.690104648424, 21203.681944770822, 22870.428239293986, 22883.771162288394, 23115.768842311612, 18171.0, 23210.303690889257, 23817.761822381814, 23925.04299828005, 24023.039078436843, 6426.80719578411, 6959.338862808037, 7065.622203670287, 7179.784606461814, 7589.924100759004, 7871.330936870344, 7986.600669966508, 7988.880166797503, 7990.560519171443, 8022.478538894982, 7731.922680773205, 8081.919180808205, 8153.8776918346275, 8182.4681395709085, 8195.877061844078, 7386.704531818694, 7700.614969251571, 7817.101033381177, 7984.520928744294, 8048.637811298477, 7452.627368631547, 7638.350740187091, 7643.732469363601, 7643.770686879402, 7796.220377962215, 22921.083156673714, 27235.0, 27370.45259094827, 27496.862515687495, 27738.0, 3001.714837090473, 3014.8794048238046, 3068.6777888321626, 3086.90739277822, 3092.6443459002053, 17141.142942852806, 17334.566635834206, 17444.0, 17495.0, 17564.560885977826, 2189.6606026065983, 2242.6523888797265, 2290.9656355154684, 2312.90748370065, 2529.8988040478357, 155255.2237238818, 164549.94975276475, 165615.0, 166179.0, 166258.0, 16847.57881052959, 18757.842848072647, 19314.841109533474, 21778.455538611663, 22128.44678883041, 32500.0, 32707.345853082807, 33425.0, 33432.3313533728, 33447.33105337928, 10728.249022568501, 10854.62008829703, 11148.581826812731, 11196.496157672924, 11358.375289358999, 7698.307152356203, 9197.448153110701, 9235.630574776933, 9296.814063718688, 9454.385464944757, 7914.841703165905, 9925.454100024568, 10020.799584008279, 10123.544440500194, 10165.034321739422, 4129.194807012664, 4154.646855017358, 4170.47869016373, 4198.6431153352305, 4222.831086756495, 953.3850666320185, 1040.843873418978, 1062.53370348918, 1070.1866581398037, 1114.053054903342, 21746.56506869854, 22039.779602204093, 22058.88970555137, 22079.0, 22147.778522214892, 1046.874375074996, 1047.1361127070197, 1052.221356196409, 1084.1164450972433, 1086.5002099034532, 11093.889061109447, 11390.316581005001, 11391.943040284745, 11410.372429516456, 11486.42567871603, 550109.0, 658683.4131658622, 682082.5895870489, 728063.0, 768578.0, 147706.3073423173, 173727.13136434238, 175411.0, 175881.0, 182364.0, 1289.7549465601505, 1322.418136020148, 1346.5017943360824, 1350.8581598931971, 1352.783554631274, 19848.40454786359, 20030.0, 20431.0, 20471.795282047584, 20492.795072049685, 43523.78238108789, 43564.78217608892, 54043.72978135084, 57814.132788009054, 59548.40451595429, 61772.0, 96097.30311976527, 96448.0, 96914.51542742523, 98369.52445713463, 11650.951414372747, 11729.882701172879, 11968.281903085843, 12001.099917506397, 12029.518819247482, 13039.608811735663, 13457.663558410726, 13501.932490337485, 13503.45986160582, 13827.101238419471, 11450.370229637118, 11478.82781758291, 11751.412429378668, 11879.930806227481, 11988.280703157838, 10894.67315980522, 10914.617988370368, 11009.339439633648, 11040.0, 11189.888101118884, 10135.442550659785, 11651.88348116508, 11695.356755378536, 11897.405129743305, 12001.339926304137, 11714.941425292818, 11780.352080635304, 11938.403079845799, 11970.281783093042, 12052.397380130784, 11414.48634811419, 11474.196806223485, 11540.24988375753, 11697.883021169679, 11705.590304339306], "value": 12143.577957568466, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1614.7981502312211, 1677.312301956198, 1685.384834535395, 1696.0247857481943, 1704.3864208884802, 11142.442877856116, 11562.53749850005, 11895.881041189585, 11939.880601193985, 11994.520219191221, 11766.764664706701, 11783.646490605295, 11991.820122698167, 12004.159708820363, 12022.51909923602, 2456.533258680854, 2478.603423452248, 2491.9377015574573, 2498.750124987496, 2499.212747984388, 2429.8056155507516, 2435.5007223519137, 2469.1358024691303, 2474.146419485284, 2474.232987773787, 2434.573949558829, 2445.9877700611473, 2454.0797201049604, 2465.9876700616473, 2470.888810003554, 2406.518696260755, 2424.212131057405, 2430.5746494363502, 2437.0, 2437.305367970129, 2019.8788072715683, 2314.143766806276, 2424.381782645414, 2440.0849681369486, 2442.023190723707, 11511.769764604745, 11703.0, 11714.589989350417, 11716.53133874644, 11760.764784704343, 5858.443447872445, 5979.820605381846, 5983.282006159246, 6054.878902421971, 6063.9090413643835, 3558.1638314995957, 3612.259486805199, 3676.6323367663285, 3678.1540245743627, 3702.8703995360033, 1260.2627462934195, 1261.9432125554372, 1299.7660421124194, 1308.319673769644, 1310.6461255461068, 13184.0, 13429.194248345131, 14982.400703971827, 15894.52316430509, 16037.83962160375, 4647.0938167057775, 4740.265258884879, 4772.618190544748, 4778.116048531033, 4783.0, 12078.818817717565, 12174.57388991381, 12203.45084471201, 12215.877841221474, 12241.326727030098, 5415.9438909413075, 5447.264619276426, 5827.038538641077, 5851.590388672752, 5901.7639294427745, 4945.738836596717, 5235.58115350771, 5345.0, 5473.781048758085, 5477.260569823103, 3092.6134233220846, 3232.9030129096163, 3991.4212439196735, 3994.08136128692, 3994.500687414073, 883.1962913748458, 1031.8142734307746, 1032.1381646325253, 1043.3166276089173, 1045.6967479431032, 830.767385132164, 833.3250067445389, 906.71891713569, 971.0920289529313, 976.1135191225969, 5607.299087614048, 5900.940990590039, 6396.5202609804455, 6763.628000460022, 6792.966035169891, 875.2472873328932, 1005.8491226315965, 1018.6332920148741, 1044.5717255925035, 1082.1667316166508, 4125.50493940729, 4132.359484279941, 4194.811233494439, 4318.935215971763, 4344.695871288979, 10862.619808306672, 13759.312034398241, 14002.369893354824, 14221.502247421498, 14325.997180197495, 11774.293542387484, 14026.509072182425, 14142.434302627986, 14242.359093840802, 14285.357158927873, 10284.742881428025, 10377.481125943672, 10432.06111449958, 10482.318649287772, 10486.636737224264, 6469.320721324381, 6492.577982431127, 6575.736970521126, 6699.229588597374, 6709.329067093252, 9889.258305626963, 12198.268103913793, 12425.19236249641, 12604.243745375306, 12665.81001284963, 11479.713007174554, 11548.884511155116, 12601.936990314989, 12965.22208667483, 13071.019673524714, 9237.722868313962, 9521.333506654744, 9532.952335238557, 9634.084761947604, 9640.276979226446, 10490.475476226311, 10493.2130090242, 10599.735006624896, 10601.734956626146, 10659.25385223027, 9139.040400757825, 9145.885100140738, 9201.079892010745, 9365.719028429157, 9396.530173491436, 8868.423552469068, 8885.289176865965, 8931.866022009805, 8977.506237157018, 9073.500957447404, 8256.422050456411, 8282.0, 8314.87527687073, 8387.83224335522, 8401.579921003804, 5391.568674506108, 5453.400125986059, 5982.760689572542, 6066.211392518944, 6106.267247930276, 6043.8186854394435, 6056.273247210363, 6084.695765211633, 6148.600340977822, 6292.244930608356], "value": 5219.002335785593, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.726618705036, 355.47744815121763, 367.42131143449075, 371.90144611677925, 384.799904049894, 4680.625549956004, 5093.567046801017, 5418.9458105418935, 5428.45715428457, 5594.615257769052, 4661.976690116553, 4962.602991760659, 5292.497212764791, 5372.194170874371, 5438.972805135979, 4055.3308704063793, 4494.943688233263, 4521.480029796583, 4521.706089104214, 4526.52471490492, 3815.7789507357656, 4127.988642782522, 4234.9153016939645, 4243.151369726052, 4460.79926403311, 3790.7915064671433, 4065.8170382332864, 4263.467066616673, 4401.0, 4495.43807024122, 4100.384942258685, 4480.574345437179, 4497.955020449786, 4518.819247230123, 4624.8150073997, 4024.3561030235023, 4093.3246014407587, 4141.399497072907, 4149.33610622299, 4165.0, 3987.8604248851443, 4627.490975992638, 5191.44386095522, 5206.921896171561, 5232.607554433433, 4567.885802854922, 4817.169475599285, 5089.706705026768, 5115.18157094863, 5195.766190521435, 4924.6060315175055, 5071.594272458231, 5808.448197421239, 5853.85365365865, 5908.0, 3855.151866589346, 4153.231652144363, 4220.725652832587, 4323.286657701476, 4558.202314594966, 5255.973720131413, 5296.92054619181, 5934.317553481362, 5936.584439089309, 6186.979148440502, 4348.695591308578, 4890.070886531547, 5036.496350364934, 5086.618503612244, 5148.742562871841, 4472.284434490466, 4703.95296047042, 5071.996561358243, 5254.658447200996, 5318.308619879468, 4760.285957106462, 5110.310108135354, 5275.076861549213, 5361.284388995562, 5515.0, 5167.457416971164, 5219.791208351623, 5420.864478388072, 5577.470140336662, 5847.024023663865, 1611.8729664451162, 1676.488670955346, 1700.681971472119, 1813.7279408088891, 1839.117223732616, 1307.4770091963292, 1349.9730005399838, 1431.4918204037417, 1452.1359790924382, 1480.8000919875894, 4724.622030237572, 5034.370703662043, 5181.341970569381, 5351.491608297206, 5379.273798037215, 5070.391553013662, 5291.756437237246, 5454.59090568209, 5596.692181930033, 5893.631043917647, 4689.437267527918, 4823.252395878644, 4946.752662366867, 5431.293931788842, 5464.836054918359, 2753.650711151521, 2996.2060054085687, 3088.9536656950163, 3088.984555077255, 3102.674219206951, 5418.593605479605, 5703.600747947687, 6014.293567894378, 6155.384461553809, 6336.746530138743, 5745.770169193269, 5809.68046757424, 6114.38856114385, 6239.719212635351, 6251.187345645128, 5498.642588231752, 6096.969515152396, 6246.906296405557, 6292.968535157295, 6299.370062993756, 5672.886542269214, 5680.2794169818835, 5877.324107727666, 5905.055191169564, 6072.939270607238, 5056.60943240597, 5398.922614110077, 5538.307711536058, 5567.800718716166, 5691.288588926384, 5210.843674689765, 5629.986602411605, 5890.263717035371, 6009.669468179292, 6309.842253943688, 4284.335927931175, 4321.2005778931, 5026.346574945233, 5077.045337544998, 5114.0, 4807.086653535956, 5480.095784195563, 5662.292213473316, 5713.20034189229, 5972.940270597239, 4977.726225057657, 5578.52169175177, 5820.970895145497, 5866.530677545871, 5867.274280831617, 5405.432414606341, 5566.693831839126, 5602.551795856403, 5639.067684803961, 5873.618214816023, 4720.575148236675, 5116.948830511647, 5285.260063591023, 5379.310344827586, 5531.197976293495, 4744.952550474451, 5477.0, 5861.779518746495, 5868.735906884286, 5932.4279066046665, 4768.546988036131, 5246.662101164179, 5386.7037312946595, 5644.520215781705, 5690.971545142248], "value": 4416.244014986221, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [768.9384849212064, 779.4543819326473, 816.6774124220933, 838.3460900497611, 900.4597241655006, 15469.84530154698, 17858.910705446488, 17891.64216715665, 17904.194311255993, 17913.820861791395, 12840.871591284083, 13800.723985520284, 15645.530634080968, 15937.804664650168, 16264.512064638051, 990.2721499697734, 1023.6570748799124, 1028.7068185567116, 1031.6440827914357, 1032.6179313653959, 982.287841315048, 1016.623849175806, 1019.0777346501428, 1022.2639699416409, 1035.4874337203057, 1050.721558786922, 1066.072516910289, 1067.3915867955268, 1070.4701172919376, 1071.8928107189258, 1053.6944286157004, 1063.7127975446585, 1063.7925604507125, 1064.0, 1064.4943651765427, 782.7690831204808, 789.5617932047711, 793.274154148953, 793.436659971421, 795.9602019898982, 17523.912380438145, 17769.822301777076, 17809.46571602854, 18221.0889455528, 18390.908045459822, 12445.813312800316, 12955.89874860638, 14080.92959535206, 15038.774418383733, 15113.697726045417, 12062.0, 12147.574834880737, 12238.755224895453, 12406.56577019809, 12421.00631949442, 3847.4036524338494, 3944.582550776915, 3976.5824588418377, 4001.1797581495725, 4104.979475102636, 112100.0, 113449.2223238791, 115265.27102093476, 115312.84687153104, 115642.0, 11509.194356394972, 11546.249493782878, 11717.355545445082, 11827.822582661267, 11828.763424731456, 23887.283381498582, 24260.0, 24294.635580466307, 24324.513509729706, 24363.878180608983, 8134.430589858653, 8196.5491897945, 8220.876686849702, 8250.38122140842, 8323.791905202297, 3903.4535165076913, 4442.866713998521, 4589.816407343736, 4638.582527572467, 4668.743219122913, 3329.0, 3558.3061303045656, 3619.60184379718, 3683.097641077953, 3703.9444408333898, 661.5567569728294, 668.7759600533876, 678.2114651449851, 687.1753895325629, 696.310652454077, 612.146537546148, 731.9853602927911, 737.9889301660479, 757.2011527838183, 774.8101715079841, 10224.744381390525, 10271.794564108675, 10715.624953126604, 11146.052585530158, 11218.214724969173, 540.7566595032251, 597.3966294043048, 599.9820005399757, 744.6835095084567, 760.9239076092457, 4232.195882782262, 5011.924821127686, 5147.621461010204, 5230.555402790806, 5279.41926388097, 85935.14064859397, 89082.55458722665, 91818.0, 97202.51398743103, 103840.48079759553, 87053.0, 88736.13326067684, 89202.55398723095, 91509.0, 92294.53852730828, 57149.71425142848, 77053.0, 78545.21454785494, 81001.0, 81254.37491250141, 9959.751006224902, 11089.500972456097, 11126.388048657402, 11188.776224475301, 11269.267497612629, 48934.0, 50492.49507504878, 50540.483785486496, 51091.0, 52224.738876305375, 58181.70909145427, 66719.99920001302, 69487.65256173686, 69727.65136174287, 69785.0, 12529.561465349034, 12834.229946203257, 13121.803172952605, 13178.60464186076, 13264.403101860633, 14493.492727754478, 14658.853411465749, 15129.0, 15170.31733572014, 15195.316210770321, 10725.249232553959, 11631.6510504685, 12857.0, 13444.260565668983, 13515.121517101357, 13127.474901003852, 13851.445942162201, 13879.583612491642, 13949.093308934887, 14001.089929154572, 13390.26353550564, 13837.238951857744, 14033.719325613227, 14081.718365632834, 14266.286685665882, 9943.30396872212, 10249.0, 10378.755503433722, 10499.842502362319, 10669.733256668645, 10347.689569312932, 10505.264631475724, 11129.944350278196, 11367.772644547229, 11636.592719254786], "value": 7907.589232518129, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1498.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 120.0, 118.0, 119.0, 120.0, 119.0, 120.0, 121.0, 118.0, 176.0], "value": 124.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 116.0, 120.0, 145.0, 111.0, 114.0, 110.0, 109.0, 128.0, 109.0], "value": 120.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 203.0, 197.0, 193.0, 199.0, 194.0, 195.0, 193.0, 200.0, 199.0], "value": 197.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 61.0, 87.0, 62.0, 76.0, 56.0, 100.0, 64.0, 70.0], "value": 71.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 84.0, 74.0, 72.0, 70.0, 82.0, 71.0, 69.0, 78.0], "value": 75.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 93.0, 125.0, 90.0, 89.0, 89.0, 104.0, 88.0, 91.0], "value": 94.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 97.0, 149.0, 97.0, 96.0, 98.0, 97.0, 95.0, 108.0], "value": 102.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 91.0, 126.0, 105.0, 144.0, 141.0, 92.0, 91.0, 91.0], "value": 106.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 66.0, 71.0, 70.0, 70.0, 71.0, 71.0, 69.0], "value": 68.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 61.0, 62.0, 63.0, 56.0, 62.0, 86.0, 84.0, 88.0, 68.0], "value": 68.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 83.0, 81.0, 85.0, 87.0, 83.0, 87.0, 89.0, 100.0], "value": 86.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 145.0, 136.0, 161.0, 153.0, 150.0, 147.0, 138.0, 136.0, 139.0], "value": 143.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 165.0, 161.0, 162.0, 164.0, 163.0, 165.0, 183.0, 221.0, 198.0], "value": 174.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 66.0, 66.0, 63.0, 64.0, 62.0, 64.0, 65.0, 63.0, 62.0], "value": 63.7, "unit": "score"}]}]} 09:04:45 INFO - Return code: 0 09:04:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:04:45 ERROR - # TBPL SUCCESS # 09:04:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:04:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:04:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:04:45 INFO - 2017-05-23 09:04:45.703 screenresolution[1659:10635] starting screenresolution argv=screenresolution get 09:04:45 INFO - 2017-05-23 09:04:45.705 screenresolution[1659:10635] Display 0: 1600x1200x32@60 09:04:45 INFO - 2017-05-23 09:04:45.711 screenresolution[1660:10638] starting screenresolution argv=screenresolution list 09:04:45 INFO - Available Modes on Display 0 09:04:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:04:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:04:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:04:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:04:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:04:45 INFO - Intel Iris: 09:04:45 INFO - Chipset Model: Intel Iris 09:04:45 INFO - Type: GPU 09:04:45 INFO - Bus: Built-In 09:04:45 INFO - VRAM (Dynamic, Max): 1536 MB 09:04:45 INFO - Vendor: Intel (0x8086) 09:04:45 INFO - Device ID: 0x0a2e 09:04:45 INFO - Revision ID: 0x0009 09:04:45 INFO - Displays: 09:04:45 INFO - Display: 09:04:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:04:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:04:45 INFO - Display Serial Number: 1600x1200 60 09:04:45 INFO - Main Display: Yes 09:04:45 INFO - Mirror: Off 09:04:45 INFO - Online: Yes 09:04:45 INFO - Rotation: Supported 09:04:45 INFO - Return code: 0 09:04:45 INFO - Running post-action listener: _resource_record_post_action 09:04:45 INFO - [mozharness: 2017-05-23 16:04:45.946735Z] Finished run-tests step (success) 09:04:45 INFO - Running post-run listener: _resource_record_post_run 09:04:46 INFO - Total resource usage - Wall time: 1191s; CPU: 54.0%; Read bytes: 39962624; Write bytes: 426045952; Read time: 253; Write time: 1460 09:04:46 INFO - TinderboxPrint: CPU usage
54.4% 09:04:46 INFO - TinderboxPrint: I/O read bytes / time
39,962,624 / 253 09:04:46 INFO - TinderboxPrint: I/O write bytes / time
426,045,952 / 1,460 09:04:46 INFO - TinderboxPrint: CPU idle
2,171.2 (45.6%) 09:04:46 INFO - TinderboxPrint: CPU system
954.3 (20.0%) 09:04:46 INFO - TinderboxPrint: CPU user
1,640.2 (34.4%) 09:04:46 INFO - TinderboxPrint: Swap in / out
162,484,224 / 0 09:04:46 INFO - install - Wall time: 16s; CPU: 53.0%; Read bytes: 117760512; Write bytes: 115983872; Read time: 10834; Write time: 262 09:04:46 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 38008832; Write bytes: 299269120; Read time: 233; Write time: 1163 09:04:46 INFO - Running post-run listener: _upload_blobber_files 09:04:46 INFO - Blob upload gear active. 09:04:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:04:46 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 09:04:46 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'] 09:04:46 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 09:04:46 INFO - (blobuploader) - INFO - Open directory for files ... 09:04:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:04:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:04:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:04:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:04:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:04:48 INFO - (blobuploader) - INFO - Done attempting. 09:04:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:04:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:04:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:04:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:04:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:04:49 INFO - (blobuploader) - INFO - Done attempting. 09:04:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:04:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:04:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:04:50 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:04:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:04:50 INFO - (blobuploader) - INFO - Done attempting. 09:04:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:04:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:04:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:04:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:04:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:04:52 INFO - (blobuploader) - INFO - Done attempting. 09:04:52 INFO - (blobuploader) - INFO - Iteration through files over. 09:04:52 INFO - Return code: 0 09:04:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:04:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:04:52 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b92a91aea2ded64a68c729de4391e9861fa91598a08a99ecd1df0d1b6e50dfefd8930be49b7ddfc16e1193ad0ca0bb2a1b3411c43b7c8e666d15a53f7655976e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/33ae7060bddda7dba93a0d83df435f511f9d54859836e5a12fe3d60cb46367ffe8202025660c582c315ea3f25a7ec462adfe4c03cf47b16ddaa930670cb18529", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/662ea3ab18feab847f267f6c42ff4b4746b250ed3fafe84d70628af47499421ae45773b56530c4e41f6f13bc2cea465ffbaa4a63e74840d7c8a3e2992ae3d0e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f2f112792a6dafe9401652cf29a75c7867b05fa5d60b362dad12688b53aae276f51c59dbe4e7ebb26db5d6c5033f2a8a80462d8d571a75e22452c621ba489824"} 09:04:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:04:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:04:52 INFO - Contents: 09:04:52 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b92a91aea2ded64a68c729de4391e9861fa91598a08a99ecd1df0d1b6e50dfefd8930be49b7ddfc16e1193ad0ca0bb2a1b3411c43b7c8e666d15a53f7655976e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/33ae7060bddda7dba93a0d83df435f511f9d54859836e5a12fe3d60cb46367ffe8202025660c582c315ea3f25a7ec462adfe4c03cf47b16ddaa930670cb18529", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/662ea3ab18feab847f267f6c42ff4b4746b250ed3fafe84d70628af47499421ae45773b56530c4e41f6f13bc2cea465ffbaa4a63e74840d7c8a3e2992ae3d0e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f2f112792a6dafe9401652cf29a75c7867b05fa5d60b362dad12688b53aae276f51c59dbe4e7ebb26db5d6c5033f2a8a80462d8d571a75e22452c621ba489824"} 09:04:52 INFO - Running post-run listener: copy_logs_to_upload_dir 09:04:52 INFO - Copying logs to upload dir... 09:04:52 INFO - mkdir: /builds/slave/test/build/upload/logs 09:04:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.546699 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-23 09:04:52.381088) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-23 09:04:52.384425) ========= 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.HLOyCfiZXC/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.fvL13g7au9/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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/b92a91aea2ded64a68c729de4391e9861fa91598a08a99ecd1df0d1b6e50dfefd8930be49b7ddfc16e1193ad0ca0bb2a1b3411c43b7c8e666d15a53f7655976e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/33ae7060bddda7dba93a0d83df435f511f9d54859836e5a12fe3d60cb46367ffe8202025660c582c315ea3f25a7ec462adfe4c03cf47b16ddaa930670cb18529", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/662ea3ab18feab847f267f6c42ff4b4746b250ed3fafe84d70628af47499421ae45773b56530c4e41f6f13bc2cea465ffbaa4a63e74840d7c8a3e2992ae3d0e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f2f112792a6dafe9401652cf29a75c7867b05fa5d60b362dad12688b53aae276f51c59dbe4e7ebb26db5d6c5033f2a8a80462d8d571a75e22452c621ba489824"} build_url:https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011101 build_url: 'https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/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/b92a91aea2ded64a68c729de4391e9861fa91598a08a99ecd1df0d1b6e50dfefd8930be49b7ddfc16e1193ad0ca0bb2a1b3411c43b7c8e666d15a53f7655976e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/33ae7060bddda7dba93a0d83df435f511f9d54859836e5a12fe3d60cb46367ffe8202025660c582c315ea3f25a7ec462adfe4c03cf47b16ddaa930670cb18529", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/662ea3ab18feab847f267f6c42ff4b4746b250ed3fafe84d70628af47499421ae45773b56530c4e41f6f13bc2cea465ffbaa4a63e74840d7c8a3e2992ae3d0e4", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f2f112792a6dafe9401652cf29a75c7867b05fa5d60b362dad12688b53aae276f51c59dbe4e7ebb26db5d6c5033f2a8a80462d8d571a75e22452c621ba489824"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-23 09:04:52.417499) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 09:04:52.418037) ========= 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.HLOyCfiZXC/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.fvL13g7au9/Listeners TMPDIR=/var/folders/c_/gxd8chw13618g2dtm2y0dy8000000w/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.005141 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 09:04:52.466640) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-23 09:04:52.467006) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-23 09:04:52.467331) ========= ========= Total master_lag: 0.17 =========