builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0304 starttime: 1497398536.57 results: success (0) buildid: 20170613153934 builduid: 5baf7c77824843ab827c31adc51af8a3 revision: d956c96a1926c1755d9ce46a110ba7893f4d478a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 17:02:16.573120) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-13 17:02:16.573670) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 17:02:16.574003) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-13 17:02:16.812473) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 17:02:16.812878) ========= 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.zvnUntTPyt/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.oDnYwCYmak/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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-13 17:02:16-- 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% 25.6M=0s 2017-06-13 17:02:17 (25.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.578198 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 17:02:17.414854) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 17:02:17.415135) ========= 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.zvnUntTPyt/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.oDnYwCYmak/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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.021115 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 17:02:17.463972) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-13 17:02:17.464317) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d956c96a1926c1755d9ce46a110ba7893f4d478a --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 d956c96a1926c1755d9ce46a110ba7893f4d478a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zvnUntTPyt/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.oDnYwCYmak/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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-13 17:02:17,545 truncating revision to first 12 chars 2017-06-13 17:02:17,546 Setting DEBUG logging. 2017-06-13 17:02:17,546 attempt 1/10 2017-06-13 17:02:17,546 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d956c96a1926?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-13 17:02:19,250 unpacking tar archive at: mozilla-beta-d956c96a1926/testing/mozharness/ program finished with exit code 0 elapsedTime=1.978226 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-13 17:02:19.463745) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 17:02:19.464051) ========= script_repo_revision: d956c96a1926c1755d9ce46a110ba7893f4d478a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-13 17:02:19.464487) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 17:02:19.464750) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-13 17:02:19.489008) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 13 secs) (at 2017-06-13 17:02:19.489398) ========= /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.zvnUntTPyt/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.oDnYwCYmak/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 17:02:19 INFO - MultiFileLogger online at 20170613 17:02:19 in /builds/slave/test 17:02:19 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 17:02:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:02:19 INFO - {'append_to_log': False, 17:02:19 INFO - 'base_work_dir': '/builds/slave/test', 17:02:19 INFO - 'blob_upload_branch': 'Mozilla-Beta', 17:02:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:02:19 INFO - 'branch': 'Mozilla-Beta', 17:02:19 INFO - 'buildbot_json_path': 'buildprops.json', 17:02:19 INFO - 'config_files': ('talos/mac_config.py',), 17:02:19 INFO - 'default_actions': ('clobber', 17:02:19 INFO - 'read-buildbot-config', 17:02:19 INFO - 'download-and-extract', 17:02:19 INFO - 'populate-webroot', 17:02:19 INFO - 'create-virtualenv', 17:02:19 INFO - 'install', 17:02:19 INFO - 'setup-mitmproxy', 17:02:19 INFO - 'run-tests'), 17:02:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:02:19 INFO - 'download_minidump_stackwalk': True, 17:02:19 INFO - 'download_symbols': 'ondemand', 17:02:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:02:19 INFO - 'tooltool.py': '/tools/tooltool.py', 17:02:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:02:19 INFO - '/tools/misc-python/virtualenv.py')}, 17:02:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:02:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:02:19 INFO - 'gecko_profile': False, 17:02:19 INFO - 'gecko_profile_interval': 0, 17:02:19 INFO - 'installer_path': 'installer.exe', 17:02:19 INFO - 'log_level': 'info', 17:02:19 INFO - 'log_name': 'talos', 17:02:19 INFO - 'log_to_console': True, 17:02:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:02:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:02:19 INFO - 'opt_config_files': (), 17:02:19 INFO - 'pip_index': False, 17:02:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:02:19 INFO - 'cmd': ('bash', 17:02:19 INFO - '-c', 17:02:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:02:19 INFO - 'enabled': True, 17:02:19 INFO - 'halt_on_failure': False, 17:02:19 INFO - 'name': 'check_screen_resolution'},), 17:02:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:02:19 INFO - 'cmd': ('bash', 17:02:19 INFO - '-c', 17:02:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:02:19 INFO - 'enabled': True, 17:02:19 INFO - 'halt_on_failure': False, 17:02:19 INFO - 'name': 'check_screen_resolution'},), 17:02:19 INFO - 'run_cmd_checks_enabled': True, 17:02:19 INFO - 'suite': 'dromaeojs-e10s', 17:02:19 INFO - 'system_bits': '32', 17:02:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:02:19 INFO - 'title': 't-yosemite-r7-0304', 17:02:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:02:19 INFO - 'use_talos_json': True, 17:02:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:02:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:02:19 INFO - 'work_dir': 'build'} 17:02:19 INFO - [mozharness: 2017-06-14 00:02:19.692292Z] Running clobber step. 17:02:19 INFO - Running pre-action listener: _resource_record_pre_action 17:02:19 INFO - Running main action method: clobber 17:02:19 INFO - rmtree: /builds/slave/test/build 17:02:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:02:20 INFO - Running post-action listener: _resource_record_post_action 17:02:20 INFO - [mozharness: 2017-06-14 00:02:20.435682Z] Finished clobber step (success) 17:02:20 INFO - [mozharness: 2017-06-14 00:02:20.435963Z] Running read-buildbot-config step. 17:02:20 INFO - Running pre-action listener: _resource_record_pre_action 17:02:20 INFO - Running main action method: read_buildbot_config 17:02:20 INFO - Using buildbot properties: 17:02:20 INFO - { 17:02:20 INFO - "project": "", 17:02:20 INFO - "product": "firefox", 17:02:20 INFO - "script_repo_revision": "production", 17:02:20 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 17:02:20 INFO - "repository": "", 17:02:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 17:02:20 INFO - "buildid": "20170613153934", 17:02:20 INFO - "pgo_build": "False", 17:02:20 INFO - "basedir": "/builds/slave/test", 17:02:20 INFO - "buildnumber": 1, 17:02:20 INFO - "slavename": "t-yosemite-r7-0304", 17:02:20 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 17:02:20 INFO - "platform": "yosemite_r7_devedition", 17:02:20 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 17:02:20 INFO - "branch": "mozilla-beta", 17:02:20 INFO - "repo_path": "releases/mozilla-beta", 17:02:20 INFO - "revision": "d956c96a1926c1755d9ce46a110ba7893f4d478a", 17:02:20 INFO - "stage_platform": "macosx64-devedition", 17:02:20 INFO - "builduid": "5baf7c77824843ab827c31adc51af8a3", 17:02:20 INFO - "slavebuilddir": "test" 17:02:20 INFO - } 17:02:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/HHmfoFndSsejYCGeQdQXrA/artifacts/public/build/firefox-55.0.en-US.mac.dmg. 17:02:20 INFO - Running post-action listener: _resource_record_post_action 17:02:20 INFO - [mozharness: 2017-06-14 00:02:20.441631Z] Finished read-buildbot-config step (success) 17:02:20 INFO - [mozharness: 2017-06-14 00:02:20.441700Z] Running download-and-extract step. 17:02:20 INFO - Running pre-action listener: _resource_record_pre_action 17:02:20 INFO - Running main action method: download_and_extract 17:02:20 INFO - mkdir: /builds/slave/test/build/tests 17:02:20 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')]} 17:02:20 INFO - trying https://queue.taskcluster.net/v1/task/HHmfoFndSsejYCGeQdQXrA/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 17:02:20 INFO - Downloading https://queue.taskcluster.net/v1/task/HHmfoFndSsejYCGeQdQXrA/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 17:02:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HHmfoFndSsejYCGeQdQXrA/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 17:02:21 INFO - Downloaded 1290 bytes. 17:02:21 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 17:02:21 INFO - Using the following test package requirements: 17:02:21 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 17:02:21 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 17:02:21 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 17:02:21 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 17:02:21 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 17:02:21 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 17:02:21 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 17:02:21 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 17:02:21 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 17:02:21 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 17:02:21 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 17:02:21 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 17:02:21 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 17:02:21 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 17:02:21 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 17:02:21 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 17:02:21 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 17:02:21 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 17:02:21 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 17:02:21 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 17:02:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HHmfoFndSsejYCGeQdQXrA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 17:02:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HHmfoFndSsejYCGeQdQXrA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 17:02:21 INFO - Fetch https://queue.taskcluster.net/v1/task/HHmfoFndSsejYCGeQdQXrA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 17:02:22 INFO - Content-Length response header: 59545453 17:02:22 INFO - Bytes received: 59545453 17:02:41 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 17:02:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HHmfoFndSsejYCGeQdQXrA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 17:02:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HHmfoFndSsejYCGeQdQXrA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 17:02:41 INFO - Fetch https://queue.taskcluster.net/v1/task/HHmfoFndSsejYCGeQdQXrA/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 17:02:43 INFO - Content-Length response header: 59545453 17:02:43 INFO - Bytes received: 59545453 17:03:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HHmfoFndSsejYCGeQdQXrA/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 17:03:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HHmfoFndSsejYCGeQdQXrA/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 17:03:00 INFO - Fetch https://queue.taskcluster.net/v1/task/HHmfoFndSsejYCGeQdQXrA/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 17:03:01 INFO - Content-Length response header: 14450018 17:03:01 INFO - Bytes received: 14450018 17:03:02 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')]} 17:03:02 INFO - trying https://queue.taskcluster.net/v1/task/HHmfoFndSsejYCGeQdQXrA/artifacts/public/build/firefox-55.0.en-US.mac.dmg 17:03:02 INFO - Downloading https://queue.taskcluster.net/v1/task/HHmfoFndSsejYCGeQdQXrA/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 17:03:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HHmfoFndSsejYCGeQdQXrA/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 17:03:04 INFO - Downloaded 66878135 bytes. 17:03:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HHmfoFndSsejYCGeQdQXrA/artifacts/public/build/firefox-55.0.en-US.mac.dmg 17:03:04 INFO - mkdir: /builds/slave/test/properties 17:03:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:03:04 INFO - Writing to file /builds/slave/test/properties/build_url 17:03:04 INFO - Contents: 17:03:04 INFO - build_url:https://queue.taskcluster.net/v1/task/HHmfoFndSsejYCGeQdQXrA/artifacts/public/build/firefox-55.0.en-US.mac.dmg 17:03:04 INFO - Running post-action listener: _resource_record_post_action 17:03:04 INFO - Running post-action listener: set_extra_try_arguments 17:03:04 INFO - [mozharness: 2017-06-14 00:03:04.775963Z] Finished download-and-extract step (success) 17:03:04 INFO - [mozharness: 2017-06-14 00:03:04.776127Z] Running populate-webroot step. 17:03:04 INFO - Running pre-action listener: _resource_record_pre_action 17:03:04 INFO - Running main action method: populate_webroot 17:03:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:03:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:03:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:03:04 INFO - '--tppagecycles', 17:03:04 INFO - '7'], 17:03:04 INFO - 'tests': ['tsvgm']}}, 17:03:04 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:03:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:03:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:03:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:03:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:03:04 INFO - 'talos_options': ['--disable-e10s'], 17:03:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:03:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:03:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:03:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:03:04 INFO - 'talos_options': ['--disable-e10s'], 17:03:04 INFO - 'tests': ['damp', 'tps']}, 17:03:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:03:04 INFO - 'tests': ['damp', 'tps']}, 17:03:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:03:04 INFO - 'tests': ['dromaeo_dom']}, 17:03:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:03:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:03:04 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:03:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:03:04 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:03:04 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:03:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:03:04 INFO - 'tests': ['a11yr', 17:03:04 INFO - 'ts_paint', 17:03:04 INFO - 'tpaint', 17:03:04 INFO - 'sessionrestore', 17:03:04 INFO - 'sessionrestore_no_auto_restore', 17:03:04 INFO - 'tabpaint']}, 17:03:04 INFO - 'other-e10s': {'tests': ['a11yr', 17:03:04 INFO - 'ts_paint', 17:03:04 INFO - 'tpaint', 17:03:04 INFO - 'sessionrestore', 17:03:04 INFO - 'sessionrestore_no_auto_restore', 17:03:04 INFO - 'tabpaint']}, 17:03:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:03:04 INFO - 'ts_paint', 17:03:04 INFO - 'tpaint', 17:03:04 INFO - 'sessionrestore', 17:03:04 INFO - 'sessionrestore_no_auto_restore', 17:03:04 INFO - 'tabpaint']}, 17:03:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:03:04 INFO - 'ts_paint', 17:03:04 INFO - 'tpaint', 17:03:04 INFO - 'sessionrestore', 17:03:04 INFO - 'sessionrestore_no_auto_restore', 17:03:04 INFO - 'tabpaint']}, 17:03:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:03:04 INFO - 'tests': ['a11yr', 17:03:04 INFO - 'ts_paint', 17:03:04 INFO - 'tpaint', 17:03:04 INFO - 'sessionrestore', 17:03:04 INFO - 'sessionrestore_no_auto_restore', 17:03:04 INFO - 'tabpaint']}, 17:03:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:03:04 INFO - 'tests': ['a11yr', 17:03:04 INFO - 'ts_paint', 17:03:04 INFO - 'tpaint', 17:03:04 INFO - 'sessionrestore', 17:03:04 INFO - 'sessionrestore_no_auto_restore', 17:03:04 INFO - 'tabpaint']}, 17:03:04 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 17:03:04 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 17:03:04 INFO - 'bloom_basic_ref']}, 17:03:04 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 17:03:04 INFO - 'talos_options': ['--mitmproxy', 17:03:04 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 17:03:04 INFO - '--firstNonBlankPaint'], 17:03:04 INFO - 'tests': ['quantum_pageload_google', 17:03:04 INFO - 'quantum_pageload_youtube', 17:03:04 INFO - 'quantum_pageload_amazon', 17:03:04 INFO - 'quantum_pageload_facebook']}, 17:03:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:03:04 INFO - 'tests': ['tsvgx', 17:03:04 INFO - 'tsvgr_opacity', 17:03:04 INFO - 'tart', 17:03:04 INFO - 'tscrollx', 17:03:04 INFO - 'cart', 17:03:04 INFO - 'tsvg_static']}, 17:03:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:03:04 INFO - 'tsvgr_opacity', 17:03:04 INFO - 'tart', 17:03:04 INFO - 'tscrollx', 17:03:04 INFO - 'cart', 17:03:04 INFO - 'tsvg_static']}, 17:03:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:03:04 INFO - 'talos_options': ['--disable-e10s'], 17:03:04 INFO - 'tests': ['tp5o']}, 17:03:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:03:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:03:04 INFO - 'talos_options': ['--disable-e10s', 17:03:04 INFO - '--xperf_path', 17:03:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:03:04 INFO - 'tests': ['tp5n']}, 17:03:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:03:04 INFO - 'talos_options': ['--xperf_path', 17:03:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:03:04 INFO - 'tests': ['tp5n']}}, 17:03:04 INFO - 'talos.zip': {'path': '', 17:03:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:03:04 INFO - Running post-action listener: _resource_record_post_action 17:03:04 INFO - [mozharness: 2017-06-14 00:03:04.797335Z] Finished populate-webroot step (success) 17:03:04 INFO - [mozharness: 2017-06-14 00:03:04.797448Z] Running create-virtualenv step. 17:03:04 INFO - Running pre-action listener: _resource_record_pre_action 17:03:04 INFO - Running main action method: create_virtualenv 17:03:04 INFO - Creating virtualenv /builds/slave/test/build/venv 17:03:04 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 17:03:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:03:04 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 17:03:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:03:04 INFO - Using real prefix '/tools/python27' 17:03:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:03:05 INFO - Installing distribute.............................................................................................................................................................................................done. 17:03:08 INFO - Installing pip.................done. 17:03:08 INFO - Return code: 0 17:03:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:03:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:03:08 INFO - Reading from file tmpfile_stdout 17:03:08 INFO - Output received: 17:03:08 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) 17:03:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:03:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:03:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:03:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:08 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 0x1059cac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00adb0280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105720d30>, '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 0x1056eacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105711be8>, '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.zvnUntTPyt/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.oDnYwCYmak/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 17:03:08 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 17:03:08 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 17:03:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zvnUntTPyt/Render', 17:03:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:03:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:03:08 INFO - 'HOME': '/Users/cltbld', 17:03:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:03:08 INFO - 'LOGNAME': 'cltbld', 17:03:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:08 INFO - 'MOZ_NO_REMOTE': '1', 17:03:08 INFO - 'NO_EM_RESTART': '1', 17:03:08 INFO - 'PAGER': '/bin/cat', 17:03:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:03:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:08 INFO - 'PWD': '/builds/slave/test', 17:03:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:03:08 INFO - 'SHELL': '/bin/bash', 17:03:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oDnYwCYmak/Listeners', 17:03:08 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 17:03:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:03:08 INFO - 'USER': 'cltbld', 17:03:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:03:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:03:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:08 INFO - 'XPC_FLAGS': '0x0', 17:03:08 INFO - 'XPC_SERVICE_NAME': '0', 17:03:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:03:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:09 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 17:03:09 INFO - Cleaning up... 17:03:09 INFO - Return code: 0 17:03:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:03: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')]} 17:03:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03: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')]} 17:03:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03: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', '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 0x1059cac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00adb0280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105720d30>, '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 0x1056eacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105711be8>, '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.zvnUntTPyt/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.oDnYwCYmak/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 17:03: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', 'psutil>=3.1.1'] in /builds/slave/test/build 17:03: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 psutil>=3.1.1 17:03:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zvnUntTPyt/Render', 17:03:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:03:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:03:09 INFO - 'HOME': '/Users/cltbld', 17:03:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:03:09 INFO - 'LOGNAME': 'cltbld', 17:03:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:09 INFO - 'MOZ_NO_REMOTE': '1', 17:03:09 INFO - 'NO_EM_RESTART': '1', 17:03:09 INFO - 'PAGER': '/bin/cat', 17:03:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:03:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:09 INFO - 'PWD': '/builds/slave/test', 17:03:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:03:09 INFO - 'SHELL': '/bin/bash', 17:03:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oDnYwCYmak/Listeners', 17:03:09 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 17:03:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:03:09 INFO - 'USER': 'cltbld', 17:03:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:03:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:03:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:09 INFO - 'XPC_FLAGS': '0x0', 17:03:09 INFO - 'XPC_SERVICE_NAME': '0', 17:03:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:03:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:09 INFO - Downloading/unpacking psutil>=3.1.1 17:03: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 17:03: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 17:03: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 17:03: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 17:03:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:03:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:03:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:03:11 INFO - Installing collected packages: psutil 17:03:11 INFO - Running setup.py install for psutil 17:03:11 INFO - building 'psutil._psutil_osx' extension 17:03:11 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 17:03:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 17:03:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 17:03:12 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 17:03:12 INFO - building 'psutil._psutil_posix' extension 17:03:12 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 17:03:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:03:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:03:12 INFO - ^ 17:03:12 INFO - 1 warning generated. 17:03:12 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 17:03:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:03:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:03:12 INFO - Successfully installed psutil 17:03:12 INFO - Cleaning up... 17:03:12 INFO - Return code: 0 17:03:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:03: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')]} 17:03:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03: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')]} 17:03:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1059cac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00adb0280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105720d30>, '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 0x1056eacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105711be8>, '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.zvnUntTPyt/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.oDnYwCYmak/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 17:03:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 17:03:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 17:03:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zvnUntTPyt/Render', 17:03:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:03:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:03:12 INFO - 'HOME': '/Users/cltbld', 17:03:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:03:12 INFO - 'LOGNAME': 'cltbld', 17:03:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:12 INFO - 'MOZ_NO_REMOTE': '1', 17:03:12 INFO - 'NO_EM_RESTART': '1', 17:03:12 INFO - 'PAGER': '/bin/cat', 17:03:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:03:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:12 INFO - 'PWD': '/builds/slave/test', 17:03:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:03:12 INFO - 'SHELL': '/bin/bash', 17:03:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oDnYwCYmak/Listeners', 17:03:12 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 17:03:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:03:12 INFO - 'USER': 'cltbld', 17:03:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:03:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:03:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:12 INFO - 'XPC_FLAGS': '0x0', 17:03:12 INFO - 'XPC_SERVICE_NAME': '0', 17:03:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:03:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:03:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:03:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:03:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:03:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:03:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:03:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:03:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:03:14 INFO - Installing collected packages: mozsystemmonitor 17:03:14 INFO - Running setup.py install for mozsystemmonitor 17:03:14 INFO - Successfully installed mozsystemmonitor 17:03:14 INFO - Cleaning up... 17:03:14 INFO - Return code: 0 17:03:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:03:14 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')]} 17:03:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:14 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')]} 17:03:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:14 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 0x1059cac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00adb0280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105720d30>, '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 0x1056eacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105711be8>, '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.zvnUntTPyt/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.oDnYwCYmak/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 17:03:14 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 17:03:14 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 17:03:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zvnUntTPyt/Render', 17:03:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:03:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:03:14 INFO - 'HOME': '/Users/cltbld', 17:03:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:03:14 INFO - 'LOGNAME': 'cltbld', 17:03:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:14 INFO - 'MOZ_NO_REMOTE': '1', 17:03:14 INFO - 'NO_EM_RESTART': '1', 17:03:14 INFO - 'PAGER': '/bin/cat', 17:03:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:03:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:14 INFO - 'PWD': '/builds/slave/test', 17:03:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:03:14 INFO - 'SHELL': '/bin/bash', 17:03:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oDnYwCYmak/Listeners', 17:03:14 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 17:03:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:03:14 INFO - 'USER': 'cltbld', 17:03:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:03:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:03:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:14 INFO - 'XPC_FLAGS': '0x0', 17:03:14 INFO - 'XPC_SERVICE_NAME': '0', 17:03:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:03:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:14 INFO - Downloading/unpacking jsonschema==2.5.1 17:03:14 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 17:03:14 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 17:03:14 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 17:03:14 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 17:03:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:03:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:03:16 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 17:03:16 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 17:03:16 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 17:03:16 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 17:03:16 INFO - Downloading functools32-3.2.3-2.tar.gz 17:03:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:03:16 INFO - warning: no files found matching '*.txt' 17:03:16 INFO - no previously-included directories found matching 'build' 17:03:16 INFO - no previously-included directories found matching 'dist' 17:03:16 INFO - no previously-included directories found matching '.git*' 17:03:16 INFO - Installing collected packages: jsonschema, functools32 17:03:16 INFO - Running setup.py install for functools32 17:03:16 INFO - warning: no files found matching '*.txt' 17:03:16 INFO - no previously-included directories found matching 'build' 17:03:16 INFO - no previously-included directories found matching 'dist' 17:03:16 INFO - no previously-included directories found matching '.git*' 17:03:16 INFO - Successfully installed jsonschema functools32 17:03:16 INFO - Cleaning up... 17:03:16 INFO - Return code: 0 17:03:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:03:16 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')]} 17:03:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:16 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')]} 17:03:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:16 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 0x1059cac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00adb0280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105720d30>, '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 0x1056eacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105711be8>, '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.zvnUntTPyt/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.oDnYwCYmak/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 17:03:16 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 17:03:16 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 17:03:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zvnUntTPyt/Render', 17:03:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:03:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:03:16 INFO - 'HOME': '/Users/cltbld', 17:03:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:03:16 INFO - 'LOGNAME': 'cltbld', 17:03:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:16 INFO - 'MOZ_NO_REMOTE': '1', 17:03:16 INFO - 'NO_EM_RESTART': '1', 17:03:16 INFO - 'PAGER': '/bin/cat', 17:03:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:03:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:16 INFO - 'PWD': '/builds/slave/test', 17:03:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:03:16 INFO - 'SHELL': '/bin/bash', 17:03:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oDnYwCYmak/Listeners', 17:03:16 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 17:03:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:03:16 INFO - 'USER': 'cltbld', 17:03:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:03:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:03:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:16 INFO - 'XPC_FLAGS': '0x0', 17:03:16 INFO - 'XPC_SERVICE_NAME': '0', 17:03:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:03:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:03:17 INFO - Cleaning up... 17:03:17 INFO - Return code: 0 17:03:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:03: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')]} 17:03:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03: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')]} 17:03:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1059cac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00adb0280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105720d30>, '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 0x1056eacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105711be8>, '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.zvnUntTPyt/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.oDnYwCYmak/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 17:03:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:03:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:03:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zvnUntTPyt/Render', 17:03:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:03:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:03:17 INFO - 'HOME': '/Users/cltbld', 17:03:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:03:17 INFO - 'LOGNAME': 'cltbld', 17:03:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:17 INFO - 'MOZ_NO_REMOTE': '1', 17:03:17 INFO - 'NO_EM_RESTART': '1', 17:03:17 INFO - 'PAGER': '/bin/cat', 17:03:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:03:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:17 INFO - 'PWD': '/builds/slave/test', 17:03:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:03:17 INFO - 'SHELL': '/bin/bash', 17:03:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oDnYwCYmak/Listeners', 17:03:17 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 17:03:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:03:17 INFO - 'USER': 'cltbld', 17:03:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:03:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:03:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:17 INFO - 'XPC_FLAGS': '0x0', 17:03:17 INFO - 'XPC_SERVICE_NAME': '0', 17:03:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:03:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:17 INFO - Downloading/unpacking blobuploader==1.2.4 17:03:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:03:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:03:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:03:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:03:18 INFO - Downloading blobuploader-1.2.4.tar.gz 17:03:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:03:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:03:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:03:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:03:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:03:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:03:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:03:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:03: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 17:03: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 17:03: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 17:03: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 17:03:19 INFO - Downloading docopt-0.6.1.tar.gz 17:03:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:03:19 INFO - Installing collected packages: blobuploader, requests, docopt 17:03:19 INFO - Running setup.py install for blobuploader 17:03:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:03:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:03:19 INFO - Running setup.py install for requests 17:03:20 INFO - Running setup.py install for docopt 17:03:20 INFO - Successfully installed blobuploader requests docopt 17:03:20 INFO - Cleaning up... 17:03:20 INFO - Return code: 0 17:03:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:03:20 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')]} 17:03:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:20 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')]} 17:03:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:20 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 0x1059cac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00adb0280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105720d30>, '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 0x1056eacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105711be8>, '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.zvnUntTPyt/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.oDnYwCYmak/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 17:03:20 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 17:03:20 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 17:03:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zvnUntTPyt/Render', 17:03:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:03:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:03:20 INFO - 'HOME': '/Users/cltbld', 17:03:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:03:20 INFO - 'LOGNAME': 'cltbld', 17:03:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:20 INFO - 'MOZ_NO_REMOTE': '1', 17:03:20 INFO - 'NO_EM_RESTART': '1', 17:03:20 INFO - 'PAGER': '/bin/cat', 17:03:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:03:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:20 INFO - 'PWD': '/builds/slave/test', 17:03:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:03:20 INFO - 'SHELL': '/bin/bash', 17:03:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oDnYwCYmak/Listeners', 17:03:20 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 17:03:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:03:20 INFO - 'USER': 'cltbld', 17:03:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:03:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:03:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:20 INFO - 'XPC_FLAGS': '0x0', 17:03:20 INFO - 'XPC_SERVICE_NAME': '0', 17:03:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:03:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:03:20 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-3fa_u0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:03:20 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-vTLlfH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:03:20 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-lK6gwl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:03:21 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-CSIjds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:03:21 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-BGWmll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:03:21 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-zvFQPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:03:21 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-9xmXBC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:03:21 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-UmIdYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:03:21 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-NYKZrt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:03:21 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-5yx67W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:03:21 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-InfKM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:03:21 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-cErY0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:03:22 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-LUTqfe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:03:22 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-DSJgyv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:03:22 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-BxKuKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:03:22 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-jW5MfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:03:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:03:22 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-cWzoxI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:03:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:03:22 INFO - Running setup.py install for manifestparser 17:03:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:03:22 INFO - Running setup.py install for mozcrash 17:03:22 INFO - Running setup.py install for mozdebug 17:03:22 INFO - Running setup.py install for mozdevice 17:03:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:03:23 INFO - Running setup.py install for mozfile 17:03:23 INFO - Running setup.py install for mozhttpd 17:03:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:03:23 INFO - Running setup.py install for mozinfo 17:03:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:03:23 INFO - Running setup.py install for mozInstall 17:03:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:03:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:03:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:03:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:03:23 INFO - Running setup.py install for mozleak 17:03:23 INFO - Running setup.py install for mozlog 17:03:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:03:24 INFO - Running setup.py install for moznetwork 17:03:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:03:24 INFO - Running setup.py install for mozprocess 17:03:24 INFO - Running setup.py install for mozprofile 17:03:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:03:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:03:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:03:24 INFO - Running setup.py install for mozrunner 17:03:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:03:24 INFO - Running setup.py install for mozscreenshot 17:03:24 INFO - Running setup.py install for moztest 17:03:25 INFO - Running setup.py install for mozversion 17:03:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:03:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:03:25 INFO - Cleaning up... 17:03:25 INFO - Return code: 0 17:03:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:03:25 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')]} 17:03:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:25 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')]} 17:03:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:25 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 0x1059cac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00adb0280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105720d30>, '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 0x1056eacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105711be8>, '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.zvnUntTPyt/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.oDnYwCYmak/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 17:03:25 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 17:03:25 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 17:03:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zvnUntTPyt/Render', 17:03:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:03:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:03:25 INFO - 'HOME': '/Users/cltbld', 17:03:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:03:25 INFO - 'LOGNAME': 'cltbld', 17:03:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:25 INFO - 'MOZ_NO_REMOTE': '1', 17:03:25 INFO - 'NO_EM_RESTART': '1', 17:03:25 INFO - 'PAGER': '/bin/cat', 17:03:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:03:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:25 INFO - 'PWD': '/builds/slave/test', 17:03:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:03:25 INFO - 'SHELL': '/bin/bash', 17:03:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oDnYwCYmak/Listeners', 17:03:25 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 17:03:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:03:25 INFO - 'USER': 'cltbld', 17:03:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:03:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:03:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:25 INFO - 'XPC_FLAGS': '0x0', 17:03:25 INFO - 'XPC_SERVICE_NAME': '0', 17:03:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:03:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:03:25 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-yVUMyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:03:25 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)) 17:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:03:25 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-AFt7Nc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:03:25 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)) 17:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:03:25 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-_toYYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:03:25 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)) 17:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:03:25 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-1c_pUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:03:25 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)) 17:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:03:25 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-lO35Ap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:03:25 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)) 17:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:03:25 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-fI8fmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:03:26 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)) 17:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:03:26 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-dFLFHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:03:26 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)) 17:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:03:26 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-EwxAg_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:03:26 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)) 17:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:03:26 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-GXDFYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:03:26 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)) 17:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:03:26 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-VeLvil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:03:26 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)) 17:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:03:26 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-RJivXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:03:26 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)) 17:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:03:26 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-vrR3hy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:03:26 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)) 17:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:03:26 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-BMESZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:03:26 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)) 17:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:03:26 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-KWq4YP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:03:26 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)) 17:03:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:03:26 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-Bz4m6v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:03:27 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)) 17:03:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:03:27 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-ghF10u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:03:27 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)) 17:03:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:03:27 INFO - Running setup.py (path:/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/pip-05sffa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:03:27 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)) 17:03:27 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)) 17:03:27 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)) 17:03:27 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)) 17:03:27 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)) 17:03:27 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)) 17:03:27 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)) 17:03:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:03:27 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 17:03:27 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 17:03:27 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 17:03:27 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 17:03:28 INFO - Downloading blessings-1.6.tar.gz 17:03:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:03:28 INFO - Installing collected packages: blessings 17:03:28 INFO - Running setup.py install for blessings 17:03:28 INFO - Successfully installed blessings 17:03:28 INFO - Cleaning up... 17:03:28 INFO - Return code: 0 17:03:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:03:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:03:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:03:29 INFO - Reading from file tmpfile_stdout 17:03:29 INFO - Current package versions: 17:03:29 INFO - blessings == 1.6 17:03:29 INFO - blobuploader == 1.2.4 17:03:29 INFO - docopt == 0.6.1 17:03:29 INFO - functools32 == 3.2.3-2 17:03:29 INFO - jsonschema == 2.5.1 17:03:29 INFO - manifestparser == 1.1 17:03:29 INFO - mozInstall == 1.13 17:03:29 INFO - mozcrash == 1.0 17:03:29 INFO - mozdebug == 0.1 17:03:29 INFO - mozdevice == 0.51 17:03:29 INFO - mozfile == 1.2 17:03:29 INFO - mozhttpd == 0.7 17:03:29 INFO - mozinfo == 0.10 17:03:29 INFO - mozleak == 0.1 17:03:29 INFO - mozlog == 3.4 17:03:29 INFO - moznetwork == 0.27 17:03:29 INFO - mozprocess == 0.25 17:03:29 INFO - mozprofile == 0.28 17:03:29 INFO - mozrunner == 6.13 17:03:29 INFO - mozscreenshot == 0.1 17:03:29 INFO - mozsystemmonitor == 0.3 17:03:29 INFO - moztest == 0.8 17:03:29 INFO - mozversion == 1.4 17:03:29 INFO - psutil == 3.1.1 17:03:29 INFO - requests == 1.2.3 17:03:29 INFO - wsgiref == 0.1.2 17:03:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:03:29 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')]} 17:03:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:29 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')]} 17:03:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:29 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 0x1059cac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00adb0280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105720d30>, '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 0x1056eacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105711be8>, '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.zvnUntTPyt/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.oDnYwCYmak/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 17:03:29 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 17:03:29 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 17:03:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zvnUntTPyt/Render', 17:03:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:03:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:03:29 INFO - 'HOME': '/Users/cltbld', 17:03:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:03:29 INFO - 'LOGNAME': 'cltbld', 17:03:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:29 INFO - 'MOZ_NO_REMOTE': '1', 17:03:29 INFO - 'NO_EM_RESTART': '1', 17:03:29 INFO - 'PAGER': '/bin/cat', 17:03:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:03:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:29 INFO - 'PWD': '/builds/slave/test', 17:03:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:03:29 INFO - 'SHELL': '/bin/bash', 17:03:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oDnYwCYmak/Listeners', 17:03:29 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 17:03:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:03:29 INFO - 'USER': 'cltbld', 17:03:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:03:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:03:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:29 INFO - 'XPC_FLAGS': '0x0', 17:03:29 INFO - 'XPC_SERVICE_NAME': '0', 17:03:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:03:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:29 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)) 17:03:29 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)) 17:03:29 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)) 17:03:29 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)) 17:03:29 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)) 17:03:29 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)) 17:03:29 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)) 17:03:29 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)) 17:03:29 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)) 17:03:29 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:03:29 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 17:03:29 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 17:03:29 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 17:03:29 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 17:03:30 INFO - Downloading marionette_driver-1.4.0.tar.gz 17:03:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 17:03:31 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:03:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:03:31 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)) 17:03:31 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)) 17:03:31 INFO - Installing collected packages: marionette-driver 17:03:31 INFO - Running setup.py install for marionette-driver 17:03:31 INFO - Successfully installed marionette-driver 17:03:31 INFO - Cleaning up... 17:03:31 INFO - Return code: 0 17:03:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:03:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:03:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:03:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1059cac10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059ce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb00adb0280>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105720d30>, '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 0x1056eacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105711be8>, '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.zvnUntTPyt/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.oDnYwCYmak/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 17:03:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 17:03:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 17:03:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zvnUntTPyt/Render', 17:03:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:03:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:03:31 INFO - 'HOME': '/Users/cltbld', 17:03:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:03:31 INFO - 'LOGNAME': 'cltbld', 17:03:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:31 INFO - 'MOZ_NO_REMOTE': '1', 17:03:31 INFO - 'NO_EM_RESTART': '1', 17:03:31 INFO - 'PAGER': '/bin/cat', 17:03:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:03:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:31 INFO - 'PWD': '/builds/slave/test', 17:03:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:03:31 INFO - 'SHELL': '/bin/bash', 17:03:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oDnYwCYmak/Listeners', 17:03:31 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 17:03:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:03:31 INFO - 'USER': 'cltbld', 17:03:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:03:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:03:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:31 INFO - 'XPC_FLAGS': '0x0', 17:03:31 INFO - 'XPC_SERVICE_NAME': '0', 17:03:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:03:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:03:31 INFO - Cleaning up... 17:03:31 INFO - Return code: 0 17:03:31 INFO - Running post-action listener: _resource_record_post_action 17:03:31 INFO - Running post-action listener: _start_resource_monitoring 17:03:31 INFO - Starting resource monitoring. 17:03:31 INFO - [mozharness: 2017-06-14 00:03:31.545255Z] Finished create-virtualenv step (success) 17:03:31 INFO - [mozharness: 2017-06-14 00:03:31.546251Z] Running install step. 17:03:31 INFO - Running pre-action listener: _resource_record_pre_action 17:03:31 INFO - Running main action method: install 17:03:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:03:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:03:31 INFO - Reading from file tmpfile_stdout 17:03:31 INFO - Detecting whether we're running mozinstall >=1.0... 17:03:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:03:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:03:31 INFO - Reading from file tmpfile_stdout 17:03:31 INFO - Output received: 17:03:31 INFO - Usage: mozinstall [options] installer 17:03:31 INFO - Options: 17:03:31 INFO - -h, --help show this help message and exit 17:03:31 INFO - -d DEST, --destination=DEST 17:03:31 INFO - Directory to install application into. [default: 17:03:31 INFO - "/builds/slave/test"] 17:03:31 INFO - --app=APP Application being installed. [default: firefox] 17:03:31 INFO - mkdir: /builds/slave/test/build/application 17:03:31 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'] 17:03:31 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 17:03:53 INFO - Reading from file tmpfile_stdout 17:03:53 INFO - Output received: 17:03:53 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 17:03:53 INFO - Running post-action listener: _resource_record_post_action 17:03:53 INFO - [mozharness: 2017-06-14 00:03:53.040337Z] Finished install step (success) 17:03:53 INFO - [mozharness: 2017-06-14 00:03:53.040452Z] Running setup-mitmproxy step. 17:03:53 INFO - Running pre-action listener: _resource_record_pre_action 17:03:53 INFO - Running main action method: setup_mitmproxy 17:03:53 INFO - Skipping: mitmproxy is not required 17:03:53 INFO - Running post-action listener: _resource_record_post_action 17:03:53 INFO - [mozharness: 2017-06-14 00:03:53.041182Z] Finished setup-mitmproxy step (success) 17:03:53 INFO - [mozharness: 2017-06-14 00:03:53.041318Z] Running run-tests step. 17:03:53 INFO - Running pre-action listener: _resource_record_pre_action 17:03:53 INFO - Running main action method: run_tests 17:03:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:03:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:03:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:03:53 INFO - 2017-06-13 17:03:53.055 screenresolution[6760:125576] starting screenresolution argv=screenresolution get 17:03:53 INFO - 2017-06-13 17:03:53.058 screenresolution[6760:125576] Display 0: 1600x1200x32@60 17:03:53 INFO - 2017-06-13 17:03:53.064 screenresolution[6761:125579] starting screenresolution argv=screenresolution list 17:03:53 INFO - Available Modes on Display 0 17:03:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:03:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:03:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:03:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:03:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:03:53 INFO - Intel Iris: 17:03:53 INFO - Chipset Model: Intel Iris 17:03:53 INFO - Type: GPU 17:03:53 INFO - Bus: Built-In 17:03:53 INFO - VRAM (Dynamic, Max): 1536 MB 17:03:53 INFO - Vendor: Intel (0x8086) 17:03:53 INFO - Device ID: 0x0a2e 17:03:53 INFO - Revision ID: 0x0009 17:03:53 INFO - Displays: 17:03:53 INFO - Display: 17:03:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:03:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:03:53 INFO - Display Serial Number: 1600x1200 60 17:03:53 INFO - Main Display: Yes 17:03:53 INFO - Mirror: Off 17:03:53 INFO - Online: Yes 17:03:53 INFO - Rotation: Supported 17:03:53 INFO - Return code: 0 17:03:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:03:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:03:53 INFO - Python 2.7.3 17:03:53 INFO - Return code: 0 17:03:53 INFO - grabbing minidump binary from tooltool 17:03:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:03:53 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 0x105720d30>, '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 0x1056eacd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105711be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:03:53 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 17:03:53 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 17:03:53 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 17:03:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:03:53 INFO - Return code: 0 17:03:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:03:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:03:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:03:53 INFO - ENV: RUST_BACKTRACE is now 1 17:03:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:03:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:03:53 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/HHmfoFndSsejYCGeQdQXrA/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0304', '--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 17:03:53 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/HHmfoFndSsejYCGeQdQXrA/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0304 --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 17:03:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zvnUntTPyt/Render', 17:03:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:03:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:03:53 INFO - 'HOME': '/Users/cltbld', 17:03:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:03:53 INFO - 'LOGNAME': 'cltbld', 17:03:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:03:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:03:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:53 INFO - 'MOZ_NO_REMOTE': '1', 17:03:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:03:53 INFO - 'NO_EM_RESTART': '1', 17:03:53 INFO - 'PAGER': '/bin/cat', 17:03:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:03:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:53 INFO - 'PWD': '/builds/slave/test', 17:03:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:03:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:03:53 INFO - 'RUST_BACKTRACE': '1', 17:03:53 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:03:53 INFO - 'SHELL': '/bin/bash', 17:03:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.oDnYwCYmak/Listeners', 17:03:53 INFO - 'TMPDIR': '/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/', 17:03:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:03:53 INFO - 'USER': 'cltbld', 17:03:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:03:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:03:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:53 INFO - 'XPC_FLAGS': '0x0', 17:03:53 INFO - 'XPC_SERVICE_NAME': '0', 17:03:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:03:53 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/HHmfoFndSsejYCGeQdQXrA/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0304', '--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 17:03:53 INFO - mozversion application_buildid: 20170613153934 17:03:53 INFO - mozversion application_changeset: d956c96a1926c1755d9ce46a110ba7893f4d478a 17:03:53 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:03:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:03:53 INFO - mozversion application_name: Firefox 17:03:53 INFO - mozversion application_remotingname: firefox-dev 17:03:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 17:03:53 INFO - mozversion application_vendor: Mozilla 17:03:53 INFO - mozversion application_version: 55.0 17:03:53 INFO - mozversion platform_buildid: 20170613153934 17:03:53 INFO - mozversion platform_changeset: d956c96a1926c1755d9ce46a110ba7893f4d478a 17:03:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 17:03:53 INFO - mozversion platform_version: 55.0 17:03:53 INFO - using testdate: 1497398633 17:03:53 INFO - actual date: 1497398633 17:03:53 INFO - starting webserver on 'localhost:57801' 17:03:53 INFO - SUITE-START | Running 2 tests 17:03:53 INFO - TEST-START | dromaeo_css 17:03:53 INFO - operating with platform_type : mac_ 17:03:53 INFO - Initialising browser for dromaeo_css test... 17:03:53 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:57801/getInfo.html -foreground -profile /var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/tmpEnUxSX/profile 17:03:53 INFO - TEST-INFO | started process 6771 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:57801/getInfo.html -foreground) 17:04:27 INFO - TEST-INFO | 6771: killed by SIGTERM 17:04:27 INFO - Browser initialized. 17:04:27 INFO - Running cycle 1/1 for dromaeo_css test... 17:04:27 INFO - TEST-INFO | started process 6779 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/tmpEnUxSX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:04:29 INFO - PID 6779 | 2017-06-13 17:04:29.074 plugin-container[6781:126211] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 17:04:29 INFO - PID 6779 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:04:29 INFO - PID 6779 | 2017-06-13 17:04:29.075 plugin-container[6781:126211] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b17, name = 'com.apple.CFPasteboardClient' 17:04:29 INFO - PID 6779 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:04:29 INFO - PID 6779 | 2017-06-13 17:04:29.075 plugin-container[6781:126211] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:04:29 INFO - PID 6779 | 2017-06-13 17:04:29.821 plugin-container[6782:126254] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943b, name = 'com.apple.tsm.portname' 17:04:29 INFO - PID 6779 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:04:29 INFO - PID 6779 | 2017-06-13 17:04:29.822 plugin-container[6782:126254] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x954f, name = 'com.apple.CFPasteboardClient' 17:04:29 INFO - PID 6779 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:04:29 INFO - PID 6779 | 2017-06-13 17:04:29.822 plugin-container[6782:126254] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:04:39 INFO - PID 6779 | RSS: Main: 215998464 17:04:39 INFO - PID 6779 | 17:07:42 INFO - PID 6779 | Cycle 1(1): loaded http://localhost:57801/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57801/tests/dromaeo/cssquery-ext.html) 17:07:43 INFO - PID 6779 | RSS: Main: 231260160 17:07:43 INFO - PID 6779 | 17:10:45 INFO - PID 6779 | Cycle 1(1): loaded http://localhost:57801/tests/dromaeo/cssquery-ext.html (next: http://localhost:57801/tests/dromaeo/cssquery-jquery.html) 17:10:46 INFO - PID 6779 | RSS: Main: 242778112 17:10:46 INFO - PID 6779 | 17:13:47 INFO - PID 6779 | Cycle 1(1): loaded http://localhost:57801/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57801/tests/dromaeo/cssquery-mootools.html) 17:13:48 INFO - PID 6779 | RSS: Main: 245592064 17:13:48 INFO - PID 6779 | 17:16:50 INFO - PID 6779 | Cycle 1(1): loaded http://localhost:57801/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57801/tests/dromaeo/cssquery-prototype.html) 17:16:50 INFO - PID 6779 | RSS: Main: 247291904 17:16:50 INFO - PID 6779 | 17:19:53 INFO - PID 6779 | Cycle 1(1): loaded http://localhost:57801/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57801/tests/dromaeo/cssquery-yui.html) 17:19:54 INFO - PID 6779 | RSS: Main: 249147392 17:19:54 INFO - PID 6779 | 17:22:55 INFO - PID 6779 | Cycle 1(1): loaded http://localhost:57801/tests/dromaeo/cssquery-yui.html (next: http://localhost:57801/tests/dromaeo/cssquery-dojo.html) 17:22:55 INFO - PID 6779 | RSS: Main: 247263232 17:22:55 INFO - PID 6779 | 17:22:55 INFO - PID 6779 | __start_tp_report 17:22:55 INFO - PID 6779 | _x_x_mozilla_page_load 17:22:55 INFO - PID 6779 | _x_x_mozilla_page_load_details 17:22:55 INFO - PID 6779 | |i|pagename|runs| 17:22:55 INFO - PID 6779 | |0;dojo.html;3038.164504761191;3356.882509112181;3499.3351263259965;3621.963780362197;3656.341858465477;6234.314225435204;6291.33940936202;6292.8741425171465;6302.495800335973;6311.62130272184;6317.936820631792;6330.398612131851;6334.366563343663;6342.873142537146;6349.968250158754;5551.583631227654;5556.972215138919;5564.8052318168875;5611.382747897728;5672.659640421567;5504.146857237135;5538.953575533461;5566.304211973504;5621.690807005613;5635.013872572304;5379.8386048418415;5489.917651235235;5519.448055194489;5535.612507124503;5553.555715542747;4705.988212534322;5486.91769623456;5571.1086226203615;5571.275734154575;5591.552675785968;5405.8107966221;5535.944640553583;5537.363203231599;5546.195801608744;5556.6943818089985;5197.7401129943355;6232.626042437469;6255.655938923357;6332.968335158341;6348.841278968017;6614.206295244553;6663.766768163091;6693.799186024427;6875.106236189264;6896.20693620235;6908.620025898574;6912.654367281616;6974.163100427931;6974.337437943387;6979.89530157048;6471.546991710535;6530.216374035099;6550.475961923034;6573.112629795012;6579.243387010555;7145.6784444700115;7151.427885769126;7172.856542869114;7190.3888169505135;7202.351788339075;6559.901601475982;6666;6666.033425153422;6667.766628167991;6668.866622667617;6918.100646915868;6940.861182776316;6996.720131194797;7001.36987671112;7025.367716905503;6785.084013658192;6842.178938527309;6895.275996020346;6898.379145876896;6918.308169183041;6891.724331026703;6894.724211031503;6897.55165914214;6900.723971041202;6918.5502942308585;5512.200730893998;5514.972425137928;5517.034518959246;5527.613067085265;5531.557475401958;4790.952090479051;4794.113089078532;4819.662623616383;4821.614270858323;4846.418429788378;6228;6358.046293056079;6361.61830290184;6384.936150638434;6463.192100987377;7007.299270072952;7021.297870212938;7039.92960070403;7053.188883278386;7065.646717664096;6424.357564243538;6467.902981455282;6469.838254043687;6477.870442591122;6483.675816209171;6099.817005489843;6184.783532576338;6184.979478386016;6199.535034872402;6216.2540495140875;6868.656567171622;6948.722051117898;6963.09479767627;7004.4396448284015;7148.678309476086;6966.825829354307;6977.267386924503;7033.366996970297;7064.116985376828;7087.149542054986;7113.786586402416;7164.605946672983;7167.1757747859665;7169.892451613126;7175.892361614476;6867.313268673092;6900.068490753684;6915.757948471779;6923.238443771282;6952.339527744856;7013.509054366145;7035.190953040261;7040.0847889774;7049.894251586333;7085.6457177141965;6554.31179726141;6618.172728408949;6636.900446493403;6646.169228846395;6653.301403352578;6680.699368528525;6748.797536073926;6758.459323254227;6767.424768894502;6785.287544807724;6571.605703657796;6645.966770166118;6652.800415987528;6660.900086498609;6675.0654908313845;6789.015592739124;6789.2192397872905;6795.354441328066;6814.557053791487;6816.556923799937;6367.395097465734;6477.190351206099;6479.157709497735;6489.967550162219;6497.285298617243 17:22:55 INFO - PID 6779 | |1;ext.html;822.2476434062833;830.5182993863559;835.4903508859595;855.9486430814152;863.2403484933258;32167.51748723771;39228.41157382638;40143.996400090015;40295.79852100739;40393;39238.82283531492;39385.40921886174;39438;39596.406053909144;39708.007299817444;7539.886901696465;7671.194524574923;7675.654595543214;7691.730789422371;7699.114601820778;7366.226546212651;7490.988716523254;7573.24267573241;7581.279778421042;8427.915720842775;7541.396688264926;7936.690446076419;8434;8479.533625650587;8555.914440855606;8454.196851299117;8472.406931514797;8516.701915432992;8522.957385213096;8524.190201930838;8320.958395208047;8350.832983340299;8366.748997530083;8425.831483370359;8428;37384.62615373866;37421.06447338838;37496.62503374959;37528.437073443645;37537.62462375368;3551.520544726455;3556.697680697144;3572.1962558424407;3592.2276710507103;3599.4420864766003;30752.846235768677;30990;31053.378932421227;31083.533746993588;31099.533506997413;2950.9409811803644;2956.541736030919;2959.3193565479846;2972.1826497713087;3033.6511301200426;220757;260543;264259.9999999981;323019;323027;48523;49081.50918490769;49660.75848103826;49729.259475918894;49804;41485.37771933423;51218.48781512137;51303;51415;51460.22809657858;15442.305096270695;15470.917035807386;15546.14496202697;15628.296726647328;15628.921855390878;12555.121141520005;12633.620991370273;12683.429245683967;12700.42848071839;12763.680907977188;13931.442742290195;13938.11835402228;13943.721125577431;13980.510682126076;14067;6069.484093851984;6153.446189842846;6178.969105154535;6185.938140618537;6198.721057552421;894.1684233662756;959.9808003839884;996.1731762636994;998.3510718033336;1031.118393773328;18185.909070454563;22874;23181.652275215885;23347.649785253234;23520.64719029216;988.930774845754;1092.3391348234363;1155.399192419937;1155.485808815086;1155.653304008811;12031.217970832044;12412.820782025874;12585.119041667178;12601.810972835416;12720.809187862189;331151;331249;331260;332619;336042;53734;53953.46046539563;54002.45997540053;54016;54114;1334.2128144394853;1456.3300881594373;1525.8855585830886;1531.892767506264;1547.1490680125885;22142;22554.5489090214;22697.886510567343;22773.430664233525;24245.151419700225;37326.440103399014;37416;37531.81234093812;37567.6243237575;37631;284678;285909.5704521464;286822;286922;287530;32903.01290961275;33094.83452582722;33372.499412509314;33996.830015849766;34393;42574.78712606417;43208.70373888788;43959.340609891515;44585.66243012715;44757.55242447664;27202;28817.84728610832;30463;31091.533626995162;31161.532577011818;30022.84988575043;30065.3986920256;30257.940972065873;30986.0704178875;30991;35040.64959350374;36168.73409430657;36766.44850327301;36815.07962300964;36817;27771.722282777722;28906.566401504417;29341.706582934752;30917.8454107737;30993.8450307747;22040.33878983633;22164.667529987386;23903;23964.822644178024;24184 17:22:55 INFO - PID 6779 | |2;jquery.html;501.8745313671582;509.57959683261316;510.5532658923442;530.2205757536425;531.6305167908304;21159.259425920056;22387.55224895501;22528.873556322178;22694.88652556737;23354.18260360888;22530.986105625245;22697;23881.880590597066;24688.012479500798;24690.382740431454;6257.311695713468;6464.838379040514;6471.0940468334475;6474.352564743535;6474.449671777905;5294.470552944694;5913.8817223655715;6250.249970003606;6300.8739825203465;6359.014352775327;6181.81454556364;6196.287426945892;6200.379962003809;6215.56491045627;6221.346758590351;6210.751569937197;6223.035429508411;6229.4704950079295;6235.812925612239;6236.314005459419;5609.607327487078;5645.661260324394;5646.604737668365;5649.8587535311535;5652.293463317085;23634.409139771473;26832.463350733073;28900;29404.70595294041;30442.391152177053;2260.2541161416693;2305.319930620471;2322.6167682332393;2323.0939933425975;2323.1520495019195;18699.906500467547;19109.9044504778;19228.80771192284;19272.32546860853;19280.32518861847;2151.601953638582;2175.369142948543;2195.8243340532717;2215.7562668106493;2239.1715065425733;166578;175742.121289394;176216;184163;187077;13546.729065418636;13857;14657.48698795537;14982.775258371134;15061.322240499203;20107.095180716904;20108.497287567927;20206.797932020785;20241.291554795513;20242.493937651678;10092.646757363456;10099.44453055089;10174.168649642987;10313.84529232062;10345.482725863827;9670;9728.416295022322;9776.364536305118;9908.603655853685;9944.45305508204;8907.198352148338;8989.730308090768;9064.138906803842;9078.727638170865;9082.22801061904;2069.865458745177;2599.272203782944;2599.2981894888276;2599.3371690218873;2706.174616741913;824.381713714714;926.2451102351562;947.1475671895349;1003.1022235099545;1009.8232809258351;20429.69355459669;20443.46673999456;21065;21628.243011494524;21954.560908782056;891.4740303221131;980.3382716666364;992.5434300221834;999.2455695949657;1015.3603229965074;9431.528423578793;10594.523246453928;10811.297265677706;10961.232713709964;10982.968150892111;530981;543361;544381;546645;560989;148947;156331;158112.20943895206;159265.2036739809;162869.99999999764;1010.7624708193568;1082.5399205337858;1085.6308854989184;1182.2788099259444;1259.168948494008;21792.564148717254;24076.638850416908;24105;24122;24136.03455861746;70392.64803675949;70583;72515;74913.6254318725;75602;69026;69963.9010829676;81338.18661813306;89042.55478722564;90756.5462172685;11681.474333655176;11805.350705711267;11868.940655296668;11991.64025079249;11993.64019079429;18097.276108955495;18193.636127277645;18442.907785460986;18659.720104198175;18776.436706898814;14737.263136843329;14954.25228738537;15015.699686006;15235.923820380827;15257.85566082526;13132.474701012236;13228.338583071001;13236.669083272995;13353.799693004807;13615.591532254048;15023;15054.870884683474;15231.390744370101;15271.847281527043;15381.615459613151;9874.50627468638;11722.062235021349;11842.230255033395;11871.881281187423;11901.583444579399;14644.12135271887;14736.926315368355;14764.409423622934;14819.629509261924;14838.258087095817 17:22:56 INFO - PID 6779 | |3;mootools.html;1287.1376177960765;1482.3699927530797;1482.8359737605974;1492.835788063312;1501.5420296809473;13641.590752277423;14118.152910825358;14192;14220.999999999987;14228.928855355722;13798.724025519483;14266.286685665727;14267.2866356682;14272.4291028359;14286.07140535867;2620.3580122869926;2681.986590067047;2840.218939791553;2848.985755071222;2849.871755770995;2326.581215381231;2782.6939036705944;2844.459552684983;2844.8150870193476;2847.900323488678;2389.928302150938;2395.940101497459;2744.4922689302443;2844.1041072062235;2844.914652560416;2798.0906205483298;2805;2817.7886658500693;2819.9013034543796;2823.181277429543;2739.712335202452;2761.1578468567077;2767.0730305347734;2769.404578015716;2769.8615069246457;13638;13684.589462316146;13810.37853296604;13811.723765524634;13847.376868040963;7535.585542795144;7772.261635144652;7905.367570594339;7918.208179182093;7932.444728868982;4690.859274221744;4897.559219670246;4912.975435122838;5006.07387633289;5059.924101138486;1340.1557019077918;1379.655086228443;1399.839018512874;1400.544822932551;1400.544822932551;20879.26922557703;20934.057967391353;21069.051892664866;21094.578108437898;21116.155353785984;6593.604383736991;6761.18865736105;6815.2843951385385;6898.482613803984;6905.792826215222;14507.492237771627;14728.55814325572;14782.334794934253;14804.259787010606;14804.407823686932;6083.66539840313;7641;7703.268189521987;7703.306702396699;7715.537067775951;6711.496637752188;6918.273581273894;7053.823654408681;7137.4290056795335;7151.392131668867;5220.243064755589;5372.059889519397;5374.435684253175;5430.67415955044;5454.181872719045;1570.5916461719808;1571.7799508068879;1572.3553343129263;1574.6457047164527;1601.4154833485693;685.8182581615876;822.8847961285425;834.6911642692243;849.4605925237519;880.4541184465672;7742.71460247066;7861.331786798186;8172.346212303001;8432.030316513557;8613.095624959415;971.7424882406168;974.3423189347162;1012.7215015870695;1013.3109485549896;1033.8035773203067;4698.083873644675;4764.690295130806;4980.8754781130765;5023.547880690755;5243.764030618631;13800.93099534516;18375.448736537925;18957.81042189588;18983.955882426322;19070.713939290923;18427.72358414625;18899.90550047241;19006.619867602847;19022.904885475484;19039.52401189981;9421.90578094224;9435.528223588793;9476.099770521789;9478.668246611336;9601.47191904438;7199.640017998974;7945.523268603903;7953.244441778022;8223.424360294961;8666.003409607723;16615.418460354314;16695.499135025482;16871.987680739196;17383.304667813652;17472.650546989244;16620.25208865628;17101.973881567144;17490.47528574145;17566.297348105934;17888.731669024713;10793.730156746144;10893.56425742993;11008.779824403628;11344.489497972776;11346.546138154381;10765.569377225136;12944.029197810014;12974.610761677164;13094.14888032275;13122.934385328012;8953.104689530996;10013.649522266685;10132.240081994027;10221.131203847755;10251.48742562854;9786.706398807763;9853.113219810251;9928.801423971336;10115.59537618487;10186.898131018595;9441.811163776823;9444.150026497648;9519.57161927701;9523.476208808583;9535.570899309409;6549;6852.143482064742;6904.551204171713;7132.037174981519;7185.101862267216;7053.858922821412;7111.43108551325;7232.0959880015;7285.562866228043;7298.343149116605 17:22:56 INFO - PID 6779 | |4;prototype.html;429.95270520242786;442.9379886815846;460.098207513274;467.94150731158595;468.4308565093411;5096.3374761281075;6610.30591787862;6616.305287944769;6629.535932484722;6709.060731497594;6215.316315205324;6343.397377249154;6422.9357706422925;6437.710303036363;6481.935180648192;4822.324874517571;4858.805647774085;4858.927116093261;4863.562279394845;4883.145449546333;4772.331873537708;4790.928136077944;4792.75388399017;4855.5872750816225;4873.561379475864;3768.604296548864;4034.1326614777963;4316.633086187662;4771.117343291485;4777.761111944406;4032.3145065338963;4571.885702857438;4666.743329116897;4783.832565860213;4789.401324834395;4197.446944630507;4202.432671589327;4203.684723645739;4203.810828512725;4217.641500472463;5276.815311464119;5422.9728851355885;5577.764896360101;6172.271763906114;6204.875902481925;5713.428657134295;5724.427557244284;5730.36965933747;5741.856453588652;5747.252857128547;5520.91718624221;6279.372062793684;6579.144711187563;6827.726890924357;6957.5129740918155;4211.368294755781;4433.889152771174;4828.927566086511;4829.734364609945;4836.782344794493;7328.8167795805;7357.1539272983755;7367;7397.593132377771;7451.21762214965;3952.6837852972267;4401.6698747594055;4460.687751857404;4942.097292542397;4944.134776414185;4423.668224883147;5065.138926382524;5067.4258315853;5069.569086627678;5070.645054846125;4801.399825021872;5463.235147079412;5784.2769653793275;5784.913226301609;5788.913166302509;5620.4660557247;6469.838254043592;6956.478264130211;7016.228214896358;7028.332308430691;1424.444466658004;1463.1075044223016;1564.5071802382213;1677.8322167783124;1694.8644108471292;1124.966251012471;1133.4106264742288;1142.6743378137191;1145.1811954452605;1148.3569201247328;6177.69111544421;6322.609026014269;6417.780621681865;6420.133282006869;6438.742450301936;6520.380563846427;7197.568145911262;7272.8181795454475;7294.744683936036;7326.413886889036;5662.065759149694;6185;6470.352964703492;6520.347965203537;6578.671066446658;3195.984020079885;3622.6415094339623;3659.7255205859665;3763.5860055393887;3764.284785890672;6151.9384806151365;7378.926210737931;7420.703171873172;7432.814179645552;7436.330730234305;6740.325967403221;7349.566834467228;7386.519876208029;7407.296306850841;7409.88885166723;5878;7421.146568144733;7440.441966852508;7453.142888567776;7477.289657482531;5430.8099216527235;7194.100737407824;7229.385502232369;7231.963840180765;7288.854222915618;6345.619262844244;6346.270178929482;7048;7066.893996589953;7080.645967701697;6934.271901450476;7272.781816545512;7318.085239345082;7356.374708149867;7388.261173882784;4277.315629499265;4441.178381999341;4558.5897269245925;4833.637477189154;4865.95134048655;7071.752488662872;7178.953998110514;7181.281871812777;7256.492045556761;7263.636818159177;6765.898511522233;6894.586324820527;6907;6942.722291108299;6944.444444444332;5856.238688970577;6465.321141280097;6619.470442364695;6933.410660093747;6938.06336144614;5655.377908429987;6783.118194634666;6795.1845778506895;6818.556793808387;6824.829379265359;6244.781432649836;6926.168859736864;6947.478939079489;6948.6178260196175;6958.582485050913;5849.3858144894175;5980.581359304807;6390;6734.800969539429;6743.9325606743305 17:22:56 INFO - PID 6779 | |5;yui.html;718.3570704219724;808.9676412943483;811.1523457986403;845.1928408370007;847.0978407995483;14486.058406203589;14638.634034149138;14744.262786860667;14787.964842461019;14915.477958271462;17160.656786864285;17755.64488710225;18465.630687386278;18744.156512956917;18744.718829217574;835.8035861572525;965.2471072563412;982.9852552211722;985.3299756165804;985.9359141655806;855.4140413816524;968.9224862011022;971.9076687714692;975.2490582251662;981.3768257156686;883.8674198870157;918.9586468608895;1010.2524132142198;1021.4075836015094;1032.194887987371;846.8010017645879;995.4027583449872;1002.9699109026741;1007.0332480818423;1007.4610083605272;732.9486935914488;733.7945375294926;733.9889901651479;734.5262305812721;736.0946036375288;16983.915080424642;17159.571010724707;17214;17375.73936390955;17409.21658525357;17477.56306092345;17793.822061779345;18055.819441805546;18098.547536311566;18155.72766408505;15065.397384104623;15075.396984120622;15102;15228.086314821147;15357.923210383877;3140.262038420969;3532.0640030391965;3570.410882204433;3596.1549035976523;3597.7301702372165;135212;136828.31585842106;138394.30802846022;157153;165742.51386229217;12328.568500102454;13982.440702371994;14245.501407450896;14245.92877035629;14543.92728036353;25897.870510647703;27200.455990880073;27255.318617034733;27310.726892730818;27358.726412736018;7950.522968621901;8035.477693949874;8036.758897233092;8064.959675201704;8159.755207343789;3410.812405317731;3456.982715086459;3480.2517458746493;3494.545709057806;3498.755087143926;4676.69601475903;4764.95235047652;4781.450133234653;4801.303810947393;4888.9755551222015;868.2880038368477;905.1988989744124;1054.1198099586777;1061.851340812287;1063.5426766490484;550.3285991090863;645.9773907913201;649.9675016249168;660.0791895306118;675.9522739753363;8838.690645827364;9578.712638620853;10183.7454063649;11285.21003529745;12164.81752773709;632.9936700632935;644.4007073421669;653.9803805885831;666.976655817044;671.6373158494456;5262.578993680497;5598.328200615871;5659.179418984224;5852.795152169739;5936.614120082181;143371;145226.5477345213;145288.27355863154;146558.2672086654;148693;99986.00013999913;114381.42809286066;116279.8372016269;120919.18621220689;122409.38795305966;107484;108746;111231;111977.44011279891;114563.42718286521;11036.779264414827;11086.445677716243;11341.319520828776;11385.487653055467;11808.94095529517;43373.78313108414;45239;45249.86875328143;45582;45639.31541026821;55555.16667250075;56312.71843640756;57938;59940;60793.0881036776;15787.605309867346;17954.371596994042;18214.635707285513;18402.539936501693;18472.891626502453;19018;19046.904765476083;19123.617527649647;19183.32858349951;19227.134778934702;17239.310427582757;17741.64516709633;18117.45647630573;18119.637607247518;18585.81414185841;17707.911460442614;17847.107644617976;18203.908980455544;18286.177122029276;18422;17067.06131162798;17482.650346992734;17502.387416440364;17514.47456576305;17797.1991260391;11170.162237832032;11696;11780.469878855301;12081.516739330329;12572.748545028866;13037.739245215233;14649.85350146485;14766.409343626136;14773.335199915815;14806.407743690132 17:22:56 INFO - PID 6779 | __end_tp_report 17:22:56 INFO - PID 6779 | __start_cc_report 17:22:56 INFO - PID 6779 | _x_x_mozilla_cycle_collect,404 17:22:56 INFO - PID 6779 | __end_cc_report 17:22:56 INFO - PID 6779 | __startTimestamp1497399775988__endTimestamp 17:22:56 INFO - PID 6779 | 17:22:56 INFO - PID 6779 | ------- Summary: start ------- 17:22:56 INFO - PID 6779 | Number of tests: 6 17:22:56 INFO - PID 6779 | 17:22:56 INFO - PID 6779 | [#0] dojo.html Cycles:165 Average:6341.51 Median:6579.24 stddev:804.45 (12.2%) stddev-sans-first:764.04 17:22:56 INFO - PID 6779 | Values: 3038.2 3356.9 3499.3 3622.0 3656.3 6234.3 6291.3 6292.9 6302.5 6311.6 6317.9 6330.4 6334.4 6342.9 6350.0 5551.6 5557.0 5564.8 5611.4 5672.7 5504.1 5539.0 5566.3 5621.7 5635.0 5379.8 5489.9 5519.4 5535.6 5553.6 4706.0 5486.9 5571.1 5571.3 5591.6 5405.8 5535.9 5537.4 5546.2 5556.7 5197.7 6232.6 6255.7 6333.0 6348.8 6614.2 6663.8 6693.8 6875.1 6896.2 6908.6 6912.7 6974.2 6974.3 6979.9 6471.5 6530.2 6550.5 6573.1 6579.2 7145.7 7151.4 7172.9 7190.4 7202.4 6559.9 6666.0 6666.0 6667.8 6668.9 6918.1 6940.9 6996.7 7001.4 7025.4 6785.1 6842.2 6895.3 6898.4 6918.3 6891.7 6894.7 6897.6 6900.7 6918.6 5512.2 5515.0 5517.0 5527.6 5531.6 4791.0 4794.1 4819.7 4821.6 4846.4 6228.0 6358.0 6361.6 6384.9 6463.2 7007.3 7021.3 7039.9 7053.2 7065.6 6424.4 6467.9 6469.8 6477.9 6483.7 6099.8 6184.8 6185.0 6199.5 6216.3 6868.7 6948.7 6963.1 7004.4 7148.7 6966.8 6977.3 7033.4 7064.1 7087.1 7113.8 7164.6 7167.2 7169.9 7175.9 6867.3 6900.1 6915.8 6923.2 6952.3 7013.5 7035.2 7040.1 7049.9 7085.6 6554.3 6618.2 6636.9 6646.2 6653.3 6680.7 6748.8 6758.5 6767.4 6785.3 6571.6 6646.0 6652.8 6660.9 6675.1 6789.0 6789.2 6795.4 6814.6 6816.6 6367.4 6477.2 6479.2 6490.0 6497.3 17:22:56 INFO - PID 6779 | 17:22:56 INFO - PID 6779 | [#1] ext.html Cycles:180 Average:44937.80 Median:34716.82 stddev:78970.64 (227.5%) stddev-sans-first:79122.71 17:22:56 INFO - PID 6779 | Values: 822.2 830.5 835.5 855.9 863.2 32167.5 39228.4 40144.0 40295.8 40393.0 39238.8 39385.4 39438.0 39596.4 39708.0 7539.9 7671.2 7675.7 7691.7 7699.1 7366.2 7491.0 7573.2 7581.3 8427.9 7541.4 7936.7 8434.0 8479.5 8555.9 8454.2 8472.4 8516.7 8523.0 8524.2 8321.0 8350.8 8366.7 8425.8 8428.0 37384.6 37421.1 37496.6 37528.4 37537.6 3551.5 3556.7 3572.2 3592.2 3599.4 30752.8 30990.0 31053.4 31083.5 31099.5 2950.9 2956.5 2959.3 2972.2 3033.7 220757.0 260543.0 264260.0 323019.0 323027.0 48523.0 49081.5 49660.8 49729.3 49804.0 41485.4 51218.5 51303.0 51415.0 51460.2 15442.3 15470.9 15546.1 15628.3 15628.9 12555.1 12633.6 12683.4 12700.4 12763.7 13931.4 13938.1 13943.7 13980.5 14067.0 6069.5 6153.4 6179.0 6185.9 6198.7 894.2 960.0 996.2 998.4 1031.1 18185.9 22874.0 23181.7 23347.6 23520.6 988.9 1092.3 1155.4 1155.5 1155.7 12031.2 12412.8 12585.1 12601.8 12720.8 331151.0 331249.0 331260.0 332619.0 336042.0 53734.0 53953.5 54002.5 54016.0 54114.0 1334.2 1456.3 1525.9 1531.9 1547.1 22142.0 22554.5 22697.9 22773.4 24245.2 37326.4 37416.0 37531.8 37567.6 37631.0 284678.0 285909.6 286822.0 286922.0 287530.0 32903.0 33094.8 33372.5 33996.8 34393.0 42574.8 43208.7 43959.3 44585.7 44757.6 27202.0 28817.8 30463.0 31091.5 31161.5 30022.8 30065.4 30257.9 30986.1 30991.0 35040.6 36168.7 36766.4 36815.1 36817.0 27771.7 28906.6 29341.7 30917.8 30993.8 22040.3 22164.7 23903.0 23964.8 24184.0 17:22:56 INFO - PID 6779 | 17:22:56 INFO - PID 6779 | [#2] jquery.html Cycles:180 Average:38509.36 Median:22529.93 stddev:94268.73 (418.4%) stddev-sans-first:94489.98 17:22:56 INFO - PID 6779 | Values: 501.9 509.6 510.6 530.2 531.6 21159.3 22387.6 22528.9 22694.9 23354.2 22531.0 22697.0 23881.9 24688.0 24690.4 6257.3 6464.8 6471.1 6474.4 6474.4 5294.5 5913.9 6250.2 6300.9 6359.0 6181.8 6196.3 6200.4 6215.6 6221.3 6210.8 6223.0 6229.5 6235.8 6236.3 5609.6 5645.7 5646.6 5649.9 5652.3 23634.4 26832.5 28900.0 29404.7 30442.4 2260.3 2305.3 2322.6 2323.1 2323.2 18699.9 19109.9 19228.8 19272.3 19280.3 2151.6 2175.4 2195.8 2215.8 2239.2 166578.0 175742.1 176216.0 184163.0 187077.0 13546.7 13857.0 14657.5 14982.8 15061.3 20107.1 20108.5 20206.8 20241.3 20242.5 10092.6 10099.4 10174.2 10313.8 10345.5 9670.0 9728.4 9776.4 9908.6 9944.5 8907.2 8989.7 9064.1 9078.7 9082.2 2069.9 2599.3 2599.3 2599.3 2706.2 824.4 926.2 947.1 1003.1 1009.8 20429.7 20443.5 21065.0 21628.2 21954.6 891.5 980.3 992.5 999.2 1015.4 9431.5 10594.5 10811.3 10961.2 10983.0 530981.0 543361.0 544381.0 546645.0 560989.0 148947.0 156331.0 158112.2 159265.2 162870.0 1010.8 1082.5 1085.6 1182.3 1259.2 21792.6 24076.6 24105.0 24122.0 24136.0 70392.6 70583.0 72515.0 74913.6 75602.0 69026.0 69963.9 81338.2 89042.6 90756.5 11681.5 11805.4 11868.9 11991.6 11993.6 18097.3 18193.6 18442.9 18659.7 18776.4 14737.3 14954.3 15015.7 15235.9 15257.9 13132.5 13228.3 13236.7 13353.8 13615.6 15023.0 15054.9 15231.4 15271.8 15381.6 9874.5 11722.1 11842.2 11871.9 11901.6 14644.1 14736.9 14764.4 14819.6 14838.3 17:22:56 INFO - PID 6779 | 17:22:56 INFO - PID 6779 | [#3] mootools.html Cycles:180 Average:8493.46 Median:2790.39 stddev:5653.22 (202.6%) stddev-sans-first:5643.15 17:22:56 INFO - PID 6779 | Values: 1287.1 1482.4 1482.8 1492.8 1501.5 13641.6 14118.2 14192.0 14221.0 14228.9 13798.7 14266.3 14267.3 14272.4 14286.1 2620.4 2682.0 2840.2 2849.0 2849.9 2326.6 2782.7 2844.5 2844.8 2847.9 2389.9 2395.9 2744.5 2844.1 2844.9 2798.1 2805.0 2817.8 2819.9 2823.2 2739.7 2761.2 2767.1 2769.4 2769.9 13638.0 13684.6 13810.4 13811.7 13847.4 7535.6 7772.3 7905.4 7918.2 7932.4 4690.9 4897.6 4913.0 5006.1 5059.9 1340.2 1379.7 1399.8 1400.5 1400.5 20879.3 20934.1 21069.1 21094.6 21116.2 6593.6 6761.2 6815.3 6898.5 6905.8 14507.5 14728.6 14782.3 14804.3 14804.4 6083.7 7641.0 7703.3 7703.3 7715.5 6711.5 6918.3 7053.8 7137.4 7151.4 5220.2 5372.1 5374.4 5430.7 5454.2 1570.6 1571.8 1572.4 1574.6 1601.4 685.8 822.9 834.7 849.5 880.5 7742.7 7861.3 8172.3 8432.0 8613.1 971.7 974.3 1012.7 1013.3 1033.8 4698.1 4764.7 4980.9 5023.5 5243.8 13800.9 18375.4 18957.8 18984.0 19070.7 18427.7 18899.9 19006.6 19022.9 19039.5 9421.9 9435.5 9476.1 9478.7 9601.5 7199.6 7945.5 7953.2 8223.4 8666.0 16615.4 16695.5 16872.0 17383.3 17472.7 16620.3 17102.0 17490.5 17566.3 17888.7 10793.7 10893.6 11008.8 11344.5 11346.5 10765.6 12944.0 12974.6 13094.1 13122.9 8953.1 10013.6 10132.2 10221.1 10251.5 9786.7 9853.1 9928.8 10115.6 10186.9 9441.8 9444.2 9519.6 9523.5 9535.6 6549.0 6852.1 6904.6 7132.0 7185.1 7053.9 7111.4 7232.1 7285.6 7298.3 17:22:56 INFO - PID 6779 | 17:22:56 INFO - PID 6779 | [#4] prototype.html Cycles:180 Average:5582.32 Median:6181.35 stddev:1759.42 (28.5%) stddev-sans-first:1721.33 17:22:56 INFO - PID 6779 | Values: 430.0 442.9 460.1 467.9 468.4 5096.3 6610.3 6616.3 6629.5 6709.1 6215.3 6343.4 6422.9 6437.7 6481.9 4822.3 4858.8 4858.9 4863.6 4883.1 4772.3 4790.9 4792.8 4855.6 4873.6 3768.6 4034.1 4316.6 4771.1 4777.8 4032.3 4571.9 4666.7 4783.8 4789.4 4197.4 4202.4 4203.7 4203.8 4217.6 5276.8 5423.0 5577.8 6172.3 6204.9 5713.4 5724.4 5730.4 5741.9 5747.3 5520.9 6279.4 6579.1 6827.7 6957.5 4211.4 4433.9 4828.9 4829.7 4836.8 7328.8 7357.2 7367.0 7397.6 7451.2 3952.7 4401.7 4460.7 4942.1 4944.1 4423.7 5065.1 5067.4 5069.6 5070.6 4801.4 5463.2 5784.3 5784.9 5788.9 5620.5 6469.8 6956.5 7016.2 7028.3 1424.4 1463.1 1564.5 1677.8 1694.9 1125.0 1133.4 1142.7 1145.2 1148.4 6177.7 6322.6 6417.8 6420.1 6438.7 6520.4 7197.6 7272.8 7294.7 7326.4 5662.1 6185.0 6470.4 6520.3 6578.7 3196.0 3622.6 3659.7 3763.6 3764.3 6151.9 7378.9 7420.7 7432.8 7436.3 6740.3 7349.6 7386.5 7407.3 7409.9 5878.0 7421.1 7440.4 7453.1 7477.3 5430.8 7194.1 7229.4 7232.0 7288.9 6345.6 6346.3 7048.0 7066.9 7080.6 6934.3 7272.8 7318.1 7356.4 7388.3 4277.3 4441.2 4558.6 4833.6 4866.0 7071.8 7179.0 7181.3 7256.5 7263.6 6765.9 6894.6 6907.0 6942.7 6944.4 5856.2 6465.3 6619.5 6933.4 6938.1 5655.4 6783.1 6795.2 6818.6 6824.8 6244.8 6926.2 6947.5 6948.6 6958.6 5849.4 5980.6 6390.0 6734.8 6743.9 17:22:56 INFO - PID 6779 | 17:22:56 INFO - PID 6779 | [#5] yui.html Cycles:180 Average:25467.41 Median:18443.82 stddev:39270.58 (212.9%) stddev-sans-first:39336.77 17:22:56 INFO - PID 6779 | Values: 718.4 809.0 811.2 845.2 847.1 14486.1 14638.6 14744.3 14788.0 14915.5 17160.7 17755.6 18465.6 18744.2 18744.7 835.8 965.2 983.0 985.3 985.9 855.4 968.9 971.9 975.2 981.4 883.9 919.0 1010.3 1021.4 1032.2 846.8 995.4 1003.0 1007.0 1007.5 732.9 733.8 734.0 734.5 736.1 16983.9 17159.6 17214.0 17375.7 17409.2 17477.6 17793.8 18055.8 18098.5 18155.7 15065.4 15075.4 15102.0 15228.1 15357.9 3140.3 3532.1 3570.4 3596.2 3597.7 135212.0 136828.3 138394.3 157153.0 165742.5 12328.6 13982.4 14245.5 14245.9 14543.9 25897.9 27200.5 27255.3 27310.7 27358.7 7950.5 8035.5 8036.8 8065.0 8159.8 3410.8 3457.0 3480.3 3494.5 3498.8 4676.7 4765.0 4781.5 4801.3 4889.0 868.3 905.2 1054.1 1061.9 1063.5 550.3 646.0 650.0 660.1 676.0 8838.7 9578.7 10183.7 11285.2 12164.8 633.0 644.4 654.0 667.0 671.6 5262.6 5598.3 5659.2 5852.8 5936.6 143371.0 145226.5 145288.3 146558.3 148693.0 99986.0 114381.4 116279.8 120919.2 122409.4 107484.0 108746.0 111231.0 111977.4 114563.4 11036.8 11086.4 11341.3 11385.5 11808.9 43373.8 45239.0 45249.9 45582.0 45639.3 55555.2 56312.7 57938.0 59940.0 60793.1 15787.6 17954.4 18214.6 18402.5 18472.9 19018.0 19046.9 19123.6 19183.3 19227.1 17239.3 17741.6 18117.5 18119.6 18585.8 17707.9 17847.1 18203.9 18286.2 18422.0 17067.1 17482.7 17502.4 17514.5 17797.2 11170.2 11696.0 11780.5 12081.5 12572.7 13037.7 14649.9 14766.4 14773.3 14806.4 17:22:56 INFO - PID 6779 | -------- Summary: end -------- 17:22:56 INFO - PID 6779 | 17:22:56 INFO - TEST-INFO | 6779: exit 0 17:22:56 INFO - TEST-OK | dromaeo_css | took 1142844ms 17:22:56 INFO - TEST-START | kraken 17:22:56 INFO - operating with platform_type : mac_ 17:22:56 INFO - Initialising browser for kraken test... 17:22:56 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:57801/getInfo.html -foreground -profile /var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/tmprlpuKm/profile 17:22:56 INFO - TEST-INFO | started process 6787 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:57801/getInfo.html -foreground) 17:23:29 INFO - TEST-INFO | 6787: killed by SIGTERM 17:23:29 INFO - Browser initialized. 17:23:29 INFO - Running cycle 1/1 for kraken test... 17:23:29 INFO - TEST-INFO | started process 6791 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/32/8yjhndkj27bg5s40136tly8r00000w/T/tmprlpuKm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:23:31 INFO - PID 6791 | 2017-06-13 17:23:31.647 plugin-container[6792:128611] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 17:23:31 INFO - PID 6791 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:23:31 INFO - PID 6791 | 2017-06-13 17:23:31.648 plugin-container[6792:128611] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d03, name = 'com.apple.CFPasteboardClient' 17:23:31 INFO - PID 6791 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:23:31 INFO - PID 6791 | 2017-06-13 17:23:31.648 plugin-container[6792:128611] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:23:32 INFO - PID 6791 | 2017-06-13 17:23:32.406 plugin-container[6793:128656] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 17:23:32 INFO - PID 6791 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:23:32 INFO - PID 6791 | 2017-06-13 17:23:32.406 plugin-container[6793:128656] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b0b, name = 'com.apple.CFPasteboardClient' 17:23:32 INFO - PID 6791 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:23:32 INFO - PID 6791 | 2017-06-13 17:23:32.406 plugin-container[6793:128656] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:23:41 INFO - PID 6791 | RSS: Main: 214888448 17:23:41 INFO - PID 6791 | 17:23:44 INFO - PID 6791 | Cycle 1(1): loaded http://localhost:57801/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57801/tests/kraken/driver.html?testName=audio-beat-detection) 17:23:45 INFO - PID 6791 | RSS: Main: 228352000 17:23:45 INFO - PID 6791 | 17:23:48 INFO - PID 6791 | Cycle 1(1): loaded http://localhost:57801/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57801/tests/kraken/driver.html?testName=audio-dft) 17:23:48 INFO - PID 6791 | RSS: Main: 239300608 17:23:48 INFO - PID 6791 | 17:23:52 INFO - PID 6791 | Cycle 1(1): loaded http://localhost:57801/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57801/tests/kraken/driver.html?testName=audio-fft) 17:23:52 INFO - PID 6791 | RSS: Main: 243924992 17:23:52 INFO - PID 6791 | 17:23:54 INFO - PID 6791 | Cycle 1(1): loaded http://localhost:57801/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57801/tests/kraken/driver.html?testName=audio-oscillator) 17:23:55 INFO - PID 6791 | RSS: Main: 244408320 17:23:55 INFO - PID 6791 | 17:23:57 INFO - PID 6791 | Cycle 1(1): loaded http://localhost:57801/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57801/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:23:57 INFO - PID 6791 | RSS: Main: 246165504 17:23:57 INFO - PID 6791 | 17:24:00 INFO - PID 6791 | Cycle 1(1): loaded http://localhost:57801/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57801/tests/kraken/driver.html?testName=imaging-darkroom) 17:24:01 INFO - PID 6791 | RSS: Main: 250867712 17:24:01 INFO - PID 6791 | 17:24:04 INFO - PID 6791 | Cycle 1(1): loaded http://localhost:57801/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57801/tests/kraken/driver.html?testName=imaging-desaturate) 17:24:04 INFO - PID 6791 | RSS: Main: 250695680 17:24:04 INFO - PID 6791 | 17:24:07 INFO - PID 6791 | Cycle 1(1): loaded http://localhost:57801/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57801/tests/kraken/driver.html?testName=json-parse-financial) 17:24:08 INFO - PID 6791 | RSS: Main: 250834944 17:24:08 INFO - PID 6791 | 17:24:09 INFO - PID 6791 | Cycle 1(1): loaded http://localhost:57801/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57801/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:24:10 INFO - PID 6791 | RSS: Main: 249810944 17:24:10 INFO - PID 6791 | 17:24:12 INFO - PID 6791 | Cycle 1(1): loaded http://localhost:57801/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57801/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:24:12 INFO - PID 6791 | RSS: Main: 249815040 17:24:12 INFO - PID 6791 | 17:24:14 INFO - PID 6791 | Cycle 1(1): loaded http://localhost:57801/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57801/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:24:15 INFO - PID 6791 | RSS: Main: 249729024 17:24:15 INFO - PID 6791 | 17:24:18 INFO - PID 6791 | Cycle 1(1): loaded http://localhost:57801/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57801/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:24:19 INFO - PID 6791 | RSS: Main: 249896960 17:24:19 INFO - PID 6791 | 17:24:22 INFO - PID 6791 | Cycle 1(1): loaded http://localhost:57801/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57801/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:24:22 INFO - PID 6791 | RSS: Main: 250195968 17:24:22 INFO - PID 6791 | 17:24:24 INFO - PID 6791 | Cycle 1(1): loaded http://localhost:57801/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57801/tests/kraken/driver.html?testName=ai-astar) 17:24:24 INFO - PID 6791 | RSS: Main: 252694528 17:24:24 INFO - PID 6791 | 17:24:24 INFO - PID 6791 | __start_tp_report 17:24:24 INFO - PID 6791 | _x_x_mozilla_page_load 17:24:24 INFO - PID 6791 | _x_x_mozilla_page_load_details 17:24:24 INFO - PID 6791 | |i|pagename|runs| 17:24:24 INFO - PID 6791 | |0;ai-astar;106;167;153;121;166;172;122;118;117;120 17:24:24 INFO - PID 6791 | |1;audio-beat-detection;148;111;129;147;111;144;167;128;163;109 17:24:24 INFO - PID 6791 | |2;audio-dft;196;203;197;204;200;197;199;195;205;198 17:24:24 INFO - PID 6791 | |3;audio-fft;68;91;79;169;58;58;58;62;130;64 17:24:24 INFO - PID 6791 | |4;audio-oscillator;68;76;124;68;70;72;106;71;74;111 17:24:24 INFO - PID 6791 | |5;imaging-gaussian-blur;88;89;88;89;91;88;90;89;90;88 17:24:24 INFO - PID 6791 | |6;imaging-darkroom;95;95;95;96;96;96;96;95;95;95 17:24:24 INFO - PID 6791 | |7;imaging-desaturate;91;129;151;96;92;96;95;88;91;95 17:24:24 INFO - PID 6791 | |8;json-parse-financial;48;49;48;50;53;52;53;53;53;52 17:24:24 INFO - PID 6791 | |9;json-stringify-tinderbox;53;54;57;59;62;60;55;57;56;57 17:24:24 INFO - PID 6791 | |10;stanford-crypto-aes;79;81;82;81;77;80;80;82;80;79 17:24:24 INFO - PID 6791 | |11;stanford-crypto-ccm;135;144;135;153;149;135;140;147;134;177 17:24:24 INFO - PID 6791 | |12;stanford-crypto-pbkdf2;218;232;193;177;180;182;180;177;178;179 17:24:24 INFO - PID 6791 | |13;stanford-crypto-sha256-iterative;68;67;63;67;66;66;66;66;62;67 17:24:24 INFO - PID 6791 | __end_tp_report 17:24:24 INFO - PID 6791 | __start_cc_report 17:24:24 INFO - PID 6791 | _x_x_mozilla_cycle_collect,1016 17:24:24 INFO - PID 6791 | __end_cc_report 17:24:24 INFO - PID 6791 | __startTimestamp1497399864607__endTimestamp 17:24:24 INFO - PID 6791 | 17:24:24 INFO - PID 6791 | ------- Summary: start ------- 17:24:24 INFO - PID 6791 | Number of tests: 14 17:24:24 INFO - PID 6791 | 17:24:24 INFO - PID 6791 | [#0] ai-astar Cycles:10 Average:136.20 Median:137.50 stddev:25.18 (18.3%) stddev-sans-first:24.22 17:24:24 INFO - PID 6791 | Values: 106.0 167.0 153.0 121.0 166.0 172.0 122.0 118.0 117.0 120.0 17:24:24 INFO - PID 6791 | 17:24:24 INFO - PID 6791 | [#1] audio-beat-detection Cycles:10 Average:135.70 Median:145.50 stddev:21.37 (14.7%) stddev-sans-first:22.20 17:24:24 INFO - PID 6791 | Values: 148.0 111.0 129.0 147.0 111.0 144.0 167.0 128.0 163.0 109.0 17:24:24 INFO - PID 6791 | 17:24:24 INFO - PID 6791 | [#2] audio-dft Cycles:10 Average:199.40 Median:199.50 stddev:3.50 (1.8%) stddev-sans-first:3.49 17:24:24 INFO - PID 6791 | Values: 196.0 203.0 197.0 204.0 200.0 197.0 199.0 195.0 205.0 198.0 17:24:24 INFO - PID 6791 | 17:24:24 INFO - PID 6791 | [#3] audio-fft Cycles:10 Average:83.70 Median:63.00 stddev:37.39 (59.3%) stddev-sans-first:39.22 17:24:24 INFO - PID 6791 | Values: 68.0 91.0 79.0 169.0 58.0 58.0 58.0 62.0 130.0 64.0 17:24:24 INFO - PID 6791 | 17:24:24 INFO - PID 6791 | [#4] audio-oscillator Cycles:10 Average:84.00 Median:70.50 stddev:21.08 (29.9%) stddev-sans-first:21.55 17:24:24 INFO - PID 6791 | Values: 68.0 76.0 124.0 68.0 70.0 72.0 106.0 71.0 74.0 111.0 17:24:24 INFO - PID 6791 | 17:24:24 INFO - PID 6791 | [#5] imaging-gaussian-blur Cycles:10 Average:89.00 Median:89.00 stddev:1.05 (1.2%) stddev-sans-first:1.05 17:24:24 INFO - PID 6791 | Values: 88.0 89.0 88.0 89.0 91.0 88.0 90.0 89.0 90.0 88.0 17:24:24 INFO - PID 6791 | 17:24:24 INFO - PID 6791 | [#6] imaging-darkroom Cycles:10 Average:95.40 Median:95.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 17:24:24 INFO - PID 6791 | Values: 95.0 95.0 95.0 96.0 96.0 96.0 96.0 95.0 95.0 95.0 17:24:24 INFO - PID 6791 | 17:24:24 INFO - PID 6791 | [#7] imaging-desaturate Cycles:10 Average:102.40 Median:93.50 stddev:20.65 (22.1%) stddev-sans-first:21.48 17:24:24 INFO - PID 6791 | Values: 91.0 129.0 151.0 96.0 92.0 96.0 95.0 88.0 91.0 95.0 17:24:24 INFO - PID 6791 | 17:24:24 INFO - PID 6791 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:52.50 stddev:2.13 (4.1%) stddev-sans-first:1.94 17:24:24 INFO - PID 6791 | Values: 48.0 49.0 48.0 50.0 53.0 52.0 53.0 53.0 53.0 52.0 17:24:24 INFO - PID 6791 | 17:24:24 INFO - PID 6791 | [#9] json-stringify-tinderbox Cycles:10 Average:57.00 Median:57.00 stddev:2.75 (4.8%) stddev-sans-first:2.51 17:24:24 INFO - PID 6791 | Values: 53.0 54.0 57.0 59.0 62.0 60.0 55.0 57.0 56.0 57.0 17:24:24 INFO - PID 6791 | 17:24:24 INFO - PID 6791 | [#10] stanford-crypto-aes Cycles:10 Average:80.10 Median:80.50 stddev:1.52 (1.9%) stddev-sans-first:1.56 17:24:24 INFO - PID 6791 | Values: 79.0 81.0 82.0 81.0 77.0 80.0 80.0 82.0 80.0 79.0 17:24:24 INFO - PID 6791 | 17:24:24 INFO - PID 6791 | [#11] stanford-crypto-ccm Cycles:10 Average:144.90 Median:145.50 stddev:13.14 (9.0%) stddev-sans-first:13.44 17:24:24 INFO - PID 6791 | Values: 135.0 144.0 135.0 153.0 149.0 135.0 140.0 147.0 134.0 177.0 17:24:24 INFO - PID 6791 | 17:24:24 INFO - PID 6791 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.60 Median:181.00 stddev:19.50 (10.8%) stddev-sans-first:17.77 17:24:24 INFO - PID 6791 | Values: 218.0 232.0 193.0 177.0 180.0 182.0 180.0 177.0 178.0 179.0 17:24:24 INFO - PID 6791 | 17:24:24 INFO - PID 6791 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.50 stddev:1.87 (2.8%) stddev-sans-first:1.81 17:24:24 INFO - PID 6791 | Values: 68.0 67.0 63.0 67.0 66.0 66.0 66.0 66.0 62.0 67.0 17:24:24 INFO - PID 6791 | -------- Summary: end -------- 17:24:24 INFO - PID 6791 | 17:24:25 INFO - TEST-INFO | 6791: exit 0 17:24:25 INFO - TEST-OK | kraken | took 88881ms 17:24:25 INFO - SUITE-END | took 1231s 17:24:25 INFO - Completed test suite (00:20:32) 17:24:25 INFO - javascript benchmark 17:24:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8391.807473975206, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3038.164504761191, 3356.882509112181, 3499.3351263259965, 3621.963780362197, 3656.341858465477, 6234.314225435204, 6291.33940936202, 6292.8741425171465, 6302.495800335973, 6311.62130272184, 6317.936820631792, 6330.398612131851, 6334.366563343663, 6342.873142537146, 6349.968250158754, 5551.583631227654, 5556.972215138919, 5564.8052318168875, 5611.382747897728, 5672.659640421567, 5504.146857237135, 5538.953575533461, 5566.304211973504, 5621.690807005613, 5635.013872572304, 5379.8386048418415, 5489.917651235235, 5519.448055194489, 5535.612507124503, 5553.555715542747, 4705.988212534322, 5486.91769623456, 5571.1086226203615, 5571.275734154575, 5591.552675785968, 5405.8107966221, 5535.944640553583, 5537.363203231599, 5546.195801608744, 5556.6943818089985, 5197.7401129943355, 6232.626042437469, 6255.655938923357, 6332.968335158341, 6348.841278968017, 6614.206295244553, 6663.766768163091, 6693.799186024427, 6875.106236189264, 6896.20693620235, 6908.620025898574, 6912.654367281616, 6974.163100427931, 6974.337437943387, 6979.89530157048, 6471.546991710535, 6530.216374035099, 6550.475961923034, 6573.112629795012, 6579.243387010555, 7145.6784444700115, 7151.427885769126, 7172.856542869114, 7190.3888169505135, 7202.351788339075, 6559.901601475982, 6666.0, 6666.033425153422, 6667.766628167991, 6668.866622667617, 6918.100646915868, 6940.861182776316, 6996.720131194797, 7001.36987671112, 7025.367716905503, 6785.084013658192, 6842.178938527309, 6895.275996020346, 6898.379145876896, 6918.308169183041, 6891.724331026703, 6894.724211031503, 6897.55165914214, 6900.723971041202, 6918.5502942308585, 5512.200730893998, 5514.972425137928, 5517.034518959246, 5527.613067085265, 5531.557475401958, 4790.952090479051, 4794.113089078532, 4819.662623616383, 4821.614270858323, 4846.418429788378, 6228.0, 6358.046293056079, 6361.61830290184, 6384.936150638434, 6463.192100987377, 7007.299270072952, 7021.297870212938, 7039.92960070403, 7053.188883278386, 7065.646717664096, 6424.357564243538, 6467.902981455282, 6469.838254043687, 6477.870442591122, 6483.675816209171, 6099.817005489843, 6184.783532576338, 6184.979478386016, 6199.535034872402, 6216.2540495140875, 6868.656567171622, 6948.722051117898, 6963.09479767627, 7004.4396448284015, 7148.678309476086, 6966.825829354307, 6977.267386924503, 7033.366996970297, 7064.116985376828, 7087.149542054986, 7113.786586402416, 7164.605946672983, 7167.1757747859665, 7169.892451613126, 7175.892361614476, 6867.313268673092, 6900.068490753684, 6915.757948471779, 6923.238443771282, 6952.339527744856, 7013.509054366145, 7035.190953040261, 7040.0847889774, 7049.894251586333, 7085.6457177141965, 6554.31179726141, 6618.172728408949, 6636.900446493403, 6646.169228846395, 6653.301403352578, 6680.699368528525, 6748.797536073926, 6758.459323254227, 6767.424768894502, 6785.287544807724, 6571.605703657796, 6645.966770166118, 6652.800415987528, 6660.900086498609, 6675.0654908313845, 6789.015592739124, 6789.2192397872905, 6795.354441328066, 6814.557053791487, 6816.556923799937, 6367.395097465734, 6477.190351206099, 6479.157709497735, 6489.967550162219, 6497.285298617243], "value": 6281.335639525225, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [822.2476434062833, 830.5182993863559, 835.4903508859595, 855.9486430814152, 863.2403484933258, 32167.51748723771, 39228.41157382638, 40143.996400090015, 40295.79852100739, 40393.0, 39238.82283531492, 39385.40921886174, 39438.0, 39596.406053909144, 39708.007299817444, 7539.886901696465, 7671.194524574923, 7675.654595543214, 7691.730789422371, 7699.114601820778, 7366.226546212651, 7490.988716523254, 7573.24267573241, 7581.279778421042, 8427.915720842775, 7541.396688264926, 7936.690446076419, 8434.0, 8479.533625650587, 8555.914440855606, 8454.196851299117, 8472.406931514797, 8516.701915432992, 8522.957385213096, 8524.190201930838, 8320.958395208047, 8350.832983340299, 8366.748997530083, 8425.831483370359, 8428.0, 37384.62615373866, 37421.06447338838, 37496.62503374959, 37528.437073443645, 37537.62462375368, 3551.520544726455, 3556.697680697144, 3572.1962558424407, 3592.2276710507103, 3599.4420864766003, 30752.846235768677, 30990.0, 31053.378932421227, 31083.533746993588, 31099.533506997413, 2950.9409811803644, 2956.541736030919, 2959.3193565479846, 2972.1826497713087, 3033.6511301200426, 220757.0, 260543.0, 264259.9999999981, 323019.0, 323027.0, 48523.0, 49081.50918490769, 49660.75848103826, 49729.259475918894, 49804.0, 41485.37771933423, 51218.48781512137, 51303.0, 51415.0, 51460.22809657858, 15442.305096270695, 15470.917035807386, 15546.14496202697, 15628.296726647328, 15628.921855390878, 12555.121141520005, 12633.620991370273, 12683.429245683967, 12700.42848071839, 12763.680907977188, 13931.442742290195, 13938.11835402228, 13943.721125577431, 13980.510682126076, 14067.0, 6069.484093851984, 6153.446189842846, 6178.969105154535, 6185.938140618537, 6198.721057552421, 894.1684233662756, 959.9808003839884, 996.1731762636994, 998.3510718033336, 1031.118393773328, 18185.909070454563, 22874.0, 23181.652275215885, 23347.649785253234, 23520.64719029216, 988.930774845754, 1092.3391348234363, 1155.399192419937, 1155.485808815086, 1155.653304008811, 12031.217970832044, 12412.820782025874, 12585.119041667178, 12601.810972835416, 12720.809187862189, 331151.0, 331249.0, 331260.0, 332619.0, 336042.0, 53734.0, 53953.46046539563, 54002.45997540053, 54016.0, 54114.0, 1334.2128144394853, 1456.3300881594373, 1525.8855585830886, 1531.892767506264, 1547.1490680125885, 22142.0, 22554.5489090214, 22697.886510567343, 22773.430664233525, 24245.151419700225, 37326.440103399014, 37416.0, 37531.81234093812, 37567.6243237575, 37631.0, 284678.0, 285909.5704521464, 286822.0, 286922.0, 287530.0, 32903.01290961275, 33094.83452582722, 33372.499412509314, 33996.830015849766, 34393.0, 42574.78712606417, 43208.70373888788, 43959.340609891515, 44585.66243012715, 44757.55242447664, 27202.0, 28817.84728610832, 30463.0, 31091.533626995162, 31161.532577011818, 30022.84988575043, 30065.3986920256, 30257.940972065873, 30986.0704178875, 30991.0, 35040.64959350374, 36168.73409430657, 36766.44850327301, 36815.07962300964, 36817.0, 27771.722282777722, 28906.566401504417, 29341.706582934752, 30917.8454107737, 30993.8450307747, 22040.33878983633, 22164.667529987386, 23903.0, 23964.822644178024, 24184.0], "value": 17343.817499541437, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.8745313671582, 509.57959683261316, 510.5532658923442, 530.2205757536425, 531.6305167908304, 21159.259425920056, 22387.55224895501, 22528.873556322178, 22694.88652556737, 23354.18260360888, 22530.986105625245, 22697.0, 23881.880590597066, 24688.012479500798, 24690.382740431454, 6257.311695713468, 6464.838379040514, 6471.0940468334475, 6474.352564743535, 6474.449671777905, 5294.470552944694, 5913.8817223655715, 6250.249970003606, 6300.8739825203465, 6359.014352775327, 6181.81454556364, 6196.287426945892, 6200.379962003809, 6215.56491045627, 6221.346758590351, 6210.751569937197, 6223.035429508411, 6229.4704950079295, 6235.812925612239, 6236.314005459419, 5609.607327487078, 5645.661260324394, 5646.604737668365, 5649.8587535311535, 5652.293463317085, 23634.409139771473, 26832.463350733073, 28900.0, 29404.70595294041, 30442.391152177053, 2260.2541161416693, 2305.319930620471, 2322.6167682332393, 2323.0939933425975, 2323.1520495019195, 18699.906500467547, 19109.9044504778, 19228.80771192284, 19272.32546860853, 19280.32518861847, 2151.601953638582, 2175.369142948543, 2195.8243340532717, 2215.7562668106493, 2239.1715065425733, 166578.0, 175742.121289394, 176216.0, 184163.0, 187077.0, 13546.729065418636, 13857.0, 14657.48698795537, 14982.775258371134, 15061.322240499203, 20107.095180716904, 20108.497287567927, 20206.797932020785, 20241.291554795513, 20242.493937651678, 10092.646757363456, 10099.44453055089, 10174.168649642987, 10313.84529232062, 10345.482725863827, 9670.0, 9728.416295022322, 9776.364536305118, 9908.603655853685, 9944.45305508204, 8907.198352148338, 8989.730308090768, 9064.138906803842, 9078.727638170865, 9082.22801061904, 2069.865458745177, 2599.272203782944, 2599.2981894888276, 2599.3371690218873, 2706.174616741913, 824.381713714714, 926.2451102351562, 947.1475671895349, 1003.1022235099545, 1009.8232809258351, 20429.69355459669, 20443.46673999456, 21065.0, 21628.243011494524, 21954.560908782056, 891.4740303221131, 980.3382716666364, 992.5434300221834, 999.2455695949657, 1015.3603229965074, 9431.528423578793, 10594.523246453928, 10811.297265677706, 10961.232713709964, 10982.968150892111, 530981.0, 543361.0, 544381.0, 546645.0, 560989.0, 148947.0, 156331.0, 158112.20943895206, 159265.2036739809, 162869.99999999764, 1010.7624708193568, 1082.5399205337858, 1085.6308854989184, 1182.2788099259444, 1259.168948494008, 21792.564148717254, 24076.638850416908, 24105.0, 24122.0, 24136.03455861746, 70392.64803675949, 70583.0, 72515.0, 74913.6254318725, 75602.0, 69026.0, 69963.9010829676, 81338.18661813306, 89042.55478722564, 90756.5462172685, 11681.474333655176, 11805.350705711267, 11868.940655296668, 11991.64025079249, 11993.64019079429, 18097.276108955495, 18193.636127277645, 18442.907785460986, 18659.720104198175, 18776.436706898814, 14737.263136843329, 14954.25228738537, 15015.699686006, 15235.923820380827, 15257.85566082526, 13132.474701012236, 13228.338583071001, 13236.669083272995, 13353.799693004807, 13615.591532254048, 15023.0, 15054.870884683474, 15231.390744370101, 15271.847281527043, 15381.615459613151, 9874.50627468638, 11722.062235021349, 11842.230255033395, 11871.881281187423, 11901.583444579399, 14644.12135271887, 14736.926315368355, 14764.409423622934, 14819.629509261924, 14838.258087095817], "value": 11624.368836993192, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1287.1376177960765, 1482.3699927530797, 1482.8359737605974, 1492.835788063312, 1501.5420296809473, 13641.590752277423, 14118.152910825358, 14192.0, 14220.999999999987, 14228.928855355722, 13798.724025519483, 14266.286685665727, 14267.2866356682, 14272.4291028359, 14286.07140535867, 2620.3580122869926, 2681.986590067047, 2840.218939791553, 2848.985755071222, 2849.871755770995, 2326.581215381231, 2782.6939036705944, 2844.459552684983, 2844.8150870193476, 2847.900323488678, 2389.928302150938, 2395.940101497459, 2744.4922689302443, 2844.1041072062235, 2844.914652560416, 2798.0906205483298, 2805.0, 2817.7886658500693, 2819.9013034543796, 2823.181277429543, 2739.712335202452, 2761.1578468567077, 2767.0730305347734, 2769.404578015716, 2769.8615069246457, 13638.0, 13684.589462316146, 13810.37853296604, 13811.723765524634, 13847.376868040963, 7535.585542795144, 7772.261635144652, 7905.367570594339, 7918.208179182093, 7932.444728868982, 4690.859274221744, 4897.559219670246, 4912.975435122838, 5006.07387633289, 5059.924101138486, 1340.1557019077918, 1379.655086228443, 1399.839018512874, 1400.544822932551, 1400.544822932551, 20879.26922557703, 20934.057967391353, 21069.051892664866, 21094.578108437898, 21116.155353785984, 6593.604383736991, 6761.18865736105, 6815.2843951385385, 6898.482613803984, 6905.792826215222, 14507.492237771627, 14728.55814325572, 14782.334794934253, 14804.259787010606, 14804.407823686932, 6083.66539840313, 7641.0, 7703.268189521987, 7703.306702396699, 7715.537067775951, 6711.496637752188, 6918.273581273894, 7053.823654408681, 7137.4290056795335, 7151.392131668867, 5220.243064755589, 5372.059889519397, 5374.435684253175, 5430.67415955044, 5454.181872719045, 1570.5916461719808, 1571.7799508068879, 1572.3553343129263, 1574.6457047164527, 1601.4154833485693, 685.8182581615876, 822.8847961285425, 834.6911642692243, 849.4605925237519, 880.4541184465672, 7742.71460247066, 7861.331786798186, 8172.346212303001, 8432.030316513557, 8613.095624959415, 971.7424882406168, 974.3423189347162, 1012.7215015870695, 1013.3109485549896, 1033.8035773203067, 4698.083873644675, 4764.690295130806, 4980.8754781130765, 5023.547880690755, 5243.764030618631, 13800.93099534516, 18375.448736537925, 18957.81042189588, 18983.955882426322, 19070.713939290923, 18427.72358414625, 18899.90550047241, 19006.619867602847, 19022.904885475484, 19039.52401189981, 9421.90578094224, 9435.528223588793, 9476.099770521789, 9478.668246611336, 9601.47191904438, 7199.640017998974, 7945.523268603903, 7953.244441778022, 8223.424360294961, 8666.003409607723, 16615.418460354314, 16695.499135025482, 16871.987680739196, 17383.304667813652, 17472.650546989244, 16620.25208865628, 17101.973881567144, 17490.47528574145, 17566.297348105934, 17888.731669024713, 10793.730156746144, 10893.56425742993, 11008.779824403628, 11344.489497972776, 11346.546138154381, 10765.569377225136, 12944.029197810014, 12974.610761677164, 13094.14888032275, 13122.934385328012, 8953.104689530996, 10013.649522266685, 10132.240081994027, 10221.131203847755, 10251.48742562854, 9786.706398807763, 9853.113219810251, 9928.801423971336, 10115.59537618487, 10186.898131018595, 9441.811163776823, 9444.150026497648, 9519.57161927701, 9523.476208808583, 9535.570899309409, 6549.0, 6852.143482064742, 6904.551204171713, 7132.037174981519, 7185.101862267216, 7053.858922821412, 7111.43108551325, 7232.0959880015, 7285.562866228043, 7298.343149116605], "value": 6264.911987915521, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [429.95270520242786, 442.9379886815846, 460.098207513274, 467.94150731158595, 468.4308565093411, 5096.3374761281075, 6610.30591787862, 6616.305287944769, 6629.535932484722, 6709.060731497594, 6215.316315205324, 6343.397377249154, 6422.9357706422925, 6437.710303036363, 6481.935180648192, 4822.324874517571, 4858.805647774085, 4858.927116093261, 4863.562279394845, 4883.145449546333, 4772.331873537708, 4790.928136077944, 4792.75388399017, 4855.5872750816225, 4873.561379475864, 3768.604296548864, 4034.1326614777963, 4316.633086187662, 4771.117343291485, 4777.761111944406, 4032.3145065338963, 4571.885702857438, 4666.743329116897, 4783.832565860213, 4789.401324834395, 4197.446944630507, 4202.432671589327, 4203.684723645739, 4203.810828512725, 4217.641500472463, 5276.815311464119, 5422.9728851355885, 5577.764896360101, 6172.271763906114, 6204.875902481925, 5713.428657134295, 5724.427557244284, 5730.36965933747, 5741.856453588652, 5747.252857128547, 5520.91718624221, 6279.372062793684, 6579.144711187563, 6827.726890924357, 6957.5129740918155, 4211.368294755781, 4433.889152771174, 4828.927566086511, 4829.734364609945, 4836.782344794493, 7328.8167795805, 7357.1539272983755, 7367.0, 7397.593132377771, 7451.21762214965, 3952.6837852972267, 4401.6698747594055, 4460.687751857404, 4942.097292542397, 4944.134776414185, 4423.668224883147, 5065.138926382524, 5067.4258315853, 5069.569086627678, 5070.645054846125, 4801.399825021872, 5463.235147079412, 5784.2769653793275, 5784.913226301609, 5788.913166302509, 5620.4660557247, 6469.838254043592, 6956.478264130211, 7016.228214896358, 7028.332308430691, 1424.444466658004, 1463.1075044223016, 1564.5071802382213, 1677.8322167783124, 1694.8644108471292, 1124.966251012471, 1133.4106264742288, 1142.6743378137191, 1145.1811954452605, 1148.3569201247328, 6177.69111544421, 6322.609026014269, 6417.780621681865, 6420.133282006869, 6438.742450301936, 6520.380563846427, 7197.568145911262, 7272.8181795454475, 7294.744683936036, 7326.413886889036, 5662.065759149694, 6185.0, 6470.352964703492, 6520.347965203537, 6578.671066446658, 3195.984020079885, 3622.6415094339623, 3659.7255205859665, 3763.5860055393887, 3764.284785890672, 6151.9384806151365, 7378.926210737931, 7420.703171873172, 7432.814179645552, 7436.330730234305, 6740.325967403221, 7349.566834467228, 7386.519876208029, 7407.296306850841, 7409.88885166723, 5878.0, 7421.146568144733, 7440.441966852508, 7453.142888567776, 7477.289657482531, 5430.8099216527235, 7194.100737407824, 7229.385502232369, 7231.963840180765, 7288.854222915618, 6345.619262844244, 6346.270178929482, 7048.0, 7066.893996589953, 7080.645967701697, 6934.271901450476, 7272.781816545512, 7318.085239345082, 7356.374708149867, 7388.261173882784, 4277.315629499265, 4441.178381999341, 4558.5897269245925, 4833.637477189154, 4865.95134048655, 7071.752488662872, 7178.953998110514, 7181.281871812777, 7256.492045556761, 7263.636818159177, 6765.898511522233, 6894.586324820527, 6907.0, 6942.722291108299, 6944.444444444332, 5856.238688970577, 6465.321141280097, 6619.470442364695, 6933.410660093747, 6938.06336144614, 5655.377908429987, 6783.118194634666, 6795.1845778506895, 6818.556793808387, 6824.829379265359, 6244.781432649836, 6926.168859736864, 6947.478939079489, 6948.6178260196175, 6958.582485050913, 5849.3858144894175, 5980.581359304807, 6390.0, 6734.800969539429, 6743.9325606743305], "value": 5048.238896383096, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.3570704219724, 808.9676412943483, 811.1523457986403, 845.1928408370007, 847.0978407995483, 14486.058406203589, 14638.634034149138, 14744.262786860667, 14787.964842461019, 14915.477958271462, 17160.656786864285, 17755.64488710225, 18465.630687386278, 18744.156512956917, 18744.718829217574, 835.8035861572525, 965.2471072563412, 982.9852552211722, 985.3299756165804, 985.9359141655806, 855.4140413816524, 968.9224862011022, 971.9076687714692, 975.2490582251662, 981.3768257156686, 883.8674198870157, 918.9586468608895, 1010.2524132142198, 1021.4075836015094, 1032.194887987371, 846.8010017645879, 995.4027583449872, 1002.9699109026741, 1007.0332480818423, 1007.4610083605272, 732.9486935914488, 733.7945375294926, 733.9889901651479, 734.5262305812721, 736.0946036375288, 16983.915080424642, 17159.571010724707, 17214.0, 17375.73936390955, 17409.21658525357, 17477.56306092345, 17793.822061779345, 18055.819441805546, 18098.547536311566, 18155.72766408505, 15065.397384104623, 15075.396984120622, 15102.0, 15228.086314821147, 15357.923210383877, 3140.262038420969, 3532.0640030391965, 3570.410882204433, 3596.1549035976523, 3597.7301702372165, 135212.0, 136828.31585842106, 138394.30802846022, 157153.0, 165742.51386229217, 12328.568500102454, 13982.440702371994, 14245.501407450896, 14245.92877035629, 14543.92728036353, 25897.870510647703, 27200.455990880073, 27255.318617034733, 27310.726892730818, 27358.726412736018, 7950.522968621901, 8035.477693949874, 8036.758897233092, 8064.959675201704, 8159.755207343789, 3410.812405317731, 3456.982715086459, 3480.2517458746493, 3494.545709057806, 3498.755087143926, 4676.69601475903, 4764.95235047652, 4781.450133234653, 4801.303810947393, 4888.9755551222015, 868.2880038368477, 905.1988989744124, 1054.1198099586777, 1061.851340812287, 1063.5426766490484, 550.3285991090863, 645.9773907913201, 649.9675016249168, 660.0791895306118, 675.9522739753363, 8838.690645827364, 9578.712638620853, 10183.7454063649, 11285.21003529745, 12164.81752773709, 632.9936700632935, 644.4007073421669, 653.9803805885831, 666.976655817044, 671.6373158494456, 5262.578993680497, 5598.328200615871, 5659.179418984224, 5852.795152169739, 5936.614120082181, 143371.0, 145226.5477345213, 145288.27355863154, 146558.2672086654, 148693.0, 99986.00013999913, 114381.42809286066, 116279.8372016269, 120919.18621220689, 122409.38795305966, 107484.0, 108746.0, 111231.0, 111977.44011279891, 114563.42718286521, 11036.779264414827, 11086.445677716243, 11341.319520828776, 11385.487653055467, 11808.94095529517, 43373.78313108414, 45239.0, 45249.86875328143, 45582.0, 45639.31541026821, 55555.16667250075, 56312.71843640756, 57938.0, 59940.0, 60793.0881036776, 15787.605309867346, 17954.371596994042, 18214.635707285513, 18402.539936501693, 18472.891626502453, 19018.0, 19046.904765476083, 19123.617527649647, 19183.32858349951, 19227.134778934702, 17239.310427582757, 17741.64516709633, 18117.45647630573, 18119.637607247518, 18585.81414185841, 17707.911460442614, 17847.107644617976, 18203.908980455544, 18286.177122029276, 18422.0, 17067.06131162798, 17482.650346992734, 17502.387416440364, 17514.47456576305, 17797.1991260391, 11170.162237832032, 11696.0, 11780.469878855301, 12081.516739330329, 12572.748545028866, 13037.739245215233, 14649.85350146485, 14766.409343626136, 14773.335199915815, 14806.407743690132], "value": 8719.386715348754, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1514.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 167.0, 153.0, 121.0, 166.0, 172.0, 122.0, 118.0, 117.0, 120.0], "value": 136.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 111.0, 129.0, 147.0, 111.0, 144.0, 167.0, 128.0, 163.0, 109.0], "value": 135.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 203.0, 197.0, 204.0, 200.0, 197.0, 199.0, 195.0, 205.0, 198.0], "value": 199.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 91.0, 79.0, 169.0, 58.0, 58.0, 58.0, 62.0, 130.0, 64.0], "value": 83.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 76.0, 124.0, 68.0, 70.0, 72.0, 106.0, 71.0, 74.0, 111.0], "value": 84.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 88.0, 89.0, 91.0, 88.0, 90.0, 89.0, 90.0, 88.0], "value": 89.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0, 95.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 129.0, 151.0, 96.0, 92.0, 96.0, 95.0, 88.0, 91.0, 95.0], "value": 102.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 49.0, 48.0, 50.0, 53.0, 52.0, 53.0, 53.0, 53.0, 52.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 57.0, 59.0, 62.0, 60.0, 55.0, 57.0, 56.0, 57.0], "value": 57.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 81.0, 82.0, 81.0, 77.0, 80.0, 80.0, 82.0, 80.0, 79.0], "value": 80.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 144.0, 135.0, 153.0, 149.0, 135.0, 140.0, 147.0, 134.0, 177.0], "value": 144.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [218.0, 232.0, 193.0, 177.0, 180.0, 182.0, 180.0, 177.0, 178.0, 179.0], "value": 189.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 63.0, 67.0, 66.0, 66.0, 66.0, 66.0, 62.0, 67.0], "value": 65.8, "unit": "score"}]}]} 17:24:25 INFO - Return code: 0 17:24:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:24:25 INFO - # TBPL SUCCESS # 17:24:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:24:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:24:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:24:26 INFO - 2017-06-13 17:24:26.002 screenresolution[6799:129038] starting screenresolution argv=screenresolution get 17:24:26 INFO - 2017-06-13 17:24:26.005 screenresolution[6799:129038] Display 0: 1600x1200x32@60 17:24:26 INFO - 2017-06-13 17:24:26.011 screenresolution[6800:129041] starting screenresolution argv=screenresolution list 17:24:26 INFO - Available Modes on Display 0 17:24:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:24:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:24:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:24:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:24:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:24:26 INFO - Intel Iris: 17:24:26 INFO - Chipset Model: Intel Iris 17:24:26 INFO - Type: GPU 17:24:26 INFO - Bus: Built-In 17:24:26 INFO - VRAM (Dynamic, Max): 1536 MB 17:24:26 INFO - Vendor: Intel (0x8086) 17:24:26 INFO - Device ID: 0x0a2e 17:24:26 INFO - Revision ID: 0x0009 17:24:26 INFO - Displays: 17:24:26 INFO - Display: 17:24:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:24:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:24:26 INFO - Display Serial Number: 1600x1200 60 17:24:26 INFO - Main Display: Yes 17:24:26 INFO - Mirror: Off 17:24:26 INFO - Online: Yes 17:24:26 INFO - Rotation: Supported 17:24:26 INFO - Return code: 0 17:24:26 INFO - Running post-action listener: _resource_record_post_action 17:24:26 INFO - [mozharness: 2017-06-14 00:24:26.244814Z] Finished run-tests step (success) 17:24:26 INFO - Running post-run listener: _resource_record_post_run 17:24:26 INFO - Total resource usage - Wall time: 1254s; CPU: 53.0%; Read bytes: 3403776; Write bytes: 562719744; Read time: 32; Write time: 1760 17:24:26 INFO - TinderboxPrint: CPU usage
52.9% 17:24:26 INFO - TinderboxPrint: I/O read bytes / time
3,403,776 / 32 17:24:26 INFO - TinderboxPrint: I/O write bytes / time
562,719,744 / 1,760 17:24:26 INFO - TinderboxPrint: CPU idle
2,365.2 (47.1%) 17:24:26 INFO - TinderboxPrint: CPU system
1,012.3 (20.2%) 17:24:26 INFO - TinderboxPrint: CPU user
1,640.2 (32.7%) 17:24:26 INFO - TinderboxPrint: Swap in / out
246,730,752 / 0 17:24:26 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 233882112; Write bytes: 227665920; Read time: 16969; Write time: 489 17:24:26 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:24:26 INFO - run-tests - Wall time: 1233s; CPU: 53.0%; Read bytes: 3403776; Write bytes: 316785664; Read time: 32; Write time: 1218 17:24:26 INFO - Running post-run listener: _upload_blobber_files 17:24:26 INFO - Blob upload gear active. 17:24:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:24:26 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 17:24:26 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'] 17:24:26 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 17:24:27 INFO - (blobuploader) - INFO - Open directory for files ... 17:24:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:24:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:24:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:24:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:24:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:24:28 INFO - (blobuploader) - INFO - Done attempting. 17:24:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:24:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:24:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:24:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:24:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:24:29 INFO - (blobuploader) - INFO - Done attempting. 17:24:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:24:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:24:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:24:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:24:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:24:30 INFO - (blobuploader) - INFO - Done attempting. 17:24:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:24:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:24:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:24:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:24:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:24:32 INFO - (blobuploader) - INFO - Done attempting. 17:24:32 INFO - (blobuploader) - INFO - Iteration through files over. 17:24:32 INFO - Return code: 0 17:24:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:24:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:24:32 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ce98b8b5eafb5bd50199a82ea8810af670037119113a84570f6e3e4e91db453ba198a3c486ef9ed2b4903c5d57207ea5e9d97481449c719166d37a855866fcd9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f68ee8dc8b44ce238fda6523643d5f5a29b39fdcd6e9622df102d3a35bb343195624b08b6c41e4517615c250be8690c8df24bba8a8c25d9cd60a5b5bae71e109", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/10a7488f158bd71e970366685d186baf2a71a4012a1ee622f7f671721d7893d2d90341dd5545efc280589a5457363d1680bfb24b13ae2515a9b41d564ffb3969", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/41d77ce1059a5b4f49e5da4c2aaac5783d77b96b0d01bffac7a6af2bde4f4734daeaa049e5de1404f23f49eebe5baf98ead6cddb11fb15252de1c7d31eea2056"} 17:24:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:24:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:24:32 INFO - Contents: 17:24:32 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ce98b8b5eafb5bd50199a82ea8810af670037119113a84570f6e3e4e91db453ba198a3c486ef9ed2b4903c5d57207ea5e9d97481449c719166d37a855866fcd9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f68ee8dc8b44ce238fda6523643d5f5a29b39fdcd6e9622df102d3a35bb343195624b08b6c41e4517615c250be8690c8df24bba8a8c25d9cd60a5b5bae71e109", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/10a7488f158bd71e970366685d186baf2a71a4012a1ee622f7f671721d7893d2d90341dd5545efc280589a5457363d1680bfb24b13ae2515a9b41d564ffb3969", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/41d77ce1059a5b4f49e5da4c2aaac5783d77b96b0d01bffac7a6af2bde4f4734daeaa049e5de1404f23f49eebe5baf98ead6cddb11fb15252de1c7d31eea2056"} 17:24:32 INFO - Running post-run listener: copy_logs_to_upload_dir 17:24:32 INFO - Copying logs to upload dir... 17:24:32 INFO - mkdir: /builds/slave/test/build/upload/logs 17:24:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1333.315019 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 13 secs) (at 2017-06-13 17:24:32.843000) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 17:24:32.846581) ========= 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.zvnUntTPyt/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.oDnYwCYmak/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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/ce98b8b5eafb5bd50199a82ea8810af670037119113a84570f6e3e4e91db453ba198a3c486ef9ed2b4903c5d57207ea5e9d97481449c719166d37a855866fcd9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f68ee8dc8b44ce238fda6523643d5f5a29b39fdcd6e9622df102d3a35bb343195624b08b6c41e4517615c250be8690c8df24bba8a8c25d9cd60a5b5bae71e109", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/10a7488f158bd71e970366685d186baf2a71a4012a1ee622f7f671721d7893d2d90341dd5545efc280589a5457363d1680bfb24b13ae2515a9b41d564ffb3969", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/41d77ce1059a5b4f49e5da4c2aaac5783d77b96b0d01bffac7a6af2bde4f4734daeaa049e5de1404f23f49eebe5baf98ead6cddb11fb15252de1c7d31eea2056"} build_url:https://queue.taskcluster.net/v1/task/HHmfoFndSsejYCGeQdQXrA/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011621 build_url: 'https://queue.taskcluster.net/v1/task/HHmfoFndSsejYCGeQdQXrA/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/ce98b8b5eafb5bd50199a82ea8810af670037119113a84570f6e3e4e91db453ba198a3c486ef9ed2b4903c5d57207ea5e9d97481449c719166d37a855866fcd9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f68ee8dc8b44ce238fda6523643d5f5a29b39fdcd6e9622df102d3a35bb343195624b08b6c41e4517615c250be8690c8df24bba8a8c25d9cd60a5b5bae71e109", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/10a7488f158bd71e970366685d186baf2a71a4012a1ee622f7f671721d7893d2d90341dd5545efc280589a5457363d1680bfb24b13ae2515a9b41d564ffb3969", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/41d77ce1059a5b4f49e5da4c2aaac5783d77b96b0d01bffac7a6af2bde4f4734daeaa049e5de1404f23f49eebe5baf98ead6cddb11fb15252de1c7d31eea2056"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-13 17:24:32.880095) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-13 17:24:32.880393) ========= 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.zvnUntTPyt/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.oDnYwCYmak/Listeners TMPDIR=/var/folders/32/8yjhndkj27bg5s40136tly8r00000w/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 17:24:32.928625) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 17:24:32.928898) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-13 17:24:32.929188) ========= ========= Total master_lag: 0.18 =========