builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0164 starttime: 1497336726.18 results: success (0) buildid: 20170612224034 builduid: 002c2a68a24c4a5a9a5d7b16650e04e3 revision: 6872377277a618b2b9e0d2b4c2b9e51765ac199e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 23:52:06.181211) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-12 23:52:06.181604) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 23:52:06.181869) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-12 23:52:06.396029) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 23:52:06.396402) ========= 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.yi7jrc3txg/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.yAFeeHJeeX/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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-12 23:52:06-- 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% 12.2M=0.001s 2017-06-12 23:52:06 (12.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.114816 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 23:52:06.554232) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 23:52:06.554553) ========= 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.yi7jrc3txg/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.yAFeeHJeeX/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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.029496 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-12 23:52:06.628365) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-12 23:52:06.628684) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 6872377277a618b2b9e0d2b4c2b9e51765ac199e --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 6872377277a618b2b9e0d2b4c2b9e51765ac199e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yi7jrc3txg/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.yAFeeHJeeX/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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-12 23:52:06,730 truncating revision to first 12 chars 2017-06-12 23:52:06,730 Setting DEBUG logging. 2017-06-12 23:52:06,730 attempt 1/10 2017-06-12 23:52:06,730 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/6872377277a6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-12 23:52:08,942 unpacking tar archive at: mozilla-beta-6872377277a6/testing/mozharness/ program finished with exit code 0 elapsedTime=2.510165 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-12 23:52:09.165971) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 23:52:09.166320) ========= script_repo_revision: 6872377277a618b2b9e0d2b4c2b9e51765ac199e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-12 23:52:09.166789) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 23:52:09.167095) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-12 23:52:09.186385) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-12 23:52:09.186854) ========= /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.yi7jrc3txg/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.yAFeeHJeeX/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 23:52:09 INFO - MultiFileLogger online at 20170612 23:52:09 in /builds/slave/test 23:52:09 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 23:52:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:52:09 INFO - {'append_to_log': False, 23:52:09 INFO - 'base_work_dir': '/builds/slave/test', 23:52:09 INFO - 'blob_upload_branch': 'Mozilla-Beta', 23:52:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:52:09 INFO - 'branch': 'Mozilla-Beta', 23:52:09 INFO - 'buildbot_json_path': 'buildprops.json', 23:52:09 INFO - 'config_files': ('talos/mac_config.py',), 23:52:09 INFO - 'default_actions': ('clobber', 23:52:09 INFO - 'read-buildbot-config', 23:52:09 INFO - 'download-and-extract', 23:52:09 INFO - 'populate-webroot', 23:52:09 INFO - 'create-virtualenv', 23:52:09 INFO - 'install', 23:52:09 INFO - 'setup-mitmproxy', 23:52:09 INFO - 'run-tests'), 23:52:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:52:09 INFO - 'download_minidump_stackwalk': True, 23:52:09 INFO - 'download_symbols': 'ondemand', 23:52:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:52:09 INFO - 'tooltool.py': '/tools/tooltool.py', 23:52:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:52:09 INFO - '/tools/misc-python/virtualenv.py')}, 23:52:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:52:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:52:09 INFO - 'gecko_profile': False, 23:52:09 INFO - 'gecko_profile_interval': 0, 23:52:09 INFO - 'installer_path': 'installer.exe', 23:52:09 INFO - 'log_level': 'info', 23:52:09 INFO - 'log_name': 'talos', 23:52:09 INFO - 'log_to_console': True, 23:52:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:52:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:52:09 INFO - 'opt_config_files': (), 23:52:09 INFO - 'pip_index': False, 23:52:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:52:09 INFO - 'cmd': ('bash', 23:52:09 INFO - '-c', 23:52:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:52:09 INFO - 'enabled': True, 23:52:09 INFO - 'halt_on_failure': False, 23:52:09 INFO - 'name': 'check_screen_resolution'},), 23:52:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:52:09 INFO - 'cmd': ('bash', 23:52:09 INFO - '-c', 23:52:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:52:09 INFO - 'enabled': True, 23:52:09 INFO - 'halt_on_failure': False, 23:52:09 INFO - 'name': 'check_screen_resolution'},), 23:52:09 INFO - 'run_cmd_checks_enabled': True, 23:52:09 INFO - 'suite': 'dromaeojs-e10s', 23:52:09 INFO - 'system_bits': '32', 23:52:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:52:09 INFO - 'title': 't-yosemite-r7-0164', 23:52:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:52:09 INFO - 'use_talos_json': True, 23:52:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:52:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:52:09 INFO - 'work_dir': 'build'} 23:52:09 INFO - [mozharness: 2017-06-13 06:52:09.400921Z] Running clobber step. 23:52:09 INFO - Running pre-action listener: _resource_record_pre_action 23:52:09 INFO - Running main action method: clobber 23:52:09 INFO - rmtree: /builds/slave/test/build 23:52:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:52:12 INFO - Running post-action listener: _resource_record_post_action 23:52:12 INFO - [mozharness: 2017-06-13 06:52:12.020899Z] Finished clobber step (success) 23:52:12 INFO - [mozharness: 2017-06-13 06:52:12.021053Z] Running read-buildbot-config step. 23:52:12 INFO - Running pre-action listener: _resource_record_pre_action 23:52:12 INFO - Running main action method: read_buildbot_config 23:52:12 INFO - Using buildbot properties: 23:52:12 INFO - { 23:52:12 INFO - "project": "", 23:52:12 INFO - "product": "firefox", 23:52:12 INFO - "script_repo_revision": "production", 23:52:12 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 23:52:12 INFO - "repository": "", 23:52:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 23:52:12 INFO - "buildid": "20170612224034", 23:52:12 INFO - "pgo_build": "False", 23:52:12 INFO - "basedir": "/builds/slave/test", 23:52:12 INFO - "buildnumber": 3, 23:52:12 INFO - "slavename": "t-yosemite-r7-0164", 23:52:12 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 23:52:12 INFO - "platform": "yosemite_r7_devedition", 23:52:12 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 23:52:12 INFO - "branch": "mozilla-beta", 23:52:12 INFO - "repo_path": "releases/mozilla-beta", 23:52:12 INFO - "revision": "6872377277a618b2b9e0d2b4c2b9e51765ac199e", 23:52:12 INFO - "stage_platform": "macosx64-devedition", 23:52:12 INFO - "builduid": "002c2a68a24c4a5a9a5d7b16650e04e3", 23:52:12 INFO - "slavebuilddir": "test" 23:52:12 INFO - } 23:52:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/HvnJjdsRTNyQwNqBJeysSw/artifacts/public/build/firefox-55.0.en-US.mac.dmg. 23:52:12 INFO - Running post-action listener: _resource_record_post_action 23:52:12 INFO - [mozharness: 2017-06-13 06:52:12.024349Z] Finished read-buildbot-config step (success) 23:52:12 INFO - [mozharness: 2017-06-13 06:52:12.024465Z] Running download-and-extract step. 23:52:12 INFO - Running pre-action listener: _resource_record_pre_action 23:52:12 INFO - Running main action method: download_and_extract 23:52:12 INFO - mkdir: /builds/slave/test/build/tests 23:52: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')]} 23:52:12 INFO - trying https://queue.taskcluster.net/v1/task/HvnJjdsRTNyQwNqBJeysSw/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 23:52:12 INFO - Downloading https://queue.taskcluster.net/v1/task/HvnJjdsRTNyQwNqBJeysSw/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 23:52:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HvnJjdsRTNyQwNqBJeysSw/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json'}, attempt #1 23:52:13 INFO - Downloaded 1290 bytes. 23:52:13 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 23:52:13 INFO - Using the following test package requirements: 23:52:13 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 23:52:13 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 23:52:13 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 23:52:13 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 23:52:13 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 23:52:13 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 23:52:13 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 23:52:13 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 23:52:13 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 23:52:13 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 23:52:13 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 23:52:13 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 23:52:13 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 23:52:13 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 23:52:13 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 23:52:13 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 23:52:13 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 23:52:13 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 23:52:13 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 23:52:13 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 23:52:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HvnJjdsRTNyQwNqBJeysSw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 23:52:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HvnJjdsRTNyQwNqBJeysSw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 23:52:13 INFO - Fetch https://queue.taskcluster.net/v1/task/HvnJjdsRTNyQwNqBJeysSw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 23:52:15 INFO - Content-Length response header: 59545161 23:52:15 INFO - Bytes received: 59545161 23:52:33 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip', u'firefox-55.0.en-US.mac.talos.tests.zip'] for test suite category: talos 23:52:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HvnJjdsRTNyQwNqBJeysSw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 23:52:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HvnJjdsRTNyQwNqBJeysSw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 23:52:33 INFO - Fetch https://queue.taskcluster.net/v1/task/HvnJjdsRTNyQwNqBJeysSw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 23:52:35 INFO - Content-Length response header: 59545161 23:52:35 INFO - Bytes received: 59545161 23:52:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HvnJjdsRTNyQwNqBJeysSw/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 23:52:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HvnJjdsRTNyQwNqBJeysSw/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 23:52:52 INFO - Fetch https://queue.taskcluster.net/v1/task/HvnJjdsRTNyQwNqBJeysSw/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 23:52:54 INFO - Content-Length response header: 14450018 23:52:54 INFO - Bytes received: 14450018 23:52: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')]} 23:52:54 INFO - trying https://queue.taskcluster.net/v1/task/HvnJjdsRTNyQwNqBJeysSw/artifacts/public/build/firefox-55.0.en-US.mac.dmg 23:52:54 INFO - Downloading https://queue.taskcluster.net/v1/task/HvnJjdsRTNyQwNqBJeysSw/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 23:52:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HvnJjdsRTNyQwNqBJeysSw/artifacts/public/build/firefox-55.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg'}, attempt #1 23:52:56 INFO - Downloaded 66876540 bytes. 23:52:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HvnJjdsRTNyQwNqBJeysSw/artifacts/public/build/firefox-55.0.en-US.mac.dmg 23:52:56 INFO - mkdir: /builds/slave/test/properties 23:52:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:52:56 INFO - Writing to file /builds/slave/test/properties/build_url 23:52:56 INFO - Contents: 23:52:56 INFO - build_url:https://queue.taskcluster.net/v1/task/HvnJjdsRTNyQwNqBJeysSw/artifacts/public/build/firefox-55.0.en-US.mac.dmg 23:52:57 INFO - Running post-action listener: _resource_record_post_action 23:52:57 INFO - Running post-action listener: set_extra_try_arguments 23:52:57 INFO - [mozharness: 2017-06-13 06:52:57.147675Z] Finished download-and-extract step (success) 23:52:57 INFO - [mozharness: 2017-06-13 06:52:57.147829Z] Running populate-webroot step. 23:52:57 INFO - Running pre-action listener: _resource_record_pre_action 23:52:57 INFO - Running main action method: populate_webroot 23:52:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:52:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:52:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:52:57 INFO - '--tppagecycles', 23:52:57 INFO - '7'], 23:52:57 INFO - 'tests': ['tsvgm']}}, 23:52:57 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:52:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:52:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:52:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:52:57 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 23:52:57 INFO - 'talos_options': ['--disable-e10s'], 23:52:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:52:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 23:52:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:52:57 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 23:52:57 INFO - 'talos_options': ['--disable-e10s'], 23:52:57 INFO - 'tests': ['damp', 'tps']}, 23:52:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 23:52:57 INFO - 'tests': ['damp', 'tps']}, 23:52:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:52:57 INFO - 'tests': ['dromaeo_dom']}, 23:52:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:52:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 23:52:57 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 23:52:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 23:52:57 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 23:52:57 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 23:52:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:52:57 INFO - 'tests': ['a11yr', 23:52:57 INFO - 'ts_paint', 23:52:57 INFO - 'tpaint', 23:52:57 INFO - 'sessionrestore', 23:52:57 INFO - 'sessionrestore_no_auto_restore', 23:52:57 INFO - 'tabpaint']}, 23:52:57 INFO - 'other-e10s': {'tests': ['a11yr', 23:52:57 INFO - 'ts_paint', 23:52:57 INFO - 'tpaint', 23:52:57 INFO - 'sessionrestore', 23:52:57 INFO - 'sessionrestore_no_auto_restore', 23:52:57 INFO - 'tabpaint']}, 23:52:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:52:57 INFO - 'ts_paint', 23:52:57 INFO - 'tpaint', 23:52:57 INFO - 'sessionrestore', 23:52:57 INFO - 'sessionrestore_no_auto_restore', 23:52:57 INFO - 'tabpaint']}, 23:52:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:52:57 INFO - 'ts_paint', 23:52:57 INFO - 'tpaint', 23:52:57 INFO - 'sessionrestore', 23:52:57 INFO - 'sessionrestore_no_auto_restore', 23:52:57 INFO - 'tabpaint']}, 23:52:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:52:57 INFO - 'tests': ['a11yr', 23:52:57 INFO - 'ts_paint', 23:52:57 INFO - 'tpaint', 23:52:57 INFO - 'sessionrestore', 23:52:57 INFO - 'sessionrestore_no_auto_restore', 23:52:57 INFO - 'tabpaint']}, 23:52:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:52:57 INFO - 'tests': ['a11yr', 23:52:57 INFO - 'ts_paint', 23:52:57 INFO - 'tpaint', 23:52:57 INFO - 'sessionrestore', 23:52:57 INFO - 'sessionrestore_no_auto_restore', 23:52:57 INFO - 'tabpaint']}, 23:52:57 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 23:52:57 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 23:52:57 INFO - 'bloom_basic_ref']}, 23:52:57 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 23:52:57 INFO - 'talos_options': ['--mitmproxy', 23:52:57 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 23:52:57 INFO - '--firstNonBlankPaint'], 23:52:57 INFO - 'tests': ['quantum_pageload_google', 23:52:57 INFO - 'quantum_pageload_youtube', 23:52:57 INFO - 'quantum_pageload_amazon', 23:52:57 INFO - 'quantum_pageload_facebook']}, 23:52:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:52:57 INFO - 'tests': ['tsvgx', 23:52:57 INFO - 'tsvgr_opacity', 23:52:57 INFO - 'tart', 23:52:57 INFO - 'tscrollx', 23:52:57 INFO - 'cart', 23:52:57 INFO - 'tsvg_static']}, 23:52:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:52:57 INFO - 'tsvgr_opacity', 23:52:57 INFO - 'tart', 23:52:57 INFO - 'tscrollx', 23:52:57 INFO - 'cart', 23:52:57 INFO - 'tsvg_static']}, 23:52:57 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 23:52:57 INFO - 'talos_options': ['--disable-e10s'], 23:52:57 INFO - 'tests': ['tp5o']}, 23:52:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 23:52:57 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 23:52:57 INFO - 'talos_options': ['--disable-e10s', 23:52:57 INFO - '--xperf_path', 23:52:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:52:57 INFO - 'tests': ['tp5n']}, 23:52:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 23:52:57 INFO - 'talos_options': ['--xperf_path', 23:52:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:52:57 INFO - 'tests': ['tp5n']}}, 23:52:57 INFO - 'talos.zip': {'path': '', 23:52:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:52:57 INFO - Running post-action listener: _resource_record_post_action 23:52:57 INFO - [mozharness: 2017-06-13 06:52:57.169134Z] Finished populate-webroot step (success) 23:52:57 INFO - [mozharness: 2017-06-13 06:52:57.169252Z] Running create-virtualenv step. 23:52:57 INFO - Running pre-action listener: _resource_record_pre_action 23:52:57 INFO - Running main action method: create_virtualenv 23:52:57 INFO - Creating virtualenv /builds/slave/test/build/venv 23:52:57 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 23:52:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:52:57 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 23:52:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:52:57 INFO - Using real prefix '/tools/python27' 23:52:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:52:58 INFO - Installing distribute.............................................................................................................................................................................................done. 23:53:00 INFO - Installing pip.................done. 23:53:00 INFO - Return code: 0 23:53:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 23:53:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 23:53:01 INFO - Reading from file tmpfile_stdout 23:53:01 INFO - Output received: 23:53:01 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) 23:53:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:53:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:53:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:53:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108b25c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf51d8bcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10887bd30>, '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 0x108845cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10886cbe8>, '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.yi7jrc3txg/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.yAFeeHJeeX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 23:53:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 23:53:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 23:53:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yi7jrc3txg/Render', 23:53:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:53:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:53:01 INFO - 'HOME': '/Users/cltbld', 23:53:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:53:01 INFO - 'LOGNAME': 'cltbld', 23:53:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:53:01 INFO - 'MOZ_NO_REMOTE': '1', 23:53:01 INFO - 'NO_EM_RESTART': '1', 23:53:01 INFO - 'PAGER': '/bin/cat', 23:53:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:53:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:53:01 INFO - 'PWD': '/builds/slave/test', 23:53:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:53:01 INFO - 'SHELL': '/bin/bash', 23:53:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yAFeeHJeeX/Listeners', 23:53:01 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 23:53:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:53:01 INFO - 'USER': 'cltbld', 23:53:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:53:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:53:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:53:01 INFO - 'XPC_FLAGS': '0x0', 23:53:01 INFO - 'XPC_SERVICE_NAME': '0', 23:53:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:53:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:53:01 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 23:53:01 INFO - Cleaning up... 23:53:01 INFO - Return code: 0 23:53:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 23:53:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:53:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:53:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108b25c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf51d8bcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10887bd30>, '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 0x108845cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10886cbe8>, '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.yi7jrc3txg/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.yAFeeHJeeX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 23:53:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 23:53:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 23:53:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yi7jrc3txg/Render', 23:53:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:53:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:53:01 INFO - 'HOME': '/Users/cltbld', 23:53:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:53:01 INFO - 'LOGNAME': 'cltbld', 23:53:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:53:01 INFO - 'MOZ_NO_REMOTE': '1', 23:53:01 INFO - 'NO_EM_RESTART': '1', 23:53:01 INFO - 'PAGER': '/bin/cat', 23:53:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:53:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:53:01 INFO - 'PWD': '/builds/slave/test', 23:53:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:53:01 INFO - 'SHELL': '/bin/bash', 23:53:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yAFeeHJeeX/Listeners', 23:53:01 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 23:53:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:53:01 INFO - 'USER': 'cltbld', 23:53:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:53:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:53:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:53:01 INFO - 'XPC_FLAGS': '0x0', 23:53:01 INFO - 'XPC_SERVICE_NAME': '0', 23:53:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:53:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:53:01 INFO - Downloading/unpacking psutil>=3.1.1 23:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:53:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:53:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:53:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:53:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:53:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:53:03 INFO - Installing collected packages: psutil 23:53:03 INFO - Running setup.py install for psutil 23:53:03 INFO - building 'psutil._psutil_osx' extension 23:53:03 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 23:53:04 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 23:53:04 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 23:53:04 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 23:53:04 INFO - building 'psutil._psutil_posix' extension 23:53:04 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 23:53:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:53:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:53:04 INFO - ^ 23:53:04 INFO - 1 warning generated. 23:53:04 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 23:53:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:53:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:53:05 INFO - Successfully installed psutil 23:53:05 INFO - Cleaning up... 23:53:05 INFO - Return code: 0 23:53:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 23:53:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:53:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:53:05 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 0x108b25c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf51d8bcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10887bd30>, '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 0x108845cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10886cbe8>, '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.yi7jrc3txg/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.yAFeeHJeeX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 23:53:05 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 23:53:05 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 23:53:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yi7jrc3txg/Render', 23:53:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:53:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:53:05 INFO - 'HOME': '/Users/cltbld', 23:53:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:53:05 INFO - 'LOGNAME': 'cltbld', 23:53:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:53:05 INFO - 'MOZ_NO_REMOTE': '1', 23:53:05 INFO - 'NO_EM_RESTART': '1', 23:53:05 INFO - 'PAGER': '/bin/cat', 23:53:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:53:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:53:05 INFO - 'PWD': '/builds/slave/test', 23:53:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:53:05 INFO - 'SHELL': '/bin/bash', 23:53:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yAFeeHJeeX/Listeners', 23:53:05 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 23:53:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:53:05 INFO - 'USER': 'cltbld', 23:53:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:53:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:53:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:53:05 INFO - 'XPC_FLAGS': '0x0', 23:53:05 INFO - 'XPC_SERVICE_NAME': '0', 23:53:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:53:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:53:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 23:53:05 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 23:53:05 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 23:53:05 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 23:53:05 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 23:53:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 23:53:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:53:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 23:53:06 INFO - Installing collected packages: mozsystemmonitor 23:53:06 INFO - Running setup.py install for mozsystemmonitor 23:53:07 INFO - Successfully installed mozsystemmonitor 23:53:07 INFO - Cleaning up... 23:53:07 INFO - Return code: 0 23:53:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 23:53:07 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')]} 23:53:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:53:07 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')]} 23:53:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:53:07 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 0x108b25c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf51d8bcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10887bd30>, '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 0x108845cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10886cbe8>, '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.yi7jrc3txg/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.yAFeeHJeeX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 23:53:07 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 23:53:07 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 23:53:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yi7jrc3txg/Render', 23:53:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:53:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:53:07 INFO - 'HOME': '/Users/cltbld', 23:53:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:53:07 INFO - 'LOGNAME': 'cltbld', 23:53:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:53:07 INFO - 'MOZ_NO_REMOTE': '1', 23:53:07 INFO - 'NO_EM_RESTART': '1', 23:53:07 INFO - 'PAGER': '/bin/cat', 23:53:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:53:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:53:07 INFO - 'PWD': '/builds/slave/test', 23:53:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:53:07 INFO - 'SHELL': '/bin/bash', 23:53:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yAFeeHJeeX/Listeners', 23:53:07 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 23:53:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:53:07 INFO - 'USER': 'cltbld', 23:53:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:53:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:53:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:53:07 INFO - 'XPC_FLAGS': '0x0', 23:53:07 INFO - 'XPC_SERVICE_NAME': '0', 23:53:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:53:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:53:07 INFO - Downloading/unpacking jsonschema==2.5.1 23:53:07 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 23:53:07 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 23:53:07 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 23:53:07 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 23:53:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:53:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 23:53:08 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 23:53:08 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 23:53:08 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 23:53:08 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 23:53:08 INFO - Downloading functools32-3.2.3-2.tar.gz 23:53:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:53:09 INFO - warning: no files found matching '*.txt' 23:53:09 INFO - no previously-included directories found matching 'build' 23:53:09 INFO - no previously-included directories found matching 'dist' 23:53:09 INFO - no previously-included directories found matching '.git*' 23:53:09 INFO - Installing collected packages: jsonschema, functools32 23:53:09 INFO - Running setup.py install for functools32 23:53:09 INFO - warning: no files found matching '*.txt' 23:53:09 INFO - no previously-included directories found matching 'build' 23:53:09 INFO - no previously-included directories found matching 'dist' 23:53:09 INFO - no previously-included directories found matching '.git*' 23:53:09 INFO - Successfully installed jsonschema functools32 23:53:09 INFO - Cleaning up... 23:53:09 INFO - Return code: 0 23:53:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 23:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:53:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108b25c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf51d8bcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10887bd30>, '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 0x108845cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10886cbe8>, '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.yi7jrc3txg/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.yAFeeHJeeX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 23:53:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 23:53:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 23:53:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yi7jrc3txg/Render', 23:53:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:53:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:53:09 INFO - 'HOME': '/Users/cltbld', 23:53:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:53:09 INFO - 'LOGNAME': 'cltbld', 23:53:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:53:09 INFO - 'MOZ_NO_REMOTE': '1', 23:53:09 INFO - 'NO_EM_RESTART': '1', 23:53:09 INFO - 'PAGER': '/bin/cat', 23:53:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:53:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:53:09 INFO - 'PWD': '/builds/slave/test', 23:53:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:53:09 INFO - 'SHELL': '/bin/bash', 23:53:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yAFeeHJeeX/Listeners', 23:53:09 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 23:53:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:53:09 INFO - 'USER': 'cltbld', 23:53:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:53:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:53:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:53:09 INFO - 'XPC_FLAGS': '0x0', 23:53:09 INFO - 'XPC_SERVICE_NAME': '0', 23:53:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:53:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:53:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 23:53:09 INFO - Cleaning up... 23:53:09 INFO - Return code: 0 23:53:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:53:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108b25c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf51d8bcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10887bd30>, '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 0x108845cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10886cbe8>, '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.yi7jrc3txg/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.yAFeeHJeeX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 23:53:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:53:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:53:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yi7jrc3txg/Render', 23:53:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:53:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:53:09 INFO - 'HOME': '/Users/cltbld', 23:53:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:53:09 INFO - 'LOGNAME': 'cltbld', 23:53:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:53:09 INFO - 'MOZ_NO_REMOTE': '1', 23:53:09 INFO - 'NO_EM_RESTART': '1', 23:53:09 INFO - 'PAGER': '/bin/cat', 23:53:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:53:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:53:09 INFO - 'PWD': '/builds/slave/test', 23:53:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:53:09 INFO - 'SHELL': '/bin/bash', 23:53:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yAFeeHJeeX/Listeners', 23:53:09 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 23:53:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:53:09 INFO - 'USER': 'cltbld', 23:53:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:53:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:53:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:53:09 INFO - 'XPC_FLAGS': '0x0', 23:53:09 INFO - 'XPC_SERVICE_NAME': '0', 23:53:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:53:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:53:09 INFO - Downloading/unpacking blobuploader==1.2.4 23:53:09 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 23:53:09 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 23:53:09 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 23:53:09 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 23:53:11 INFO - Downloading blobuploader-1.2.4.tar.gz 23:53:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:53:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:53:11 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 23:53:11 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 23:53:11 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 23:53:11 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 23:53:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:53:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:53:11 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 23:53:11 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 23:53:11 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 23:53:11 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 23:53:12 INFO - Downloading docopt-0.6.1.tar.gz 23:53:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:53:12 INFO - Installing collected packages: blobuploader, requests, docopt 23:53:12 INFO - Running setup.py install for blobuploader 23:53:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:53:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:53:12 INFO - Running setup.py install for requests 23:53:12 INFO - Running setup.py install for docopt 23:53:13 INFO - Successfully installed blobuploader requests docopt 23:53:13 INFO - Cleaning up... 23:53:13 INFO - Return code: 0 23:53:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:53:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:53:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:53:13 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 0x108b25c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf51d8bcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10887bd30>, '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 0x108845cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10886cbe8>, '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.yi7jrc3txg/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.yAFeeHJeeX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 23:53:13 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 23:53:13 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 23:53:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yi7jrc3txg/Render', 23:53:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:53:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:53:13 INFO - 'HOME': '/Users/cltbld', 23:53:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:53:13 INFO - 'LOGNAME': 'cltbld', 23:53:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:53:13 INFO - 'MOZ_NO_REMOTE': '1', 23:53:13 INFO - 'NO_EM_RESTART': '1', 23:53:13 INFO - 'PAGER': '/bin/cat', 23:53:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:53:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:53:13 INFO - 'PWD': '/builds/slave/test', 23:53:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:53:13 INFO - 'SHELL': '/bin/bash', 23:53:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yAFeeHJeeX/Listeners', 23:53:13 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 23:53:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:53:13 INFO - 'USER': 'cltbld', 23:53:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:53:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:53:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:53:13 INFO - 'XPC_FLAGS': '0x0', 23:53:13 INFO - 'XPC_SERVICE_NAME': '0', 23:53:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:53:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:53:13 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-oS8pqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:53:13 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-O623V1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:53:13 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-LVVLKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:53:13 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-WOHbBD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:53:13 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-cfkipB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:53:13 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-42cmv1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:53:13 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-NohgeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:53:14 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-BF_6sH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:53:14 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-T5u6TI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:53:14 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-D7eq5K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:53:14 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-A8Y9C_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:53:14 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-uYc7Xz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:53:14 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-s3_aBf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:53:14 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-dXnJRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:53:14 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-1sGuSY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:53:14 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-zgZXkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:53:14 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-tJnhAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:53:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:53:15 INFO - Running setup.py install for manifestparser 23:53:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:53:15 INFO - Running setup.py install for mozcrash 23:53:15 INFO - Running setup.py install for mozdebug 23:53:15 INFO - Running setup.py install for mozdevice 23:53:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:53:15 INFO - Running setup.py install for mozfile 23:53:15 INFO - Running setup.py install for mozhttpd 23:53:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:53:15 INFO - Running setup.py install for mozinfo 23:53:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:53:16 INFO - Running setup.py install for mozInstall 23:53:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:53:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:53:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:53:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:53:16 INFO - Running setup.py install for mozleak 23:53:16 INFO - Running setup.py install for mozlog 23:53:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:53:16 INFO - Running setup.py install for moznetwork 23:53:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:53:16 INFO - Running setup.py install for mozprocess 23:53:16 INFO - Running setup.py install for mozprofile 23:53:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:53:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:53:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:53:17 INFO - Running setup.py install for mozrunner 23:53:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:53:17 INFO - Running setup.py install for mozscreenshot 23:53:17 INFO - Running setup.py install for moztest 23:53:17 INFO - Running setup.py install for mozversion 23:53:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:53:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:53:17 INFO - Cleaning up... 23:53:17 INFO - Return code: 0 23:53:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:53:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:53:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:53:17 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 0x108b25c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf51d8bcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10887bd30>, '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 0x108845cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10886cbe8>, '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.yi7jrc3txg/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.yAFeeHJeeX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 23:53:17 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 23:53:17 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 23:53:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yi7jrc3txg/Render', 23:53:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:53:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:53:17 INFO - 'HOME': '/Users/cltbld', 23:53:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:53:17 INFO - 'LOGNAME': 'cltbld', 23:53:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:53:17 INFO - 'MOZ_NO_REMOTE': '1', 23:53:17 INFO - 'NO_EM_RESTART': '1', 23:53:17 INFO - 'PAGER': '/bin/cat', 23:53:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:53:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:53:17 INFO - 'PWD': '/builds/slave/test', 23:53:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:53:17 INFO - 'SHELL': '/bin/bash', 23:53:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yAFeeHJeeX/Listeners', 23:53:17 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 23:53:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:53:17 INFO - 'USER': 'cltbld', 23:53:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:53:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:53:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:53:17 INFO - 'XPC_FLAGS': '0x0', 23:53:17 INFO - 'XPC_SERVICE_NAME': '0', 23:53:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:53:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:53:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:53:17 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-wDpOD0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:53:18 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)) 23:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:53:18 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-gvALm0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:53:18 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)) 23:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:53:18 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-AfIPWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:53:18 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)) 23:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:53:18 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-B_lxDr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:53:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 23:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:53:18 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-mg307U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:53:18 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)) 23:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:53:18 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-1IPxXa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:53:18 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)) 23:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:53:18 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-ZJW66a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:53:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 23:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:53:18 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-nfFvXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:53:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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)) 23:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:53:18 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-98mIX8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:53:18 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)) 23:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:53:18 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-qMvFMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:53:18 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)) 23:53:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:53:18 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-jSVhgY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:53:19 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)) 23:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:53:19 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-X5Z4nw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:53:19 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)) 23:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:53:19 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-ZhT1J_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:53:19 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)) 23:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:53:19 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-yjT0BA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:53:19 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)) 23:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:53:19 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-LkTdTB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:53:19 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)) 23:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:53:19 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-EbJiV7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:53:19 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)) 23:53:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:53:19 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-Mo8MJV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:53:19 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)) 23:53:19 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)) 23:53:19 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)) 23:53:19 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)) 23:53:19 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:53:19 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 23:53:19 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 23:53:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:53:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:53:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:53:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:53:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:53:21 INFO - Downloading blessings-1.6.tar.gz 23:53:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:53:21 INFO - Installing collected packages: blessings 23:53:21 INFO - Running setup.py install for blessings 23:53:21 INFO - Successfully installed blessings 23:53:21 INFO - Cleaning up... 23:53:21 INFO - Return code: 0 23:53:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:53:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:53:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:53:21 INFO - Reading from file tmpfile_stdout 23:53:21 INFO - Current package versions: 23:53:21 INFO - blessings == 1.6 23:53:21 INFO - blobuploader == 1.2.4 23:53:21 INFO - docopt == 0.6.1 23:53:21 INFO - functools32 == 3.2.3-2 23:53:21 INFO - jsonschema == 2.5.1 23:53:21 INFO - manifestparser == 1.1 23:53:21 INFO - mozInstall == 1.13 23:53:21 INFO - mozcrash == 1.0 23:53:21 INFO - mozdebug == 0.1 23:53:21 INFO - mozdevice == 0.51 23:53:21 INFO - mozfile == 1.2 23:53:21 INFO - mozhttpd == 0.7 23:53:21 INFO - mozinfo == 0.10 23:53:21 INFO - mozleak == 0.1 23:53:21 INFO - mozlog == 3.4 23:53:21 INFO - moznetwork == 0.27 23:53:21 INFO - mozprocess == 0.25 23:53:21 INFO - mozprofile == 0.28 23:53:21 INFO - mozrunner == 6.13 23:53:21 INFO - mozscreenshot == 0.1 23:53:21 INFO - mozsystemmonitor == 0.3 23:53:21 INFO - moztest == 0.8 23:53:21 INFO - mozversion == 1.4 23:53:21 INFO - psutil == 3.1.1 23:53:21 INFO - requests == 1.2.3 23:53:21 INFO - wsgiref == 0.1.2 23:53:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:53:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:53:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:53:21 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 0x108b25c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf51d8bcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10887bd30>, '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 0x108845cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10886cbe8>, '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.yi7jrc3txg/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.yAFeeHJeeX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 23:53:21 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 23:53:21 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 23:53:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yi7jrc3txg/Render', 23:53:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:53:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:53:21 INFO - 'HOME': '/Users/cltbld', 23:53:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:53:21 INFO - 'LOGNAME': 'cltbld', 23:53:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:53:21 INFO - 'MOZ_NO_REMOTE': '1', 23:53:21 INFO - 'NO_EM_RESTART': '1', 23:53:21 INFO - 'PAGER': '/bin/cat', 23:53:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:53:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:53:21 INFO - 'PWD': '/builds/slave/test', 23:53:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:53:21 INFO - 'SHELL': '/bin/bash', 23:53:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yAFeeHJeeX/Listeners', 23:53:21 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 23:53:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:53:21 INFO - 'USER': 'cltbld', 23:53:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:53:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:53:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:53:21 INFO - 'XPC_FLAGS': '0x0', 23:53:21 INFO - 'XPC_SERVICE_NAME': '0', 23:53:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:53:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:53:21 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)) 23:53:21 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)) 23:53:21 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)) 23:53:21 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)) 23:53:21 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)) 23:53:21 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)) 23:53:21 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)) 23:53:21 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)) 23:53:21 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)) 23:53:21 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 23:53:21 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 23:53:21 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 23:53:21 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 23:53:21 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 23:53:23 INFO - Downloading marionette_driver-1.4.0.tar.gz 23:53:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 23:53:23 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)) 23:53:23 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)) 23:53:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 23:53:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 23:53:23 INFO - Installing collected packages: marionette-driver 23:53:23 INFO - Running setup.py install for marionette-driver 23:53:23 INFO - Successfully installed marionette-driver 23:53:23 INFO - Cleaning up... 23:53:23 INFO - Return code: 0 23:53:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:53:23 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')]} 23:53:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:53:23 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')]} 23:53:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:53:23 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 0x108b25c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf51d8bcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10887bd30>, '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 0x108845cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10886cbe8>, '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.yi7jrc3txg/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.yAFeeHJeeX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 23:53:23 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 23:53:23 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 23:53:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yi7jrc3txg/Render', 23:53:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:53:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:53:23 INFO - 'HOME': '/Users/cltbld', 23:53:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:53:23 INFO - 'LOGNAME': 'cltbld', 23:53:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:53:23 INFO - 'MOZ_NO_REMOTE': '1', 23:53:23 INFO - 'NO_EM_RESTART': '1', 23:53:23 INFO - 'PAGER': '/bin/cat', 23:53:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:53:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:53:23 INFO - 'PWD': '/builds/slave/test', 23:53:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:53:23 INFO - 'SHELL': '/bin/bash', 23:53:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yAFeeHJeeX/Listeners', 23:53:23 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 23:53:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:53:23 INFO - 'USER': 'cltbld', 23:53:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:53:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:53:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:53:23 INFO - 'XPC_FLAGS': '0x0', 23:53:23 INFO - 'XPC_SERVICE_NAME': '0', 23:53:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:53:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:53:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 23:53:24 INFO - Cleaning up... 23:53:24 INFO - Return code: 0 23:53:24 INFO - Running post-action listener: _resource_record_post_action 23:53:24 INFO - Running post-action listener: _start_resource_monitoring 23:53:24 INFO - Starting resource monitoring. 23:53:24 INFO - [mozharness: 2017-06-13 06:53:24.211562Z] Finished create-virtualenv step (success) 23:53:24 INFO - [mozharness: 2017-06-13 06:53:24.212560Z] Running install step. 23:53:24 INFO - Running pre-action listener: _resource_record_pre_action 23:53:24 INFO - Running main action method: install 23:53:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:53:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:53:24 INFO - Reading from file tmpfile_stdout 23:53:24 INFO - Detecting whether we're running mozinstall >=1.0... 23:53:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:53:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:53:24 INFO - Reading from file tmpfile_stdout 23:53:24 INFO - Output received: 23:53:24 INFO - Usage: mozinstall [options] installer 23:53:24 INFO - Options: 23:53:24 INFO - -h, --help show this help message and exit 23:53:24 INFO - -d DEST, --destination=DEST 23:53:24 INFO - Directory to install application into. [default: 23:53:24 INFO - "/builds/slave/test"] 23:53:24 INFO - --app=APP Application being installed. [default: firefox] 23:53:24 INFO - mkdir: /builds/slave/test/build/application 23:53:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 23:53:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0.en-US.mac.dmg --destination /builds/slave/test/build/application 23:53:45 INFO - Reading from file tmpfile_stdout 23:53:45 INFO - Output received: 23:53:45 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 23:53:45 INFO - Running post-action listener: _resource_record_post_action 23:53:45 INFO - [mozharness: 2017-06-13 06:53:45.963920Z] Finished install step (success) 23:53:45 INFO - [mozharness: 2017-06-13 06:53:45.964059Z] Running setup-mitmproxy step. 23:53:45 INFO - Running pre-action listener: _resource_record_pre_action 23:53:45 INFO - Running main action method: setup_mitmproxy 23:53:45 INFO - Skipping: mitmproxy is not required 23:53:45 INFO - Running post-action listener: _resource_record_post_action 23:53:45 INFO - [mozharness: 2017-06-13 06:53:45.964659Z] Finished setup-mitmproxy step (success) 23:53:45 INFO - [mozharness: 2017-06-13 06:53:45.964780Z] Running run-tests step. 23:53:45 INFO - Running pre-action listener: _resource_record_pre_action 23:53:45 INFO - Running main action method: run_tests 23:53:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:53:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:53:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:53:45 INFO - 2017-06-12 23:53:45.977 screenresolution[1637:9259] starting screenresolution argv=screenresolution get 23:53:45 INFO - 2017-06-12 23:53:45.980 screenresolution[1637:9259] Display 0: 1600x1200x32@60 23:53:45 INFO - 2017-06-12 23:53:45.985 screenresolution[1638:9262] starting screenresolution argv=screenresolution list 23:53:45 INFO - Available Modes on Display 0 23:53:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:53:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:53:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:53:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:53:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:53:46 INFO - Intel Iris: 23:53:46 INFO - Chipset Model: Intel Iris 23:53:46 INFO - Type: GPU 23:53:46 INFO - Bus: Built-In 23:53:46 INFO - VRAM (Dynamic, Max): 1536 MB 23:53:46 INFO - Vendor: Intel (0x8086) 23:53:46 INFO - Device ID: 0x0a2e 23:53:46 INFO - Revision ID: 0x0009 23:53:46 INFO - Displays: 23:53:46 INFO - Display: 23:53:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:53:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:53:46 INFO - Display Serial Number: 1600x1200 60 23:53:46 INFO - Main Display: Yes 23:53:46 INFO - Mirror: Off 23:53:46 INFO - Online: Yes 23:53:46 INFO - Rotation: Supported 23:53:46 INFO - Return code: 0 23:53:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:53:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:53:46 INFO - Python 2.7.3 23:53:46 INFO - Return code: 0 23:53:46 INFO - grabbing minidump binary from tooltool 23:53:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 23:53:46 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10887bd30>, '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 0x108845cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10886cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:53:46 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 23:53:46 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 23:53:46 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 23:53:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:53:46 INFO - Return code: 0 23:53:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:53:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:53:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:53:46 INFO - ENV: RUST_BACKTRACE is now 1 23:53:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:53:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:53:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HvnJjdsRTNyQwNqBJeysSw/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0164', '--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 23:53:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HvnJjdsRTNyQwNqBJeysSw/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0164 --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 23:53:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yi7jrc3txg/Render', 23:53:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:53:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:53:46 INFO - 'HOME': '/Users/cltbld', 23:53:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:53:46 INFO - 'LOGNAME': 'cltbld', 23:53:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:53:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:53:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:53:46 INFO - 'MOZ_NO_REMOTE': '1', 23:53:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:53:46 INFO - 'NO_EM_RESTART': '1', 23:53:46 INFO - 'PAGER': '/bin/cat', 23:53:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:53:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:53:46 INFO - 'PWD': '/builds/slave/test', 23:53:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:53:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:53:46 INFO - 'RUST_BACKTRACE': '1', 23:53:46 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 23:53:46 INFO - 'SHELL': '/bin/bash', 23:53:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yAFeeHJeeX/Listeners', 23:53:46 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 23:53:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:53:46 INFO - 'USER': 'cltbld', 23:53:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:53:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:53:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:53:46 INFO - 'XPC_FLAGS': '0x0', 23:53:46 INFO - 'XPC_SERVICE_NAME': '0', 23:53:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:53:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HvnJjdsRTNyQwNqBJeysSw/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0164', '--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 23:53:46 INFO - mozversion application_buildid: 20170612224034 23:53:46 INFO - mozversion application_changeset: 6872377277a618b2b9e0d2b4c2b9e51765ac199e 23:53:46 INFO - mozversion application_display_name: FirefoxDeveloperEdition 23:53:46 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:53:46 INFO - mozversion application_name: Firefox 23:53:46 INFO - mozversion application_remotingname: firefox-dev 23:53:46 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 23:53:46 INFO - mozversion application_vendor: Mozilla 23:53:46 INFO - mozversion application_version: 55.0 23:53:46 INFO - mozversion platform_buildid: 20170612224034 23:53:46 INFO - mozversion platform_changeset: 6872377277a618b2b9e0d2b4c2b9e51765ac199e 23:53:46 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 23:53:46 INFO - mozversion platform_version: 55.0 23:53:46 INFO - using testdate: 1497336826 23:53:46 INFO - actual date: 1497336826 23:53:46 INFO - starting webserver on 'localhost:49227' 23:53:46 INFO - SUITE-START | Running 2 tests 23:53:46 INFO - TEST-START | dromaeo_css 23:53:46 INFO - operating with platform_type : mac_ 23:53:46 INFO - Initialising browser for dromaeo_css test... 23:53:46 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpa5PrRq/profile 23:53:46 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 23:54:20 INFO - TEST-INFO | 1648: killed by SIGTERM 23:54:20 INFO - Browser initialized. 23:54:20 INFO - Running cycle 1/1 for dromaeo_css test... 23:54:20 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpa5PrRq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:54:21 INFO - PID 1658 | 2017-06-12 23:54:21.835 plugin-container[1659:9876] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x933f, name = 'com.apple.tsm.portname' 23:54:21 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:54:21 INFO - PID 1658 | 2017-06-12 23:54:21.835 plugin-container[1659:9876] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9817, name = 'com.apple.CFPasteboardClient' 23:54:21 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:54:21 INFO - PID 1658 | 2017-06-12 23:54:21.835 plugin-container[1659:9876] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:54:22 INFO - PID 1658 | 2017-06-12 23:54:22.623 plugin-container[1660:9920] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 23:54:22 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:54:22 INFO - PID 1658 | 2017-06-12 23:54:22.624 plugin-container[1660:9920] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a03, name = 'com.apple.CFPasteboardClient' 23:54:22 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 23:54:22 INFO - PID 1658 | 2017-06-12 23:54:22.624 plugin-container[1660:9920] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 23:54:31 INFO - PID 1658 | RSS: Main: 214917120 23:54:31 INFO - PID 1658 | 23:57:35 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49227/tests/dromaeo/cssquery-ext.html) 23:57:36 INFO - PID 1658 | RSS: Main: 236695552 23:57:36 INFO - PID 1658 | 00:00:38 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-ext.html (next: http://localhost:49227/tests/dromaeo/cssquery-jquery.html) 00:00:39 INFO - PID 1658 | RSS: Main: 246939648 00:00:39 INFO - PID 1658 | 00:03:40 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49227/tests/dromaeo/cssquery-mootools.html) 00:03:41 INFO - PID 1658 | RSS: Main: 251564032 00:03:41 INFO - PID 1658 | 00:06:43 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49227/tests/dromaeo/cssquery-prototype.html) 00:06:43 INFO - PID 1658 | RSS: Main: 254066688 00:06:43 INFO - PID 1658 | 00:09:46 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49227/tests/dromaeo/cssquery-yui.html) 00:09:47 INFO - PID 1658 | RSS: Main: 256585728 00:09:47 INFO - PID 1658 | 00:12:48 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-yui.html (next: http://localhost:49227/tests/dromaeo/cssquery-dojo.html) 00:12:49 INFO - PID 1658 | RSS: Main: 256229376 00:12:49 INFO - PID 1658 | 00:12:49 INFO - PID 1658 | __start_tp_report 00:12:49 INFO - PID 1658 | _x_x_mozilla_page_load 00:12:49 INFO - PID 1658 | _x_x_mozilla_page_load_details 00:12:49 INFO - PID 1658 | |i|pagename|runs| 00:12:49 INFO - PID 1658 | |0;dojo.html;2945.3078526546265;3158.7946783459065;3511.245082307307;3655.2141289622728;3676.9632303676954;6299.874002519959;6308.936910630892;6331.176946996896;6342.143810585575;6360.618362898229;6369.267534233564;6384.904226436607;6385.169927909377;6412;6414.5830521016105;5566.944330556704;5608.074667679828;5613.017721898662;5658.094704847225;5687.118496633007;5630.662160270376;5700.458456446631;5702.600817942745;5704.48659620634;5722.685252311121;4469.642428605704;5484.369297530795;5549.417311182328;5584.888302233953;5625.887482250332;5478.616496845222;5516.14499752535;5524.723763811833;5537.58468114893;5599.104143337087;5527.778609422878;5560.4161563035705;5582.86042848928;5594.0769772987405;5621;4379.4525684289465;4889.682170658896;5756.683382413966;6358.872822543523;6389.552731308764;6903.1716194056535;6922.896156557656;6923.342282483156;6936;6940.9652951734915;6705.832354191184;6713.630750308731;6846.691898864563;6984.930150698478;6990.231074581792;6412.9679351602945;6419.935800642027;6477.093206951031;6494.772682956074;6497.480201583862;7142.321479459443;7142.4286057115305;7152.24901385347;7155.284471552803;7158.749443769443;6500.739970401226;6570.178727659043;6591.373819487141;6788.49086318528;6790.253072162058;6768.593884366954;6781.762638307636;6827.795166145023;6930.757423490154;6945.47908906834;6808.761693340808;6811.1145551079035;6865.794026179223;6870.20992585859;6912.205096413876;6855.5886646801355;6873.48448866337;6901.792946211622;6906.758263460755;6908.6545672716165;5497.697626630574;5508.20131080999;5525.917111243334;5540.972295138498;5561.249231353796;4061.89845253871;4741.359916411309;4779.091972525279;4913.778879950411;4922.433920099163;4976.90046199074;6325.304216536178;6362;6431.485481161496;6438.871222575523;5580.665160090408;6867.828304292432;6962.721491140298;6963.512554121163;6976.4767642426;5545.112781954868;6286.811395658137;6431.324710905381;6440.806775796734;6461.870762584722;6127.969360153171;6208.192934918432;6210.72051757672;6211.409916057967;6223.222097237845;7143.749968751069;7168.498205125591;7174.713011479483;7177.497575169897;7192.352688258082;6702.798916032527;6754.898676519954;6762.357576030466;6791.083203767428;6959.652017399211;6917.9308206917285;6993;7138.321859423347;7198.316159964795;7203;6492.902606460812;6852.965735171491;6895.5173137879865;6896.655167241718;6902.896456553055;6997.8250543734775;7022.12223472066;7023.22744498115;7039.788806335818;7058.294170583106;6766.255711871789;6803.047573339835;6816.182058153054;6819.4544436443975;6837.931620683729;6530.8367290818105;6568.6058836469965;6633.70148343316;6641.26946035926;6760.797176084725;6514.6416947068365;6527.477801775752;6579.309172537006;6592.110065141144;6632.369924857131;6747.021681856201;6768.984652302194;6770.695318710768;6774.593524388552;6807;6391.456726178327;6422.710978005907;6562.80311590653;6566.8358291043105;6574.474042076718 00:12:49 INFO - PID 1658 | |1;ext.html;793.6626933553239;828.0684230240979;842.907280199178;852.4203541591719;1203.4764877278378;32774.18064548387;36602.63397366025;36732.81633591835;36817.26365472689;36892;37161.44257836135;37427.25145497095;37513.624863751356;37545.43681844768;37581.81209093957;7459.328660420561;7580.658870350848;7583;7586.544807311551;7593.810154746148;6364.681765911709;7541.396688264926;7755.844883102334;8028.879566806502;8045.758627241192;8078.788181772722;8082.39382046346;8111.067227268881;8124.959375203145;8329.167083291653;8008.27925486706;8024.117347091816;8068.233517815798;8101.675932962675;8114.715984940528;6974.0236366908675;7298.744543940937;7595.670757617395;8046.074701409354;8077.7576672699915;27581.448371032468;28910.277243068882;34216.657833421596;34501.96494105181;34612.6538734612;2936.4861149298786;3419.880304189366;3452.067941655739;3456.1532424555894;3469.3928562501715;28815.9914402997;28953.42093158125;28968;28981.71018289811;28999.85500072507;2656.8964657793654;3076.707712767306;3089.5365695145683;3095.272610936428;3139.5290706393994;259199;262238.3776162252;262415;325334;326506.36746816116;45953.31070033952;46155;46360.07279854384;46417.76791116023;46450.76774616173;48660.02679946381;48921;49117.26324105141;49144.26283605749;49304.76738081576;12816.743665126645;13521.647835216596;14819.407223711147;14835.406583736529;14933;12348.629541113782;12377.443015064344;12409.93795031019;12413.627591172279;12459.377031148406;11380.772384552429;12628.926541244096;13250.469981200644;13281.535146269835;13347.399367028507;5719.914201286984;5759.65442073477;5766.163906233573;5773.758641892014;5777.768889244383;985.3693636072919;985.7092157813462;993.9503024848873;1044.8380501022355;1046.8691413573304;22205.000774965167;22205.165028350642;22693.205737799097;22806.771932280797;23938.52122957531;919.1589695428607;993.326245276711;1052.747230795351;1108.6507750058868;1140.5779861451317;13233.53682621123;15915.726741860855;15923.44267950616;16014.767859008825;16044.679106417807;357447;358275;360330;360699;360783;53009.46990530122;53273.46726532763;53279.73360133175;53403.19895201575;53432.198517022276;1391.408651323204;1469.235997281429;1471.154086400324;1472.9410823566936;1485.0569888120906;27902.883884644387;28467;28926.710732893243;29435.705642943296;29542;37429.877103685845;37574.81212593919;37674.62325376821;37704;37710.057248569006;235673;286069.5696521504;298822;301299;301508;26459.867700661373;29279.707202927697;30602.387952241275;32219.550120244177;32302.83848580742;37662;39309.82070537889;39875.00312492211;41237;41314.58685413108;27627;27921.581176281965;28124.43751125007;28246.011389601266;29333.41333173282;28828.855855720587;28927.85536072306;29240.561391578718;29435.705642943296;29538.556921645762;32525.34949301048;34248;34418.82790586131;34469.31061378808;34558.82720586381;26680.210254336496;28819.991300304388;29221.41557168887;29905.551416729202;31126.22184445407;22051.33845984623;23742.48884234631;23957.760422395553;23979.760202397752;24005.759942400353 00:12:49 INFO - PID 1658 | |2;jquery.html;416.94996600407956;506.73063974743263;506.95437410633036;518.3802551281926;530.7425898439254;18584.907075464605;21320.89339553302;21548.8922555387;21790.346289611316;22122.336329910107;20067.09698063587;23809.047638094456;25239.990400383962;25248.621270680953;25296.241112766602;6273.404026617475;6366.045093236005;6396.392342727433;6409.070684750708;6425.421712045915;6292.622442653433;6302.747890084414;6348.841278968017;6352.269489008777;6352.6823658816875;5782.82651520453;6216.756648670262;6332.461740752042;6367.6816159192085;6376.42612164905;6311.652859092748;6359.077933699634;6363.554551181419;6387.073874288249;6393.296737358887;5819.505342045931;5830.7084645767545;5839.532837373;5854.297484301868;5866.384029676908;28193.15420537387;30744.69255307441;31037.81094527363;31171;31406.842965785254;2243.383069655841;2253.8534995225423;2259.7062381890414;2285.748567657556;2289.0157232390065;15718.921405392899;18569.907150464296;19044;19051.333203337817;19097.949612771292;2175.4887601413657;2188.518525924294;2209.668549717539;2225.499262665903;2240.9551808963893;166593;170186.29813701828;179468;179661;200413;23252.883735581214;23721.406964826016;24081.51836963286;26117.216483505526;26981.595276070875;44844.775776120914;45127.54872451234;45131;45140;45157.322640160426;12048.819267710991;12068.275903445645;12138.878611213775;12149.392530373623;12158.635240942785;9830.21358291335;9894.950525247328;9916.454594997344;9946.303758736818;9960.800783984425;10199.607248502265;12001.759524928395;12376;12428.56500022495;12500.062495312888;4135.834566617302;4761.428628564595;4812;4852.56326930578;4893.330002498126;931.2410385535767;934.8971613122551;948.3172116076414;1000.0199804191933;1006.3408467453829;15707.528774136566;17354.566135846453;21468.819214943014;21890.89054554749;21953.231636892633;918.2562124679031;971.9951400242953;972.4408465132577;997.9650712225038;1034.4414016431194;9672.177864881423;10390.688279351632;10846.132309415229;10949.83575246372;10966.013058824745;440866;530836;548236;559886;563209;154669;156166;156822.43177568077;158131.20934395253;158871;993.2947607198826;1051.269367789379;1055.9419231942318;1056.587930707025;1130.1354463835337;22765.544689105795;24653.876730616234;24879.875600621883;25041.624375634015;25146.24561263165;52657.156999504834;52666;53865.192022118914;59269.11096333472;59318.22045338474;91801;93132.53433732787;93966.53016734873;95877;96201;14344.641383965485;16847.41034063802;16865.988040717595;16902.985820850787;16987.89578677382;16133.354665813706;16218.351265949701;17233.052182130104;17691.203895824463;18967.90516047411;14831.33259003369;15334.079955202724;15444.382224710886;15568.988015778938;15757.133357665438;12285.447154878231;13628.795568066686;13698.972577056562;13749.656258593615;13875.098118622258;15832.28754706018;15851.128187949313;16015.759763603322;16135.67728645444;16168.514944551682;10273.743156420851;12058.758824823279;12149.878501214875;12189.329586872807;12273.447694853932;14928;15244.542663720105;15274.083554986737;15306.387744490095;15321.233938302817 00:12:49 INFO - PID 1658 | |3;mootools.html;1182.7752726981873;1417.348019910841;1422.7723564229725;1431.6063082652277;1444.0613601159248;13738;14243.430262789489;14288.357023933922;14325.498607548738;14330.785038224434;13640.931795341034;14077.648058798535;14088.859111408883;14092.64768380791;14100.57698269051;2780.402213524092;2847.103162503815;2856.8143070700444;2858.814177078483;2861.341891364987;2317.7682231776857;2756.6140740296373;2808.2698498390464;2810.283377738684;2813.704561021094;2382.797462215714;2415.625578035407;2419.903203871852;2777.2362600284882;2834.1214223590755;2809.3117186289287;2809.6628404591374;2809.7049809769883;2813.2685501769483;2818.5067613167716;2749.0790585153995;2769.3630464993166;2784.317842128671;2801.4117035422382;2805.523061079601;13318.467261309535;13370.197788132744;13453;13460.596182114552;13484.528041518597;7497.587632680201;7757.883631745528;7777.2222777722345;7782.844343113163;7783.182765809622;4632.490426053131;4882.194437917739;4969.676970996874;4973.303737476756;5003.524665156804;1388.9513867014707;1394.5258612071943;1397.6855207578215;1423.238567366454;1423.3808293392303;20551;20879.895600522053;20963.475913102142;20979.475513112142;20999.055042523123;7106.71573137079;7154.821129471701;7212.098487689039;7337.156227033853;7366.34257292105;16861.494155175365;17079;17083.658326833392;17099.658006840044;17120.828791712174;7709.190534993857;7902.17027212146;7917.722879699183;7922.286994170552;7939.483933544301;6777.186737591521;7036.331548502884;7038.225795162631;7038.788836334918;7094.042304288958;5544.500994910478;5718.714064296769;5744.195812586241;5757.712114394263;5866.589338746332;1377.6280404290985;1558.4311726219848;1658.0797657300072;1741.3382914492665;1767.6199617082389;650.9511786615928;813.1705660226536;828.4076885027234;830.9667613295521;860.7991851367308;6355.428011478896;7388.519746216479;7875.094364148082;8428.32573394127;8468.830623387497;954.7279025477844;955.8900726416412;998.9650362237287;1002.2032484175098;1044.6865940217904;4844.370231869834;4861;4924.556789888928;4951.925721114186;5269.5257426831;18155.909220453814;19038.904805475882;19053.42839714811;19055.142518586697;19061;14926.925365373321;17983;18821.90589047046;19002.71495927562;19029.809701902803;8121.634526446324;9474.957754646983;9730.270229732798;9745.61017559304;9813.656522021694;7170.605616690923;7562.810929726801;7591.924080759342;8074.636641351036;8084.63619137152;16064.196790160211;16807.327706892076;16935.745963810306;17180.31278748836;17228.569285767957;15521.767173492632;17086.658266834344;17622.383216587357;17707.822921771134;17796.733049004015;9189.494577798285;9687.225021998082;9976;9979.45113018791;9981.60073597048;12258.325792081521;12316.199447035913;12385.876141238832;12394.876051239733;12398.13213075076;9800.852987205055;9953.253505987224;9993.600255989679;10055.648052318134;10230;9671.17794987434;9821.36161149523;9963.302568820112;10011.349262297927;10110.39337639744;8982.640694372152;9019.143181397756;9160;9164.541772911462;9167.633294668138;6537.150170477998;6583.539152259296;7002.684879180347;7002.719891204295;7092.751753688596;6964.512484126062;6971;7117.466190035664;7230.710771569078;7243.130824301117 00:12:49 INFO - PID 1658 | |4;prototype.html;394.4359565820876;424.5436156132155;459.9931001034983;460.1027995408952;471.7476150259612;6281.717322720478;6642.800715978517;6654.434373078282;6656.434203092736;6762.526623136382;5596.328440587125;5640.435956404358;6050.8487287817825;6490.383413575714;6509.934900650992;4849.757512124397;4867.3915760529935;4887.364642596475;4897.436794768594;4909.582685471722;4040.4949381327333;4690.90618187636;4869.196582563891;4883.682560633548;4891.5353041461;4765.473549573308;4845.69166325092;4848.733319667417;4866.172750632401;4877.3903262092235;4619.422572178478;4817.229243321051;4821.951780482185;4826.203676393391;4835.951640483603;4238.512571054337;4249.4475718156755;4252.617264446215;4261.595148460891;4262.403263543107;6036.426539478743;6061.818145455643;6436.93563064368;6448.260009194656;6456.6771661417215;4529.8641040768825;5639.900219457208;5775.942240577582;5797.709904587664;5798.362180160179;6488.383603557654;6651.401373876326;7021.9297807021785;7060.505764596481;7094.71621135154;4835;4861.586765124968;4864.8162580181015;4869.780859861315;4885.8778530536665;6726.562773419761;6920.584764914071;7394.2975417335265;7415.406767458644;7448.2179371165785;4696.436427628638;4920.261960705922;4931.161702510582;4963.429205641398;4964.8758781030765;4524.932126018112;4683.929741053887;4791.520847915251;5037.4458809530925;5060.671056381397;4588.977055114703;4604.861854144381;5315.787368505216;5756.395578464201;5800.303963524319;6124.387561243929;6528.67356632176;6817.965910170417;6998.335158159966;7123.679434425463;1698.7089811742917;1698.8046374666824;1718.613312004814;1720.9827901721073;1723.7672914156428;1001.6744558018673;1120.4789772755544;1126.6282126898104;1162.8546431696038;1168.2406435816617;5770.596058275964;5931.673757943268;6025.487833534199;6385.744570217233;6574.079628851964;6373.649449280334;6379.96810015947;7062.505624606229;7248.586525627557;7270.7091716331815;5737;6510.869782604322;6553.868922621521;6564.8358791030605;6570.025134793476;3337.8331083445732;3767.849286028583;3771.867984620525;3785.9432108518395;3799.411091280856;6792.913133898553;7028.262032486516;7306.488545801849;7523.3981281497345;7548.697242571681;6782.321767823179;6784.830379240559;7296.270372962767;7484.663190156456;7492.587907665131;7474.5888976106835;7487.588182650006;7493.100827900686;7522.134954480195;7524.623768811538;5969.582129250911;6651.866962660622;7289.671964761493;7296.562206267641;7301.707931682886;6035.417239053945;7156.642167891689;7158.850105953364;7159.463040271896;7177.564487102496;7098.787036388917;7498.725216713063;7502;7507.897788619195;7525.435592330487;3756.7182461314965;4885.169521181337;4886.706797592155;4899.706017638953;4918.852434426973;5585.720713964204;5993.850153746192;6767.627780472121;7235.384992275715;7367.631618419165;6877;6981;6989.825254368681;6996.300369962963;7034.507584469038;5735.971320143372;6885.758998435031;6954.965225173842;6984.615846128511;7032.402245809163;6010.934423443988;6599.010148477767;6840.657967101525;6873.896891546731;6873.931260687529;6909.998050282781;6974.6861391238535;6978.4298532831735;6993.825154371181;7027.961891051537;5683.777987732492;6733.66331683404;6803.625800580818;6819.386255237053;6863.725450982104 00:12:49 INFO - PID 1658 | |5;yui.html;749.771319747477;759.6125975752367;827.3339961331126;852.1350828908655;852.9829403411932;15727.606809829746;15922.681546369065;16094.436694715687;16098.83901160988;18327.358542451017;18431.631367372644;18480;18592.51259899208;18602.813971860276;18606.813931860677;970.257752819094;982.4007355513166;986.309583291695;990.9207263418946;994.1301361308855;699.3565919354206;706.8904319830434;926.106307413346;1012.8480727890802;1013.564167408018;1042.807080690071;1057.5663977769116;1058.3491152940924;1059.311447559085;1063.7074804428767;1035.2287545778406;1051.4847724614883;1052.489542571852;1053.6628278950739;1054.0302921312411;767.3515879082166;775.3835700618022;775.9573223472706;777.3470284960604;778.3267473635321;16695.91652041732;17220.311187552485;17472.388466403743;17675.469735907944;17683;17116.828831711773;17333.47999560015;17400.30398784047;17563.73654395185;17694.646107077915;14766.557003289918;14767.704645907022;14785.260736963217;14927.626809329746;14974.176420296879;3375.8480868360984;3554.235839294564;3565.8217089145437;3600.42393217084;3665.725070619714;105289;146769.26615366963;149507.50492495045;165067.3493265064;168169;13455.865441345657;13725.725485490235;13848.169109853441;14036.71926561463;14246.145231286157;26488.8675556621;26583.73416265851;26779.598306025426;26855.328616784347;26864.731352686613;7931.365490760725;8079.596020199084;8091.757247282591;8101.473404228706;8102.39232057598;4113.4446849675505;4188.81150348235;4190.057237121684;4198.748075115503;4202.012527056139;4688.718676879398;4725.810967561328;4763.523647635278;4801.639877009238;4806.759662016885;948.0756262643868;964.1322809471532;968.6948611187454;970.9271804614682;974.2449601558707;649.6102338596804;660.923993740726;660.9966950165218;690.3200347657553;692.0449779304528;11231.438428078563;13104.934475327562;13731.382087806072;14233;14345.211013394364;638.0779773227641;638.581728967527;639.3925770518026;676.6346173066489;677.109600874848;5586.86032849182;5691.402402747652;5720.399358067426;5915.852103697442;6039.365866584033;118690.4065479667;144461.27769361087;145134;145502;151003;117136;124934;127107;127508;134332;86792.69810952841;98887;104957.47521262498;107955;109924.45037774919;11421.857814218512;12190.817137743119;12377.876221237673;12429.937850310691;12573;49149;50458.49541504685;50657;51203.743981279855;51435.742821285654;66945;70087.24781880494;80301;84832;89301.66047509163;14224.50214242538;16254.43109491162;17668.02825844591;17788.932664039683;18004.90997545004;18778.53053673669;18820.24719011224;19482.512937176685;19503.414897553095;19711;17471;17737.201825917607;17827.910860445616;18255.361062362754;18276.923690057254;17019.06395148279;17022.659546809242;18112.72830907511;18246.72629910526;18555.72166417532;14650.608192221722;16740.49778506647;17752.911235444255;17766.822331776515;17893;9718.69717444998;9729.124378805658;10093.394396336244;10734.14126869833;10989.890101099207;11719.707007324885;11727.648170554898;11779.998700110244;12119.75760484803;12154.574589889311 00:12:49 INFO - PID 1658 | __end_tp_report 00:12:49 INFO - PID 1658 | __start_cc_report 00:12:49 INFO - PID 1658 | _x_x_mozilla_cycle_collect,409 00:12:49 INFO - PID 1658 | __end_cc_report 00:12:49 INFO - PID 1658 | __startTimestamp1497337969062__endTimestamp 00:12:49 INFO - PID 1658 | 00:12:49 INFO - PID 1658 | ------- Summary: start ------- 00:12:49 INFO - PID 1658 | Number of tests: 6 00:12:49 INFO - PID 1658 | 00:12:49 INFO - PID 1658 | [#0] dojo.html Cycles:165 Average:6292.54 Median:6530.84 stddev:833.64 (12.8%) stddev-sans-first:793.77 00:12:49 INFO - PID 1658 | Values: 2945.3 3158.8 3511.2 3655.2 3677.0 6299.9 6308.9 6331.2 6342.1 6360.6 6369.3 6384.9 6385.2 6412.0 6414.6 5566.9 5608.1 5613.0 5658.1 5687.1 5630.7 5700.5 5702.6 5704.5 5722.7 4469.6 5484.4 5549.4 5584.9 5625.9 5478.6 5516.1 5524.7 5537.6 5599.1 5527.8 5560.4 5582.9 5594.1 5621.0 4379.5 4889.7 5756.7 6358.9 6389.6 6903.2 6922.9 6923.3 6936.0 6941.0 6705.8 6713.6 6846.7 6984.9 6990.2 6413.0 6419.9 6477.1 6494.8 6497.5 7142.3 7142.4 7152.2 7155.3 7158.7 6500.7 6570.2 6591.4 6788.5 6790.3 6768.6 6781.8 6827.8 6930.8 6945.5 6808.8 6811.1 6865.8 6870.2 6912.2 6855.6 6873.5 6901.8 6906.8 6908.7 5497.7 5508.2 5525.9 5541.0 5561.2 4061.9 4741.4 4779.1 4913.8 4922.4 4976.9 6325.3 6362.0 6431.5 6438.9 5580.7 6867.8 6962.7 6963.5 6976.5 5545.1 6286.8 6431.3 6440.8 6461.9 6128.0 6208.2 6210.7 6211.4 6223.2 7143.7 7168.5 7174.7 7177.5 7192.4 6702.8 6754.9 6762.4 6791.1 6959.7 6917.9 6993.0 7138.3 7198.3 7203.0 6492.9 6853.0 6895.5 6896.7 6902.9 6997.8 7022.1 7023.2 7039.8 7058.3 6766.3 6803.0 6816.2 6819.5 6837.9 6530.8 6568.6 6633.7 6641.3 6760.8 6514.6 6527.5 6579.3 6592.1 6632.4 6747.0 6769.0 6770.7 6774.6 6807.0 6391.5 6422.7 6562.8 6566.8 6574.5 00:12:49 INFO - PID 1658 | 00:12:49 INFO - PID 1658 | [#1] ext.html Cycles:180 Average:45220.49 Median:18097.03 stddev:82441.61 (455.6%) stddev-sans-first:82605.40 00:12:49 INFO - PID 1658 | Values: 793.7 828.1 842.9 852.4 1203.5 32774.2 36602.6 36732.8 36817.3 36892.0 37161.4 37427.3 37513.6 37545.4 37581.8 7459.3 7580.7 7583.0 7586.5 7593.8 6364.7 7541.4 7755.8 8028.9 8045.8 8078.8 8082.4 8111.1 8125.0 8329.2 8008.3 8024.1 8068.2 8101.7 8114.7 6974.0 7298.7 7595.7 8046.1 8077.8 27581.4 28910.3 34216.7 34502.0 34612.7 2936.5 3419.9 3452.1 3456.2 3469.4 28816.0 28953.4 28968.0 28981.7 28999.9 2656.9 3076.7 3089.5 3095.3 3139.5 259199.0 262238.4 262415.0 325334.0 326506.4 45953.3 46155.0 46360.1 46417.8 46450.8 48660.0 48921.0 49117.3 49144.3 49304.8 12816.7 13521.6 14819.4 14835.4 14933.0 12348.6 12377.4 12409.9 12413.6 12459.4 11380.8 12628.9 13250.5 13281.5 13347.4 5719.9 5759.7 5766.2 5773.8 5777.8 985.4 985.7 994.0 1044.8 1046.9 22205.0 22205.2 22693.2 22806.8 23938.5 919.2 993.3 1052.7 1108.7 1140.6 13233.5 15915.7 15923.4 16014.8 16044.7 357447.0 358275.0 360330.0 360699.0 360783.0 53009.5 53273.5 53279.7 53403.2 53432.2 1391.4 1469.2 1471.2 1472.9 1485.1 27902.9 28467.0 28926.7 29435.7 29542.0 37429.9 37574.8 37674.6 37704.0 37710.1 235673.0 286069.6 298822.0 301299.0 301508.0 26459.9 29279.7 30602.4 32219.6 32302.8 37662.0 39309.8 39875.0 41237.0 41314.6 27627.0 27921.6 28124.4 28246.0 29333.4 28828.9 28927.9 29240.6 29435.7 29538.6 32525.3 34248.0 34418.8 34469.3 34558.8 26680.2 28820.0 29221.4 29905.6 31126.2 22051.3 23742.5 23957.8 23979.8 24005.8 00:12:49 INFO - PID 1658 | 00:12:49 INFO - PID 1658 | [#2] jquery.html Cycles:180 Average:39338.96 Median:12525.65 stddev:91986.15 (734.4%) stddev-sans-first:92197.77 00:12:49 INFO - PID 1658 | Values: 416.9 506.7 507.0 518.4 530.7 18584.9 21320.9 21548.9 21790.3 22122.3 20067.1 23809.0 25240.0 25248.6 25296.2 6273.4 6366.0 6396.4 6409.1 6425.4 6292.6 6302.7 6348.8 6352.3 6352.7 5782.8 6216.8 6332.5 6367.7 6376.4 6311.7 6359.1 6363.6 6387.1 6393.3 5819.5 5830.7 5839.5 5854.3 5866.4 28193.2 30744.7 31037.8 31171.0 31406.8 2243.4 2253.9 2259.7 2285.7 2289.0 15718.9 18569.9 19044.0 19051.3 19097.9 2175.5 2188.5 2209.7 2225.5 2241.0 166593.0 170186.3 179468.0 179661.0 200413.0 23252.9 23721.4 24081.5 26117.2 26981.6 44844.8 45127.5 45131.0 45140.0 45157.3 12048.8 12068.3 12138.9 12149.4 12158.6 9830.2 9895.0 9916.5 9946.3 9960.8 10199.6 12001.8 12376.0 12428.6 12500.1 4135.8 4761.4 4812.0 4852.6 4893.3 931.2 934.9 948.3 1000.0 1006.3 15707.5 17354.6 21468.8 21890.9 21953.2 918.3 972.0 972.4 998.0 1034.4 9672.2 10390.7 10846.1 10949.8 10966.0 440866.0 530836.0 548236.0 559886.0 563209.0 154669.0 156166.0 156822.4 158131.2 158871.0 993.3 1051.3 1055.9 1056.6 1130.1 22765.5 24653.9 24879.9 25041.6 25146.2 52657.2 52666.0 53865.2 59269.1 59318.2 91801.0 93132.5 93966.5 95877.0 96201.0 14344.6 16847.4 16866.0 16903.0 16987.9 16133.4 16218.4 17233.1 17691.2 18967.9 14831.3 15334.1 15444.4 15569.0 15757.1 12285.4 13628.8 13699.0 13749.7 13875.1 15832.3 15851.1 16015.8 16135.7 16168.5 10273.7 12058.8 12149.9 12189.3 12273.4 14928.0 15244.5 15274.1 15306.4 15321.2 00:12:49 INFO - PID 1658 | 00:12:49 INFO - PID 1658 | [#3] mootools.html Cycles:180 Average:8486.63 Median:2826.31 stddev:5680.62 (201.0%) stddev-sans-first:5670.04 00:12:49 INFO - PID 1658 | Values: 1182.8 1417.3 1422.8 1431.6 1444.1 13738.0 14243.4 14288.4 14325.5 14330.8 13640.9 14077.6 14088.9 14092.6 14100.6 2780.4 2847.1 2856.8 2858.8 2861.3 2317.8 2756.6 2808.3 2810.3 2813.7 2382.8 2415.6 2419.9 2777.2 2834.1 2809.3 2809.7 2809.7 2813.3 2818.5 2749.1 2769.4 2784.3 2801.4 2805.5 13318.5 13370.2 13453.0 13460.6 13484.5 7497.6 7757.9 7777.2 7782.8 7783.2 4632.5 4882.2 4969.7 4973.3 5003.5 1389.0 1394.5 1397.7 1423.2 1423.4 20551.0 20879.9 20963.5 20979.5 20999.1 7106.7 7154.8 7212.1 7337.2 7366.3 16861.5 17079.0 17083.7 17099.7 17120.8 7709.2 7902.2 7917.7 7922.3 7939.5 6777.2 7036.3 7038.2 7038.8 7094.0 5544.5 5718.7 5744.2 5757.7 5866.6 1377.6 1558.4 1658.1 1741.3 1767.6 651.0 813.2 828.4 831.0 860.8 6355.4 7388.5 7875.1 8428.3 8468.8 954.7 955.9 999.0 1002.2 1044.7 4844.4 4861.0 4924.6 4951.9 5269.5 18155.9 19038.9 19053.4 19055.1 19061.0 14926.9 17983.0 18821.9 19002.7 19029.8 8121.6 9475.0 9730.3 9745.6 9813.7 7170.6 7562.8 7591.9 8074.6 8084.6 16064.2 16807.3 16935.7 17180.3 17228.6 15521.8 17086.7 17622.4 17707.8 17796.7 9189.5 9687.2 9976.0 9979.5 9981.6 12258.3 12316.2 12385.9 12394.9 12398.1 9800.9 9953.3 9993.6 10055.6 10230.0 9671.2 9821.4 9963.3 10011.3 10110.4 8982.6 9019.1 9160.0 9164.5 9167.6 6537.2 6583.5 7002.7 7002.7 7092.8 6964.5 6971.0 7117.5 7230.7 7243.1 00:12:49 INFO - PID 1658 | 00:12:49 INFO - PID 1658 | [#4] prototype.html Cycles:180 Average:5642.34 Median:6093.10 stddev:1746.70 (28.7%) stddev-sans-first:1706.61 00:12:49 INFO - PID 1658 | Values: 394.4 424.5 460.0 460.1 471.7 6281.7 6642.8 6654.4 6656.4 6762.5 5596.3 5640.4 6050.8 6490.4 6509.9 4849.8 4867.4 4887.4 4897.4 4909.6 4040.5 4690.9 4869.2 4883.7 4891.5 4765.5 4845.7 4848.7 4866.2 4877.4 4619.4 4817.2 4822.0 4826.2 4836.0 4238.5 4249.4 4252.6 4261.6 4262.4 6036.4 6061.8 6436.9 6448.3 6456.7 4529.9 5639.9 5775.9 5797.7 5798.4 6488.4 6651.4 7021.9 7060.5 7094.7 4835.0 4861.6 4864.8 4869.8 4885.9 6726.6 6920.6 7394.3 7415.4 7448.2 4696.4 4920.3 4931.2 4963.4 4964.9 4524.9 4683.9 4791.5 5037.4 5060.7 4589.0 4604.9 5315.8 5756.4 5800.3 6124.4 6528.7 6818.0 6998.3 7123.7 1698.7 1698.8 1718.6 1721.0 1723.8 1001.7 1120.5 1126.6 1162.9 1168.2 5770.6 5931.7 6025.5 6385.7 6574.1 6373.6 6380.0 7062.5 7248.6 7270.7 5737.0 6510.9 6553.9 6564.8 6570.0 3337.8 3767.8 3771.9 3785.9 3799.4 6792.9 7028.3 7306.5 7523.4 7548.7 6782.3 6784.8 7296.3 7484.7 7492.6 7474.6 7487.6 7493.1 7522.1 7524.6 5969.6 6651.9 7289.7 7296.6 7301.7 6035.4 7156.6 7158.9 7159.5 7177.6 7098.8 7498.7 7502.0 7507.9 7525.4 3756.7 4885.2 4886.7 4899.7 4918.9 5585.7 5993.9 6767.6 7235.4 7367.6 6877.0 6981.0 6989.8 6996.3 7034.5 5736.0 6885.8 6955.0 6984.6 7032.4 6010.9 6599.0 6840.7 6873.9 6873.9 6910.0 6974.7 6978.4 6993.8 7028.0 5683.8 6733.7 6803.6 6819.4 6863.7 00:12:49 INFO - PID 1658 | 00:12:49 INFO - PID 1658 | [#5] yui.html Cycles:180 Average:26123.31 Median:18302.14 stddev:40016.23 (218.6%) stddev-sans-first:40083.13 00:12:49 INFO - PID 1658 | Values: 749.8 759.6 827.3 852.1 853.0 15727.6 15922.7 16094.4 16098.8 18327.4 18431.6 18480.0 18592.5 18602.8 18606.8 970.3 982.4 986.3 990.9 994.1 699.4 706.9 926.1 1012.8 1013.6 1042.8 1057.6 1058.3 1059.3 1063.7 1035.2 1051.5 1052.5 1053.7 1054.0 767.4 775.4 776.0 777.3 778.3 16695.9 17220.3 17472.4 17675.5 17683.0 17116.8 17333.5 17400.3 17563.7 17694.6 14766.6 14767.7 14785.3 14927.6 14974.2 3375.8 3554.2 3565.8 3600.4 3665.7 105289.0 146769.3 149507.5 165067.3 168169.0 13455.9 13725.7 13848.2 14036.7 14246.1 26488.9 26583.7 26779.6 26855.3 26864.7 7931.4 8079.6 8091.8 8101.5 8102.4 4113.4 4188.8 4190.1 4198.7 4202.0 4688.7 4725.8 4763.5 4801.6 4806.8 948.1 964.1 968.7 970.9 974.2 649.6 660.9 661.0 690.3 692.0 11231.4 13104.9 13731.4 14233.0 14345.2 638.1 638.6 639.4 676.6 677.1 5586.9 5691.4 5720.4 5915.9 6039.4 118690.4 144461.3 145134.0 145502.0 151003.0 117136.0 124934.0 127107.0 127508.0 134332.0 86792.7 98887.0 104957.5 107955.0 109924.5 11421.9 12190.8 12377.9 12429.9 12573.0 49149.0 50458.5 50657.0 51203.7 51435.7 66945.0 70087.2 80301.0 84832.0 89301.7 14224.5 16254.4 17668.0 17788.9 18004.9 18778.5 18820.2 19482.5 19503.4 19711.0 17471.0 17737.2 17827.9 18255.4 18276.9 17019.1 17022.7 18112.7 18246.7 18555.7 14650.6 16740.5 17752.9 17766.8 17893.0 9718.7 9729.1 10093.4 10734.1 10989.9 11719.7 11727.6 11780.0 12119.8 12154.6 00:12:49 INFO - PID 1658 | -------- Summary: end -------- 00:12:49 INFO - PID 1658 | 00:12:49 INFO - TEST-INFO | 1658: exit 0 00:12:49 INFO - TEST-OK | dromaeo_css | took 1142939ms 00:12:49 INFO - TEST-START | kraken 00:12:49 INFO - operating with platform_type : mac_ 00:12:49 INFO - Initialising browser for kraken test... 00:12:49 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpVhGxXo/profile 00:12:49 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 00:13:23 INFO - TEST-INFO | 1668: killed by SIGTERM 00:13:23 INFO - Browser initialized. 00:13:23 INFO - Running cycle 1/1 for kraken test... 00:13:23 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpVhGxXo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:13:24 INFO - PID 1672 | 2017-06-13 00:13:24.758 plugin-container[1673:12509] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x933f, name = 'com.apple.tsm.portname' 00:13:24 INFO - PID 1672 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:13:24 INFO - PID 1672 | 2017-06-13 00:13:24.759 plugin-container[1673:12509] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x991b, name = 'com.apple.CFPasteboardClient' 00:13:24 INFO - PID 1672 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:13:24 INFO - PID 1672 | 2017-06-13 00:13:24.759 plugin-container[1673:12509] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:13:25 INFO - PID 1672 | 2017-06-13 00:13:25.582 plugin-container[1674:12553] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 00:13:25 INFO - PID 1672 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:13:25 INFO - PID 1672 | 2017-06-13 00:13:25.583 plugin-container[1674:12553] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x470b, name = 'com.apple.CFPasteboardClient' 00:13:25 INFO - PID 1672 | See /usr/include/servers/bootstrap_defs.h for the error codes. 00:13:25 INFO - PID 1672 | 2017-06-13 00:13:25.583 plugin-container[1674:12553] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 00:13:34 INFO - PID 1672 | RSS: Main: 214798336 00:13:34 INFO - PID 1672 | 00:13:37 INFO - PID 1672 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection) 00:13:38 INFO - PID 1672 | RSS: Main: 229154816 00:13:38 INFO - PID 1672 | 00:13:41 INFO - PID 1672 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-dft) 00:13:41 INFO - PID 1672 | RSS: Main: 239366144 00:13:41 INFO - PID 1672 | 00:13:45 INFO - PID 1672 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-fft) 00:13:45 INFO - PID 1672 | RSS: Main: 243793920 00:13:45 INFO - PID 1672 | 00:13:47 INFO - PID 1672 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator) 00:13:48 INFO - PID 1672 | RSS: Main: 245420032 00:13:48 INFO - PID 1672 | 00:13:50 INFO - PID 1672 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:13:50 INFO - PID 1672 | RSS: Main: 245682176 00:13:50 INFO - PID 1672 | 00:13:53 INFO - PID 1672 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom) 00:13:54 INFO - PID 1672 | RSS: Main: 251904000 00:13:54 INFO - PID 1672 | 00:13:57 INFO - PID 1672 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate) 00:13:58 INFO - PID 1672 | RSS: Main: 252035072 00:13:58 INFO - PID 1672 | 00:14:00 INFO - PID 1672 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial) 00:14:01 INFO - PID 1672 | RSS: Main: 252239872 00:14:01 INFO - PID 1672 | 00:14:03 INFO - PID 1672 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:14:03 INFO - PID 1672 | RSS: Main: 251518976 00:14:03 INFO - PID 1672 | 00:14:05 INFO - PID 1672 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:14:06 INFO - PID 1672 | RSS: Main: 251154432 00:14:06 INFO - PID 1672 | 00:14:08 INFO - PID 1672 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:14:08 INFO - PID 1672 | RSS: Main: 251199488 00:14:08 INFO - PID 1672 | 00:14:11 INFO - PID 1672 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:14:12 INFO - PID 1672 | RSS: Main: 251154432 00:14:12 INFO - PID 1672 | 00:14:15 INFO - PID 1672 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:14:15 INFO - PID 1672 | RSS: Main: 251482112 00:14:15 INFO - PID 1672 | 00:14:17 INFO - PID 1672 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49227/tests/kraken/driver.html?testName=ai-astar) 00:14:17 INFO - PID 1672 | RSS: Main: 253001728 00:14:17 INFO - PID 1672 | 00:14:17 INFO - PID 1672 | __start_tp_report 00:14:17 INFO - PID 1672 | _x_x_mozilla_page_load 00:14:17 INFO - PID 1672 | _x_x_mozilla_page_load_details 00:14:17 INFO - PID 1672 | |i|pagename|runs| 00:14:17 INFO - PID 1672 | |0;ai-astar;106;117;107;116;142;169;114;105;115;121 00:14:17 INFO - PID 1672 | |1;audio-beat-detection;128;110;147;133;107;132;109;151;161;146 00:14:17 INFO - PID 1672 | |2;audio-dft;187;247;189;284;255;199;206;196;198;202 00:14:17 INFO - PID 1672 | |3;audio-fft;68;86;68;193;85;88;87;68;120;57 00:14:17 INFO - PID 1672 | |4;audio-oscillator;90;72;115;70;74;74;108;70;70;105 00:14:17 INFO - PID 1672 | |5;imaging-gaussian-blur;91;92;91;90;92;89;91;94;135;92 00:14:17 INFO - PID 1672 | |6;imaging-darkroom;97;98;97;98;98;97;98;99;98;101 00:14:17 INFO - PID 1672 | |7;imaging-desaturate;91;112;93;91;90;93;94;93;92;91 00:14:17 INFO - PID 1672 | |8;json-parse-financial;50;49;49;52;50;53;52;51;53;53 00:14:17 INFO - PID 1672 | |9;json-stringify-tinderbox;56;56;55;56;61;56;76;77;55;56 00:14:17 INFO - PID 1672 | |10;stanford-crypto-aes;79;82;80;81;80;80;82;82;72;89 00:14:17 INFO - PID 1672 | |11;stanford-crypto-ccm;139;132;133;168;149;134;139;139;136;161 00:14:17 INFO - PID 1672 | |12;stanford-crypto-pbkdf2;179;188;181;177;202;229;187;175;178;180 00:14:17 INFO - PID 1672 | |13;stanford-crypto-sha256-iterative;66;80;98;63;79;93;94;93;79;68 00:14:17 INFO - PID 1672 | __end_tp_report 00:14:17 INFO - PID 1672 | __start_cc_report 00:14:17 INFO - PID 1672 | _x_x_mozilla_cycle_collect,1020 00:14:17 INFO - PID 1672 | __end_cc_report 00:14:17 INFO - PID 1672 | __startTimestamp1497338057972__endTimestamp 00:14:17 INFO - PID 1672 | 00:14:17 INFO - PID 1672 | ------- Summary: start ------- 00:14:17 INFO - PID 1672 | Number of tests: 14 00:14:17 INFO - PID 1672 | 00:14:17 INFO - PID 1672 | [#0] ai-astar Cycles:10 Average:121.20 Median:116.50 stddev:19.85 (17.0%) stddev-sans-first:20.28 00:14:17 INFO - PID 1672 | Values: 106.0 117.0 107.0 116.0 142.0 169.0 114.0 105.0 115.0 121.0 00:14:17 INFO - PID 1672 | 00:14:17 INFO - PID 1672 | [#1] audio-beat-detection Cycles:10 Average:132.40 Median:139.50 stddev:19.08 (13.7%) stddev-sans-first:20.17 00:14:17 INFO - PID 1672 | Values: 128.0 110.0 147.0 133.0 107.0 132.0 109.0 151.0 161.0 146.0 00:14:17 INFO - PID 1672 | 00:14:17 INFO - PID 1672 | [#2] audio-dft Cycles:10 Average:216.30 Median:204.00 stddev:33.31 (16.3%) stddev-sans-first:33.60 00:14:17 INFO - PID 1672 | Values: 187.0 247.0 189.0 284.0 255.0 199.0 206.0 196.0 198.0 202.0 00:14:17 INFO - PID 1672 | 00:14:17 INFO - PID 1672 | [#3] audio-fft Cycles:10 Average:92.00 Median:76.50 stddev:39.53 (51.7%) stddev-sans-first:40.96 00:14:17 INFO - PID 1672 | Values: 68.0 86.0 68.0 193.0 85.0 88.0 87.0 68.0 120.0 57.0 00:14:17 INFO - PID 1672 | 00:14:17 INFO - PID 1672 | [#4] audio-oscillator Cycles:10 Average:84.80 Median:71.00 stddev:18.07 (25.5%) stddev-sans-first:19.07 00:14:17 INFO - PID 1672 | Values: 90.0 72.0 115.0 70.0 74.0 74.0 108.0 70.0 70.0 105.0 00:14:17 INFO - PID 1672 | 00:14:17 INFO - PID 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:95.70 Median:91.50 stddev:13.87 (15.2%) stddev-sans-first:14.61 00:14:17 INFO - PID 1672 | Values: 91.0 92.0 91.0 90.0 92.0 89.0 91.0 94.0 135.0 92.0 00:14:17 INFO - PID 1672 | 00:14:17 INFO - PID 1672 | [#6] imaging-darkroom Cycles:10 Average:98.10 Median:98.00 stddev:1.20 (1.2%) stddev-sans-first:1.20 00:14:17 INFO - PID 1672 | Values: 97.0 98.0 97.0 98.0 98.0 97.0 98.0 99.0 98.0 101.0 00:14:17 INFO - PID 1672 | 00:14:17 INFO - PID 1672 | [#7] imaging-desaturate Cycles:10 Average:94.00 Median:92.50 stddev:6.45 (7.0%) stddev-sans-first:6.75 00:14:17 INFO - PID 1672 | Values: 91.0 112.0 93.0 91.0 90.0 93.0 94.0 93.0 92.0 91.0 00:14:17 INFO - PID 1672 | 00:14:17 INFO - PID 1672 | [#8] json-parse-financial Cycles:10 Average:51.20 Median:52.00 stddev:1.62 (3.1%) stddev-sans-first:1.66 00:14:17 INFO - PID 1672 | Values: 50.0 49.0 49.0 52.0 50.0 53.0 52.0 51.0 53.0 53.0 00:14:17 INFO - PID 1672 | 00:14:17 INFO - PID 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:60.40 Median:56.00 stddev:8.66 (15.5%) stddev-sans-first:9.03 00:14:17 INFO - PID 1672 | Values: 56.0 56.0 55.0 56.0 61.0 56.0 76.0 77.0 55.0 56.0 00:14:17 INFO - PID 1672 | 00:14:17 INFO - PID 1672 | [#10] stanford-crypto-aes Cycles:10 Average:80.70 Median:81.50 stddev:4.14 (5.1%) stddev-sans-first:4.34 00:14:17 INFO - PID 1672 | Values: 79.0 82.0 80.0 81.0 80.0 80.0 82.0 82.0 72.0 89.0 00:14:17 INFO - PID 1672 | 00:14:17 INFO - PID 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:143.00 Median:139.00 stddev:12.40 (8.9%) stddev-sans-first:13.07 00:14:17 INFO - PID 1672 | Values: 139.0 132.0 133.0 168.0 149.0 134.0 139.0 139.0 136.0 161.0 00:14:17 INFO - PID 1672 | 00:14:17 INFO - PID 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.60 Median:184.00 stddev:16.53 (9.0%) stddev-sans-first:17.24 00:14:17 INFO - PID 1672 | Values: 179.0 188.0 181.0 177.0 202.0 229.0 187.0 175.0 178.0 180.0 00:14:17 INFO - PID 1672 | 00:14:17 INFO - PID 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:81.30 Median:86.50 stddev:12.79 (14.8%) stddev-sans-first:12.31 00:14:17 INFO - PID 1672 | Values: 66.0 80.0 98.0 63.0 79.0 93.0 94.0 93.0 79.0 68.0 00:14:17 INFO - PID 1672 | -------- Summary: end -------- 00:14:17 INFO - PID 1672 | 00:14:18 INFO - TEST-INFO | 1672: exit 0 00:14:18 INFO - TEST-OK | kraken | took 89187ms 00:14:18 INFO - SUITE-END | took 1232s 00:14:19 INFO - Completed test suite (00:20:32) 00:14:19 INFO - javascript benchmark 00:14:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8501.396529372134, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2945.3078526546265, 3158.7946783459065, 3511.245082307307, 3655.2141289622728, 3676.9632303676954, 6299.874002519959, 6308.936910630892, 6331.176946996896, 6342.143810585575, 6360.618362898229, 6369.267534233564, 6384.904226436607, 6385.169927909377, 6412.0, 6414.5830521016105, 5566.944330556704, 5608.074667679828, 5613.017721898662, 5658.094704847225, 5687.118496633007, 5630.662160270376, 5700.458456446631, 5702.600817942745, 5704.48659620634, 5722.685252311121, 4469.642428605704, 5484.369297530795, 5549.417311182328, 5584.888302233953, 5625.887482250332, 5478.616496845222, 5516.14499752535, 5524.723763811833, 5537.58468114893, 5599.104143337087, 5527.778609422878, 5560.4161563035705, 5582.86042848928, 5594.0769772987405, 5621.0, 4379.4525684289465, 4889.682170658896, 5756.683382413966, 6358.872822543523, 6389.552731308764, 6903.1716194056535, 6922.896156557656, 6923.342282483156, 6936.0, 6940.9652951734915, 6705.832354191184, 6713.630750308731, 6846.691898864563, 6984.930150698478, 6990.231074581792, 6412.9679351602945, 6419.935800642027, 6477.093206951031, 6494.772682956074, 6497.480201583862, 7142.321479459443, 7142.4286057115305, 7152.24901385347, 7155.284471552803, 7158.749443769443, 6500.739970401226, 6570.178727659043, 6591.373819487141, 6788.49086318528, 6790.253072162058, 6768.593884366954, 6781.762638307636, 6827.795166145023, 6930.757423490154, 6945.47908906834, 6808.761693340808, 6811.1145551079035, 6865.794026179223, 6870.20992585859, 6912.205096413876, 6855.5886646801355, 6873.48448866337, 6901.792946211622, 6906.758263460755, 6908.6545672716165, 5497.697626630574, 5508.20131080999, 5525.917111243334, 5540.972295138498, 5561.249231353796, 4061.89845253871, 4741.359916411309, 4779.091972525279, 4913.778879950411, 4922.433920099163, 4976.90046199074, 6325.304216536178, 6362.0, 6431.485481161496, 6438.871222575523, 5580.665160090408, 6867.828304292432, 6962.721491140298, 6963.512554121163, 6976.4767642426, 5545.112781954868, 6286.811395658137, 6431.324710905381, 6440.806775796734, 6461.870762584722, 6127.969360153171, 6208.192934918432, 6210.72051757672, 6211.409916057967, 6223.222097237845, 7143.749968751069, 7168.498205125591, 7174.713011479483, 7177.497575169897, 7192.352688258082, 6702.798916032527, 6754.898676519954, 6762.357576030466, 6791.083203767428, 6959.652017399211, 6917.9308206917285, 6993.0, 7138.321859423347, 7198.316159964795, 7203.0, 6492.902606460812, 6852.965735171491, 6895.5173137879865, 6896.655167241718, 6902.896456553055, 6997.8250543734775, 7022.12223472066, 7023.22744498115, 7039.788806335818, 7058.294170583106, 6766.255711871789, 6803.047573339835, 6816.182058153054, 6819.4544436443975, 6837.931620683729, 6530.8367290818105, 6568.6058836469965, 6633.70148343316, 6641.26946035926, 6760.797176084725, 6514.6416947068365, 6527.477801775752, 6579.309172537006, 6592.110065141144, 6632.369924857131, 6747.021681856201, 6768.984652302194, 6770.695318710768, 6774.593524388552, 6807.0, 6391.456726178327, 6422.710978005907, 6562.80311590653, 6566.8358291043105, 6574.474042076718], "value": 6231.497019506499, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [793.6626933553239, 828.0684230240979, 842.907280199178, 852.4203541591719, 1203.4764877278378, 32774.18064548387, 36602.63397366025, 36732.81633591835, 36817.26365472689, 36892.0, 37161.44257836135, 37427.25145497095, 37513.624863751356, 37545.43681844768, 37581.81209093957, 7459.328660420561, 7580.658870350848, 7583.0, 7586.544807311551, 7593.810154746148, 6364.681765911709, 7541.396688264926, 7755.844883102334, 8028.879566806502, 8045.758627241192, 8078.788181772722, 8082.39382046346, 8111.067227268881, 8124.959375203145, 8329.167083291653, 8008.27925486706, 8024.117347091816, 8068.233517815798, 8101.675932962675, 8114.715984940528, 6974.0236366908675, 7298.744543940937, 7595.670757617395, 8046.074701409354, 8077.7576672699915, 27581.448371032468, 28910.277243068882, 34216.657833421596, 34501.96494105181, 34612.6538734612, 2936.4861149298786, 3419.880304189366, 3452.067941655739, 3456.1532424555894, 3469.3928562501715, 28815.9914402997, 28953.42093158125, 28968.0, 28981.71018289811, 28999.85500072507, 2656.8964657793654, 3076.707712767306, 3089.5365695145683, 3095.272610936428, 3139.5290706393994, 259199.0, 262238.3776162252, 262415.0, 325334.0, 326506.36746816116, 45953.31070033952, 46155.0, 46360.07279854384, 46417.76791116023, 46450.76774616173, 48660.02679946381, 48921.0, 49117.26324105141, 49144.26283605749, 49304.76738081576, 12816.743665126645, 13521.647835216596, 14819.407223711147, 14835.406583736529, 14933.0, 12348.629541113782, 12377.443015064344, 12409.93795031019, 12413.627591172279, 12459.377031148406, 11380.772384552429, 12628.926541244096, 13250.469981200644, 13281.535146269835, 13347.399367028507, 5719.914201286984, 5759.65442073477, 5766.163906233573, 5773.758641892014, 5777.768889244383, 985.3693636072919, 985.7092157813462, 993.9503024848873, 1044.8380501022355, 1046.8691413573304, 22205.000774965167, 22205.165028350642, 22693.205737799097, 22806.771932280797, 23938.52122957531, 919.1589695428607, 993.326245276711, 1052.747230795351, 1108.6507750058868, 1140.5779861451317, 13233.53682621123, 15915.726741860855, 15923.44267950616, 16014.767859008825, 16044.679106417807, 357447.0, 358275.0, 360330.0, 360699.0, 360783.0, 53009.46990530122, 53273.46726532763, 53279.73360133175, 53403.19895201575, 53432.198517022276, 1391.408651323204, 1469.235997281429, 1471.154086400324, 1472.9410823566936, 1485.0569888120906, 27902.883884644387, 28467.0, 28926.710732893243, 29435.705642943296, 29542.0, 37429.877103685845, 37574.81212593919, 37674.62325376821, 37704.0, 37710.057248569006, 235673.0, 286069.5696521504, 298822.0, 301299.0, 301508.0, 26459.867700661373, 29279.707202927697, 30602.387952241275, 32219.550120244177, 32302.83848580742, 37662.0, 39309.82070537889, 39875.00312492211, 41237.0, 41314.58685413108, 27627.0, 27921.581176281965, 28124.43751125007, 28246.011389601266, 29333.41333173282, 28828.855855720587, 28927.85536072306, 29240.561391578718, 29435.705642943296, 29538.556921645762, 32525.34949301048, 34248.0, 34418.82790586131, 34469.31061378808, 34558.82720586381, 26680.210254336496, 28819.991300304388, 29221.41557168887, 29905.551416729202, 31126.22184445407, 22051.33845984623, 23742.48884234631, 23957.760422395553, 23979.760202397752, 24005.759942400353], "value": 17012.212247649593, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [416.94996600407956, 506.73063974743263, 506.95437410633036, 518.3802551281926, 530.7425898439254, 18584.907075464605, 21320.89339553302, 21548.8922555387, 21790.346289611316, 22122.336329910107, 20067.09698063587, 23809.047638094456, 25239.990400383962, 25248.621270680953, 25296.241112766602, 6273.404026617475, 6366.045093236005, 6396.392342727433, 6409.070684750708, 6425.421712045915, 6292.622442653433, 6302.747890084414, 6348.841278968017, 6352.269489008777, 6352.6823658816875, 5782.82651520453, 6216.756648670262, 6332.461740752042, 6367.6816159192085, 6376.42612164905, 6311.652859092748, 6359.077933699634, 6363.554551181419, 6387.073874288249, 6393.296737358887, 5819.505342045931, 5830.7084645767545, 5839.532837373, 5854.297484301868, 5866.384029676908, 28193.15420537387, 30744.69255307441, 31037.81094527363, 31171.0, 31406.842965785254, 2243.383069655841, 2253.8534995225423, 2259.7062381890414, 2285.748567657556, 2289.0157232390065, 15718.921405392899, 18569.907150464296, 19044.0, 19051.333203337817, 19097.949612771292, 2175.4887601413657, 2188.518525924294, 2209.668549717539, 2225.499262665903, 2240.9551808963893, 166593.0, 170186.29813701828, 179468.0, 179661.0, 200413.0, 23252.883735581214, 23721.406964826016, 24081.51836963286, 26117.216483505526, 26981.595276070875, 44844.775776120914, 45127.54872451234, 45131.0, 45140.0, 45157.322640160426, 12048.819267710991, 12068.275903445645, 12138.878611213775, 12149.392530373623, 12158.635240942785, 9830.21358291335, 9894.950525247328, 9916.454594997344, 9946.303758736818, 9960.800783984425, 10199.607248502265, 12001.759524928395, 12376.0, 12428.56500022495, 12500.062495312888, 4135.834566617302, 4761.428628564595, 4812.0, 4852.56326930578, 4893.330002498126, 931.2410385535767, 934.8971613122551, 948.3172116076414, 1000.0199804191933, 1006.3408467453829, 15707.528774136566, 17354.566135846453, 21468.819214943014, 21890.89054554749, 21953.231636892633, 918.2562124679031, 971.9951400242953, 972.4408465132577, 997.9650712225038, 1034.4414016431194, 9672.177864881423, 10390.688279351632, 10846.132309415229, 10949.83575246372, 10966.013058824745, 440866.0, 530836.0, 548236.0, 559886.0, 563209.0, 154669.0, 156166.0, 156822.43177568077, 158131.20934395253, 158871.0, 993.2947607198826, 1051.269367789379, 1055.9419231942318, 1056.587930707025, 1130.1354463835337, 22765.544689105795, 24653.876730616234, 24879.875600621883, 25041.624375634015, 25146.24561263165, 52657.156999504834, 52666.0, 53865.192022118914, 59269.11096333472, 59318.22045338474, 91801.0, 93132.53433732787, 93966.53016734873, 95877.0, 96201.0, 14344.641383965485, 16847.41034063802, 16865.988040717595, 16902.985820850787, 16987.89578677382, 16133.354665813706, 16218.351265949701, 17233.052182130104, 17691.203895824463, 18967.90516047411, 14831.33259003369, 15334.079955202724, 15444.382224710886, 15568.988015778938, 15757.133357665438, 12285.447154878231, 13628.795568066686, 13698.972577056562, 13749.656258593615, 13875.098118622258, 15832.28754706018, 15851.128187949313, 16015.759763603322, 16135.67728645444, 16168.514944551682, 10273.743156420851, 12058.758824823279, 12149.878501214875, 12189.329586872807, 12273.447694853932, 14928.0, 15244.542663720105, 15274.083554986737, 15306.387744490095, 15321.233938302817], "value": 12519.747934373507, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1182.7752726981873, 1417.348019910841, 1422.7723564229725, 1431.6063082652277, 1444.0613601159248, 13738.0, 14243.430262789489, 14288.357023933922, 14325.498607548738, 14330.785038224434, 13640.931795341034, 14077.648058798535, 14088.859111408883, 14092.64768380791, 14100.57698269051, 2780.402213524092, 2847.103162503815, 2856.8143070700444, 2858.814177078483, 2861.341891364987, 2317.7682231776857, 2756.6140740296373, 2808.2698498390464, 2810.283377738684, 2813.704561021094, 2382.797462215714, 2415.625578035407, 2419.903203871852, 2777.2362600284882, 2834.1214223590755, 2809.3117186289287, 2809.6628404591374, 2809.7049809769883, 2813.2685501769483, 2818.5067613167716, 2749.0790585153995, 2769.3630464993166, 2784.317842128671, 2801.4117035422382, 2805.523061079601, 13318.467261309535, 13370.197788132744, 13453.0, 13460.596182114552, 13484.528041518597, 7497.587632680201, 7757.883631745528, 7777.2222777722345, 7782.844343113163, 7783.182765809622, 4632.490426053131, 4882.194437917739, 4969.676970996874, 4973.303737476756, 5003.524665156804, 1388.9513867014707, 1394.5258612071943, 1397.6855207578215, 1423.238567366454, 1423.3808293392303, 20551.0, 20879.895600522053, 20963.475913102142, 20979.475513112142, 20999.055042523123, 7106.71573137079, 7154.821129471701, 7212.098487689039, 7337.156227033853, 7366.34257292105, 16861.494155175365, 17079.0, 17083.658326833392, 17099.658006840044, 17120.828791712174, 7709.190534993857, 7902.17027212146, 7917.722879699183, 7922.286994170552, 7939.483933544301, 6777.186737591521, 7036.331548502884, 7038.225795162631, 7038.788836334918, 7094.042304288958, 5544.500994910478, 5718.714064296769, 5744.195812586241, 5757.712114394263, 5866.589338746332, 1377.6280404290985, 1558.4311726219848, 1658.0797657300072, 1741.3382914492665, 1767.6199617082389, 650.9511786615928, 813.1705660226536, 828.4076885027234, 830.9667613295521, 860.7991851367308, 6355.428011478896, 7388.519746216479, 7875.094364148082, 8428.32573394127, 8468.830623387497, 954.7279025477844, 955.8900726416412, 998.9650362237287, 1002.2032484175098, 1044.6865940217904, 4844.370231869834, 4861.0, 4924.556789888928, 4951.925721114186, 5269.5257426831, 18155.909220453814, 19038.904805475882, 19053.42839714811, 19055.142518586697, 19061.0, 14926.925365373321, 17983.0, 18821.90589047046, 19002.71495927562, 19029.809701902803, 8121.634526446324, 9474.957754646983, 9730.270229732798, 9745.61017559304, 9813.656522021694, 7170.605616690923, 7562.810929726801, 7591.924080759342, 8074.636641351036, 8084.63619137152, 16064.196790160211, 16807.327706892076, 16935.745963810306, 17180.31278748836, 17228.569285767957, 15521.767173492632, 17086.658266834344, 17622.383216587357, 17707.822921771134, 17796.733049004015, 9189.494577798285, 9687.225021998082, 9976.0, 9979.45113018791, 9981.60073597048, 12258.325792081521, 12316.199447035913, 12385.876141238832, 12394.876051239733, 12398.13213075076, 9800.852987205055, 9953.253505987224, 9993.600255989679, 10055.648052318134, 10230.0, 9671.17794987434, 9821.36161149523, 9963.302568820112, 10011.349262297927, 10110.39337639744, 8982.640694372152, 9019.143181397756, 9160.0, 9164.541772911462, 9167.633294668138, 6537.150170477998, 6583.539152259296, 7002.684879180347, 7002.719891204295, 7092.751753688596, 6964.512484126062, 6971.0, 7117.466190035664, 7230.710771569078, 7243.130824301117], "value": 6250.306109033333, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [394.4359565820876, 424.5436156132155, 459.9931001034983, 460.1027995408952, 471.7476150259612, 6281.717322720478, 6642.800715978517, 6654.434373078282, 6656.434203092736, 6762.526623136382, 5596.328440587125, 5640.435956404358, 6050.8487287817825, 6490.383413575714, 6509.934900650992, 4849.757512124397, 4867.3915760529935, 4887.364642596475, 4897.436794768594, 4909.582685471722, 4040.4949381327333, 4690.90618187636, 4869.196582563891, 4883.682560633548, 4891.5353041461, 4765.473549573308, 4845.69166325092, 4848.733319667417, 4866.172750632401, 4877.3903262092235, 4619.422572178478, 4817.229243321051, 4821.951780482185, 4826.203676393391, 4835.951640483603, 4238.512571054337, 4249.4475718156755, 4252.617264446215, 4261.595148460891, 4262.403263543107, 6036.426539478743, 6061.818145455643, 6436.93563064368, 6448.260009194656, 6456.6771661417215, 4529.8641040768825, 5639.900219457208, 5775.942240577582, 5797.709904587664, 5798.362180160179, 6488.383603557654, 6651.401373876326, 7021.9297807021785, 7060.505764596481, 7094.71621135154, 4835.0, 4861.586765124968, 4864.8162580181015, 4869.780859861315, 4885.8778530536665, 6726.562773419761, 6920.584764914071, 7394.2975417335265, 7415.406767458644, 7448.2179371165785, 4696.436427628638, 4920.261960705922, 4931.161702510582, 4963.429205641398, 4964.8758781030765, 4524.932126018112, 4683.929741053887, 4791.520847915251, 5037.4458809530925, 5060.671056381397, 4588.977055114703, 4604.861854144381, 5315.787368505216, 5756.395578464201, 5800.303963524319, 6124.387561243929, 6528.67356632176, 6817.965910170417, 6998.335158159966, 7123.679434425463, 1698.7089811742917, 1698.8046374666824, 1718.613312004814, 1720.9827901721073, 1723.7672914156428, 1001.6744558018673, 1120.4789772755544, 1126.6282126898104, 1162.8546431696038, 1168.2406435816617, 5770.596058275964, 5931.673757943268, 6025.487833534199, 6385.744570217233, 6574.079628851964, 6373.649449280334, 6379.96810015947, 7062.505624606229, 7248.586525627557, 7270.7091716331815, 5737.0, 6510.869782604322, 6553.868922621521, 6564.8358791030605, 6570.025134793476, 3337.8331083445732, 3767.849286028583, 3771.867984620525, 3785.9432108518395, 3799.411091280856, 6792.913133898553, 7028.262032486516, 7306.488545801849, 7523.3981281497345, 7548.697242571681, 6782.321767823179, 6784.830379240559, 7296.270372962767, 7484.663190156456, 7492.587907665131, 7474.5888976106835, 7487.588182650006, 7493.100827900686, 7522.134954480195, 7524.623768811538, 5969.582129250911, 6651.866962660622, 7289.671964761493, 7296.562206267641, 7301.707931682886, 6035.417239053945, 7156.642167891689, 7158.850105953364, 7159.463040271896, 7177.564487102496, 7098.787036388917, 7498.725216713063, 7502.0, 7507.897788619195, 7525.435592330487, 3756.7182461314965, 4885.169521181337, 4886.706797592155, 4899.706017638953, 4918.852434426973, 5585.720713964204, 5993.850153746192, 6767.627780472121, 7235.384992275715, 7367.631618419165, 6877.0, 6981.0, 6989.825254368681, 6996.300369962963, 7034.507584469038, 5735.971320143372, 6885.758998435031, 6954.965225173842, 6984.615846128511, 7032.402245809163, 6010.934423443988, 6599.010148477767, 6840.657967101525, 6873.896891546731, 6873.931260687529, 6909.998050282781, 6974.6861391238535, 6978.4298532831735, 6993.825154371181, 7027.961891051537, 5683.777987732492, 6733.66331683404, 6803.625800580818, 6819.386255237053, 6863.725450982104], "value": 5108.823611359403, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [749.771319747477, 759.6125975752367, 827.3339961331126, 852.1350828908655, 852.9829403411932, 15727.606809829746, 15922.681546369065, 16094.436694715687, 16098.83901160988, 18327.358542451017, 18431.631367372644, 18480.0, 18592.51259899208, 18602.813971860276, 18606.813931860677, 970.257752819094, 982.4007355513166, 986.309583291695, 990.9207263418946, 994.1301361308855, 699.3565919354206, 706.8904319830434, 926.106307413346, 1012.8480727890802, 1013.564167408018, 1042.807080690071, 1057.5663977769116, 1058.3491152940924, 1059.311447559085, 1063.7074804428767, 1035.2287545778406, 1051.4847724614883, 1052.489542571852, 1053.6628278950739, 1054.0302921312411, 767.3515879082166, 775.3835700618022, 775.9573223472706, 777.3470284960604, 778.3267473635321, 16695.91652041732, 17220.311187552485, 17472.388466403743, 17675.469735907944, 17683.0, 17116.828831711773, 17333.47999560015, 17400.30398784047, 17563.73654395185, 17694.646107077915, 14766.557003289918, 14767.704645907022, 14785.260736963217, 14927.626809329746, 14974.176420296879, 3375.8480868360984, 3554.235839294564, 3565.8217089145437, 3600.42393217084, 3665.725070619714, 105289.0, 146769.26615366963, 149507.50492495045, 165067.3493265064, 168169.0, 13455.865441345657, 13725.725485490235, 13848.169109853441, 14036.71926561463, 14246.145231286157, 26488.8675556621, 26583.73416265851, 26779.598306025426, 26855.328616784347, 26864.731352686613, 7931.365490760725, 8079.596020199084, 8091.757247282591, 8101.473404228706, 8102.39232057598, 4113.4446849675505, 4188.81150348235, 4190.057237121684, 4198.748075115503, 4202.012527056139, 4688.718676879398, 4725.810967561328, 4763.523647635278, 4801.639877009238, 4806.759662016885, 948.0756262643868, 964.1322809471532, 968.6948611187454, 970.9271804614682, 974.2449601558707, 649.6102338596804, 660.923993740726, 660.9966950165218, 690.3200347657553, 692.0449779304528, 11231.438428078563, 13104.934475327562, 13731.382087806072, 14233.0, 14345.211013394364, 638.0779773227641, 638.581728967527, 639.3925770518026, 676.6346173066489, 677.109600874848, 5586.86032849182, 5691.402402747652, 5720.399358067426, 5915.852103697442, 6039.365866584033, 118690.4065479667, 144461.27769361087, 145134.0, 145502.0, 151003.0, 117136.0, 124934.0, 127107.0, 127508.0, 134332.0, 86792.69810952841, 98887.0, 104957.47521262498, 107955.0, 109924.45037774919, 11421.857814218512, 12190.817137743119, 12377.876221237673, 12429.937850310691, 12573.0, 49149.0, 50458.49541504685, 50657.0, 51203.743981279855, 51435.742821285654, 66945.0, 70087.24781880494, 80301.0, 84832.0, 89301.66047509163, 14224.50214242538, 16254.43109491162, 17668.02825844591, 17788.932664039683, 18004.90997545004, 18778.53053673669, 18820.24719011224, 19482.512937176685, 19503.414897553095, 19711.0, 17471.0, 17737.201825917607, 17827.910860445616, 18255.361062362754, 18276.923690057254, 17019.06395148279, 17022.659546809242, 18112.72830907511, 18246.72629910526, 18555.72166417532, 14650.608192221722, 16740.49778506647, 17752.911235444255, 17766.822331776515, 17893.0, 9718.69717444998, 9729.124378805658, 10093.394396336244, 10734.14126869833, 10989.890101099207, 11719.707007324885, 11727.648170554898, 11779.998700110244, 12119.75760484803, 12154.574589889311], "value": 8907.257995406799, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1538.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 117.0, 107.0, 116.0, 142.0, 169.0, 114.0, 105.0, 115.0, 121.0], "value": 121.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 110.0, 147.0, 133.0, 107.0, 132.0, 109.0, 151.0, 161.0, 146.0], "value": 132.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 247.0, 189.0, 284.0, 255.0, 199.0, 206.0, 196.0, 198.0, 202.0], "value": 216.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 86.0, 68.0, 193.0, 85.0, 88.0, 87.0, 68.0, 120.0, 57.0], "value": 92.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 72.0, 115.0, 70.0, 74.0, 74.0, 108.0, 70.0, 70.0, 105.0], "value": 84.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 91.0, 90.0, 92.0, 89.0, 91.0, 94.0, 135.0, 92.0], "value": 95.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 97.0, 98.0, 98.0, 97.0, 98.0, 99.0, 98.0, 101.0], "value": 98.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 112.0, 93.0, 91.0, 90.0, 93.0, 94.0, 93.0, 92.0, 91.0], "value": 94.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 49.0, 52.0, 50.0, 53.0, 52.0, 51.0, 53.0, 53.0], "value": 51.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 56.0, 61.0, 56.0, 76.0, 77.0, 55.0, 56.0], "value": 60.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 82.0, 80.0, 81.0, 80.0, 80.0, 82.0, 82.0, 72.0, 89.0], "value": 80.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 132.0, 133.0, 168.0, 149.0, 134.0, 139.0, 139.0, 136.0, 161.0], "value": 143.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 188.0, 181.0, 177.0, 202.0, 229.0, 187.0, 175.0, 178.0, 180.0], "value": 187.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 80.0, 98.0, 63.0, 79.0, 93.0, 94.0, 93.0, 79.0, 68.0], "value": 81.3, "unit": "score"}]}]} 00:14:19 INFO - Return code: 0 00:14:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 00:14:19 INFO - # TBPL SUCCESS # 00:14:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:14:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:14:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:14:19 INFO - 2017-06-13 00:14:19.391 screenresolution[1680:12935] starting screenresolution argv=screenresolution get 00:14:19 INFO - 2017-06-13 00:14:19.393 screenresolution[1680:12935] Display 0: 1600x1200x32@60 00:14:19 INFO - 2017-06-13 00:14:19.399 screenresolution[1681:12939] starting screenresolution argv=screenresolution list 00:14:19 INFO - Available Modes on Display 0 00:14:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:14:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:14:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:14:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:14:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:14:19 INFO - Intel Iris: 00:14:19 INFO - Chipset Model: Intel Iris 00:14:19 INFO - Type: GPU 00:14:19 INFO - Bus: Built-In 00:14:19 INFO - VRAM (Dynamic, Max): 1536 MB 00:14:19 INFO - Vendor: Intel (0x8086) 00:14:19 INFO - Device ID: 0x0a2e 00:14:19 INFO - Revision ID: 0x0009 00:14:19 INFO - Displays: 00:14:19 INFO - Display: 00:14:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:14:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:14:19 INFO - Display Serial Number: 1600x1200 60 00:14:19 INFO - Main Display: Yes 00:14:19 INFO - Mirror: Off 00:14:19 INFO - Online: Yes 00:14:19 INFO - Rotation: Supported 00:14:19 INFO - Return code: 0 00:14:19 INFO - Running post-action listener: _resource_record_post_action 00:14:19 INFO - [mozharness: 2017-06-13 07:14:19.636175Z] Finished run-tests step (success) 00:14:19 INFO - Running post-run listener: _resource_record_post_run 00:14:19 INFO - Total resource usage - Wall time: 1254s; CPU: 53.0%; Read bytes: 44700160; Write bytes: 580001280; Read time: 548; Write time: 3169 00:14:19 INFO - TinderboxPrint: CPU usage
53.1% 00:14:19 INFO - TinderboxPrint: I/O read bytes / time
44,700,160 / 548 00:14:19 INFO - TinderboxPrint: I/O write bytes / time
580,001,280 / 3,169 00:14:19 INFO - TinderboxPrint: CPU idle
2,351.0 (46.9%) 00:14:19 INFO - TinderboxPrint: CPU system
1,019.1 (20.3%) 00:14:19 INFO - TinderboxPrint: CPU user
1,647.6 (32.8%) 00:14:19 INFO - TinderboxPrint: Swap in / out
279,179,264 / 0 00:14:19 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 227873280; Write bytes: 225378304; Read time: 16713; Write time: 381 00:14:19 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 00:14:19 INFO - run-tests - Wall time: 1234s; CPU: 53.0%; Read bytes: 43713024; Write bytes: 336178688; Read time: 530; Write time: 2744 00:14:20 INFO - Running post-run listener: _upload_blobber_files 00:14:20 INFO - Blob upload gear active. 00:14:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:14:20 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 00:14:20 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'] 00:14:20 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 00:14:20 INFO - (blobuploader) - INFO - Open directory for files ... 00:14:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 00:14:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:14:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:14:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 00:14:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:14:21 INFO - (blobuploader) - INFO - Done attempting. 00:14:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 00:14:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:14:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:14:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 00:14:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:14:22 INFO - (blobuploader) - INFO - Done attempting. 00:14:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 00:14:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:14:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:14:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 00:14:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:14:23 INFO - (blobuploader) - INFO - Done attempting. 00:14:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 00:14:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:14:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:14:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 00:14:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:14:26 INFO - (blobuploader) - INFO - Done attempting. 00:14:26 INFO - (blobuploader) - INFO - Iteration through files over. 00:14:26 INFO - Return code: 0 00:14:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:14:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:14:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/220c35986f64d8db08e193411bde7c0805e1bbe26d615560df5cb295fbdb638e2354c75f3d483100fa9e2d29fd7e998b1530f6b007f61b8f162c613eb120d07b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6b587464a7c93deff9b87bc4c7bf494b10a556d2e6112d94b06b5d6110486361d265707f837798c22f58ac1f228ab71b4c248db23929fafd0e83346c88adbca4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6094b7ced874745d7475ab1c36bc2b949f16b2caaa000e9774b547b34d6124dbbd2ad533fcb952310a384d26e42bf734db903dd6f8ccfef2b4860fe6170b5bb7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9936b08b9e9d1cae52cb2fd699bd57a9b7a00f142933a92c690cecc36364b749216a5d5e852cf0232b69102afe9addea26addfc883dbd65dba1054133e53788a"} 00:14:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:14:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:14:26 INFO - Contents: 00:14:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/220c35986f64d8db08e193411bde7c0805e1bbe26d615560df5cb295fbdb638e2354c75f3d483100fa9e2d29fd7e998b1530f6b007f61b8f162c613eb120d07b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6b587464a7c93deff9b87bc4c7bf494b10a556d2e6112d94b06b5d6110486361d265707f837798c22f58ac1f228ab71b4c248db23929fafd0e83346c88adbca4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6094b7ced874745d7475ab1c36bc2b949f16b2caaa000e9774b547b34d6124dbbd2ad533fcb952310a384d26e42bf734db903dd6f8ccfef2b4860fe6170b5bb7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9936b08b9e9d1cae52cb2fd699bd57a9b7a00f142933a92c690cecc36364b749216a5d5e852cf0232b69102afe9addea26addfc883dbd65dba1054133e53788a"} 00:14:26 INFO - Running post-run listener: copy_logs_to_upload_dir 00:14:26 INFO - Copying logs to upload dir... 00:14:26 INFO - mkdir: /builds/slave/test/build/upload/logs 00:14:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1336.971067 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-13 00:14:26.198567) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 00:14:26.201957) ========= 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.yi7jrc3txg/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.yAFeeHJeeX/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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/220c35986f64d8db08e193411bde7c0805e1bbe26d615560df5cb295fbdb638e2354c75f3d483100fa9e2d29fd7e998b1530f6b007f61b8f162c613eb120d07b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6b587464a7c93deff9b87bc4c7bf494b10a556d2e6112d94b06b5d6110486361d265707f837798c22f58ac1f228ab71b4c248db23929fafd0e83346c88adbca4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6094b7ced874745d7475ab1c36bc2b949f16b2caaa000e9774b547b34d6124dbbd2ad533fcb952310a384d26e42bf734db903dd6f8ccfef2b4860fe6170b5bb7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9936b08b9e9d1cae52cb2fd699bd57a9b7a00f142933a92c690cecc36364b749216a5d5e852cf0232b69102afe9addea26addfc883dbd65dba1054133e53788a"} build_url:https://queue.taskcluster.net/v1/task/HvnJjdsRTNyQwNqBJeysSw/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011253 build_url: 'https://queue.taskcluster.net/v1/task/HvnJjdsRTNyQwNqBJeysSw/artifacts/public/build/firefox-55.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/220c35986f64d8db08e193411bde7c0805e1bbe26d615560df5cb295fbdb638e2354c75f3d483100fa9e2d29fd7e998b1530f6b007f61b8f162c613eb120d07b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6b587464a7c93deff9b87bc4c7bf494b10a556d2e6112d94b06b5d6110486361d265707f837798c22f58ac1f228ab71b4c248db23929fafd0e83346c88adbca4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6094b7ced874745d7475ab1c36bc2b949f16b2caaa000e9774b547b34d6124dbbd2ad533fcb952310a384d26e42bf734db903dd6f8ccfef2b4860fe6170b5bb7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9936b08b9e9d1cae52cb2fd699bd57a9b7a00f142933a92c690cecc36364b749216a5d5e852cf0232b69102afe9addea26addfc883dbd65dba1054133e53788a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 00:14:26.235934) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 00:14:26.236270) ========= 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.yi7jrc3txg/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.yAFeeHJeeX/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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.005178 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 00:14:26.286243) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 00:14:26.286577) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 00:14:26.286929) ========= ========= Total master_lag: 0.22 =========