builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0082 starttime: 1496344561.66 results: success (0) buildid: 20170601104724 builduid: e777195bf60d4f09a1fd20ec4d99811f revision: 4cc48c061ec5b88c23fe45e2ee2dcf6e7451ff1e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 12:16:01.663927) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 12:16:01.664411) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 12:16:01.664704) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 12:16:01.689975) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 12:16:01.690341) ========= 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.eBrtds7zOO/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.czkEPZZqsv/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-01 12:16:01-- 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% 23.4M=0s 2017-06-01 12:16:01 (23.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.228290 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 12:16:01.941878) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 12:16:01.942198) ========= 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.eBrtds7zOO/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.czkEPZZqsv/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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.028400 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 12:16:01.991828) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-01 12:16:01.992183) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 4cc48c061ec5b88c23fe45e2ee2dcf6e7451ff1e --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 4cc48c061ec5b88c23fe45e2ee2dcf6e7451ff1e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.eBrtds7zOO/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.czkEPZZqsv/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-01 12:16:02,086 truncating revision to first 12 chars 2017-06-01 12:16:02,087 Setting DEBUG logging. 2017-06-01 12:16:02,087 attempt 1/10 2017-06-01 12:16:02,087 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/4cc48c061ec5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-01 12:16:03,099 unpacking tar archive at: mozilla-beta-4cc48c061ec5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.293236 ========= master_lag: 0.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-01 12:16:03.409955) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 12:16:03.410274) ========= script_repo_revision: 4cc48c061ec5b88c23fe45e2ee2dcf6e7451ff1e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 12:16:03.410705) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 12:16:03.410993) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 12:16:03.430310) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-06-01 12:16:03.430684) ========= /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.eBrtds7zOO/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.czkEPZZqsv/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 12:16:03 INFO - MultiFileLogger online at 20170601 12:16:03 in /builds/slave/test 12:16:03 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 12:16:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:16:03 INFO - {'append_to_log': False, 12:16:03 INFO - 'base_work_dir': '/builds/slave/test', 12:16:03 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:16:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:16:03 INFO - 'branch': 'Mozilla-Beta', 12:16:03 INFO - 'buildbot_json_path': 'buildprops.json', 12:16:03 INFO - 'config_files': ('talos/mac_config.py',), 12:16:03 INFO - 'default_actions': ('clobber', 12:16:03 INFO - 'read-buildbot-config', 12:16:03 INFO - 'download-and-extract', 12:16:03 INFO - 'populate-webroot', 12:16:03 INFO - 'create-virtualenv', 12:16:03 INFO - 'install', 12:16:03 INFO - 'run-tests'), 12:16:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:16:03 INFO - 'download_minidump_stackwalk': True, 12:16:03 INFO - 'download_symbols': 'ondemand', 12:16:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:16:03 INFO - 'tooltool.py': '/tools/tooltool.py', 12:16:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:16:03 INFO - '/tools/misc-python/virtualenv.py')}, 12:16:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:16:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:16:03 INFO - 'gecko_profile': False, 12:16:03 INFO - 'gecko_profile_interval': 0, 12:16:03 INFO - 'installer_path': 'installer.exe', 12:16:03 INFO - 'log_level': 'info', 12:16:03 INFO - 'log_name': 'talos', 12:16:03 INFO - 'log_to_console': True, 12:16:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:16:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:16:03 INFO - 'opt_config_files': (), 12:16:03 INFO - 'pip_index': False, 12:16:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:16:03 INFO - 'cmd': ('bash', 12:16:03 INFO - '-c', 12:16:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:16:03 INFO - 'enabled': True, 12:16:03 INFO - 'halt_on_failure': False, 12:16:03 INFO - 'name': 'check_screen_resolution'},), 12:16:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:16:03 INFO - 'cmd': ('bash', 12:16:03 INFO - '-c', 12:16:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:16:03 INFO - 'enabled': True, 12:16:03 INFO - 'halt_on_failure': False, 12:16:03 INFO - 'name': 'check_screen_resolution'},), 12:16:03 INFO - 'run_cmd_checks_enabled': True, 12:16:03 INFO - 'suite': 'dromaeojs-e10s', 12:16:03 INFO - 'system_bits': '32', 12:16:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:16:03 INFO - 'title': 't-yosemite-r7-0082', 12:16:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:16:03 INFO - 'use_talos_json': True, 12:16:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:16:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:16:03 INFO - 'work_dir': 'build'} 12:16:03 INFO - [mozharness: 2017-06-01 19:16:03.637177Z] Running clobber step. 12:16:03 INFO - Running pre-action listener: _resource_record_pre_action 12:16:03 INFO - Running main action method: clobber 12:16:03 INFO - rmtree: /builds/slave/test/build 12:16:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:16:06 INFO - Running post-action listener: _resource_record_post_action 12:16:06 INFO - [mozharness: 2017-06-01 19:16:06.259652Z] Finished clobber step (success) 12:16:06 INFO - [mozharness: 2017-06-01 19:16:06.259894Z] Running read-buildbot-config step. 12:16:06 INFO - Running pre-action listener: _resource_record_pre_action 12:16:06 INFO - Running main action method: read_buildbot_config 12:16:06 INFO - Using buildbot properties: 12:16:06 INFO - { 12:16:06 INFO - "project": "", 12:16:06 INFO - "product": "firefox", 12:16:06 INFO - "script_repo_revision": "production", 12:16:06 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 12:16:06 INFO - "repository": "", 12:16:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 12:16:06 INFO - "buildid": "20170601104724", 12:16:06 INFO - "pgo_build": "False", 12:16:06 INFO - "basedir": "/builds/slave/test", 12:16:06 INFO - "buildnumber": 8, 12:16:06 INFO - "slavename": "t-yosemite-r7-0082", 12:16:06 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 12:16:06 INFO - "platform": "yosemite_r7_devedition", 12:16:06 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 12:16:06 INFO - "branch": "mozilla-beta", 12:16:06 INFO - "repo_path": "releases/mozilla-beta", 12:16:06 INFO - "revision": "4cc48c061ec5b88c23fe45e2ee2dcf6e7451ff1e", 12:16:06 INFO - "stage_platform": "macosx64-devedition", 12:16:06 INFO - "builduid": "e777195bf60d4f09a1fd20ec4d99811f", 12:16:06 INFO - "slavebuilddir": "test" 12:16:06 INFO - } 12:16:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/RxCK7X--TjuCfQ-_6UkMMg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 12:16:06 INFO - Running post-action listener: _resource_record_post_action 12:16:06 INFO - [mozharness: 2017-06-01 19:16:06.265718Z] Finished read-buildbot-config step (success) 12:16:06 INFO - [mozharness: 2017-06-01 19:16:06.265924Z] Running download-and-extract step. 12:16:06 INFO - Running pre-action listener: _resource_record_pre_action 12:16:06 INFO - Running main action method: download_and_extract 12:16:06 INFO - mkdir: /builds/slave/test/build/tests 12:16:06 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')]} 12:16:06 INFO - trying https://queue.taskcluster.net/v1/task/RxCK7X--TjuCfQ-_6UkMMg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 12:16:06 INFO - Downloading https://queue.taskcluster.net/v1/task/RxCK7X--TjuCfQ-_6UkMMg/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 12:16:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RxCK7X--TjuCfQ-_6UkMMg/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 12:16:07 INFO - Downloaded 1287 bytes. 12:16:07 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 12:16:07 INFO - Using the following test package requirements: 12:16:07 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:16:07 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 12:16:07 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:16:07 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:16:07 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 12:16:07 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:16:07 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 12:16:07 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 12:16:07 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:16:07 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 12:16:07 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:16:07 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:16:07 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 12:16:07 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:16:07 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 12:16:07 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:16:07 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 12:16:07 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:16:07 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 12:16:07 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 12:16:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RxCK7X--TjuCfQ-_6UkMMg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:16:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RxCK7X--TjuCfQ-_6UkMMg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:16:07 INFO - Fetch https://queue.taskcluster.net/v1/task/RxCK7X--TjuCfQ-_6UkMMg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:16:08 INFO - Expected file size: 51963883 12:16:08 INFO - Obtained file size: 51963883 12:16:25 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 12:16:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RxCK7X--TjuCfQ-_6UkMMg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:16:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RxCK7X--TjuCfQ-_6UkMMg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:16:25 INFO - Fetch https://queue.taskcluster.net/v1/task/RxCK7X--TjuCfQ-_6UkMMg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:16:26 INFO - Expected file size: 51963883 12:16:26 INFO - Obtained file size: 51963883 12:16:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RxCK7X--TjuCfQ-_6UkMMg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 12:16:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RxCK7X--TjuCfQ-_6UkMMg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 12:16:41 INFO - Fetch https://queue.taskcluster.net/v1/task/RxCK7X--TjuCfQ-_6UkMMg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 12:16:43 INFO - Expected file size: 14404461 12:16:43 INFO - Obtained file size: 14404461 12:16:43 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')]} 12:16:43 INFO - trying https://queue.taskcluster.net/v1/task/RxCK7X--TjuCfQ-_6UkMMg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:16:43 INFO - Downloading https://queue.taskcluster.net/v1/task/RxCK7X--TjuCfQ-_6UkMMg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 12:16:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RxCK7X--TjuCfQ-_6UkMMg/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 12:16:45 INFO - Downloaded 74891187 bytes. 12:16:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RxCK7X--TjuCfQ-_6UkMMg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:16:45 INFO - mkdir: /builds/slave/test/properties 12:16:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:16:45 INFO - Writing to file /builds/slave/test/properties/build_url 12:16:45 INFO - Contents: 12:16:45 INFO - build_url:https://queue.taskcluster.net/v1/task/RxCK7X--TjuCfQ-_6UkMMg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:16:46 INFO - Running post-action listener: _resource_record_post_action 12:16:46 INFO - Running post-action listener: set_extra_try_arguments 12:16:46 INFO - [mozharness: 2017-06-01 19:16:46.550376Z] Finished download-and-extract step (success) 12:16:46 INFO - [mozharness: 2017-06-01 19:16:46.550572Z] Running populate-webroot step. 12:16:46 INFO - Running pre-action listener: _resource_record_pre_action 12:16:46 INFO - Running main action method: populate_webroot 12:16:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:16:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:16:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:16:46 INFO - '--tppagecycles', 12:16:46 INFO - '7'], 12:16:46 INFO - 'tests': ['tsvgm']}}, 12:16:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:16:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:16:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:16:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:16:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:16:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:16:46 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:16:46 INFO - 'talos_options': ['--disable-e10s'], 12:16:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:16:46 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:16:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:16:46 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:16:46 INFO - 'talos_options': ['--disable-e10s'], 12:16:46 INFO - 'tests': ['damp', 'tps']}, 12:16:46 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:16:46 INFO - 'tests': ['damp', 'tps']}, 12:16:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:16:46 INFO - 'tests': ['dromaeo_dom']}, 12:16:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:16:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:16:46 INFO - 'tests': ['basic_compositor_video']}, 12:16:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:16:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:16:46 INFO - 'tests': ['a11yr', 12:16:46 INFO - 'ts_paint', 12:16:46 INFO - 'tpaint', 12:16:46 INFO - 'sessionrestore', 12:16:46 INFO - 'sessionrestore_no_auto_restore', 12:16:46 INFO - 'tabpaint']}, 12:16:46 INFO - 'other-e10s': {'tests': ['a11yr', 12:16:46 INFO - 'ts_paint', 12:16:46 INFO - 'tpaint', 12:16:46 INFO - 'sessionrestore', 12:16:46 INFO - 'sessionrestore_no_auto_restore', 12:16:46 INFO - 'tabpaint']}, 12:16:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:16:46 INFO - 'ts_paint', 12:16:46 INFO - 'tpaint', 12:16:46 INFO - 'sessionrestore', 12:16:46 INFO - 'sessionrestore_no_auto_restore', 12:16:46 INFO - 'tabpaint']}, 12:16:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:16:46 INFO - 'ts_paint', 12:16:46 INFO - 'tpaint', 12:16:46 INFO - 'sessionrestore', 12:16:46 INFO - 'sessionrestore_no_auto_restore', 12:16:46 INFO - 'tabpaint']}, 12:16:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:16:46 INFO - 'tests': ['a11yr', 12:16:46 INFO - 'ts_paint', 12:16:46 INFO - 'tpaint', 12:16:46 INFO - 'sessionrestore', 12:16:46 INFO - 'sessionrestore_no_auto_restore', 12:16:46 INFO - 'tabpaint']}, 12:16:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:16:46 INFO - 'tests': ['a11yr', 12:16:46 INFO - 'ts_paint', 12:16:46 INFO - 'tpaint', 12:16:46 INFO - 'sessionrestore', 12:16:46 INFO - 'sessionrestore_no_auto_restore', 12:16:46 INFO - 'tabpaint']}, 12:16:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:16:46 INFO - 'tests': ['tsvgx', 12:16:46 INFO - 'tsvgr_opacity', 12:16:46 INFO - 'tart', 12:16:46 INFO - 'tscrollx', 12:16:46 INFO - 'cart', 12:16:46 INFO - 'tsvg_static']}, 12:16:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:16:46 INFO - 'tsvgr_opacity', 12:16:46 INFO - 'tart', 12:16:46 INFO - 'tscrollx', 12:16:46 INFO - 'cart', 12:16:46 INFO - 'tsvg_static']}, 12:16:46 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:16:46 INFO - 'talos_options': ['--disable-e10s'], 12:16:46 INFO - 'tests': ['tp5o']}, 12:16:46 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:16:46 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:16:46 INFO - 'talos_options': ['--disable-e10s', 12:16:46 INFO - '--xperf_path', 12:16:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:16:46 INFO - 'tests': ['tp5n']}, 12:16:46 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:16:46 INFO - 'talos_options': ['--xperf_path', 12:16:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:16:46 INFO - 'tests': ['tp5n']}}, 12:16:46 INFO - 'talos.zip': {'path': '', 12:16:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:16:46 INFO - Running post-action listener: _resource_record_post_action 12:16:46 INFO - [mozharness: 2017-06-01 19:16:46.568983Z] Finished populate-webroot step (success) 12:16:46 INFO - [mozharness: 2017-06-01 19:16:46.569102Z] Running create-virtualenv step. 12:16:46 INFO - Running pre-action listener: _resource_record_pre_action 12:16:46 INFO - Running main action method: create_virtualenv 12:16:46 INFO - Creating virtualenv /builds/slave/test/build/venv 12:16:46 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 12:16:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:16:46 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:16:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:16:46 INFO - Using real prefix '/tools/python27' 12:16:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:16:47 INFO - Installing distribute.............................................................................................................................................................................................done. 12:16:50 INFO - Installing pip.................done. 12:16:50 INFO - Return code: 0 12:16:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:16:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:16:50 INFO - Reading from file tmpfile_stdout 12:16:50 INFO - Output received: 12:16:50 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) 12:16:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:16:50 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')]} 12:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:50 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')]} 12:16:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:50 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 0x107e0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e10768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9825bce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bebd30>, '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 0x107b12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107be0be8>, '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.eBrtds7zOO/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.czkEPZZqsv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 12:16:50 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 12:16:50 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 12:16:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eBrtds7zOO/Render', 12:16:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:50 INFO - 'HOME': '/Users/cltbld', 12:16:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:50 INFO - 'LOGNAME': 'cltbld', 12:16:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:50 INFO - 'MOZ_NO_REMOTE': '1', 12:16:50 INFO - 'NO_EM_RESTART': '1', 12:16:50 INFO - 'PAGER': '/bin/cat', 12:16:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:50 INFO - 'PWD': '/builds/slave/test', 12:16:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:50 INFO - 'SHELL': '/bin/bash', 12:16:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.czkEPZZqsv/Listeners', 12:16:50 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 12:16:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:50 INFO - 'USER': 'cltbld', 12:16:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:50 INFO - 'XPC_FLAGS': '0x0', 12:16:50 INFO - 'XPC_SERVICE_NAME': '0', 12:16:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:50 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 12:16:50 INFO - Cleaning up... 12:16:50 INFO - Return code: 0 12:16:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:16:50 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')]} 12:16:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:50 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')]} 12:16:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:50 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 0x107e0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e10768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9825bce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bebd30>, '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 0x107b12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107be0be8>, '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.eBrtds7zOO/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.czkEPZZqsv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 12:16:50 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 12:16:50 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 12:16:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eBrtds7zOO/Render', 12:16:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:50 INFO - 'HOME': '/Users/cltbld', 12:16:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:50 INFO - 'LOGNAME': 'cltbld', 12:16:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:50 INFO - 'MOZ_NO_REMOTE': '1', 12:16:50 INFO - 'NO_EM_RESTART': '1', 12:16:50 INFO - 'PAGER': '/bin/cat', 12:16:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:50 INFO - 'PWD': '/builds/slave/test', 12:16:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:50 INFO - 'SHELL': '/bin/bash', 12:16:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.czkEPZZqsv/Listeners', 12:16:50 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 12:16:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:50 INFO - 'USER': 'cltbld', 12:16:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:50 INFO - 'XPC_FLAGS': '0x0', 12:16:50 INFO - 'XPC_SERVICE_NAME': '0', 12:16:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:51 INFO - Downloading/unpacking psutil>=3.1.1 12:16: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 12:16: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 12:16: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 12:16: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 12:16:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:16:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:16:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:16:52 INFO - Installing collected packages: psutil 12:16:52 INFO - Running setup.py install for psutil 12:16:53 INFO - building 'psutil._psutil_osx' extension 12:16:53 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 12:16:53 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 12:16:53 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 12:16:53 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 12:16:53 INFO - building 'psutil._psutil_posix' extension 12:16:53 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 12:16:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:16:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:16:53 INFO - ^ 12:16:53 INFO - 1 warning generated. 12:16:53 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 12:16:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:16:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:16:54 INFO - Successfully installed psutil 12:16:54 INFO - Cleaning up... 12:16:54 INFO - Return code: 0 12:16:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:16:54 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')]} 12:16:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:54 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')]} 12:16:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:54 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 0x107e0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e10768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9825bce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bebd30>, '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 0x107b12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107be0be8>, '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.eBrtds7zOO/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.czkEPZZqsv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 12:16:54 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 12:16:54 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 12:16:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eBrtds7zOO/Render', 12:16:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:54 INFO - 'HOME': '/Users/cltbld', 12:16:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:54 INFO - 'LOGNAME': 'cltbld', 12:16:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:54 INFO - 'MOZ_NO_REMOTE': '1', 12:16:54 INFO - 'NO_EM_RESTART': '1', 12:16:54 INFO - 'PAGER': '/bin/cat', 12:16:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:54 INFO - 'PWD': '/builds/slave/test', 12:16:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:54 INFO - 'SHELL': '/bin/bash', 12:16:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.czkEPZZqsv/Listeners', 12:16:54 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 12:16:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:54 INFO - 'USER': 'cltbld', 12:16:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:54 INFO - 'XPC_FLAGS': '0x0', 12:16:54 INFO - 'XPC_SERVICE_NAME': '0', 12:16:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:16:54 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 12:16:54 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 12:16:54 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 12:16:54 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 12:16:55 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:16:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:16:55 INFO - Installing collected packages: mozsystemmonitor 12:16:55 INFO - Running setup.py install for mozsystemmonitor 12:16:56 INFO - Successfully installed mozsystemmonitor 12:16:56 INFO - Cleaning up... 12:16:56 INFO - Return code: 0 12:16:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107e0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e10768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9825bce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bebd30>, '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 0x107b12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107be0be8>, '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.eBrtds7zOO/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.czkEPZZqsv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 12:16:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:16:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:16:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eBrtds7zOO/Render', 12:16:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:56 INFO - 'HOME': '/Users/cltbld', 12:16:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:56 INFO - 'LOGNAME': 'cltbld', 12:16:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:56 INFO - 'MOZ_NO_REMOTE': '1', 12:16:56 INFO - 'NO_EM_RESTART': '1', 12:16:56 INFO - 'PAGER': '/bin/cat', 12:16:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:56 INFO - 'PWD': '/builds/slave/test', 12:16:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:56 INFO - 'SHELL': '/bin/bash', 12:16:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.czkEPZZqsv/Listeners', 12:16:56 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 12:16:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:56 INFO - 'USER': 'cltbld', 12:16:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:56 INFO - 'XPC_FLAGS': '0x0', 12:16:56 INFO - 'XPC_SERVICE_NAME': '0', 12:16:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:56 INFO - Downloading/unpacking jsonschema==2.5.1 12:16:56 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 12:16:56 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 12:16:56 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 12:16:56 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 12:16:57 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:16:57 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:16:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:16:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:16:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:16:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:16:57 INFO - Downloading functools32-3.2.3-2.tar.gz 12:16:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:16:58 INFO - warning: no files found matching '*.txt' 12:16:58 INFO - no previously-included directories found matching 'build' 12:16:58 INFO - no previously-included directories found matching 'dist' 12:16:58 INFO - no previously-included directories found matching '.git*' 12:16:58 INFO - Installing collected packages: jsonschema, functools32 12:16:58 INFO - Running setup.py install for functools32 12:16:58 INFO - warning: no files found matching '*.txt' 12:16:58 INFO - no previously-included directories found matching 'build' 12:16:58 INFO - no previously-included directories found matching 'dist' 12:16:58 INFO - no previously-included directories found matching '.git*' 12:16:58 INFO - Successfully installed jsonschema functools32 12:16:58 INFO - Cleaning up... 12:16:58 INFO - Return code: 0 12:16:58 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:16:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:59 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')]} 12:16:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:59 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 0x107e0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e10768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9825bce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bebd30>, '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 0x107b12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107be0be8>, '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.eBrtds7zOO/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.czkEPZZqsv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 12:16:59 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 12:16:59 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 12:16:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eBrtds7zOO/Render', 12:16:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:59 INFO - 'HOME': '/Users/cltbld', 12:16:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:59 INFO - 'LOGNAME': 'cltbld', 12:16:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:59 INFO - 'MOZ_NO_REMOTE': '1', 12:16:59 INFO - 'NO_EM_RESTART': '1', 12:16:59 INFO - 'PAGER': '/bin/cat', 12:16:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:59 INFO - 'PWD': '/builds/slave/test', 12:16:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:59 INFO - 'SHELL': '/bin/bash', 12:16:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.czkEPZZqsv/Listeners', 12:16:59 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 12:16:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:59 INFO - 'USER': 'cltbld', 12:16:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:59 INFO - 'XPC_FLAGS': '0x0', 12:16:59 INFO - 'XPC_SERVICE_NAME': '0', 12:16:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:59 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:16:59 INFO - Cleaning up... 12:16:59 INFO - Return code: 0 12:16:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:16:59 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')]} 12:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:59 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')]} 12:16:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:00 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 0x107e0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e10768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9825bce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bebd30>, '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 0x107b12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107be0be8>, '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.eBrtds7zOO/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.czkEPZZqsv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 12:17:00 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 12:17:00 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 12:17:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eBrtds7zOO/Render', 12:17:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:00 INFO - 'HOME': '/Users/cltbld', 12:17:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:00 INFO - 'LOGNAME': 'cltbld', 12:17:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:00 INFO - 'MOZ_NO_REMOTE': '1', 12:17:00 INFO - 'NO_EM_RESTART': '1', 12:17:00 INFO - 'PAGER': '/bin/cat', 12:17:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:00 INFO - 'PWD': '/builds/slave/test', 12:17:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:00 INFO - 'SHELL': '/bin/bash', 12:17:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.czkEPZZqsv/Listeners', 12:17:00 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 12:17:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:00 INFO - 'USER': 'cltbld', 12:17:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:00 INFO - 'XPC_FLAGS': '0x0', 12:17:00 INFO - 'XPC_SERVICE_NAME': '0', 12:17:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:17:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:00 INFO - Downloading/unpacking blobuploader==1.2.4 12:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:01 INFO - Downloading blobuploader-1.2.4.tar.gz 12:17:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:17:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:17:02 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 12:17:02 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 12:17:02 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 12:17:02 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 12:17:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:17:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:17:02 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 12:17:02 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 12:17:02 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 12:17:02 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 12:17:02 INFO - Downloading docopt-0.6.1.tar.gz 12:17:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:17:02 INFO - Installing collected packages: blobuploader, requests, docopt 12:17:02 INFO - Running setup.py install for blobuploader 12:17:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:17:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:17:03 INFO - Running setup.py install for requests 12:17:03 INFO - Running setup.py install for docopt 12:17:03 INFO - Successfully installed blobuploader requests docopt 12:17:03 INFO - Cleaning up... 12:17:03 INFO - Return code: 0 12:17:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:17:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:17:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:03 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 0x107e0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e10768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9825bce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bebd30>, '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 0x107b12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107be0be8>, '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.eBrtds7zOO/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.czkEPZZqsv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 12:17:03 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 12:17:03 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 12:17:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eBrtds7zOO/Render', 12:17:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:03 INFO - 'HOME': '/Users/cltbld', 12:17:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:03 INFO - 'LOGNAME': 'cltbld', 12:17:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:03 INFO - 'MOZ_NO_REMOTE': '1', 12:17:03 INFO - 'NO_EM_RESTART': '1', 12:17:03 INFO - 'PAGER': '/bin/cat', 12:17:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:03 INFO - 'PWD': '/builds/slave/test', 12:17:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:03 INFO - 'SHELL': '/bin/bash', 12:17:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.czkEPZZqsv/Listeners', 12:17:03 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 12:17:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:03 INFO - 'USER': 'cltbld', 12:17:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:03 INFO - 'XPC_FLAGS': '0x0', 12:17:03 INFO - 'XPC_SERVICE_NAME': '0', 12:17:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:17:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:17:04 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-f6vFyS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:17:04 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-TB6yP4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:17:04 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-Vpu8Sm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:17:04 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-_oncfb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:17:04 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-AQfd6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:17:04 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-z410vw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:17:04 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-KeC1m2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:17:04 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-0lV2Vh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:17:04 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-7vmp1k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:17:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:17:04 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-SwFllq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:17:05 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-IpAQ6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:17:05 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-KaDnuM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:17:05 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-EHdmQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:17:05 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-zDy5jL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:17:05 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-NwhcZf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:17:05 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-OPKBGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:17:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:17:05 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-oi0Qnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:17:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:17:05 INFO - Running setup.py install for manifestparser 12:17:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:17:05 INFO - Running setup.py install for mozcrash 12:17:06 INFO - Running setup.py install for mozdebug 12:17:06 INFO - Running setup.py install for mozdevice 12:17:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:17:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:17:06 INFO - Running setup.py install for mozfile 12:17:06 INFO - Running setup.py install for mozhttpd 12:17:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:17:06 INFO - Running setup.py install for mozinfo 12:17:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:17:06 INFO - Running setup.py install for mozInstall 12:17:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:17:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:17:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:17:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:17:07 INFO - Running setup.py install for mozleak 12:17:07 INFO - Running setup.py install for mozlog 12:17:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:17:07 INFO - Running setup.py install for moznetwork 12:17:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:17:07 INFO - Running setup.py install for mozprocess 12:17:07 INFO - Running setup.py install for mozprofile 12:17:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:17:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:17:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:17:07 INFO - Running setup.py install for mozrunner 12:17:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:17:07 INFO - Running setup.py install for mozscreenshot 12:17:08 INFO - Running setup.py install for moztest 12:17:08 INFO - Running setup.py install for mozversion 12:17:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:17:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:17:08 INFO - Cleaning up... 12:17:08 INFO - Return code: 0 12:17:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:17:08 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')]} 12:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:08 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')]} 12:17:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:08 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 0x107e0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e10768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9825bce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bebd30>, '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 0x107b12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107be0be8>, '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.eBrtds7zOO/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.czkEPZZqsv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 12:17:08 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 12:17:08 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 12:17:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eBrtds7zOO/Render', 12:17:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:08 INFO - 'HOME': '/Users/cltbld', 12:17:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:08 INFO - 'LOGNAME': 'cltbld', 12:17:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:08 INFO - 'MOZ_NO_REMOTE': '1', 12:17:08 INFO - 'NO_EM_RESTART': '1', 12:17:08 INFO - 'PAGER': '/bin/cat', 12:17:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:08 INFO - 'PWD': '/builds/slave/test', 12:17:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:08 INFO - 'SHELL': '/bin/bash', 12:17:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.czkEPZZqsv/Listeners', 12:17:08 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 12:17:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:08 INFO - 'USER': 'cltbld', 12:17:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:08 INFO - 'XPC_FLAGS': '0x0', 12:17:08 INFO - 'XPC_SERVICE_NAME': '0', 12:17:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:17:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:17:08 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-vu94vJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:17:08 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)) 12:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:17:08 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-mpB1uN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:17:08 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)) 12:17:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:17:08 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-zHKDUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:17:09 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)) 12:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:17:09 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-nF_WTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:17:09 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)) 12:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:17:09 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-FlEl76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:17:09 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)) 12:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:17:09 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-jY35t8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:17:09 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)) 12:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:17:09 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-uTMDuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:17:09 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)) 12:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:17:09 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-5nBwwL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:17:09 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)) 12:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:17:09 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-1TzDWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:17:09 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)) 12:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:17:09 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-E_I22s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:17:09 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)) 12:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:17:09 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-XfUbm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:17:09 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)) 12:17:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:17:09 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-lRLmMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:17:10 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)) 12:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:17:10 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-koTWyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:17:10 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)) 12:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:17:10 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-84maln-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:17:10 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)) 12:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:17:10 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-StrAkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:17:10 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)) 12:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:17:10 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-cQPrAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:17:10 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)) 12:17:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:17:10 INFO - Running setup.py (path:/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/pip-eQtpCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:17:10 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)) 12:17:10 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)) 12:17:10 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)) 12:17:10 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)) 12:17:10 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)) 12:17:10 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)) 12:17:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:11 INFO - Downloading blessings-1.6.tar.gz 12:17:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:17:12 INFO - Installing collected packages: blessings 12:17:12 INFO - Running setup.py install for blessings 12:17:12 INFO - Successfully installed blessings 12:17:12 INFO - Cleaning up... 12:17:12 INFO - Return code: 0 12:17:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:17:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:17:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:17:12 INFO - Reading from file tmpfile_stdout 12:17:12 INFO - Current package versions: 12:17:12 INFO - blessings == 1.6 12:17:12 INFO - blobuploader == 1.2.4 12:17:12 INFO - docopt == 0.6.1 12:17:12 INFO - functools32 == 3.2.3-2 12:17:12 INFO - jsonschema == 2.5.1 12:17:12 INFO - manifestparser == 1.1 12:17:12 INFO - mozInstall == 1.12 12:17:12 INFO - mozcrash == 1.0 12:17:12 INFO - mozdebug == 0.1 12:17:12 INFO - mozdevice == 0.49 12:17:12 INFO - mozfile == 1.2 12:17:12 INFO - mozhttpd == 0.7 12:17:12 INFO - mozinfo == 0.9 12:17:12 INFO - mozleak == 0.1 12:17:12 INFO - mozlog == 3.4 12:17:12 INFO - moznetwork == 0.27 12:17:12 INFO - mozprocess == 0.25 12:17:12 INFO - mozprofile == 0.28 12:17:12 INFO - mozrunner == 6.13 12:17:12 INFO - mozscreenshot == 0.1 12:17:12 INFO - mozsystemmonitor == 0.3 12:17:12 INFO - moztest == 0.8 12:17:12 INFO - mozversion == 1.4 12:17:12 INFO - psutil == 3.1.1 12:17:12 INFO - requests == 1.2.3 12:17:12 INFO - wsgiref == 0.1.2 12:17:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:17:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:17:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:17:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:12 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 0x107e0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e10768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9825bce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bebd30>, '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 0x107b12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107be0be8>, '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.eBrtds7zOO/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.czkEPZZqsv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 12:17:12 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 12:17:12 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 12:17:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eBrtds7zOO/Render', 12:17:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:12 INFO - 'HOME': '/Users/cltbld', 12:17:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:12 INFO - 'LOGNAME': 'cltbld', 12:17:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:12 INFO - 'MOZ_NO_REMOTE': '1', 12:17:12 INFO - 'NO_EM_RESTART': '1', 12:17:12 INFO - 'PAGER': '/bin/cat', 12:17:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:12 INFO - 'PWD': '/builds/slave/test', 12:17:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:12 INFO - 'SHELL': '/bin/bash', 12:17:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.czkEPZZqsv/Listeners', 12:17:12 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 12:17:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:12 INFO - 'USER': 'cltbld', 12:17:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:12 INFO - 'XPC_FLAGS': '0x0', 12:17:12 INFO - 'XPC_SERVICE_NAME': '0', 12:17:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:17:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:12 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)) 12:17:12 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)) 12:17:12 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)) 12:17:12 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)) 12:17:12 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)) 12:17:12 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)) 12:17:12 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)) 12:17:12 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)) 12:17:12 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)) 12:17:12 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)) 12:17:12 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)) 12:17:12 INFO - Cleaning up... 12:17:12 INFO - Return code: 0 12:17:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:17:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:17:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:17:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:12 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 0x107e0db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e10768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff9825bce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107bebd30>, '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 0x107b12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107be0be8>, '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.eBrtds7zOO/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.czkEPZZqsv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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 12:17:12 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 12:17:12 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 12:17:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eBrtds7zOO/Render', 12:17:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:12 INFO - 'HOME': '/Users/cltbld', 12:17:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:12 INFO - 'LOGNAME': 'cltbld', 12:17:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:12 INFO - 'MOZ_NO_REMOTE': '1', 12:17:12 INFO - 'NO_EM_RESTART': '1', 12:17:12 INFO - 'PAGER': '/bin/cat', 12:17:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:12 INFO - 'PWD': '/builds/slave/test', 12:17:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:12 INFO - 'SHELL': '/bin/bash', 12:17:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.czkEPZZqsv/Listeners', 12:17:12 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 12:17:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:12 INFO - 'USER': 'cltbld', 12:17:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:12 INFO - 'XPC_FLAGS': '0x0', 12:17:12 INFO - 'XPC_SERVICE_NAME': '0', 12:17:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:17:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:17:13 INFO - Cleaning up... 12:17:13 INFO - Return code: 0 12:17:13 INFO - Running post-action listener: _resource_record_post_action 12:17:13 INFO - Running post-action listener: _start_resource_monitoring 12:17:13 INFO - Starting resource monitoring. 12:17:13 INFO - [mozharness: 2017-06-01 19:17:13.157869Z] Finished create-virtualenv step (success) 12:17:13 INFO - [mozharness: 2017-06-01 19:17:13.158883Z] Running install step. 12:17:13 INFO - Running pre-action listener: _resource_record_pre_action 12:17:13 INFO - Running main action method: install 12:17:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:17:13 INFO - Reading from file tmpfile_stdout 12:17:13 INFO - Detecting whether we're running mozinstall >=1.0... 12:17:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:17:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:17:13 INFO - Reading from file tmpfile_stdout 12:17:13 INFO - Output received: 12:17:13 INFO - Usage: mozinstall [options] installer 12:17:13 INFO - Options: 12:17:13 INFO - -h, --help show this help message and exit 12:17:13 INFO - -d DEST, --destination=DEST 12:17:13 INFO - Directory to install application into. [default: 12:17:13 INFO - "/builds/slave/test"] 12:17:13 INFO - --app=APP Application being installed. [default: firefox] 12:17:13 INFO - mkdir: /builds/slave/test/build/application 12:17:13 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'] 12:17:13 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 12:17:33 INFO - Reading from file tmpfile_stdout 12:17:33 INFO - Output received: 12:17:33 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:17:33 INFO - Running post-action listener: _resource_record_post_action 12:17:33 INFO - [mozharness: 2017-06-01 19:17:33.604696Z] Finished install step (success) 12:17:33 INFO - [mozharness: 2017-06-01 19:17:33.604855Z] Running run-tests step. 12:17:33 INFO - Running pre-action listener: _resource_record_pre_action 12:17:33 INFO - Running main action method: run_tests 12:17:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:17:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:17:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:17:33 INFO - 2017-06-01 12:17:33.619 screenresolution[1627:7255] starting screenresolution argv=screenresolution get 12:17:33 INFO - 2017-06-01 12:17:33.621 screenresolution[1627:7255] Display 0: 1600x1200x32@60 12:17:33 INFO - 2017-06-01 12:17:33.627 screenresolution[1628:7257] starting screenresolution argv=screenresolution list 12:17:33 INFO - Available Modes on Display 0 12:17:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:17:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:17:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:17:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:17:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:17:33 INFO - Intel Iris: 12:17:33 INFO - Chipset Model: Intel Iris 12:17:33 INFO - Type: GPU 12:17:33 INFO - Bus: Built-In 12:17:33 INFO - VRAM (Dynamic, Max): 1536 MB 12:17:33 INFO - Vendor: Intel (0x8086) 12:17:33 INFO - Device ID: 0x0a2e 12:17:33 INFO - Revision ID: 0x0009 12:17:33 INFO - Displays: 12:17:33 INFO - Display: 12:17:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:17:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:17:33 INFO - Display Serial Number: 1600x1200 60 12:17:33 INFO - Main Display: Yes 12:17:33 INFO - Mirror: Off 12:17:33 INFO - Online: Yes 12:17:33 INFO - Rotation: Supported 12:17:33 INFO - Return code: 0 12:17:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:17:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:17:33 INFO - Python 2.7.3 12:17:33 INFO - Return code: 0 12:17:33 INFO - grabbing minidump binary from tooltool 12:17:33 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')]} 12:17:33 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 0x107bebd30>, '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 0x107b12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107be0be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:17:33 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 12:17:33 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 12:17:33 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 12:17:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:17:33 INFO - Return code: 0 12:17:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:17:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:17:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:17:33 INFO - ENV: RUST_BACKTRACE is now 1 12:17:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:17:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:17:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RxCK7X--TjuCfQ-_6UkMMg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0082', '--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 12:17:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RxCK7X--TjuCfQ-_6UkMMg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0082 --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 12:17:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.eBrtds7zOO/Render', 12:17:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:33 INFO - 'HOME': '/Users/cltbld', 12:17:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:33 INFO - 'LOGNAME': 'cltbld', 12:17:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:17:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:17:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:33 INFO - 'MOZ_NO_REMOTE': '1', 12:17:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:17:33 INFO - 'NO_EM_RESTART': '1', 12:17:33 INFO - 'PAGER': '/bin/cat', 12:17:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:33 INFO - 'PWD': '/builds/slave/test', 12:17:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:17:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:33 INFO - 'RUST_BACKTRACE': '1', 12:17:33 INFO - 'SHELL': '/bin/bash', 12:17:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.czkEPZZqsv/Listeners', 12:17:33 INFO - 'TMPDIR': '/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/', 12:17:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:33 INFO - 'USER': 'cltbld', 12:17:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:33 INFO - 'XPC_FLAGS': '0x0', 12:17:33 INFO - 'XPC_SERVICE_NAME': '0', 12:17:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:17:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RxCK7X--TjuCfQ-_6UkMMg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0082', '--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 12:17:34 INFO - mozversion application_buildid: 20170601104724 12:17:34 INFO - mozversion application_changeset: 4cc48c061ec5b88c23fe45e2ee2dcf6e7451ff1e 12:17:34 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:17:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:17:34 INFO - mozversion application_name: Firefox 12:17:34 INFO - mozversion application_remotingname: firefox-dev 12:17:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:17:34 INFO - mozversion application_vendor: Mozilla 12:17:34 INFO - mozversion application_version: 54.0 12:17:34 INFO - mozversion platform_buildid: 20170601104724 12:17:34 INFO - mozversion platform_changeset: 4cc48c061ec5b88c23fe45e2ee2dcf6e7451ff1e 12:17:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:17:34 INFO - mozversion platform_version: 54.0 12:17:34 INFO - using testdate: 1496344654 12:17:34 INFO - actual date: 1496344654 12:17:34 INFO - starting webserver on 'localhost:49219' 12:17:34 INFO - SUITE-START | Running 2 tests 12:17:34 INFO - TEST-START | dromaeo_css 12:17:34 INFO - operating with platform_type : mac_ 12:17:34 INFO - Initialising browser for dromaeo_css test... 12:17:34 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpu_6_Gg/profile http://localhost:49219/getInfo.html) 12:17:37 INFO - PROCESS | 1638 | 2017-06-01 12:17:37.307 plugin-container[1641:7431] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3b, name = 'com.apple.tsm.portname' 12:17:37 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:17:37 INFO - PROCESS | 1638 | 2017-06-01 12:17:37.308 plugin-container[1641:7431] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a17, name = 'com.apple.CFPasteboardClient' 12:17:37 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:17:37 INFO - PROCESS | 1638 | 2017-06-01 12:17:37.308 plugin-container[1641:7431] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:17:37 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 12:17:37 INFO - PROCESS | 1638 | colorDepth:24 12:17:37 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 12:17:37 INFO - PROCESS | 1638 | __metrics 12:17:37 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 12:17:37 INFO - TEST-INFO | 1638: exit 0 12:17:37 INFO - Browser initialized. 12:17:37 INFO - Running cycle 1/1 for dromaeo_css test... 12:17:37 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmpu_6_Gg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:17:40 INFO - PROCESS | 1642 | 2017-06-01 12:17:40.252 plugin-container[1643:7561] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x424b, name = 'com.apple.tsm.portname' 12:17:40 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:17:40 INFO - PROCESS | 1642 | 2017-06-01 12:17:40.252 plugin-container[1643:7561] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8617, name = 'com.apple.CFPasteboardClient' 12:17:40 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:17:40 INFO - PROCESS | 1642 | 2017-06-01 12:17:40.252 plugin-container[1643:7561] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:17:50 INFO - PROCESS | 1642 | RSS: Main: 202977280 12:17:50 INFO - PROCESS | 1642 | 12:20:53 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49219/tests/dromaeo/cssquery-ext.html) 12:20:55 INFO - PROCESS | 1642 | RSS: Main: 221007872 12:20:55 INFO - PROCESS | 1642 | 12:23:57 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-ext.html (next: http://localhost:49219/tests/dromaeo/cssquery-jquery.html) 12:23:58 INFO - PROCESS | 1642 | RSS: Main: 231596032 12:23:58 INFO - PROCESS | 1642 | 12:26:59 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49219/tests/dromaeo/cssquery-mootools.html) 12:27:00 INFO - PROCESS | 1642 | RSS: Main: 234819584 12:27:00 INFO - PROCESS | 1642 | 12:30:02 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49219/tests/dromaeo/cssquery-prototype.html) 12:30:03 INFO - PROCESS | 1642 | RSS: Main: 238235648 12:30:03 INFO - PROCESS | 1642 | 12:33:06 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49219/tests/dromaeo/cssquery-yui.html) 12:33:06 INFO - PROCESS | 1642 | RSS: Main: 240336896 12:33:06 INFO - PROCESS | 1642 | 12:36:09 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-yui.html (next: http://localhost:49219/tests/dromaeo/cssquery-dojo.html) 12:36:09 INFO - PROCESS | 1642 | RSS: Main: 238395392 12:36:09 INFO - PROCESS | 1642 | 12:36:09 INFO - PROCESS | 1642 | __start_tp_report 12:36:09 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 12:36:09 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 12:36:09 INFO - PROCESS | 1642 | |i|pagename|runs| 12:36:09 INFO - PROCESS | 1642 | |0;dojo.html;3487.703545198658;3492.3888319544058;3515.1387909962064;3518.278752855665;3528.8764893228736;5657.434256574345;5898.3806700296445;5966.37353077927;5984.611000284989;6007.87984240316;6006;6094.87810243796;6099.0241561350085;6099.817005489843;6130.386961303868;4249.702520823544;5402.216678581593;5457.890842183154;5460.945390546083;5498.065328894098;4536.160810250098;5456.8090116845915;5497.092979658371;5509.559235261169;5511.641743286674;5316.441773613773;5331.440198779131;5509.090999984997;5518.834434966958;5548.77804887804;5440.945590544123;5448.509634132927;5455.1544510600725;5477.5070243677865;5490.450954904518;4546.704464209815;5274.945010997777;5437.864053398657;5441.59188060897;5446.7276636168035;5631.2960879890015;5742.425757424266;6053.364396738323;6108.816735497942;6155;6276.654783986879;6295.370462953714;6296.118543403927;6324.683765811691;6339.524535659798;6459.160309159826;6516.446102081329;6518.185226846645;6520.47836173105;6536.869262614721;5694.373618901917;5758.280214973129;5946.375630558816;6163.630182189083;6191.566590338633;5462.945370546323;6392.232932048184;6455.935440645628;6459.063435801782;6482.222133344029;6211.440970312603;6242.282137554149;6320.905186422207;6333.588316759396;6340.778072767431;6429;6434.74261029563;6444.323346048691;6449.258335291408;6464.77373291942;6218.129461875341;6227.96886015567;6246.312905580383;6268.49852011838;6344.873102537924;6283.7800676976085;6342.23893132827;6347.492200623939;6362.9045564316575;6378.202724659418;4886.975565122223;4929.186684197068;4958.752062396866;4959.975200123977;4960.206366981266;4166.604172603597;4380.364847097153;4469;4477.529859364785;4506.414166158378;5308.885134121878;5761.452661997104;5943.970280148658;5959.731812068468;6005.189299444606;6419.743210271537;6445.548811583144;6462.5153113515735;6486.254080780771;6494;6013.458788709037;6020.729067191987;6031.487323577545;6031.60794548353;6042.3655516170165;5382.407935127133;5384.730763461811;5554.805581804699;5563.888722225533;5642.4357564244065;6618.974059020763;6622.10601568782;6629.3039230882;6638;6641.933580664325;5651.491365777099;6405.551611387158;6455.2576453708425;6461.483081353386;6485.513586480938;6206.131141640264;6358;6473.579217350857;6485.383888530582;6589.0775291460195;6307;6383.840403989938;6408.9038664421005;6414.390632889868;6419.197600299963;5083.420099536201;6336.20797400325;6438.227412710505;6445.2587952386075;6458.483321334376;6160.10678451631;6203.689815509297;6230.470410015199;6256.31180570146;6284.308726040041;6230.221222347207;6236.875262494816;6251.937480625317;6301.235654016883;6324.209473815773;5412.809181980116;5513.007658621326;5632.746526406403;6097;6317.557770955989;6142.867137759686;6197.876042479215;6274.905876411949;6275.5607107503865;6290.622562646256;5660.858478537904;5876.824635072917;6193.876122477615;6266.592671476339;6286.119943207863 12:36:09 INFO - PROCESS | 1642 | |1;ext.html;746.2686567164179;808.9231523005315;818.7011740714639;957.3729207369175;964.2093483343662;39880.80059599702;41017;42538;42576.9355766106;42608;32762;41262.38106428398;41284.58715412845;41298.96752581187;41332.38001429981;7946.960265198666;8068.878966815502;10960.068394186465;11339.262947908359;11574.594889178881;9350.906490935105;11145.442727863616;11237.157213209042;11239.213255072149;11257.831132533018;11268.32390056595;11270.154738394653;11308.208425410225;11315.434228288594;11327.660170194866;11241.325520468758;11253.099752019818;11284.32294062359;11299.717507062307;11314.603988860392;10989.285696429706;11056.502457389437;11074.557017719282;11080.722981925435;11086.722831929266;34453.827730861434;34759.65240347589;34836.477452838226;34883.4767478488;34916;3848.268828922524;3883.611638836122;3887;3941.034446560611;3959.1289916218384;25164.622530662054;25356.619650705255;26768.795404206856;26781.928722851062;26800.463990720076;3018.743406810424;3125.87496500142;3161.7944833585743;3256.0720194744385;3272.296456261915;331815;333023;333168;333414;334183.32908335543;43588.78205608952;43760.343594846105;44392.778036110256;44455;44476;38820.8058959709;46639.76680116646;47003.76498117488;47090;47233.52766472291;13888;14158.716825663429;14242.931780116533;14248;14270.500532481314;12282.955948744278;12286;12289.139760216878;12291.815622765665;12317.876821231674;11976.101792365433;12083.093767967437;12132.57535986254;12135.5145794169;12171.96538294255;4933.125412343286;5759.337676167294;5789.3921138279875;5798.478136967693;5834.824955251349;899.8335307968047;974.9227104049922;1125.5160281079216;1139.1171841822654;1139.760650263454;27050.86474567654;27346.733518220397;27430.314242143708;27593.724062759517;27632.86183569069;1212.1151559361715;1229.5757963502576;1236.4621389695512;1260.2564486952742;1278.526945030326;11653.825192622118;12212.511499540096;13684.315784210949;13981.44074237019;14315.928420357832;373846.26153738657;433662;438658.80670596444;440804;443186;58425.53936151545;58480.12279815806;58726.70636646789;58795.11807322808;58988.70505647444;1248.2073883084306;1256.5916077274921;1284.916480428769;1434;1472.4551915791226;26150.692465377037;27797.58303625407;29473.705262947096;30808.383832323678;30819.229519262197;46426.535734642224;46486;46611;46677.99999999864;46769.29846052244;409250;414505;414529;415043.84956150054;416582.91708541266;25876.8706156468;26347.86826065934;31839.68160318367;32086.83956580202;32122.196945075626;44068.55931440645;44562.3315650272;44741.328880066176;45504;46300;30412.695873040986;30424.695753042186;30620;31108.844455777577;31725.682743173194;30610.540841886945;31236;31423.37153256968;31591.84204078965;31648.89228876978;29271.414571708872;36081.27837443184;36716.63283367132;37334;37490.62509374871;30988.84505577458;31483;32207.677923220468;32221.51667725033;32285.16346854942;23244.418889527897;24196.153134640204;24505.142320018713;24519.754802451745;24544.263672089866 12:36:09 INFO - PROCESS | 1642 | |2;jquery.html;408.28957613752067;410.42130595859845;412.68223467929676;421.38688208656407;451.47403275184394;19942.99999999998;20599.38201853943;21818.781812181875;22118.557628847433;22154.88922555389;21973.23093691721;22379.440513987116;22621.77378226217;22844.65733014006;22880.199193028246;6799.864002719943;6909.654517274142;7087.043249161349;7103.715851365965;7114.217436082027;7151.535150215245;7474.177840437547;7644.770656880302;7646.311831935125;7647.847043059135;7432.554046757212;7607.847843043136;7607.923920760777;7739.729109481169;7900.723474678387;7498.175200727916;7569.659365328574;7810.765677029698;7835.177306382833;7842.058952925686;7407.481476296662;7412.85174296511;7441.95346279772;7444.404447644176;7445.255474452566;25337.873310633513;25613.359666008313;26354.60468092961;26449.206523804318;26726.73273267262;2879.2513946373883;2962.762978961678;2982.1500872251304;2983.8060526065738;3035.9089227323216;15122.924385378114;16299.91850040754;16349.836501635069;16503;16865.988040717475;2013.9798602013939;2063.164418410546;2063.9484012899647;2073.709680644711;2093.591749608827;156317;159101.40898590983;164696.17651911784;167044.16477917653;168242.15878920772;15134.470293539893;19940.102695378453;20398;20438.386848394573;20589.279375221795;30066.69933300639;30094.69905300919;30292.24269393283;30483.847580761954;30486;10201.846972295421;10441.46294072807;10651.520681569347;10682.092022178047;10690.946545267223;8829.072947340564;8842.204201621886;8884.200421961925;8935.374523783274;9019.368644194843;9538.666146684833;9667.936526982026;9677.806443871083;9698.127168554864;9769.755756106155;3409.9659003410143;3871.070942973722;4396;4433.556644335606;4439.556044395535;788.8304014636881;878.2359347367831;879.5338470610586;886.264400547544;922.6401703335705;20143.294984675467;21085.78914210869;21397.572048559254;21514;21530.6770398441;822.2353211513231;835.2649668291986;919.2645883293338;946.2477330522252;947.7062110745691;9580.95209523948;9616.51917404127;10588.788224235472;11133.276337038067;11259.493322800494;703447;757266;798714;799337;812175;159255.20372398064;160697;160835;165919;166638.50042249376;1274.6367285323638;1293.6119164250688;1336.8596297388829;1342.0404410846293;1342.1946831900977;18976.810231897503;19352.612947740683;19708.916009619035;19876.204951801767;20040.89979550093;55687.44312556823;64070.35929640644;64495;65635;66074.66962665156;67348;90370;91454;93044;94348.11303774023;10790;11407.771844563229;11581.305121692725;11969.461374238354;12035.157538972187;10689.358638481715;11924.867137621912;12409.813852792397;13116.409761560562;13288.335583220995;10868.347899126078;11178.944105279421;11683.824742628698;12030.27818330903;12071.456784444545;9524.571394287135;10611.575536978435;10638.52126654287;10703.946480267548;10961.671149865517;11568.479418426023;11730.296182229093;11811.763764724485;11980.820287695866;12028.578999734968;11499.942500287445;11728;11796.705082372666;11797.646070617895;12000.939995299968;11381.943090284496;11503.309801411942;11537.01935335506;11752.882471175179;11907.345096019455 12:36:09 INFO - PROCESS | 1642 | |3;mootools.html;1415.1438379780232;1575.133676477937;1635.4357746577427;1635.4602981016258;1638.4101723379583;10762.139028877691;11072;11380.829287560693;11454.255473394234;11462.197646164772;11002.614908478205;11319;11320.773584528304;11355.375454350007;11362.318260904352;2394.7485514021037;2445.070873068232;2446.4984678141027;2448.192096608124;2449.3141920262265;2359.516299158677;2412.2401443545314;2432.3554258121612;2434.47658753368;2447.840890342131;2399.940001499959;2441.7192022917325;2453.901843926241;2461.064795377754;2467.8025757939413;2366.1127077345996;2395.0300128448007;2397.592409290416;2413.1433341163897;2438.5854404751235;2141.9036143373587;2395.0659242895285;2400.327908185711;2408.8193385496156;2412.19191570824;11030.338179709242;11122.110231181487;11178.385188814613;11219.38293393863;11233;5899.793507227269;5999.400059994009;6085.726142323606;6089.543284253655;6096.512279017668;3610.855565777366;3683.079230192452;3726.273376691546;3726.5341832270965;3754.0427191066105;1177.3112729053514;1190.9047276217882;1199.208522375228;1218.0864351736197;1268.987310126896;13842.307884605729;14160.92919535406;15058.397664093314;15266.542003739905;15686.450974215963;4946.3817022872145;4983.302337672729;5126.307948426989;5135.845924622267;5147.661607981951;13310.401031953585;13362.797348238706;13364.2649654268;13366.799498007344;13396.330183490787;6061;6109.327973922865;6115.847103822351;6130.448259656652;6131.84670383244;5146.305248791366;5292.708901010481;5325.786968521294;5348.251244825728;5360.3031605891765;3925.7644541327613;4016.4176194451643;4051.716379853441;4053.2298863215897;4119.443875076886;1070.2722148938797;1072.3244356055634;1073.0289088375032;1083.701981954953;1089.3844977587637;931.1340453378423;932.47315266874;933.6685476655825;946.5645802930729;990.6136606723384;6473.28793832678;6491.51313651478;6540.869182616416;6709.865802683919;6803.235090893719;996.4469719305855;1014.2038499777639;1039.4335087377397;1044.9268551201344;1073.978520429587;4284.400183974246;4297;4298.4412026436985;4419.204543182258;4420.491643460979;13157.53948611794;13770.862291377158;13784.793228101587;13791.51729689456;13801.585952421443;13240.867591324157;13414.597562073153;13689.315534223448;13743.931280343535;13744.244066576235;8628.870566941376;9824.508774561244;10147.441890695934;10157.238207134495;10299.279050466395;6353.936460635335;6593.736250549924;6619.205695316593;6817.454603631797;6861.931380686129;11478.598249061242;12206.145569810384;12227.755444890874;12326.630201093982;12528.749425011267;12487.12590118683;12613.621591352274;12633.747325053631;12695.619131426072;12768.61694149177;7896.84206315859;9243.815123697354;9364.01677823846;9391.906080939103;9467.479288639192;10046.748831279276;10358.5338659762;10360.948195258976;10362.948185259025;10369.74075648115;8648.783780405289;8846.33652476054;8906.332025098014;8929.10708929102;8947.686830960885;8668;8691.739247822576;8691.826163476822;8781.077986811293;8957.955210223907;7837.020372453443;7984.6806127754235;7992.120866704541;7995.2804247618;7997.720079797179;5253.343332083489;5870.647761134345;5899.852503687271;5916.201312822886;6139.72371243304;5957.106434034929;5994.55040871945;6055.969720151371;6136.478399336106;6235.475988630166 12:36:09 INFO - PROCESS | 1642 | |4;prototype.html;316.54259594885383;346.25555056628247;366.94679271505635;384.9403342481916;393.94681717968064;4832.492588278233;5166.535011848933;5252.133397989337;5262.7368631568415;5387.4073851876265;4943.9752801235945;5049.823256186034;5229.555487783543;5236.738163091849;5241.9596633952315;3773.3585290500682;3822.6750726188307;4497.775111244441;4513.255312873372;4605.539446055384;3500.982495087521;4314.374415709736;4572.862814115582;4648.325992731052;4656.580907718305;4065.0731473339147;4309.478954811423;4323.178596066738;4328.84849030284;4387.539308372623;4024.8931543825684;4356.8257269709175;4365.126974605076;4388.736675799462;4414.53647367025;3678.2091850252323;3882.776925268531;3950.2494526040246;4086.652634526069;4102.2821006323775;4162.583741625813;4641.698289611199;4648.74431906245;4720.026383378449;4727.834525791615;4115;4564.9044229385045;5046.6719663221775;5083.923741143886;5115.5396014358885;4637.605803506706;4920;5059.8988020239385;5637.887242255173;5716.370834312243;4211.599898009684;4213.894652633709;4548.840790572347;4560.521145279764;4583.335416364608;5509.944900550983;5645.858853528694;5936.436038576328;5948.7620495180145;6152.477039451652;3751.549814022335;3966.861159859449;3976.622220889011;4361.890952726208;4426.927226951213;4665.5567721066445;4674.883127921829;4694.906101877943;4710.456353569034;4736.973572463447;4491.393661855673;4666.416697912761;4675.509071547506;4849.133083762777;5102.984321193108;4603.58567728906;4881.633877459134;4917.483664215278;5106.565941894906;5651;1401.5444980381417;1418.2270662488968;1427.6722647937518;1565.6946895355525;1593.3228377939329;1022.8261195596766;1093.8851420600722;1134.472470301314;1216.5583783216916;1224.871388504212;4487.798049087799;4710.396546641822;4997.251511668568;5100.336956195745;5133.383993920754;5019.239418319909;5426.620136590401;5799.811038737049;5863.3257175424515;5890.705464726747;4484.596386325246;4527.547245275512;5011.298418221452;5096.388433388017;5104.157813960729;2668.506326329635;2729.2025673684634;2748.395353022332;2877.5540790328055;2926.5171246744476;5451.48210919962;5798.884022319615;6032.215811944419;6040.30871356021;6044.24446944132;5583.441655834384;5823.708814559255;5955.426743953593;6012.5886701968575;6074.934522262363;5071.492850714899;5711.742971566289;5796.811653610999;5985.910211346746;6051.515878729779;5157.086246270845;5405.594580406407;5696.319585722139;5769.711514424346;5820.82537523875;5254.65844720092;5323.467653234646;5672.12082127271;5793.36273009977;5851.303122094545;4766.569049784565;4970.6023518117745;5509.917351239815;5720.914186287292;5867.6772777497645;4050.3924411338535;4347.826086956613;4401.515833258275;4410.448693913261;4633.8146474142;5505.9724701376235;5743.629706224693;5910.970445147918;5956.463918247378;6032.306284777307;4556.521565235603;4620.722756634613;4974.5771609413605;5616.887662246814;5621.241132447068;4694.812207511662;5215.6609820361555;5586.575423267041;5603.887922241451;5668.858278543069;4941.641048711633;5127.6410651254055;5174.033867130466;5321.627486075938;5392.811251606332;4736.526347365236;4841.467438581823;5240.004399164299;5562.638428502134;5607.5513958882375;5470.945290547043;5479.410970818418;5485.835424937259;5505;5552.389237183826 12:36:09 INFO - PROCESS | 1642 | |5;yui.html;727.2872584866831;752.9171791102981;767.4781148818804;828.0932379044946;843.3759018326439;15912.681746365066;16102.597435064115;16902.91548542257;16987.490375288762;17001;15447.22763861808;15660.138692371915;15718.135502547355;16251.268692908818;16897.83102168978;957.6121670723351;983.4394395194741;986.7385142937092;987.4963768478092;990.1336330710628;941.6986564299427;977.8777652793401;978.3542861711272;983.3018556824663;984.4880662055726;1037.2531777120462;1049.9160067194643;1050.7268110291302;1053.1627356251797;1056.7041228456044;889.4218757807412;1038.7403149212696;1048.7954848804447;1049.3808652894752;1050.5482642463737;596.3201949777257;779.1585088104833;783.4241832253289;784.0356361675172;785.1402714028187;16710.41513547032;17201.655966880717;17291;17312.826871731373;17529.474115776546;14249.216293103875;14276.857231427657;14278.357473913698;14391.685469539309;14478.638034049127;11759.882401175964;11781.46983385741;11890.108241881893;11895.762084758342;12041.698957526045;3585.9103522412147;3670.8715194968313;3791.9431208531896;3938.5864484229314;4009.5389030261604;104874;109752;111113.88886111116;111330.88669113367;111644.32533512004;10315.27793054494;10776.29954052984;10871.29336593119;10996.065334446661;11287.097032237401;22236.444088897584;22510.887445562996;22521;22723.318300451014;22754.544909102056;7808.453408261481;7875.763727088197;7929.405294602928;7958.482698624571;8108.635111420001;3779.111908701453;4303.376010478463;4350.303951367766;4374.978125109354;4420.8673739787855;3505.9649403505637;3544.946825797615;3581.8209089545417;3594.4967704521387;3595.0113718727557;621.4965877639037;632.4782054804805;632.5698525003043;640.4940097323063;647.3009150117955;681.2029924987776;686.3582550315471;687.1753895325629;705.1714235772946;732.7655150351943;9115.407498512575;10303.793924121475;10433.321834080913;10478;10479.790404191874;682.9965850170718;692.7021380806302;718.0880282041778;718.8059224009488;743.650484272391;5000.1249781288125;5000.74996250186;5028.321176641107;5033.282844387808;5097.649122982412;81293.59353203315;99747.50126249467;101990.45023874314;106486.46756766166;107042;81968;83141.75287370694;83883.58058209669;85207.5739621298;86946;53413.73293133509;54478.45521544813;67771.98342024874;74112;75583;10619.469026548797;11024.228304018641;11067;11147.665570032912;11275.774484510428;39522.40716389314;42563.36154957616;44645.776771117235;47037.76481117572;48819;52040;52397.7380113097;59734;60262;61508.69245653743;12618.55835045769;12649.936750316558;12779.488820447077;12921.095523313277;13117.34413279313;14237;14247.287635618384;14428.927855361077;14785;15207.61980950441;13332.333383330988;13858.237796921267;13862.376193071537;14139.293035348397;14291.356888939814;13140.27728474943;13611.931940340235;13803;13853.79219311691;14284.07153535017;12855.485780568673;13421.463141474233;13505.864941350854;13602.523911663044;13785.172889626254;7900.881486777588;9154.908450915673;9620.903790962;9828.16460600857;9896.604135834774;7228.867939475585;10354.275200735876;10461.633842815465;10516.316439431412;11127.387993660426 12:36:09 INFO - PROCESS | 1642 | __end_tp_report 12:36:09 INFO - PROCESS | 1642 | __start_cc_report 12:36:09 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,380 12:36:09 INFO - PROCESS | 1642 | __end_cc_report 12:36:09 INFO - PROCESS | 1642 | __startTimestamp1496345769925__endTimestamp 12:36:09 INFO - PROCESS | 1642 | 12:36:09 INFO - PROCESS | 1642 | ------- Summary: start ------- 12:36:09 INFO - PROCESS | 1642 | Number of tests: 6 12:36:09 INFO - PROCESS | 1642 | 12:36:09 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:5880.06 Median:6155.00 stddev:690.81 (11.2%) stddev-sans-first:666.95 12:36:09 INFO - PROCESS | 1642 | Values: 3487.7 3492.4 3515.1 3518.3 3528.9 5657.4 5898.4 5966.4 5984.6 6007.9 6006.0 6094.9 6099.0 6099.8 6130.4 4249.7 5402.2 5457.9 5460.9 5498.1 4536.2 5456.8 5497.1 5509.6 5511.6 5316.4 5331.4 5509.1 5518.8 5548.8 5440.9 5448.5 5455.2 5477.5 5490.5 4546.7 5274.9 5437.9 5441.6 5446.7 5631.3 5742.4 6053.4 6108.8 6155.0 6276.7 6295.4 6296.1 6324.7 6339.5 6459.2 6516.4 6518.2 6520.5 6536.9 5694.4 5758.3 5946.4 6163.6 6191.6 5462.9 6392.2 6455.9 6459.1 6482.2 6211.4 6242.3 6320.9 6333.6 6340.8 6429.0 6434.7 6444.3 6449.3 6464.8 6218.1 6228.0 6246.3 6268.5 6344.9 6283.8 6342.2 6347.5 6362.9 6378.2 4887.0 4929.2 4958.8 4960.0 4960.2 4166.6 4380.4 4469.0 4477.5 4506.4 5308.9 5761.5 5944.0 5959.7 6005.2 6419.7 6445.5 6462.5 6486.3 6494.0 6013.5 6020.7 6031.5 6031.6 6042.4 5382.4 5384.7 5554.8 5563.9 5642.4 6619.0 6622.1 6629.3 6638.0 6641.9 5651.5 6405.6 6455.3 6461.5 6485.5 6206.1 6358.0 6473.6 6485.4 6589.1 6307.0 6383.8 6408.9 6414.4 6419.2 5083.4 6336.2 6438.2 6445.3 6458.5 6160.1 6203.7 6230.5 6256.3 6284.3 6230.2 6236.9 6251.9 6301.2 6324.2 5412.8 5513.0 5632.7 6097.0 6317.6 6142.9 6197.9 6274.9 6275.6 6290.6 5660.9 5876.8 6193.9 6266.6 6286.1 12:36:09 INFO - PROCESS | 1642 | 12:36:09 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:53283.99 Median:17405.34 stddev:104082.24 (598.0%) stddev-sans-first:104299.47 12:36:09 INFO - PROCESS | 1642 | Values: 746.3 808.9 818.7 957.4 964.2 39880.8 41017.0 42538.0 42576.9 42608.0 32762.0 41262.4 41284.6 41299.0 41332.4 7947.0 8068.9 10960.1 11339.3 11574.6 9350.9 11145.4 11237.2 11239.2 11257.8 11268.3 11270.2 11308.2 11315.4 11327.7 11241.3 11253.1 11284.3 11299.7 11314.6 10989.3 11056.5 11074.6 11080.7 11086.7 34453.8 34759.7 34836.5 34883.5 34916.0 3848.3 3883.6 3887.0 3941.0 3959.1 25164.6 25356.6 26768.8 26781.9 26800.5 3018.7 3125.9 3161.8 3256.1 3272.3 331815.0 333023.0 333168.0 333414.0 334183.3 43588.8 43760.3 44392.8 44455.0 44476.0 38820.8 46639.8 47003.8 47090.0 47233.5 13888.0 14158.7 14242.9 14248.0 14270.5 12283.0 12286.0 12289.1 12291.8 12317.9 11976.1 12083.1 12132.6 12135.5 12172.0 4933.1 5759.3 5789.4 5798.5 5834.8 899.8 974.9 1125.5 1139.1 1139.8 27050.9 27346.7 27430.3 27593.7 27632.9 1212.1 1229.6 1236.5 1260.3 1278.5 11653.8 12212.5 13684.3 13981.4 14315.9 373846.3 433662.0 438658.8 440804.0 443186.0 58425.5 58480.1 58726.7 58795.1 58988.7 1248.2 1256.6 1284.9 1434.0 1472.5 26150.7 27797.6 29473.7 30808.4 30819.2 46426.5 46486.0 46611.0 46678.0 46769.3 409250.0 414505.0 414529.0 415043.8 416582.9 25876.9 26347.9 31839.7 32086.8 32122.2 44068.6 44562.3 44741.3 45504.0 46300.0 30412.7 30424.7 30620.0 31108.8 31725.7 30610.5 31236.0 31423.4 31591.8 31648.9 29271.4 36081.3 36716.6 37334.0 37490.6 30988.8 31483.0 32207.7 32221.5 32285.2 23244.4 24196.2 24505.1 24519.8 24544.3 12:36:09 INFO - PROCESS | 1642 | 12:36:09 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:44376.32 Median:22862.43 stddev:129520.94 (566.5%) stddev-sans-first:129842.20 12:36:09 INFO - PROCESS | 1642 | Values: 408.3 410.4 412.7 421.4 451.5 19943.0 20599.4 21818.8 22118.6 22154.9 21973.2 22379.4 22621.8 22844.7 22880.2 6799.9 6909.7 7087.0 7103.7 7114.2 7151.5 7474.2 7644.8 7646.3 7647.8 7432.6 7607.8 7607.9 7739.7 7900.7 7498.2 7569.7 7810.8 7835.2 7842.1 7407.5 7412.9 7442.0 7444.4 7445.3 25337.9 25613.4 26354.6 26449.2 26726.7 2879.3 2962.8 2982.2 2983.8 3035.9 15122.9 16299.9 16349.8 16503.0 16866.0 2014.0 2063.2 2063.9 2073.7 2093.6 156317.0 159101.4 164696.2 167044.2 168242.2 15134.5 19940.1 20398.0 20438.4 20589.3 30066.7 30094.7 30292.2 30483.8 30486.0 10201.8 10441.5 10651.5 10682.1 10690.9 8829.1 8842.2 8884.2 8935.4 9019.4 9538.7 9667.9 9677.8 9698.1 9769.8 3410.0 3871.1 4396.0 4433.6 4439.6 788.8 878.2 879.5 886.3 922.6 20143.3 21085.8 21397.6 21514.0 21530.7 822.2 835.3 919.3 946.2 947.7 9581.0 9616.5 10588.8 11133.3 11259.5 703447.0 757266.0 798714.0 799337.0 812175.0 159255.2 160697.0 160835.0 165919.0 166638.5 1274.6 1293.6 1336.9 1342.0 1342.2 18976.8 19352.6 19708.9 19876.2 20040.9 55687.4 64070.4 64495.0 65635.0 66074.7 67348.0 90370.0 91454.0 93044.0 94348.1 10790.0 11407.8 11581.3 11969.5 12035.2 10689.4 11924.9 12409.8 13116.4 13288.3 10868.3 11178.9 11683.8 12030.3 12071.5 9524.6 10611.6 10638.5 10703.9 10961.7 11568.5 11730.3 11811.8 11980.8 12028.6 11499.9 11728.0 11796.7 11797.6 12000.9 11381.9 11503.3 11537.0 11752.9 11907.3 12:36:09 INFO - PROCESS | 1642 | 12:36:09 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:6839.69 Median:2447.17 stddev:4245.16 (173.5%) stddev-sans-first:4237.49 12:36:09 INFO - PROCESS | 1642 | Values: 1415.1 1575.1 1635.4 1635.5 1638.4 10762.1 11072.0 11380.8 11454.3 11462.2 11002.6 11319.0 11320.8 11355.4 11362.3 2394.7 2445.1 2446.5 2448.2 2449.3 2359.5 2412.2 2432.4 2434.5 2447.8 2399.9 2441.7 2453.9 2461.1 2467.8 2366.1 2395.0 2397.6 2413.1 2438.6 2141.9 2395.1 2400.3 2408.8 2412.2 11030.3 11122.1 11178.4 11219.4 11233.0 5899.8 5999.4 6085.7 6089.5 6096.5 3610.9 3683.1 3726.3 3726.5 3754.0 1177.3 1190.9 1199.2 1218.1 1269.0 13842.3 14160.9 15058.4 15266.5 15686.5 4946.4 4983.3 5126.3 5135.8 5147.7 13310.4 13362.8 13364.3 13366.8 13396.3 6061.0 6109.3 6115.8 6130.4 6131.8 5146.3 5292.7 5325.8 5348.3 5360.3 3925.8 4016.4 4051.7 4053.2 4119.4 1070.3 1072.3 1073.0 1083.7 1089.4 931.1 932.5 933.7 946.6 990.6 6473.3 6491.5 6540.9 6709.9 6803.2 996.4 1014.2 1039.4 1044.9 1074.0 4284.4 4297.0 4298.4 4419.2 4420.5 13157.5 13770.9 13784.8 13791.5 13801.6 13240.9 13414.6 13689.3 13743.9 13744.2 8628.9 9824.5 10147.4 10157.2 10299.3 6353.9 6593.7 6619.2 6817.5 6861.9 11478.6 12206.1 12227.8 12326.6 12528.7 12487.1 12613.6 12633.7 12695.6 12768.6 7896.8 9243.8 9364.0 9391.9 9467.5 10046.7 10358.5 10360.9 10362.9 10369.7 8648.8 8846.3 8906.3 8929.1 8947.7 8668.0 8691.7 8691.8 8781.1 8958.0 7837.0 7984.7 7992.1 7995.3 7997.7 5253.3 5870.6 5899.9 5916.2 6139.7 5957.1 5994.6 6056.0 6136.5 6235.5 12:36:09 INFO - PROCESS | 1642 | 12:36:09 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4623.97 Median:4918.74 stddev:1308.86 (26.6%) stddev-sans-first:1271.98 12:36:09 INFO - PROCESS | 1642 | Values: 316.5 346.3 366.9 384.9 393.9 4832.5 5166.5 5252.1 5262.7 5387.4 4944.0 5049.8 5229.6 5236.7 5242.0 3773.4 3822.7 4497.8 4513.3 4605.5 3501.0 4314.4 4572.9 4648.3 4656.6 4065.1 4309.5 4323.2 4328.8 4387.5 4024.9 4356.8 4365.1 4388.7 4414.5 3678.2 3882.8 3950.2 4086.7 4102.3 4162.6 4641.7 4648.7 4720.0 4727.8 4115.0 4564.9 5046.7 5083.9 5115.5 4637.6 4920.0 5059.9 5637.9 5716.4 4211.6 4213.9 4548.8 4560.5 4583.3 5509.9 5645.9 5936.4 5948.8 6152.5 3751.5 3966.9 3976.6 4361.9 4426.9 4665.6 4674.9 4694.9 4710.5 4737.0 4491.4 4666.4 4675.5 4849.1 5103.0 4603.6 4881.6 4917.5 5106.6 5651.0 1401.5 1418.2 1427.7 1565.7 1593.3 1022.8 1093.9 1134.5 1216.6 1224.9 4487.8 4710.4 4997.3 5100.3 5133.4 5019.2 5426.6 5799.8 5863.3 5890.7 4484.6 4527.5 5011.3 5096.4 5104.2 2668.5 2729.2 2748.4 2877.6 2926.5 5451.5 5798.9 6032.2 6040.3 6044.2 5583.4 5823.7 5955.4 6012.6 6074.9 5071.5 5711.7 5796.8 5985.9 6051.5 5157.1 5405.6 5696.3 5769.7 5820.8 5254.7 5323.5 5672.1 5793.4 5851.3 4766.6 4970.6 5509.9 5720.9 5867.7 4050.4 4347.8 4401.5 4410.4 4633.8 5506.0 5743.6 5911.0 5956.5 6032.3 4556.5 4620.7 4974.6 5616.9 5621.2 4694.8 5215.7 5586.6 5603.9 5668.9 4941.6 5127.6 5174.0 5321.6 5392.8 4736.5 4841.5 5240.0 5562.6 5607.6 5470.9 5479.4 5485.8 5505.0 5552.4 12:36:09 INFO - PROCESS | 1642 | 12:36:09 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:19736.13 Median:3525.46 stddev:27952.93 (792.9%) stddev-sans-first:27994.91 12:36:09 INFO - PROCESS | 1642 | Values: 727.3 752.9 767.5 828.1 843.4 15912.7 16102.6 16902.9 16987.5 17001.0 15447.2 15660.1 15718.1 16251.3 16897.8 957.6 983.4 986.7 987.5 990.1 941.7 977.9 978.4 983.3 984.5 1037.3 1049.9 1050.7 1053.2 1056.7 889.4 1038.7 1048.8 1049.4 1050.5 596.3 779.2 783.4 784.0 785.1 16710.4 17201.7 17291.0 17312.8 17529.5 14249.2 14276.9 14278.4 14391.7 14478.6 11759.9 11781.5 11890.1 11895.8 12041.7 3585.9 3670.9 3791.9 3938.6 4009.5 104874.0 109752.0 111113.9 111330.9 111644.3 10315.3 10776.3 10871.3 10996.1 11287.1 22236.4 22510.9 22521.0 22723.3 22754.5 7808.5 7875.8 7929.4 7958.5 8108.6 3779.1 4303.4 4350.3 4375.0 4420.9 3506.0 3544.9 3581.8 3594.5 3595.0 621.5 632.5 632.6 640.5 647.3 681.2 686.4 687.2 705.2 732.8 9115.4 10303.8 10433.3 10478.0 10479.8 683.0 692.7 718.1 718.8 743.7 5000.1 5000.7 5028.3 5033.3 5097.6 81293.6 99747.5 101990.5 106486.5 107042.0 81968.0 83141.8 83883.6 85207.6 86946.0 53413.7 54478.5 67772.0 74112.0 75583.0 10619.5 11024.2 11067.0 11147.7 11275.8 39522.4 42563.4 44645.8 47037.8 48819.0 52040.0 52397.7 59734.0 60262.0 61508.7 12618.6 12649.9 12779.5 12921.1 13117.3 14237.0 14247.3 14428.9 14785.0 15207.6 13332.3 13858.2 13862.4 14139.3 14291.4 13140.3 13611.9 13803.0 13853.8 14284.1 12855.5 13421.5 13505.9 13602.5 13785.2 7900.9 9154.9 9620.9 9828.2 9896.6 7228.9 10354.3 10461.6 10516.3 11127.4 12:36:09 INFO - PROCESS | 1642 | -------- Summary: end -------- 12:36:09 INFO - PROCESS | 1642 | 12:36:10 INFO - TEST-INFO | 1642: exit 0 12:36:10 INFO - TEST-OK | dromaeo_css | took 1116166ms 12:36:10 INFO - TEST-START | kraken 12:36:10 INFO - operating with platform_type : mac_ 12:36:10 INFO - Initialising browser for kraken test... 12:36:10 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmp5g_hRw/profile http://localhost:49219/getInfo.html) 12:36:13 INFO - PROCESS | 1655 | 2017-06-01 12:36:13.043 plugin-container[1657:10014] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x862f, name = 'com.apple.tsm.portname' 12:36:13 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:36:13 INFO - PROCESS | 1655 | 2017-06-01 12:36:13.043 plugin-container[1657:10014] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x874f, name = 'com.apple.CFPasteboardClient' 12:36:13 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:36:13 INFO - PROCESS | 1655 | 2017-06-01 12:36:13.043 plugin-container[1657:10014] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:36:13 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 12:36:13 INFO - PROCESS | 1655 | colorDepth:24 12:36:13 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 12:36:13 INFO - PROCESS | 1655 | __metrics 12:36:13 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 12:36:13 INFO - TEST-INFO | 1655: exit 0 12:36:13 INFO - Browser initialized. 12:36:13 INFO - Running cycle 1/1 for kraken test... 12:36:13 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/T/tmp5g_hRw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:36:15 INFO - PROCESS | 1658 | 2017-06-01 12:36:15.952 plugin-container[1659:10136] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x864b, name = 'com.apple.tsm.portname' 12:36:15 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:36:15 INFO - PROCESS | 1658 | 2017-06-01 12:36:15.952 plugin-container[1659:10136] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4507, name = 'com.apple.CFPasteboardClient' 12:36:15 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:36:15 INFO - PROCESS | 1658 | 2017-06-01 12:36:15.952 plugin-container[1659:10136] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:36:25 INFO - PROCESS | 1658 | RSS: Main: 202866688 12:36:25 INFO - PROCESS | 1658 | 12:36:28 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection) 12:36:29 INFO - PROCESS | 1658 | RSS: Main: 216342528 12:36:29 INFO - PROCESS | 1658 | 12:36:32 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-dft) 12:36:32 INFO - PROCESS | 1658 | RSS: Main: 224169984 12:36:32 INFO - PROCESS | 1658 | 12:36:36 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-fft) 12:36:36 INFO - PROCESS | 1658 | RSS: Main: 227885056 12:36:36 INFO - PROCESS | 1658 | 12:36:38 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator) 12:36:39 INFO - PROCESS | 1658 | RSS: Main: 229249024 12:36:39 INFO - PROCESS | 1658 | 12:36:41 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:36:41 INFO - PROCESS | 1658 | RSS: Main: 229302272 12:36:41 INFO - PROCESS | 1658 | 12:36:44 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom) 12:36:45 INFO - PROCESS | 1658 | RSS: Main: 232976384 12:36:45 INFO - PROCESS | 1658 | 12:36:48 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate) 12:36:49 INFO - PROCESS | 1658 | RSS: Main: 233533440 12:36:49 INFO - PROCESS | 1658 | 12:36:52 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial) 12:36:52 INFO - PROCESS | 1658 | RSS: Main: 232878080 12:36:52 INFO - PROCESS | 1658 | 12:36:54 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:36:55 INFO - PROCESS | 1658 | RSS: Main: 232374272 12:36:55 INFO - PROCESS | 1658 | 12:36:57 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:36:57 INFO - PROCESS | 1658 | RSS: Main: 232378368 12:36:57 INFO - PROCESS | 1658 | 12:37:00 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:37:00 INFO - PROCESS | 1658 | RSS: Main: 232456192 12:37:00 INFO - PROCESS | 1658 | 12:37:03 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:37:04 INFO - PROCESS | 1658 | RSS: Main: 232869888 12:37:04 INFO - PROCESS | 1658 | 12:37:07 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:37:08 INFO - PROCESS | 1658 | RSS: Main: 232587264 12:37:08 INFO - PROCESS | 1658 | 12:37:09 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49219/tests/kraken/driver.html?testName=ai-astar) 12:37:10 INFO - PROCESS | 1658 | RSS: Main: 234299392 12:37:10 INFO - PROCESS | 1658 | 12:37:10 INFO - PROCESS | 1658 | __start_tp_report 12:37:10 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 12:37:10 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 12:37:10 INFO - PROCESS | 1658 | |i|pagename|runs| 12:37:10 INFO - PROCESS | 1658 | |0;ai-astar;109;123;119;121;118;119;142;120;118;119 12:37:10 INFO - PROCESS | 1658 | |1;audio-beat-detection;142;112;111;146;113;115;111;111;135;117 12:37:10 INFO - PROCESS | 1658 | |2;audio-dft;275;221;198;197;197;226;258;198;198;195 12:37:10 INFO - PROCESS | 1658 | |3;audio-fft;81;62;62;85;69;64;61;61;76;61 12:37:10 INFO - PROCESS | 1658 | |4;audio-oscillator;86;71;85;71;70;70;82;72;61;100 12:37:10 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;90;91;91;92;90;90;105;90;126;92 12:37:10 INFO - PROCESS | 1658 | |6;imaging-darkroom;98;98;98;95;98;96;98;96;97;98 12:37:10 INFO - PROCESS | 1658 | |7;imaging-desaturate;139;134;88;93;89;90;132;101;124;107 12:37:10 INFO - PROCESS | 1658 | |8;json-parse-financial;68;69;67;68;71;71;70;70;99;109 12:37:10 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;58;63;64;54;64;84;59;50;79;78 12:37:10 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;85;87;88;84;83;82;89;86;90;109 12:37:10 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;148;128;128;166;162;145;142;147;170;142 12:37:10 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;168;170;168;167;163;162;219;217;166;168 12:37:10 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;67;65;76;84;83;87;85;69;67;66 12:37:10 INFO - PROCESS | 1658 | __end_tp_report 12:37:10 INFO - PROCESS | 1658 | __start_cc_report 12:37:10 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1018 12:37:10 INFO - PROCESS | 1658 | __end_cc_report 12:37:10 INFO - PROCESS | 1658 | __startTimestamp1496345830104__endTimestamp 12:37:10 INFO - PROCESS | 1658 | 12:37:10 INFO - PROCESS | 1658 | ------- Summary: start ------- 12:37:10 INFO - PROCESS | 1658 | Number of tests: 14 12:37:10 INFO - PROCESS | 1658 | 12:37:10 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:120.80 Median:119.50 stddev:8.30 (6.9%) stddev-sans-first:7.62 12:37:10 INFO - PROCESS | 1658 | Values: 109.0 123.0 119.0 121.0 118.0 119.0 142.0 120.0 118.0 119.0 12:37:10 INFO - PROCESS | 1658 | 12:37:10 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:121.30 Median:116.00 stddev:13.98 (12.0%) stddev-sans-first:12.66 12:37:10 INFO - PROCESS | 1658 | Values: 142.0 112.0 111.0 146.0 113.0 115.0 111.0 111.0 135.0 117.0 12:37:10 INFO - PROCESS | 1658 | 12:37:10 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:216.30 Median:209.50 stddev:28.88 (13.8%) stddev-sans-first:21.43 12:37:10 INFO - PROCESS | 1658 | Values: 275.0 221.0 198.0 197.0 197.0 226.0 258.0 198.0 198.0 195.0 12:37:10 INFO - PROCESS | 1658 | 12:37:10 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:68.20 Median:66.50 stddev:9.17 (13.8%) stddev-sans-first:8.48 12:37:10 INFO - PROCESS | 1658 | Values: 81.0 62.0 62.0 85.0 69.0 64.0 61.0 61.0 76.0 61.0 12:37:10 INFO - PROCESS | 1658 | 12:37:10 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:76.80 Median:71.50 stddev:11.30 (15.8%) stddev-sans-first:11.49 12:37:10 INFO - PROCESS | 1658 | Values: 86.0 71.0 85.0 71.0 70.0 70.0 82.0 72.0 61.0 100.0 12:37:10 INFO - PROCESS | 1658 | 12:37:10 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:95.70 Median:90.50 stddev:11.58 (12.8%) stddev-sans-first:12.09 12:37:10 INFO - PROCESS | 1658 | Values: 90.0 91.0 91.0 92.0 90.0 90.0 105.0 90.0 126.0 92.0 12:37:10 INFO - PROCESS | 1658 | 12:37:10 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:97.20 Median:98.00 stddev:1.14 (1.2%) stddev-sans-first:1.17 12:37:10 INFO - PROCESS | 1658 | Values: 98.0 98.0 98.0 95.0 98.0 96.0 98.0 96.0 97.0 98.0 12:37:10 INFO - PROCESS | 1658 | 12:37:10 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:109.70 Median:113.50 stddev:20.55 (18.1%) stddev-sans-first:18.86 12:37:10 INFO - PROCESS | 1658 | Values: 139.0 134.0 88.0 93.0 89.0 90.0 132.0 101.0 124.0 107.0 12:37:10 INFO - PROCESS | 1658 | 12:37:10 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:76.20 Median:70.00 stddev:14.90 (21.3%) stddev-sans-first:15.50 12:37:10 INFO - PROCESS | 1658 | Values: 68.0 69.0 67.0 68.0 71.0 71.0 70.0 70.0 99.0 109.0 12:37:10 INFO - PROCESS | 1658 | 12:37:10 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:65.30 Median:64.00 stddev:11.36 (17.8%) stddev-sans-first:11.74 12:37:10 INFO - PROCESS | 1658 | Values: 58.0 63.0 64.0 54.0 64.0 84.0 59.0 50.0 79.0 78.0 12:37:10 INFO - PROCESS | 1658 | 12:37:10 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:86.50 stddev:7.72 (8.9%) stddev-sans-first:8.09 12:37:10 INFO - PROCESS | 1658 | Values: 85.0 87.0 88.0 84.0 83.0 82.0 89.0 86.0 90.0 109.0 12:37:10 INFO - PROCESS | 1658 | 12:37:10 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:147.80 Median:147.50 stddev:14.47 (9.8%) stddev-sans-first:15.35 12:37:10 INFO - PROCESS | 1658 | Values: 148.0 128.0 128.0 166.0 162.0 145.0 142.0 147.0 170.0 142.0 12:37:10 INFO - PROCESS | 1658 | 12:37:10 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.80 Median:168.00 stddev:21.85 (13.0%) stddev-sans-first:22.94 12:37:10 INFO - PROCESS | 1658 | Values: 168.0 170.0 168.0 167.0 163.0 162.0 219.0 217.0 166.0 168.0 12:37:10 INFO - PROCESS | 1658 | 12:37:10 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.90 Median:79.50 stddev:9.04 (11.4%) stddev-sans-first:9.12 12:37:10 INFO - PROCESS | 1658 | Values: 67.0 65.0 76.0 84.0 83.0 87.0 85.0 69.0 67.0 66.0 12:37:10 INFO - PROCESS | 1658 | -------- Summary: end -------- 12:37:10 INFO - PROCESS | 1658 | 12:37:10 INFO - TEST-INFO | 1658: exit 0 12:37:10 INFO - TEST-OK | kraken | took 60468ms 12:37:10 INFO - SUITE-END | took 1176s 12:37:11 INFO - Completed test suite (00:19:37) 12:37:11 INFO - javascript benchmark 12:37:11 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7756.15888662046, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3487.703545198658, 3492.3888319544058, 3515.1387909962064, 3518.278752855665, 3528.8764893228736, 5657.434256574345, 5898.3806700296445, 5966.37353077927, 5984.611000284989, 6007.87984240316, 6006.0, 6094.87810243796, 6099.0241561350085, 6099.817005489843, 6130.386961303868, 4249.702520823544, 5402.216678581593, 5457.890842183154, 5460.945390546083, 5498.065328894098, 4536.160810250098, 5456.8090116845915, 5497.092979658371, 5509.559235261169, 5511.641743286674, 5316.441773613773, 5331.440198779131, 5509.090999984997, 5518.834434966958, 5548.77804887804, 5440.945590544123, 5448.509634132927, 5455.1544510600725, 5477.5070243677865, 5490.450954904518, 4546.704464209815, 5274.945010997777, 5437.864053398657, 5441.59188060897, 5446.7276636168035, 5631.2960879890015, 5742.425757424266, 6053.364396738323, 6108.816735497942, 6155.0, 6276.654783986879, 6295.370462953714, 6296.118543403927, 6324.683765811691, 6339.524535659798, 6459.160309159826, 6516.446102081329, 6518.185226846645, 6520.47836173105, 6536.869262614721, 5694.373618901917, 5758.280214973129, 5946.375630558816, 6163.630182189083, 6191.566590338633, 5462.945370546323, 6392.232932048184, 6455.935440645628, 6459.063435801782, 6482.222133344029, 6211.440970312603, 6242.282137554149, 6320.905186422207, 6333.588316759396, 6340.778072767431, 6429.0, 6434.74261029563, 6444.323346048691, 6449.258335291408, 6464.77373291942, 6218.129461875341, 6227.96886015567, 6246.312905580383, 6268.49852011838, 6344.873102537924, 6283.7800676976085, 6342.23893132827, 6347.492200623939, 6362.9045564316575, 6378.202724659418, 4886.975565122223, 4929.186684197068, 4958.752062396866, 4959.975200123977, 4960.206366981266, 4166.604172603597, 4380.364847097153, 4469.0, 4477.529859364785, 4506.414166158378, 5308.885134121878, 5761.452661997104, 5943.970280148658, 5959.731812068468, 6005.189299444606, 6419.743210271537, 6445.548811583144, 6462.5153113515735, 6486.254080780771, 6494.0, 6013.458788709037, 6020.729067191987, 6031.487323577545, 6031.60794548353, 6042.3655516170165, 5382.407935127133, 5384.730763461811, 5554.805581804699, 5563.888722225533, 5642.4357564244065, 6618.974059020763, 6622.10601568782, 6629.3039230882, 6638.0, 6641.933580664325, 5651.491365777099, 6405.551611387158, 6455.2576453708425, 6461.483081353386, 6485.513586480938, 6206.131141640264, 6358.0, 6473.579217350857, 6485.383888530582, 6589.0775291460195, 6307.0, 6383.840403989938, 6408.9038664421005, 6414.390632889868, 6419.197600299963, 5083.420099536201, 6336.20797400325, 6438.227412710505, 6445.2587952386075, 6458.483321334376, 6160.10678451631, 6203.689815509297, 6230.470410015199, 6256.31180570146, 6284.308726040041, 6230.221222347207, 6236.875262494816, 6251.937480625317, 6301.235654016883, 6324.209473815773, 5412.809181980116, 5513.007658621326, 5632.746526406403, 6097.0, 6317.557770955989, 6142.867137759686, 6197.876042479215, 6274.905876411949, 6275.5607107503865, 6290.622562646256, 5660.858478537904, 5876.824635072917, 6193.876122477615, 6266.592671476339, 6286.119943207863], "value": 5836.814527083101, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.2686567164179, 808.9231523005315, 818.7011740714639, 957.3729207369175, 964.2093483343662, 39880.80059599702, 41017.0, 42538.0, 42576.9355766106, 42608.0, 32762.0, 41262.38106428398, 41284.58715412845, 41298.96752581187, 41332.38001429981, 7946.960265198666, 8068.878966815502, 10960.068394186465, 11339.262947908359, 11574.594889178881, 9350.906490935105, 11145.442727863616, 11237.157213209042, 11239.213255072149, 11257.831132533018, 11268.32390056595, 11270.154738394653, 11308.208425410225, 11315.434228288594, 11327.660170194866, 11241.325520468758, 11253.099752019818, 11284.32294062359, 11299.717507062307, 11314.603988860392, 10989.285696429706, 11056.502457389437, 11074.557017719282, 11080.722981925435, 11086.722831929266, 34453.827730861434, 34759.65240347589, 34836.477452838226, 34883.4767478488, 34916.0, 3848.268828922524, 3883.611638836122, 3887.0, 3941.034446560611, 3959.1289916218384, 25164.622530662054, 25356.619650705255, 26768.795404206856, 26781.928722851062, 26800.463990720076, 3018.743406810424, 3125.87496500142, 3161.7944833585743, 3256.0720194744385, 3272.296456261915, 331815.0, 333023.0, 333168.0, 333414.0, 334183.32908335543, 43588.78205608952, 43760.343594846105, 44392.778036110256, 44455.0, 44476.0, 38820.8058959709, 46639.76680116646, 47003.76498117488, 47090.0, 47233.52766472291, 13888.0, 14158.716825663429, 14242.931780116533, 14248.0, 14270.500532481314, 12282.955948744278, 12286.0, 12289.139760216878, 12291.815622765665, 12317.876821231674, 11976.101792365433, 12083.093767967437, 12132.57535986254, 12135.5145794169, 12171.96538294255, 4933.125412343286, 5759.337676167294, 5789.3921138279875, 5798.478136967693, 5834.824955251349, 899.8335307968047, 974.9227104049922, 1125.5160281079216, 1139.1171841822654, 1139.760650263454, 27050.86474567654, 27346.733518220397, 27430.314242143708, 27593.724062759517, 27632.86183569069, 1212.1151559361715, 1229.5757963502576, 1236.4621389695512, 1260.2564486952742, 1278.526945030326, 11653.825192622118, 12212.511499540096, 13684.315784210949, 13981.44074237019, 14315.928420357832, 373846.26153738657, 433662.0, 438658.80670596444, 440804.0, 443186.0, 58425.53936151545, 58480.12279815806, 58726.70636646789, 58795.11807322808, 58988.70505647444, 1248.2073883084306, 1256.5916077274921, 1284.916480428769, 1434.0, 1472.4551915791226, 26150.692465377037, 27797.58303625407, 29473.705262947096, 30808.383832323678, 30819.229519262197, 46426.535734642224, 46486.0, 46611.0, 46677.99999999864, 46769.29846052244, 409250.0, 414505.0, 414529.0, 415043.84956150054, 416582.91708541266, 25876.8706156468, 26347.86826065934, 31839.68160318367, 32086.83956580202, 32122.196945075626, 44068.55931440645, 44562.3315650272, 44741.328880066176, 45504.0, 46300.0, 30412.695873040986, 30424.695753042186, 30620.0, 31108.844455777577, 31725.682743173194, 30610.540841886945, 31236.0, 31423.37153256968, 31591.84204078965, 31648.89228876978, 29271.414571708872, 36081.27837443184, 36716.63283367132, 37334.0, 37490.62509374871, 30988.84505577458, 31483.0, 32207.677923220468, 32221.51667725033, 32285.16346854942, 23244.418889527897, 24196.153134640204, 24505.142320018713, 24519.754802451745, 24544.263672089866], "value": 18692.621263955272, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.28957613752067, 410.42130595859845, 412.68223467929676, 421.38688208656407, 451.47403275184394, 19942.99999999998, 20599.38201853943, 21818.781812181875, 22118.557628847433, 22154.88922555389, 21973.23093691721, 22379.440513987116, 22621.77378226217, 22844.65733014006, 22880.199193028246, 6799.864002719943, 6909.654517274142, 7087.043249161349, 7103.715851365965, 7114.217436082027, 7151.535150215245, 7474.177840437547, 7644.770656880302, 7646.311831935125, 7647.847043059135, 7432.554046757212, 7607.847843043136, 7607.923920760777, 7739.729109481169, 7900.723474678387, 7498.175200727916, 7569.659365328574, 7810.765677029698, 7835.177306382833, 7842.058952925686, 7407.481476296662, 7412.85174296511, 7441.95346279772, 7444.404447644176, 7445.255474452566, 25337.873310633513, 25613.359666008313, 26354.60468092961, 26449.206523804318, 26726.73273267262, 2879.2513946373883, 2962.762978961678, 2982.1500872251304, 2983.8060526065738, 3035.9089227323216, 15122.924385378114, 16299.91850040754, 16349.836501635069, 16503.0, 16865.988040717475, 2013.9798602013939, 2063.164418410546, 2063.9484012899647, 2073.709680644711, 2093.591749608827, 156317.0, 159101.40898590983, 164696.17651911784, 167044.16477917653, 168242.15878920772, 15134.470293539893, 19940.102695378453, 20398.0, 20438.386848394573, 20589.279375221795, 30066.69933300639, 30094.69905300919, 30292.24269393283, 30483.847580761954, 30486.0, 10201.846972295421, 10441.46294072807, 10651.520681569347, 10682.092022178047, 10690.946545267223, 8829.072947340564, 8842.204201621886, 8884.200421961925, 8935.374523783274, 9019.368644194843, 9538.666146684833, 9667.936526982026, 9677.806443871083, 9698.127168554864, 9769.755756106155, 3409.9659003410143, 3871.070942973722, 4396.0, 4433.556644335606, 4439.556044395535, 788.8304014636881, 878.2359347367831, 879.5338470610586, 886.264400547544, 922.6401703335705, 20143.294984675467, 21085.78914210869, 21397.572048559254, 21514.0, 21530.6770398441, 822.2353211513231, 835.2649668291986, 919.2645883293338, 946.2477330522252, 947.7062110745691, 9580.95209523948, 9616.51917404127, 10588.788224235472, 11133.276337038067, 11259.493322800494, 703447.0, 757266.0, 798714.0, 799337.0, 812175.0, 159255.20372398064, 160697.0, 160835.0, 165919.0, 166638.50042249376, 1274.6367285323638, 1293.6119164250688, 1336.8596297388829, 1342.0404410846293, 1342.1946831900977, 18976.810231897503, 19352.612947740683, 19708.916009619035, 19876.204951801767, 20040.89979550093, 55687.44312556823, 64070.35929640644, 64495.0, 65635.0, 66074.66962665156, 67348.0, 90370.0, 91454.0, 93044.0, 94348.11303774023, 10790.0, 11407.771844563229, 11581.305121692725, 11969.461374238354, 12035.157538972187, 10689.358638481715, 11924.867137621912, 12409.813852792397, 13116.409761560562, 13288.335583220995, 10868.347899126078, 11178.944105279421, 11683.824742628698, 12030.27818330903, 12071.456784444545, 9524.571394287135, 10611.575536978435, 10638.52126654287, 10703.946480267548, 10961.671149865517, 11568.479418426023, 11730.296182229093, 11811.763764724485, 11980.820287695866, 12028.578999734968, 11499.942500287445, 11728.0, 11796.705082372666, 11797.646070617895, 12000.939995299968, 11381.943090284496, 11503.309801411942, 11537.01935335506, 11752.882471175179, 11907.345096019455], "value": 11786.067022654572, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1415.1438379780232, 1575.133676477937, 1635.4357746577427, 1635.4602981016258, 1638.4101723379583, 10762.139028877691, 11072.0, 11380.829287560693, 11454.255473394234, 11462.197646164772, 11002.614908478205, 11319.0, 11320.773584528304, 11355.375454350007, 11362.318260904352, 2394.7485514021037, 2445.070873068232, 2446.4984678141027, 2448.192096608124, 2449.3141920262265, 2359.516299158677, 2412.2401443545314, 2432.3554258121612, 2434.47658753368, 2447.840890342131, 2399.940001499959, 2441.7192022917325, 2453.901843926241, 2461.064795377754, 2467.8025757939413, 2366.1127077345996, 2395.0300128448007, 2397.592409290416, 2413.1433341163897, 2438.5854404751235, 2141.9036143373587, 2395.0659242895285, 2400.327908185711, 2408.8193385496156, 2412.19191570824, 11030.338179709242, 11122.110231181487, 11178.385188814613, 11219.38293393863, 11233.0, 5899.793507227269, 5999.400059994009, 6085.726142323606, 6089.543284253655, 6096.512279017668, 3610.855565777366, 3683.079230192452, 3726.273376691546, 3726.5341832270965, 3754.0427191066105, 1177.3112729053514, 1190.9047276217882, 1199.208522375228, 1218.0864351736197, 1268.987310126896, 13842.307884605729, 14160.92919535406, 15058.397664093314, 15266.542003739905, 15686.450974215963, 4946.3817022872145, 4983.302337672729, 5126.307948426989, 5135.845924622267, 5147.661607981951, 13310.401031953585, 13362.797348238706, 13364.2649654268, 13366.799498007344, 13396.330183490787, 6061.0, 6109.327973922865, 6115.847103822351, 6130.448259656652, 6131.84670383244, 5146.305248791366, 5292.708901010481, 5325.786968521294, 5348.251244825728, 5360.3031605891765, 3925.7644541327613, 4016.4176194451643, 4051.716379853441, 4053.2298863215897, 4119.443875076886, 1070.2722148938797, 1072.3244356055634, 1073.0289088375032, 1083.701981954953, 1089.3844977587637, 931.1340453378423, 932.47315266874, 933.6685476655825, 946.5645802930729, 990.6136606723384, 6473.28793832678, 6491.51313651478, 6540.869182616416, 6709.865802683919, 6803.235090893719, 996.4469719305855, 1014.2038499777639, 1039.4335087377397, 1044.9268551201344, 1073.978520429587, 4284.400183974246, 4297.0, 4298.4412026436985, 4419.204543182258, 4420.491643460979, 13157.53948611794, 13770.862291377158, 13784.793228101587, 13791.51729689456, 13801.585952421443, 13240.867591324157, 13414.597562073153, 13689.315534223448, 13743.931280343535, 13744.244066576235, 8628.870566941376, 9824.508774561244, 10147.441890695934, 10157.238207134495, 10299.279050466395, 6353.936460635335, 6593.736250549924, 6619.205695316593, 6817.454603631797, 6861.931380686129, 11478.598249061242, 12206.145569810384, 12227.755444890874, 12326.630201093982, 12528.749425011267, 12487.12590118683, 12613.621591352274, 12633.747325053631, 12695.619131426072, 12768.61694149177, 7896.84206315859, 9243.815123697354, 9364.01677823846, 9391.906080939103, 9467.479288639192, 10046.748831279276, 10358.5338659762, 10360.948195258976, 10362.948185259025, 10369.74075648115, 8648.783780405289, 8846.33652476054, 8906.332025098014, 8929.10708929102, 8947.686830960885, 8668.0, 8691.739247822576, 8691.826163476822, 8781.077986811293, 8957.955210223907, 7837.020372453443, 7984.6806127754235, 7992.120866704541, 7995.2804247618, 7997.720079797179, 5253.343332083489, 5870.647761134345, 5899.852503687271, 5916.201312822886, 6139.72371243304, 5957.106434034929, 5994.55040871945, 6055.969720151371, 6136.478399336106, 6235.475988630166], "value": 5230.587592947709, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.54259594885383, 346.25555056628247, 366.94679271505635, 384.9403342481916, 393.94681717968064, 4832.492588278233, 5166.535011848933, 5252.133397989337, 5262.7368631568415, 5387.4073851876265, 4943.9752801235945, 5049.823256186034, 5229.555487783543, 5236.738163091849, 5241.9596633952315, 3773.3585290500682, 3822.6750726188307, 4497.775111244441, 4513.255312873372, 4605.539446055384, 3500.982495087521, 4314.374415709736, 4572.862814115582, 4648.325992731052, 4656.580907718305, 4065.0731473339147, 4309.478954811423, 4323.178596066738, 4328.84849030284, 4387.539308372623, 4024.8931543825684, 4356.8257269709175, 4365.126974605076, 4388.736675799462, 4414.53647367025, 3678.2091850252323, 3882.776925268531, 3950.2494526040246, 4086.652634526069, 4102.2821006323775, 4162.583741625813, 4641.698289611199, 4648.74431906245, 4720.026383378449, 4727.834525791615, 4115.0, 4564.9044229385045, 5046.6719663221775, 5083.923741143886, 5115.5396014358885, 4637.605803506706, 4920.0, 5059.8988020239385, 5637.887242255173, 5716.370834312243, 4211.599898009684, 4213.894652633709, 4548.840790572347, 4560.521145279764, 4583.335416364608, 5509.944900550983, 5645.858853528694, 5936.436038576328, 5948.7620495180145, 6152.477039451652, 3751.549814022335, 3966.861159859449, 3976.622220889011, 4361.890952726208, 4426.927226951213, 4665.5567721066445, 4674.883127921829, 4694.906101877943, 4710.456353569034, 4736.973572463447, 4491.393661855673, 4666.416697912761, 4675.509071547506, 4849.133083762777, 5102.984321193108, 4603.58567728906, 4881.633877459134, 4917.483664215278, 5106.565941894906, 5651.0, 1401.5444980381417, 1418.2270662488968, 1427.6722647937518, 1565.6946895355525, 1593.3228377939329, 1022.8261195596766, 1093.8851420600722, 1134.472470301314, 1216.5583783216916, 1224.871388504212, 4487.798049087799, 4710.396546641822, 4997.251511668568, 5100.336956195745, 5133.383993920754, 5019.239418319909, 5426.620136590401, 5799.811038737049, 5863.3257175424515, 5890.705464726747, 4484.596386325246, 4527.547245275512, 5011.298418221452, 5096.388433388017, 5104.157813960729, 2668.506326329635, 2729.2025673684634, 2748.395353022332, 2877.5540790328055, 2926.5171246744476, 5451.48210919962, 5798.884022319615, 6032.215811944419, 6040.30871356021, 6044.24446944132, 5583.441655834384, 5823.708814559255, 5955.426743953593, 6012.5886701968575, 6074.934522262363, 5071.492850714899, 5711.742971566289, 5796.811653610999, 5985.910211346746, 6051.515878729779, 5157.086246270845, 5405.594580406407, 5696.319585722139, 5769.711514424346, 5820.82537523875, 5254.65844720092, 5323.467653234646, 5672.12082127271, 5793.36273009977, 5851.303122094545, 4766.569049784565, 4970.6023518117745, 5509.917351239815, 5720.914186287292, 5867.6772777497645, 4050.3924411338535, 4347.826086956613, 4401.515833258275, 4410.448693913261, 4633.8146474142, 5505.9724701376235, 5743.629706224693, 5910.970445147918, 5956.463918247378, 6032.306284777307, 4556.521565235603, 4620.722756634613, 4974.5771609413605, 5616.887662246814, 5621.241132447068, 4694.812207511662, 5215.6609820361555, 5586.575423267041, 5603.887922241451, 5668.858278543069, 4941.641048711633, 5127.6410651254055, 5174.033867130466, 5321.627486075938, 5392.811251606332, 4736.526347365236, 4841.467438581823, 5240.004399164299, 5562.638428502134, 5607.5513958882375, 5470.945290547043, 5479.410970818418, 5485.835424937259, 5505.0, 5552.389237183826], "value": 4235.571404003851, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [727.2872584866831, 752.9171791102981, 767.4781148818804, 828.0932379044946, 843.3759018326439, 15912.681746365066, 16102.597435064115, 16902.91548542257, 16987.490375288762, 17001.0, 15447.22763861808, 15660.138692371915, 15718.135502547355, 16251.268692908818, 16897.83102168978, 957.6121670723351, 983.4394395194741, 986.7385142937092, 987.4963768478092, 990.1336330710628, 941.6986564299427, 977.8777652793401, 978.3542861711272, 983.3018556824663, 984.4880662055726, 1037.2531777120462, 1049.9160067194643, 1050.7268110291302, 1053.1627356251797, 1056.7041228456044, 889.4218757807412, 1038.7403149212696, 1048.7954848804447, 1049.3808652894752, 1050.5482642463737, 596.3201949777257, 779.1585088104833, 783.4241832253289, 784.0356361675172, 785.1402714028187, 16710.41513547032, 17201.655966880717, 17291.0, 17312.826871731373, 17529.474115776546, 14249.216293103875, 14276.857231427657, 14278.357473913698, 14391.685469539309, 14478.638034049127, 11759.882401175964, 11781.46983385741, 11890.108241881893, 11895.762084758342, 12041.698957526045, 3585.9103522412147, 3670.8715194968313, 3791.9431208531896, 3938.5864484229314, 4009.5389030261604, 104874.0, 109752.0, 111113.88886111116, 111330.88669113367, 111644.32533512004, 10315.27793054494, 10776.29954052984, 10871.29336593119, 10996.065334446661, 11287.097032237401, 22236.444088897584, 22510.887445562996, 22521.0, 22723.318300451014, 22754.544909102056, 7808.453408261481, 7875.763727088197, 7929.405294602928, 7958.482698624571, 8108.635111420001, 3779.111908701453, 4303.376010478463, 4350.303951367766, 4374.978125109354, 4420.8673739787855, 3505.9649403505637, 3544.946825797615, 3581.8209089545417, 3594.4967704521387, 3595.0113718727557, 621.4965877639037, 632.4782054804805, 632.5698525003043, 640.4940097323063, 647.3009150117955, 681.2029924987776, 686.3582550315471, 687.1753895325629, 705.1714235772946, 732.7655150351943, 9115.407498512575, 10303.793924121475, 10433.321834080913, 10478.0, 10479.790404191874, 682.9965850170718, 692.7021380806302, 718.0880282041778, 718.8059224009488, 743.650484272391, 5000.1249781288125, 5000.74996250186, 5028.321176641107, 5033.282844387808, 5097.649122982412, 81293.59353203315, 99747.50126249467, 101990.45023874314, 106486.46756766166, 107042.0, 81968.0, 83141.75287370694, 83883.58058209669, 85207.5739621298, 86946.0, 53413.73293133509, 54478.45521544813, 67771.98342024874, 74112.0, 75583.0, 10619.469026548797, 11024.228304018641, 11067.0, 11147.665570032912, 11275.774484510428, 39522.40716389314, 42563.36154957616, 44645.776771117235, 47037.76481117572, 48819.0, 52040.0, 52397.7380113097, 59734.0, 60262.0, 61508.69245653743, 12618.55835045769, 12649.936750316558, 12779.488820447077, 12921.095523313277, 13117.34413279313, 14237.0, 14247.287635618384, 14428.927855361077, 14785.0, 15207.61980950441, 13332.333383330988, 13858.237796921267, 13862.376193071537, 14139.293035348397, 14291.356888939814, 13140.27728474943, 13611.931940340235, 13803.0, 13853.79219311691, 14284.07153535017, 12855.485780568673, 13421.463141474233, 13505.864941350854, 13602.523911663044, 13785.172889626254, 7900.881486777588, 9154.908450915673, 9620.903790962, 9828.16460600857, 9896.604135834774, 7228.867939475585, 10354.275200735876, 10461.633842815465, 10516.316439431412, 11127.387993660426], "value": 7641.203620299051, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1535.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 123.0, 119.0, 121.0, 118.0, 119.0, 142.0, 120.0, 118.0, 119.0], "value": 120.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 112.0, 111.0, 146.0, 113.0, 115.0, 111.0, 111.0, 135.0, 117.0], "value": 121.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [275.0, 221.0, 198.0, 197.0, 197.0, 226.0, 258.0, 198.0, 198.0, 195.0], "value": 216.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 62.0, 62.0, 85.0, 69.0, 64.0, 61.0, 61.0, 76.0, 61.0], "value": 68.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 85.0, 71.0, 70.0, 70.0, 82.0, 72.0, 61.0, 100.0], "value": 76.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 91.0, 92.0, 90.0, 90.0, 105.0, 90.0, 126.0, 92.0], "value": 95.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 98.0, 95.0, 98.0, 96.0, 98.0, 96.0, 97.0, 98.0], "value": 97.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 134.0, 88.0, 93.0, 89.0, 90.0, 132.0, 101.0, 124.0, 107.0], "value": 109.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 67.0, 68.0, 71.0, 71.0, 70.0, 70.0, 99.0, 109.0], "value": 76.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 63.0, 64.0, 54.0, 64.0, 84.0, 59.0, 50.0, 79.0, 78.0], "value": 65.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 88.0, 84.0, 83.0, 82.0, 89.0, 86.0, 90.0, 109.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 128.0, 128.0, 166.0, 162.0, 145.0, 142.0, 147.0, 170.0, 142.0], "value": 147.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 168.0, 167.0, 163.0, 162.0, 219.0, 217.0, 166.0, 168.0], "value": 176.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 76.0, 84.0, 83.0, 87.0, 85.0, 69.0, 67.0, 66.0], "value": 74.9, "unit": "score"}]}]} 12:37:11 INFO - Return code: 0 12:37:11 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:37:11 ERROR - # TBPL SUCCESS # 12:37:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:37:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:37:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:37:11 INFO - 2017-06-01 12:37:11.445 screenresolution[1663:10514] starting screenresolution argv=screenresolution get 12:37:11 INFO - 2017-06-01 12:37:11.448 screenresolution[1663:10514] Display 0: 1600x1200x32@60 12:37:11 INFO - 2017-06-01 12:37:11.453 screenresolution[1664:10518] starting screenresolution argv=screenresolution list 12:37:11 INFO - Available Modes on Display 0 12:37:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:37:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:37:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:37:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:37:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:37:11 INFO - Intel Iris: 12:37:11 INFO - Chipset Model: Intel Iris 12:37:11 INFO - Type: GPU 12:37:11 INFO - Bus: Built-In 12:37:11 INFO - VRAM (Dynamic, Max): 1536 MB 12:37:11 INFO - Vendor: Intel (0x8086) 12:37:11 INFO - Device ID: 0x0a2e 12:37:11 INFO - Revision ID: 0x0009 12:37:11 INFO - Displays: 12:37:11 INFO - Display: 12:37:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:37:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:37:11 INFO - Display Serial Number: 1600x1200 60 12:37:11 INFO - Main Display: Yes 12:37:11 INFO - Mirror: Off 12:37:11 INFO - Online: Yes 12:37:11 INFO - Rotation: Supported 12:37:11 INFO - Return code: 0 12:37:11 INFO - Running post-action listener: _resource_record_post_action 12:37:11 INFO - [mozharness: 2017-06-01 19:37:11.688830Z] Finished run-tests step (success) 12:37:11 INFO - Running post-run listener: _resource_record_post_run 12:37:11 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 45840384; Write bytes: 513944576; Read time: 478; Write time: 1858 12:37:11 INFO - TinderboxPrint: CPU usage
54.5% 12:37:11 INFO - TinderboxPrint: I/O read bytes / time
45,840,384 / 478 12:37:11 INFO - TinderboxPrint: I/O write bytes / time
513,944,576 / 1,858 12:37:11 INFO - TinderboxPrint: CPU idle
2,182.4 (45.5%) 12:37:11 INFO - TinderboxPrint: CPU system
961.5 (20.1%) 12:37:11 INFO - TinderboxPrint: CPU user
1,649.8 (34.4%) 12:37:11 INFO - TinderboxPrint: Swap in / out
249,888,768 / 0 12:37:11 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 204261888; Write bytes: 202586112; Read time: 15609; Write time: 461 12:37:11 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 43816960; Write bytes: 300499968; Read time: 455; Write time: 1362 12:37:12 INFO - Running post-run listener: _upload_blobber_files 12:37:12 INFO - Blob upload gear active. 12:37:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:37:12 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 12:37:12 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'] 12:37:12 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 12:37:12 INFO - (blobuploader) - INFO - Open directory for files ... 12:37:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:37:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:37:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:37:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:37:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:37:13 INFO - (blobuploader) - INFO - Done attempting. 12:37:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:37:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:37:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:37:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:37:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:37:14 INFO - (blobuploader) - INFO - Done attempting. 12:37:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:37:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:37:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:37:15 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:37:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:37:15 INFO - (blobuploader) - INFO - Done attempting. 12:37:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:37:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:37:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:37:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:37:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:37:17 INFO - (blobuploader) - INFO - Done attempting. 12:37:17 INFO - (blobuploader) - INFO - Iteration through files over. 12:37:17 INFO - Return code: 0 12:37:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:37:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:37:17 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b374875acba7514f0c86271fa6beb6769098be8b4cd70a1692a28a7be1660f73626e66dfd7bdac247cd6b88a52c64b5216aa698efd78a666bd51522554c148e5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/40fa3ba1d2a5793d8dafdf22852d9010272de4a7605c6177df262dfbc531fe0885c106d09902a643992dc7adf34163d69096cdf5b211dc14f5d9cd958eace257", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/02a510a4e2c4f84096801b0673aa94325f10d83f333095ab24d6b144daff9f749cc8f809d5edb1ff5d9fd3415207213b3683569468b08b51047e9c32b2a1145b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c9052d49f8426ca7018bcda668b50097d21f21cb77e9beccfa963b47bf355c1829e380be18bcd5697c92ad68b29853d4835c73073477481f18cee622e8c16a2e"} 12:37:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:37:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:37:17 INFO - Contents: 12:37:17 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b374875acba7514f0c86271fa6beb6769098be8b4cd70a1692a28a7be1660f73626e66dfd7bdac247cd6b88a52c64b5216aa698efd78a666bd51522554c148e5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/40fa3ba1d2a5793d8dafdf22852d9010272de4a7605c6177df262dfbc531fe0885c106d09902a643992dc7adf34163d69096cdf5b211dc14f5d9cd958eace257", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/02a510a4e2c4f84096801b0673aa94325f10d83f333095ab24d6b144daff9f749cc8f809d5edb1ff5d9fd3415207213b3683569468b08b51047e9c32b2a1145b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c9052d49f8426ca7018bcda668b50097d21f21cb77e9beccfa963b47bf355c1829e380be18bcd5697c92ad68b29853d4835c73073477481f18cee622e8c16a2e"} 12:37:17 INFO - Running post-run listener: copy_logs_to_upload_dir 12:37:17 INFO - Copying logs to upload dir... 12:37:17 INFO - mkdir: /builds/slave/test/build/upload/logs 12:37:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.310958 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-06-01 12:37:17.778171) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 12:37:17.781278) ========= 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.eBrtds7zOO/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.czkEPZZqsv/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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/b374875acba7514f0c86271fa6beb6769098be8b4cd70a1692a28a7be1660f73626e66dfd7bdac247cd6b88a52c64b5216aa698efd78a666bd51522554c148e5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/40fa3ba1d2a5793d8dafdf22852d9010272de4a7605c6177df262dfbc531fe0885c106d09902a643992dc7adf34163d69096cdf5b211dc14f5d9cd958eace257", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/02a510a4e2c4f84096801b0673aa94325f10d83f333095ab24d6b144daff9f749cc8f809d5edb1ff5d9fd3415207213b3683569468b08b51047e9c32b2a1145b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c9052d49f8426ca7018bcda668b50097d21f21cb77e9beccfa963b47bf355c1829e380be18bcd5697c92ad68b29853d4835c73073477481f18cee622e8c16a2e"} build_url:https://queue.taskcluster.net/v1/task/RxCK7X--TjuCfQ-_6UkMMg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011061 build_url: 'https://queue.taskcluster.net/v1/task/RxCK7X--TjuCfQ-_6UkMMg/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/b374875acba7514f0c86271fa6beb6769098be8b4cd70a1692a28a7be1660f73626e66dfd7bdac247cd6b88a52c64b5216aa698efd78a666bd51522554c148e5", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/40fa3ba1d2a5793d8dafdf22852d9010272de4a7605c6177df262dfbc531fe0885c106d09902a643992dc7adf34163d69096cdf5b211dc14f5d9cd958eace257", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/02a510a4e2c4f84096801b0673aa94325f10d83f333095ab24d6b144daff9f749cc8f809d5edb1ff5d9fd3415207213b3683569468b08b51047e9c32b2a1145b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c9052d49f8426ca7018bcda668b50097d21f21cb77e9beccfa963b47bf355c1829e380be18bcd5697c92ad68b29853d4835c73073477481f18cee622e8c16a2e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 12:37:17.814656) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 12:37:17.815004) ========= 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.eBrtds7zOO/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.czkEPZZqsv/Listeners TMPDIR=/var/folders/wd/qbtztlzd54b5bdbmhc2nsh0w00000w/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.005089 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 12:37:17.863621) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 12:37:17.863943) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 12:37:17.864298) ========= ========= Total master_lag: 0.27 =========