builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0107 starttime: 1496974568.37 results: success (0) revision: b42d50cafb154e3e50fdde3ca853635a000cb219 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-08 19:16:08.373411) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-08 19:16:08.373920) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-08 19:16:08.374241) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-08 19:16:08.400613) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 19:16:08.401018) ========= 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.u7NKI1eUWi/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.T4BeyEAOjo/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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-08 19:16:08-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 63.8M=0s 2017-06-08 19:16:08 (63.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128514 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 19:16:08.552444) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 19:16:08.552774) ========= 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.u7NKI1eUWi/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.T4BeyEAOjo/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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.029740 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 19:16:08.604029) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-08 19:16:08.604430) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev b42d50cafb154e3e50fdde3ca853635a000cb219 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev b42d50cafb154e3e50fdde3ca853635a000cb219 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.u7NKI1eUWi/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.T4BeyEAOjo/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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-08 19:16:08,698 truncating revision to first 12 chars 2017-06-08 19:16:08,698 Setting DEBUG logging. 2017-06-08 19:16:08,698 attempt 1/10 2017-06-08 19:16:08,698 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/b42d50cafb15?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-08 19:16:09,763 unpacking tar archive at: mozilla-central-b42d50cafb15/testing/mozharness/ program finished with exit code 0 elapsedTime=1.333327 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-08 19:16:09.959960) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-08 19:16:09.960277) ========= script_repo_revision: b42d50cafb154e3e50fdde3ca853635a000cb219 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-08 19:16:09.960658) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-08 19:16:09.960947) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-08 19:16:09.979883) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-06-08 19:16:09.980397) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.u7NKI1eUWi/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.T4BeyEAOjo/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 19:16:10 INFO - MultiFileLogger online at 20170608 19:16:10 in /builds/slave/test 19:16:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:16:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:16:10 INFO - {'append_to_log': False, 19:16:10 INFO - 'base_work_dir': '/builds/slave/test', 19:16:10 INFO - 'blob_upload_branch': 'Firefox', 19:16:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:16:10 INFO - 'branch': 'Firefox', 19:16:10 INFO - 'buildbot_json_path': 'buildprops.json', 19:16:10 INFO - 'config_files': ('talos/mac_config.py',), 19:16:10 INFO - 'default_actions': ('clobber', 19:16:10 INFO - 'read-buildbot-config', 19:16:10 INFO - 'download-and-extract', 19:16:10 INFO - 'populate-webroot', 19:16:10 INFO - 'create-virtualenv', 19:16:10 INFO - 'install', 19:16:10 INFO - 'setup-mitmproxy', 19:16:10 INFO - 'run-tests'), 19:16:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:16:10 INFO - 'download_minidump_stackwalk': True, 19:16:10 INFO - 'download_symbols': 'ondemand', 19:16:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:16:10 INFO - 'tooltool.py': '/tools/tooltool.py', 19:16:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:16:10 INFO - '/tools/misc-python/virtualenv.py')}, 19:16:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:16:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:16:10 INFO - 'gecko_profile': False, 19:16:10 INFO - 'gecko_profile_interval': 0, 19:16:10 INFO - 'installer_path': 'installer.exe', 19:16:10 INFO - 'log_level': 'info', 19:16:10 INFO - 'log_name': 'talos', 19:16:10 INFO - 'log_to_console': True, 19:16:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:16:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:16:10 INFO - 'opt_config_files': (), 19:16:10 INFO - 'pip_index': False, 19:16:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:16:10 INFO - 'cmd': ('bash', 19:16:10 INFO - '-c', 19:16:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:16:10 INFO - 'enabled': True, 19:16:10 INFO - 'halt_on_failure': False, 19:16:10 INFO - 'name': 'check_screen_resolution'},), 19:16:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:16:10 INFO - 'cmd': ('bash', 19:16:10 INFO - '-c', 19:16:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:16:10 INFO - 'enabled': True, 19:16:10 INFO - 'halt_on_failure': False, 19:16:10 INFO - 'name': 'check_screen_resolution'},), 19:16:10 INFO - 'run_cmd_checks_enabled': True, 19:16:10 INFO - 'suite': 'dromaeojs-e10s', 19:16:10 INFO - 'system_bits': '32', 19:16:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:16:10 INFO - 'title': 't-yosemite-r7-0107', 19:16:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:16:10 INFO - 'use_talos_json': True, 19:16:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:16:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:16:10 INFO - 'work_dir': 'build'} 19:16:10 INFO - [mozharness: 2017-06-09 02:16:10.192515Z] Running clobber step. 19:16:10 INFO - Running pre-action listener: _resource_record_pre_action 19:16:10 INFO - Running main action method: clobber 19:16:10 INFO - rmtree: /builds/slave/test/build 19:16:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:16:12 INFO - Running post-action listener: _resource_record_post_action 19:16:12 INFO - [mozharness: 2017-06-09 02:16:12.855313Z] Finished clobber step (success) 19:16:12 INFO - [mozharness: 2017-06-09 02:16:12.855551Z] Running read-buildbot-config step. 19:16:12 INFO - Running pre-action listener: _resource_record_pre_action 19:16:12 INFO - Running main action method: read_buildbot_config 19:16:12 INFO - Using buildbot properties: 19:16:12 INFO - { 19:16:12 INFO - "project": "", 19:16:12 INFO - "product": "firefox", 19:16:12 INFO - "who": "kwierso@gmail.com", 19:16:12 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 19:16:12 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 19:16:12 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 19:16:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 19:16:12 INFO - "stage_platform": "macosx64", 19:16:12 INFO - "basedir": "/builds/slave/test", 19:16:12 INFO - "buildnumber": 89, 19:16:12 INFO - "platform": "yosemite_r7", 19:16:12 INFO - "parent_task_id": "SpoS0iwSTJSr8Ev-xJPyhg", 19:16:12 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 19:16:12 INFO - "slavebuilddir": "test", 19:16:12 INFO - "taskId": "SyM4mxo9T4ydzN-sSZ9Smw", 19:16:12 INFO - "branch": "mozilla-central", 19:16:12 INFO - "script_repo_revision": "production", 19:16:12 INFO - "revision": "b42d50cafb154e3e50fdde3ca853635a000cb219", 19:16:12 INFO - "slavename": "t-yosemite-r7-0107", 19:16:12 INFO - "repo_path": "mozilla-central" 19:16:12 INFO - } 19:16:12 INFO - Finding installer, test and symbols from parent task. 19:16:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SyM4mxo9T4ydzN-sSZ9Smw'}, attempt #1 19:16:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SyM4mxo9T4ydzN-sSZ9Smw'}, attempt #1 19:16:14 INFO - Task dependencies: Xonfx1isRB2k2GxX4CyAzQ SpoS0iwSTJSr8Ev-xJPyhg 19:16:14 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg'}, attempt #1 19:16:14 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:16:14 INFO - Set test_url: https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg/artifacts/public/build/test_packages.json 19:16:14 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg/artifacts/public/build/target.crashreporter-symbols.zip 19:16:14 INFO - Running post-action listener: _resource_record_post_action 19:16:14 INFO - [mozharness: 2017-06-09 02:16:14.713936Z] Finished read-buildbot-config step (success) 19:16:14 INFO - [mozharness: 2017-06-09 02:16:14.714079Z] Running download-and-extract step. 19:16:14 INFO - Running pre-action listener: _resource_record_pre_action 19:16:14 INFO - Running main action method: download_and_extract 19:16:14 INFO - mkdir: /builds/slave/test/build/tests 19:16: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')]} 19:16:14 INFO - trying https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 19:16:14 INFO - Downloading https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 19:16:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 19:16:15 INFO - Downloaded 1328 bytes. 19:16:15 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 19:16:15 INFO - Using the following test package requirements: 19:16:15 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:16:15 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 19:16:15 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 19:16:15 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:16:15 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 19:16:15 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:16:15 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 19:16:15 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:16:15 INFO - u'jsshell-mac.zip'], 19:16:15 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:16:15 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 19:16:15 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 19:16:15 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:16:15 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 19:16:15 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:16:15 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 19:16:15 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:16:15 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 19:16:15 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 19:16:15 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 19:16:15 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:16:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 19:16:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:16:15 INFO - Fetch https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 19:16:17 INFO - Content-Length response header: 59539905 19:16:17 INFO - Bytes received: 59539905 19:16:35 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:16:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 19:16:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:16:35 INFO - Fetch https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 19:16:37 INFO - Content-Length response header: 59539905 19:16:37 INFO - Bytes received: 59539905 19:16:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 19:16:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:16:54 INFO - Fetch https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 19:16:55 INFO - Content-Length response header: 14447568 19:16:55 INFO - Bytes received: 14447568 19:16:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:56 INFO - trying https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:16:56 INFO - Downloading https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 19:16:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 19:16:57 INFO - Downloaded 68133862 bytes. 19:16:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:16:57 INFO - mkdir: /builds/slave/test/properties 19:16:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:16:57 INFO - Writing to file /builds/slave/test/properties/build_url 19:16:57 INFO - Contents: 19:16:57 INFO - build_url:https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 19:16:57 INFO - Running post-action listener: _resource_record_post_action 19:16:57 INFO - Running post-action listener: set_extra_try_arguments 19:16:57 INFO - [mozharness: 2017-06-09 02:16:57.918174Z] Finished download-and-extract step (success) 19:16:57 INFO - [mozharness: 2017-06-09 02:16:57.918303Z] Running populate-webroot step. 19:16:57 INFO - Running pre-action listener: _resource_record_pre_action 19:16:57 INFO - Running main action method: populate_webroot 19:16:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:16:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:16:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:16:57 INFO - '--tppagecycles', 19:16:57 INFO - '7'], 19:16:57 INFO - 'tests': ['tsvgm']}}, 19:16:57 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:16:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:16:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:16:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:16:57 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:16:57 INFO - 'talos_options': ['--disable-e10s'], 19:16:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:16:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:16:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:16:57 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:16:57 INFO - 'talos_options': ['--disable-e10s'], 19:16:57 INFO - 'tests': ['damp', 'tps']}, 19:16:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:16:57 INFO - 'tests': ['damp', 'tps']}, 19:16:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:16:57 INFO - 'tests': ['dromaeo_dom']}, 19:16:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:16:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:16:57 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 19:16:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 19:16:57 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 19:16:57 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 19:16:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:16:57 INFO - 'tests': ['a11yr', 19:16:57 INFO - 'ts_paint', 19:16:57 INFO - 'tpaint', 19:16:57 INFO - 'sessionrestore', 19:16:57 INFO - 'sessionrestore_no_auto_restore', 19:16:57 INFO - 'tabpaint']}, 19:16:57 INFO - 'other-e10s': {'tests': ['a11yr', 19:16:57 INFO - 'ts_paint', 19:16:57 INFO - 'tpaint', 19:16:57 INFO - 'sessionrestore', 19:16:57 INFO - 'sessionrestore_no_auto_restore', 19:16:57 INFO - 'tabpaint']}, 19:16:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:16:57 INFO - 'ts_paint', 19:16:57 INFO - 'tpaint', 19:16:57 INFO - 'sessionrestore', 19:16:57 INFO - 'sessionrestore_no_auto_restore', 19:16:57 INFO - 'tabpaint']}, 19:16:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:16:57 INFO - 'ts_paint', 19:16:57 INFO - 'tpaint', 19:16:57 INFO - 'sessionrestore', 19:16:57 INFO - 'sessionrestore_no_auto_restore', 19:16:57 INFO - 'tabpaint']}, 19:16:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:16:57 INFO - 'tests': ['a11yr', 19:16:57 INFO - 'ts_paint', 19:16:57 INFO - 'tpaint', 19:16:57 INFO - 'sessionrestore', 19:16:57 INFO - 'sessionrestore_no_auto_restore', 19:16:57 INFO - 'tabpaint']}, 19:16:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:16:57 INFO - 'tests': ['a11yr', 19:16:57 INFO - 'ts_paint', 19:16:57 INFO - 'tpaint', 19:16:57 INFO - 'sessionrestore', 19:16:57 INFO - 'sessionrestore_no_auto_restore', 19:16:57 INFO - 'tabpaint']}, 19:16:57 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 19:16:57 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 19:16:57 INFO - 'bloom_basic_ref']}, 19:16:57 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 19:16:57 INFO - 'talos_options': ['--mitmproxy', 19:16:57 INFO - 'mitmproxy-recording-1.mp', 19:16:57 INFO - '--firstNonBlankPaint'], 19:16:57 INFO - 'tests': ['Quantum_1']}, 19:16:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:16:57 INFO - 'tests': ['tsvgx', 19:16:57 INFO - 'tsvgr_opacity', 19:16:57 INFO - 'tart', 19:16:57 INFO - 'tscrollx', 19:16:57 INFO - 'cart', 19:16:57 INFO - 'tsvg_static']}, 19:16:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:16:57 INFO - 'tsvgr_opacity', 19:16:57 INFO - 'tart', 19:16:57 INFO - 'tscrollx', 19:16:57 INFO - 'cart', 19:16:57 INFO - 'tsvg_static']}, 19:16:57 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:16:57 INFO - 'talos_options': ['--disable-e10s'], 19:16:57 INFO - 'tests': ['tp5o']}, 19:16:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:16:57 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:16:57 INFO - 'talos_options': ['--disable-e10s', 19:16:57 INFO - '--xperf_path', 19:16:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:16:57 INFO - 'tests': ['tp5n']}, 19:16:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:16:57 INFO - 'talos_options': ['--xperf_path', 19:16:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:16:57 INFO - 'tests': ['tp5n']}}, 19:16:57 INFO - 'talos.zip': {'path': '', 19:16:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:16:57 INFO - Running post-action listener: _resource_record_post_action 19:16:57 INFO - [mozharness: 2017-06-09 02:16:57.939743Z] Finished populate-webroot step (success) 19:16:57 INFO - [mozharness: 2017-06-09 02:16:57.939881Z] Running create-virtualenv step. 19:16:57 INFO - Running pre-action listener: _resource_record_pre_action 19:16:57 INFO - Running main action method: create_virtualenv 19:16:57 INFO - Creating virtualenv /builds/slave/test/build/venv 19:16:57 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 19:16:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:16:57 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 19:16:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:16:58 INFO - Using real prefix '/tools/python27' 19:16:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:16:58 INFO - Installing distribute.............................................................................................................................................................................................done. 19:17:01 INFO - Installing pip.................done. 19:17:01 INFO - Return code: 0 19:17:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:17:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:17:01 INFO - Reading from file tmpfile_stdout 19:17:01 INFO - Output received: 19:17:01 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 19:17:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:17:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:17:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:17:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:17:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:17:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d5d0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b6bccaee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d326d30>, '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 0x10d2f0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d317be8>, '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.u7NKI1eUWi/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.T4BeyEAOjo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 19:17:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:17:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:17:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u7NKI1eUWi/Render', 19:17:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:17:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:17:01 INFO - 'HOME': '/Users/cltbld', 19:17:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:17:01 INFO - 'LOGNAME': 'cltbld', 19:17:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:17:01 INFO - 'MOZ_NO_REMOTE': '1', 19:17:01 INFO - 'NO_EM_RESTART': '1', 19:17:01 INFO - 'PAGER': '/bin/cat', 19:17:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:17:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:17:01 INFO - 'PWD': '/builds/slave/test', 19:17:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:17:01 INFO - 'SHELL': '/bin/bash', 19:17:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T4BeyEAOjo/Listeners', 19:17:01 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 19:17:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:17:01 INFO - 'USER': 'cltbld', 19:17:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:17:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:17:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:17:01 INFO - 'XPC_FLAGS': '0x0', 19:17:01 INFO - 'XPC_SERVICE_NAME': '0', 19:17:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:17:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:17:02 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 19:17:02 INFO - Cleaning up... 19:17:02 INFO - Return code: 0 19:17:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:17: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')]} 19:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:17: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')]} 19:17:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:17:02 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 0x10d5d0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b6bccaee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d326d30>, '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 0x10d2f0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d317be8>, '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.u7NKI1eUWi/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.T4BeyEAOjo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 19:17:02 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 19:17:02 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 19:17:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u7NKI1eUWi/Render', 19:17:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:17:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:17:02 INFO - 'HOME': '/Users/cltbld', 19:17:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:17:02 INFO - 'LOGNAME': 'cltbld', 19:17:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:17:02 INFO - 'MOZ_NO_REMOTE': '1', 19:17:02 INFO - 'NO_EM_RESTART': '1', 19:17:02 INFO - 'PAGER': '/bin/cat', 19:17:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:17:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:17:02 INFO - 'PWD': '/builds/slave/test', 19:17:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:17:02 INFO - 'SHELL': '/bin/bash', 19:17:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T4BeyEAOjo/Listeners', 19:17:02 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 19:17:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:17:02 INFO - 'USER': 'cltbld', 19:17:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:17:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:17:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:17:02 INFO - 'XPC_FLAGS': '0x0', 19:17:02 INFO - 'XPC_SERVICE_NAME': '0', 19:17:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:17:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:17:02 INFO - Downloading/unpacking psutil>=3.1.1 19:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:17:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:17:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:17:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:17:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:17:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:17:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:17:04 INFO - Installing collected packages: psutil 19:17:04 INFO - Running setup.py install for psutil 19:17:05 INFO - building 'psutil._psutil_osx' extension 19:17:05 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 19:17:05 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 19:17:05 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 19:17:05 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 19:17:05 INFO - building 'psutil._psutil_posix' extension 19:17:05 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 19:17:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:17:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:17:05 INFO - ^ 19:17:05 INFO - 1 warning generated. 19:17:05 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 19:17:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:17:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:17:06 INFO - Successfully installed psutil 19:17:06 INFO - Cleaning up... 19:17:06 INFO - Return code: 0 19:17:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:17:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:17:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:17:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:17:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:17:06 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 0x10d5d0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b6bccaee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d326d30>, '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 0x10d2f0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d317be8>, '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.u7NKI1eUWi/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.T4BeyEAOjo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 19:17:06 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 19:17:06 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 19:17:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u7NKI1eUWi/Render', 19:17:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:17:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:17:06 INFO - 'HOME': '/Users/cltbld', 19:17:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:17:06 INFO - 'LOGNAME': 'cltbld', 19:17:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:17:06 INFO - 'MOZ_NO_REMOTE': '1', 19:17:06 INFO - 'NO_EM_RESTART': '1', 19:17:06 INFO - 'PAGER': '/bin/cat', 19:17:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:17:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:17:06 INFO - 'PWD': '/builds/slave/test', 19:17:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:17:06 INFO - 'SHELL': '/bin/bash', 19:17:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T4BeyEAOjo/Listeners', 19:17:06 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 19:17:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:17:06 INFO - 'USER': 'cltbld', 19:17:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:17:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:17:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:17:06 INFO - 'XPC_FLAGS': '0x0', 19:17:06 INFO - 'XPC_SERVICE_NAME': '0', 19:17:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:17:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:17:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:17:06 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 19:17:06 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 19:17:06 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 19:17:06 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 19:17:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:17:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:17:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:17:07 INFO - Installing collected packages: mozsystemmonitor 19:17:07 INFO - Running setup.py install for mozsystemmonitor 19:17:08 INFO - Successfully installed mozsystemmonitor 19:17:08 INFO - Cleaning up... 19:17:08 INFO - Return code: 0 19:17:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:17:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:17:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:17:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:17: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', '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 0x10d5d0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b6bccaee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d326d30>, '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 0x10d2f0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d317be8>, '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.u7NKI1eUWi/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.T4BeyEAOjo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 19:17: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:17: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 jsonschema==2.5.1 19:17:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u7NKI1eUWi/Render', 19:17:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:17:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:17:08 INFO - 'HOME': '/Users/cltbld', 19:17:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:17:08 INFO - 'LOGNAME': 'cltbld', 19:17:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:17:08 INFO - 'MOZ_NO_REMOTE': '1', 19:17:08 INFO - 'NO_EM_RESTART': '1', 19:17:08 INFO - 'PAGER': '/bin/cat', 19:17:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:17:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:17:08 INFO - 'PWD': '/builds/slave/test', 19:17:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:17:08 INFO - 'SHELL': '/bin/bash', 19:17:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T4BeyEAOjo/Listeners', 19:17:08 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 19:17:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:17:08 INFO - 'USER': 'cltbld', 19:17:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:17:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:17:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:17:08 INFO - 'XPC_FLAGS': '0x0', 19:17:08 INFO - 'XPC_SERVICE_NAME': '0', 19:17:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:17:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:17:08 INFO - Downloading/unpacking jsonschema==2.5.1 19:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:17:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:17:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:17:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:17:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:17: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 19:17: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 19:17: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 19:17: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 19:17:10 INFO - Downloading functools32-3.2.3-2.tar.gz 19:17:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:17:10 INFO - warning: no files found matching '*.txt' 19:17:10 INFO - no previously-included directories found matching 'build' 19:17:10 INFO - no previously-included directories found matching 'dist' 19:17:10 INFO - no previously-included directories found matching '.git*' 19:17:10 INFO - Installing collected packages: jsonschema, functools32 19:17:10 INFO - Running setup.py install for functools32 19:17:10 INFO - warning: no files found matching '*.txt' 19:17:10 INFO - no previously-included directories found matching 'build' 19:17:10 INFO - no previously-included directories found matching 'dist' 19:17:10 INFO - no previously-included directories found matching '.git*' 19:17:10 INFO - Successfully installed jsonschema functools32 19:17:10 INFO - Cleaning up... 19:17:10 INFO - Return code: 0 19:17:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:17:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:17:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:17:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:17:10 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 0x10d5d0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b6bccaee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d326d30>, '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 0x10d2f0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d317be8>, '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.u7NKI1eUWi/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.T4BeyEAOjo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 19:17:10 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 19:17:10 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 19:17:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u7NKI1eUWi/Render', 19:17:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:17:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:17:10 INFO - 'HOME': '/Users/cltbld', 19:17:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:17:10 INFO - 'LOGNAME': 'cltbld', 19:17:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:17:10 INFO - 'MOZ_NO_REMOTE': '1', 19:17:10 INFO - 'NO_EM_RESTART': '1', 19:17:10 INFO - 'PAGER': '/bin/cat', 19:17:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:17:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:17:10 INFO - 'PWD': '/builds/slave/test', 19:17:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:17:10 INFO - 'SHELL': '/bin/bash', 19:17:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T4BeyEAOjo/Listeners', 19:17:10 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 19:17:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:17:10 INFO - 'USER': 'cltbld', 19:17:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:17:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:17:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:17:10 INFO - 'XPC_FLAGS': '0x0', 19:17:10 INFO - 'XPC_SERVICE_NAME': '0', 19:17:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:17:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:17:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:17:10 INFO - Cleaning up... 19:17:10 INFO - Return code: 0 19:17:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:17:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:17:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:17:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:17:10 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 0x10d5d0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b6bccaee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d326d30>, '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 0x10d2f0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d317be8>, '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.u7NKI1eUWi/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.T4BeyEAOjo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 19:17:10 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 19:17:10 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 19:17:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u7NKI1eUWi/Render', 19:17:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:17:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:17:10 INFO - 'HOME': '/Users/cltbld', 19:17:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:17:10 INFO - 'LOGNAME': 'cltbld', 19:17:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:17:10 INFO - 'MOZ_NO_REMOTE': '1', 19:17:10 INFO - 'NO_EM_RESTART': '1', 19:17:10 INFO - 'PAGER': '/bin/cat', 19:17:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:17:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:17:10 INFO - 'PWD': '/builds/slave/test', 19:17:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:17:10 INFO - 'SHELL': '/bin/bash', 19:17:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T4BeyEAOjo/Listeners', 19:17:10 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 19:17:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:17:10 INFO - 'USER': 'cltbld', 19:17:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:17:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:17:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:17:10 INFO - 'XPC_FLAGS': '0x0', 19:17:10 INFO - 'XPC_SERVICE_NAME': '0', 19:17:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:17:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:17:10 INFO - Downloading/unpacking blobuploader==1.2.4 19:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:17:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:17:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:17:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:17:12 INFO - Downloading blobuploader-1.2.4.tar.gz 19:17:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:17:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:17: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 19:17: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 19:17: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 19:17: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 19:17:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:17:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:17: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 19:17: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 19:17: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 19:17: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 19:17:13 INFO - Downloading docopt-0.6.1.tar.gz 19:17:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:17:13 INFO - Installing collected packages: blobuploader, requests, docopt 19:17:13 INFO - Running setup.py install for blobuploader 19:17:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:17:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:17:13 INFO - Running setup.py install for requests 19:17:14 INFO - Running setup.py install for docopt 19:17:14 INFO - Successfully installed blobuploader requests docopt 19:17:14 INFO - Cleaning up... 19:17:14 INFO - Return code: 0 19:17:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:17: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')]} 19:17:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:17: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')]} 19:17:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:17:14 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 0x10d5d0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b6bccaee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d326d30>, '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 0x10d2f0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d317be8>, '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.u7NKI1eUWi/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.T4BeyEAOjo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 19:17:14 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 19:17:14 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 19:17:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u7NKI1eUWi/Render', 19:17:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:17:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:17:14 INFO - 'HOME': '/Users/cltbld', 19:17:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:17:14 INFO - 'LOGNAME': 'cltbld', 19:17:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:17:14 INFO - 'MOZ_NO_REMOTE': '1', 19:17:14 INFO - 'NO_EM_RESTART': '1', 19:17:14 INFO - 'PAGER': '/bin/cat', 19:17:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:17:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:17:14 INFO - 'PWD': '/builds/slave/test', 19:17:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:17:14 INFO - 'SHELL': '/bin/bash', 19:17:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T4BeyEAOjo/Listeners', 19:17:14 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 19:17:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:17:14 INFO - 'USER': 'cltbld', 19:17:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:17:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:17:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:17:14 INFO - 'XPC_FLAGS': '0x0', 19:17:14 INFO - 'XPC_SERVICE_NAME': '0', 19:17:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:17:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:17:14 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-PHH0Af-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:17:14 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-N1ey87-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:17:14 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-VZReoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:17:14 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-NUdL7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:17:14 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-bNmtMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:17:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:17:14 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-kZGGZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:17:15 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-Vd01qd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:17:15 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-rECB0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:17:15 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-G1oG4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:17:15 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-3NjLld-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:17:15 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-eaMWo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:17:15 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-IkrYLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:17:15 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-EjdEVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:17:15 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-pVoRUP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:17:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:17:15 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-hjG0cW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:17:16 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-x5VAkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:17:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:17:16 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-chDcGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:17:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:17:16 INFO - Running setup.py install for manifestparser 19:17:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:17:16 INFO - Running setup.py install for mozcrash 19:17:16 INFO - Running setup.py install for mozdebug 19:17:16 INFO - Running setup.py install for mozdevice 19:17:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:17:16 INFO - Running setup.py install for mozfile 19:17:16 INFO - Running setup.py install for mozhttpd 19:17:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:17:17 INFO - Running setup.py install for mozinfo 19:17:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:17:17 INFO - Running setup.py install for mozInstall 19:17:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:17:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:17:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:17:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:17:17 INFO - Running setup.py install for mozleak 19:17:17 INFO - Running setup.py install for mozlog 19:17:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:17:17 INFO - Running setup.py install for moznetwork 19:17:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:17:17 INFO - Running setup.py install for mozprocess 19:17:18 INFO - Running setup.py install for mozprofile 19:17:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:17:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:17:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:17:18 INFO - Running setup.py install for mozrunner 19:17:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:17:18 INFO - Running setup.py install for mozscreenshot 19:17:18 INFO - Running setup.py install for moztest 19:17:18 INFO - Running setup.py install for mozversion 19:17:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:17:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:17:18 INFO - Cleaning up... 19:17:18 INFO - Return code: 0 19:17:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:17:18 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')]} 19:17:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:17:18 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')]} 19:17:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:17:18 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 0x10d5d0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b6bccaee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d326d30>, '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 0x10d2f0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d317be8>, '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.u7NKI1eUWi/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.T4BeyEAOjo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 19:17:18 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 19:17:18 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 19:17:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u7NKI1eUWi/Render', 19:17:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:17:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:17:18 INFO - 'HOME': '/Users/cltbld', 19:17:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:17:18 INFO - 'LOGNAME': 'cltbld', 19:17:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:17:18 INFO - 'MOZ_NO_REMOTE': '1', 19:17:18 INFO - 'NO_EM_RESTART': '1', 19:17:18 INFO - 'PAGER': '/bin/cat', 19:17:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:17:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:17:18 INFO - 'PWD': '/builds/slave/test', 19:17:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:17:18 INFO - 'SHELL': '/bin/bash', 19:17:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T4BeyEAOjo/Listeners', 19:17:18 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 19:17:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:17:18 INFO - 'USER': 'cltbld', 19:17:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:17:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:17:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:17:18 INFO - 'XPC_FLAGS': '0x0', 19:17:18 INFO - 'XPC_SERVICE_NAME': '0', 19:17:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:17:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:17:19 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-xvIT4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:17:19 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)) 19:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:17:19 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-Vj0WqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:17:19 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)) 19:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:17:19 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-oin0Q8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:17:19 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)) 19:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:17:19 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-AQwm7s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:17:19 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)) 19:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:17:19 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-Lo5MNo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:17:19 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)) 19:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:17:19 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-wApuLd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:17:19 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)) 19:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:17:19 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-zpfiCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:17:19 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)) 19:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:17:19 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-VKfdWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:17:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 19:17:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:17:19 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-gonZHq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:17:20 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)) 19:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:17:20 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-sc9TFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:17:20 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)) 19:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:17:20 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-L0Asyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:17:20 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)) 19:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:17:20 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-vCzJNp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:17:20 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)) 19:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:17:20 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-cEIW50-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:17:20 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)) 19:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:17:20 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-VQSGN6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:17:20 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)) 19:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:17:20 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-7yGFhH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:17:20 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)) 19:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:17:20 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-vLJUad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:17:20 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)) 19:17:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:17:20 INFO - Running setup.py (path:/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/pip-KcEmdh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:17:20 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)) 19:17:20 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)) 19:17:20 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)) 19:17:20 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)) 19:17:20 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)) 19:17:20 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)) 19:17:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:17:20 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 19:17:20 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 19:17:20 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 19:17:20 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 19:17:22 INFO - Downloading blessings-1.6.tar.gz 19:17:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:17:22 INFO - Installing collected packages: blessings 19:17:22 INFO - Running setup.py install for blessings 19:17:22 INFO - Successfully installed blessings 19:17:22 INFO - Cleaning up... 19:17:22 INFO - Return code: 0 19:17:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:17:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:17:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:17:22 INFO - Reading from file tmpfile_stdout 19:17:22 INFO - Current package versions: 19:17:22 INFO - blessings == 1.6 19:17:22 INFO - blobuploader == 1.2.4 19:17:22 INFO - docopt == 0.6.1 19:17:22 INFO - functools32 == 3.2.3-2 19:17:22 INFO - jsonschema == 2.5.1 19:17:22 INFO - manifestparser == 1.1 19:17:22 INFO - mozInstall == 1.12 19:17:22 INFO - mozcrash == 1.0 19:17:22 INFO - mozdebug == 0.1 19:17:22 INFO - mozdevice == 0.51 19:17:22 INFO - mozfile == 1.2 19:17:22 INFO - mozhttpd == 0.7 19:17:22 INFO - mozinfo == 0.10 19:17:22 INFO - mozleak == 0.1 19:17:22 INFO - mozlog == 3.4 19:17:22 INFO - moznetwork == 0.27 19:17:22 INFO - mozprocess == 0.25 19:17:22 INFO - mozprofile == 0.28 19:17:22 INFO - mozrunner == 6.13 19:17:22 INFO - mozscreenshot == 0.1 19:17:22 INFO - mozsystemmonitor == 0.3 19:17:22 INFO - moztest == 0.8 19:17:22 INFO - mozversion == 1.4 19:17:22 INFO - psutil == 3.1.1 19:17:22 INFO - requests == 1.2.3 19:17:22 INFO - wsgiref == 0.1.2 19:17:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:17:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:17:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:17:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:17:22 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 0x10d5d0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b6bccaee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d326d30>, '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 0x10d2f0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d317be8>, '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.u7NKI1eUWi/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.T4BeyEAOjo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 19:17:22 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 19:17:22 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 19:17:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u7NKI1eUWi/Render', 19:17:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:17:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:17:22 INFO - 'HOME': '/Users/cltbld', 19:17:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:17:22 INFO - 'LOGNAME': 'cltbld', 19:17:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:17:22 INFO - 'MOZ_NO_REMOTE': '1', 19:17:22 INFO - 'NO_EM_RESTART': '1', 19:17:22 INFO - 'PAGER': '/bin/cat', 19:17:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:17:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:17:22 INFO - 'PWD': '/builds/slave/test', 19:17:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:17:22 INFO - 'SHELL': '/bin/bash', 19:17:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T4BeyEAOjo/Listeners', 19:17:22 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 19:17:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:17:22 INFO - 'USER': 'cltbld', 19:17:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:17:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:17:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:17:22 INFO - 'XPC_FLAGS': '0x0', 19:17:22 INFO - 'XPC_SERVICE_NAME': '0', 19:17:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:17:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:17:23 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)) 19:17:23 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)) 19:17:23 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)) 19:17:23 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)) 19:17:23 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)) 19:17:23 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)) 19:17:23 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)) 19:17:23 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)) 19:17:23 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)) 19:17:23 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 19:17:23 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 19:17:23 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 19:17:23 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 19:17:23 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 19:17:24 INFO - Downloading marionette_driver-1.4.0.tar.gz 19:17:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 19:17:24 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)) 19:17:24 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)) 19:17:24 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)) 19:17:24 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)) 19:17:24 INFO - Installing collected packages: marionette-driver 19:17:24 INFO - Running setup.py install for marionette-driver 19:17:24 INFO - Successfully installed marionette-driver 19:17:24 INFO - Cleaning up... 19:17:24 INFO - Return code: 0 19:17:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:17:24 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')]} 19:17:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:17:24 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')]} 19:17:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:17:24 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 0x10d5d0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d5d4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b6bccaee0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d326d30>, '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 0x10d2f0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d317be8>, '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.u7NKI1eUWi/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.T4BeyEAOjo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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 19:17:24 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 19:17:24 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 19:17:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u7NKI1eUWi/Render', 19:17:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:17:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:17:24 INFO - 'HOME': '/Users/cltbld', 19:17:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:17:24 INFO - 'LOGNAME': 'cltbld', 19:17:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:17:24 INFO - 'MOZ_NO_REMOTE': '1', 19:17:24 INFO - 'NO_EM_RESTART': '1', 19:17:24 INFO - 'PAGER': '/bin/cat', 19:17:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:17:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:17:24 INFO - 'PWD': '/builds/slave/test', 19:17:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:17:24 INFO - 'SHELL': '/bin/bash', 19:17:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T4BeyEAOjo/Listeners', 19:17:24 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 19:17:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:17:24 INFO - 'USER': 'cltbld', 19:17:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:17:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:17:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:17:24 INFO - 'XPC_FLAGS': '0x0', 19:17:24 INFO - 'XPC_SERVICE_NAME': '0', 19:17:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:17:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:17:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:17:25 INFO - Cleaning up... 19:17:25 INFO - Return code: 0 19:17:25 INFO - Running post-action listener: _resource_record_post_action 19:17:25 INFO - Running post-action listener: _start_resource_monitoring 19:17:25 INFO - Starting resource monitoring. 19:17:25 INFO - [mozharness: 2017-06-09 02:17:25.259277Z] Finished create-virtualenv step (success) 19:17:25 INFO - [mozharness: 2017-06-09 02:17:25.260262Z] Running install step. 19:17:25 INFO - Running pre-action listener: _resource_record_pre_action 19:17:25 INFO - Running main action method: install 19:17:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:17:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:17:25 INFO - Reading from file tmpfile_stdout 19:17:25 INFO - Detecting whether we're running mozinstall >=1.0... 19:17:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:17:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:17:25 INFO - Reading from file tmpfile_stdout 19:17:25 INFO - Output received: 19:17:25 INFO - Usage: mozinstall [options] installer 19:17:25 INFO - Options: 19:17:25 INFO - -h, --help show this help message and exit 19:17:25 INFO - -d DEST, --destination=DEST 19:17:25 INFO - Directory to install application into. [default: 19:17:25 INFO - "/builds/slave/test"] 19:17:25 INFO - --app=APP Application being installed. [default: firefox] 19:17:25 INFO - mkdir: /builds/slave/test/build/application 19:17:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:17:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:17:47 INFO - Reading from file tmpfile_stdout 19:17:47 INFO - Output received: 19:17:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:17:47 INFO - Running post-action listener: _resource_record_post_action 19:17:47 INFO - [mozharness: 2017-06-09 02:17:47.932869Z] Finished install step (success) 19:17:47 INFO - [mozharness: 2017-06-09 02:17:47.933025Z] Running setup-mitmproxy step. 19:17:47 INFO - Running pre-action listener: _resource_record_pre_action 19:17:47 INFO - Running main action method: setup_mitmproxy 19:17:47 INFO - Skipping: mitmproxy is not required 19:17:47 INFO - Running post-action listener: _resource_record_post_action 19:17:47 INFO - [mozharness: 2017-06-09 02:17:47.933661Z] Finished setup-mitmproxy step (success) 19:17:47 INFO - [mozharness: 2017-06-09 02:17:47.933794Z] Running run-tests step. 19:17:47 INFO - Running pre-action listener: _resource_record_pre_action 19:17:47 INFO - Running main action method: run_tests 19:17:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:17:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:17:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:17:47 INFO - 2017-06-08 19:17:47.948 screenresolution[1641:9905] starting screenresolution argv=screenresolution get 19:17:47 INFO - 2017-06-08 19:17:47.951 screenresolution[1641:9905] Display 0: 1600x1200x32@60 19:17:47 INFO - 2017-06-08 19:17:47.957 screenresolution[1642:9908] starting screenresolution argv=screenresolution list 19:17:47 INFO - Available Modes on Display 0 19:17:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:17:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:17:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:17:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:17:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:17:48 INFO - Intel Iris: 19:17:48 INFO - Chipset Model: Intel Iris 19:17:48 INFO - Type: GPU 19:17:48 INFO - Bus: Built-In 19:17:48 INFO - VRAM (Dynamic, Max): 1536 MB 19:17:48 INFO - Vendor: Intel (0x8086) 19:17:48 INFO - Device ID: 0x0a2e 19:17:48 INFO - Revision ID: 0x0009 19:17:48 INFO - Displays: 19:17:48 INFO - Display: 19:17:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:17:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:17:48 INFO - Display Serial Number: 1600x1200 60 19:17:48 INFO - Main Display: Yes 19:17:48 INFO - Mirror: Off 19:17:48 INFO - Online: Yes 19:17:48 INFO - Rotation: Supported 19:17:48 INFO - Return code: 0 19:17:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:17:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:17:48 INFO - Python 2.7.3 19:17:48 INFO - Return code: 0 19:17:48 INFO - grabbing minidump binary from tooltool 19:17:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:17:48 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 0x10d326d30>, '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 0x10d2f0cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d317be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:17:48 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 19:17:48 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 19:17:48 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 19:17:48 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:17:48 INFO - Return code: 0 19:17:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:17:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:17:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:17:48 INFO - ENV: RUST_BACKTRACE is now 1 19:17:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:17:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:17:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0107', '--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 19:17:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0107 --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 19:17:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u7NKI1eUWi/Render', 19:17:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:17:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:17:48 INFO - 'HOME': '/Users/cltbld', 19:17:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:17:48 INFO - 'LOGNAME': 'cltbld', 19:17:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:17:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:17:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:17:48 INFO - 'MOZ_NO_REMOTE': '1', 19:17:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:17:48 INFO - 'NO_EM_RESTART': '1', 19:17:48 INFO - 'PAGER': '/bin/cat', 19:17:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:17:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:17:48 INFO - 'PWD': '/builds/slave/test', 19:17:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:17:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:17:48 INFO - 'RUST_BACKTRACE': '1', 19:17:48 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 19:17:48 INFO - 'SHELL': '/bin/bash', 19:17:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.T4BeyEAOjo/Listeners', 19:17:48 INFO - 'TMPDIR': '/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/', 19:17:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:17:48 INFO - 'USER': 'cltbld', 19:17:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:17:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:17:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:17:48 INFO - 'XPC_FLAGS': '0x0', 19:17:48 INFO - 'XPC_SERVICE_NAME': '0', 19:17:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:17:48 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0107', '--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 19:17:48 INFO - mozversion application_buildid: 20170608181444 19:17:48 INFO - mozversion application_changeset: b42d50cafb154e3e50fdde3ca853635a000cb219 19:17:48 INFO - mozversion application_display_name: Nightly 19:17:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:17:48 INFO - mozversion application_name: Firefox 19:17:48 INFO - mozversion application_remotingname: firefox 19:17:48 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:17:48 INFO - mozversion application_vendor: Mozilla 19:17:48 INFO - mozversion application_version: 55.0a1 19:17:48 INFO - mozversion platform_buildid: 20170608181444 19:17:48 INFO - mozversion platform_changeset: b42d50cafb154e3e50fdde3ca853635a000cb219 19:17:48 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:17:48 INFO - mozversion platform_version: 55.0a1 19:17:48 INFO - using testdate: 1496974668 19:17:48 INFO - actual date: 1496974668 19:17:48 INFO - starting webserver on 'localhost:49229' 19:17:48 INFO - SUITE-START | Running 2 tests 19:17:48 INFO - TEST-START | dromaeo_css 19:17:48 INFO - operating with platform_type : mac_ 19:17:48 INFO - Initialising browser for dromaeo_css test... 19:17:48 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmpshxx7N/profile 19:17:48 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 19:18:22 INFO - TEST-INFO | 1652: killed by SIGTERM 19:18:22 INFO - Browser initialized. 19:18:22 INFO - Running cycle 1/1 for dromaeo_css test... 19:18:22 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmpshxx7N/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:18:24 INFO - PID 1661 | 2017-06-08 19:18:24.736 plugin-container[1662:10561] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 19:18:24 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:18:24 INFO - PID 1661 | 2017-06-08 19:18:24.737 plugin-container[1662:10561] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 19:18:24 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:18:24 INFO - PID 1661 | 2017-06-08 19:18:24.737 plugin-container[1662:10561] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:18:25 INFO - PID 1661 | 2017-06-08 19:18:25.203 plugin-container[1663:10610] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 19:18:25 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:18:25 INFO - PID 1661 | 2017-06-08 19:18:25.204 plugin-container[1663:10610] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 19:18:25 INFO - PID 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:18:25 INFO - PID 1661 | 2017-06-08 19:18:25.204 plugin-container[1663:10610] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:18:34 INFO - PID 1661 | RSS: Main: 253333504 19:18:34 INFO - PID 1661 | 19:21:36 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49229/tests/dromaeo/cssquery-ext.html) 19:21:38 INFO - PID 1661 | RSS: Main: 262680576 19:21:38 INFO - PID 1661 | 19:24:40 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-ext.html (next: http://localhost:49229/tests/dromaeo/cssquery-jquery.html) 19:24:40 INFO - PID 1661 | RSS: Main: 275742720 19:24:40 INFO - PID 1661 | 19:27:42 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49229/tests/dromaeo/cssquery-mootools.html) 19:27:43 INFO - PID 1661 | RSS: Main: 275968000 19:27:43 INFO - PID 1661 | 19:30:44 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49229/tests/dromaeo/cssquery-prototype.html) 19:30:45 INFO - PID 1661 | RSS: Main: 279244800 19:30:45 INFO - PID 1661 | 19:33:48 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49229/tests/dromaeo/cssquery-yui.html) 19:33:49 INFO - PID 1661 | RSS: Main: 278347776 19:33:49 INFO - PID 1661 | 19:36:50 INFO - PID 1661 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-yui.html (next: http://localhost:49229/tests/dromaeo/cssquery-dojo.html) 19:36:51 INFO - PID 1661 | RSS: Main: 279212032 19:36:51 INFO - PID 1661 | 19:36:51 INFO - PID 1661 | __start_tp_report 19:36:51 INFO - PID 1661 | _x_x_mozilla_page_load 19:36:51 INFO - PID 1661 | _x_x_mozilla_page_load_details 19:36:51 INFO - PID 1661 | |i|pagename|runs| 19:36:51 INFO - PID 1661 | |0;dojo.html;3146.9685303146966;3585.5876574193976;3588.766730162541;3612.16920108375;3613.0967258185456;4959.826406075788;6037.456628903404;6073.514118870491;6135.29444113927;6154.538409619279;6114.0540674973245;6122.234720659918;6123.22161559001;6148.35442278561;6181.660008699531;5355.491228333302;5397.190421436777;5417.64785288957;5419.051665958461;5425.240466334716;5444.673319600817;5510.752016159283;5513.50378465938;5514.283143191379;5520;4405.713628614129;5303.575713942875;5431.809886653988;5461.69960652164;5495.587830912677;4775.737334446639;5419.3225846769155;5443.992861320628;5465.6447330923365;5492.340919089695;5376.381716102659;5385.838424847261;5388.73056347185;5396.622236443451;5436.592255580847;6039.607425517372;6078.8784224315705;6148.754049838001;6162.414570615829;6170.537209709245;6543.378379053982;6550.246721627026;6643.335666433366;6660.267370589231;6676;6749.19009718832;6754.8311292217595;6761.290064543201;6768.763093291711;6778.322167783232;6230.7819226326865;6239.500839932794;6251.999680051215;6270.122182894398;6271.466925311308;6685.0640910272605;6760.357766012221;6799;6845.657717114124;6849.212340580797;6218.751249950042;6404.42360187576;6522.086907832907;6602.10871532337;6615.272320044791;6140.657627863787;6298.685065746786;6617.735290588419;6694;6757.797266082025;6624.569402988886;6636.7013484392355;6658.667066646745;6666.100076489709;6677.866442671217;6399.55203135776;6471.611703297723;6571;6620.668966551653;6668.399844013965;4623.3758442609815;5336.813211537577;5350.91973620396;5354.759035843396;5383.838484845461;4392.3411488277015;4703.811847526129;4741.312509686076;4763.309320148559;4770.451398089194;6211.2857021442205;6238.906416403757;6243.250809902841;6288.371162883766;6354.3010268870385;5772.682502462405;6752.83117922046;6802.3537763912345;6803.727850886008;6819.795406137824;5413.296271484682;6347.174867267318;6348.206474190726;6357.396047375492;6392.328805475451;5761.9135712964335;5790.681512516852;5798.59409841307;5860.267466566679;6049.971504844187;6776.728930842711;6830.385265326145;6848.6918088687125;6873.553219040747;6899.17209934811;6732.124823772878;6743.8988415172835;6752.5948443093575;6761.154855643044;6779.491538134561;6641.900371494335;6817.795466136024;6869.793906182823;6888.690008949709;6889.483288753263;6488.221413430419;6528.967355163384;6576.473882089517;6599.868002640016;6648.235452922976;6726.7981960541265;6743.15710536183;6756.69594868242;6772.52592318533;6775.051492791112;4856.611471082234;5066.468020857904;6378.394052564999;6477.8704425912165;6562.442192413699;6541.215054193527;6554.770583029571;6575.210974683068;6595.4393876521035;6601.53789234749;5527.861803454945;6179.28938172116;6236.908541005215;6469.353064693493;6528.37980391862;6372.235331760218;6427.517936154901;6572.244191917799;6587.63767992765;6593.571417857824;6267.2165979252595;6304.71628776697;6324.367563243639;6345.1434056401795;6373.458256048288 19:36:51 INFO - PID 1661 | |1;ext.html;693.4175292754086;790.4269404681606;840.6847432212918;1237.8205160251757;1295.6890346316886;39053.609463905384;40576.79711601445;40592;40598.797006015004;40631.79684101583;40876.38685419721;41085;41089;41089;41155.38266925991;7841.137474877759;8009.479383840061;8258.710945116922;8489.915100848973;8500.829983400328;8418.831623367529;8553.358498112635;8568;8568.357373197005;8624.698135565257;8528.658853645848;8533.74398768038;8553.74338769838;8565.657373705044;8572.442791218582;8188;8541.914580854236;8544.530050847232;8546.230839224469;8568.70009549669;7068.858622827566;8364.958175209145;8380.371472139614;8392.496450212946;8393.580320983987;37872.81063594665;38046.42930356047;38108.42837357442;38133.80933095317;38270.234595307935;3627.147620309225;3668.6514781095752;3670.4861319415036;3672.8714494992814;3676.356637588411;30906.381872362428;30911.845440772653;30948.0715578533;30996.380072398424;31000.379992400252;2628.7371262873753;3108.6580476147833;3110.891118810853;3143.0413723814227;3154.5110507871086;242258;244881;277906.22093779006;290675;291975;50016.24975625369;50295.74852125716;50390.496095039314;50455.24317135246;50473.74763126234;52450;52549.73725131426;52764.736176318875;52816.73591632017;52898.73550632222;14286.428542858377;14417.279136043157;14516.854831451761;14583.635409114857;14604.634884127981;11903.285802851857;11927.343996080126;12026.579069732692;12048.759024819454;12064.939675301743;14317.427302907976;14336.784948225784;14396.920230982718;14403.91970602209;14485.348159332856;5301.416844147141;6239.75040998355;6315;6320.936790632127;6324.683765811783;974.741693451236;1007.2445665750687;1030.5105075089423;1047.3506426015767;1090.8091084060416;20695.79304206939;24949.376265593142;24991.750082499308;25273.62089568658;25509.61735573968;1113;1115.1524841120645;1139.9316041037398;1161.2219812725493;1178.0810967445407;11106.389148596743;12004.579839705568;12910.483580656857;12917.22496650204;12972.545960891324;301156.4942175319;302058;302700.4864975661;303467;303625;51759;52765.88936442499;57660.42339576551;57689.71155144197;58169.70915145397;1243.9875601244053;1486.152892851086;1524.3140586736013;1535.0636111971912;1546.798916140917;19759.802401975798;23221.651675225225;23565.528689425773;24713.505729885663;24757.62863557084;38185.6181438182;39397.2120557593;39478.40782388324;39642.60357396389;39692.40461393024;286221;289136;289172;289750;289888;36048.639513604525;37964.810175948944;38185;38357.65748198911;38461.038474038374;46236.30645540387;46484.53515464938;48344.516554834;48736.75631621819;48869.26696099633;30704.232394190323;30772.230694232825;32359.838200808845;32754.67245327516;32925.83537082299;30937.535936961413;31328.686713133488;31992.52011219787;32477.35045299128;32690;37642.43536346902;38875;39272.41091383689;39427.80286098551;39624.40563391494;30798.84600576983;31292.530612040384;32636;33034.84378046853;33350.332993339514;23593.764062359158;25118.24645260645;25258.535273820933;25477.490450191264;25541.87229063843 19:36:51 INFO - PID 1661 | |2;jquery.html;489.17573887998725;501.9734642655771;502.7335512178544;521.7938914128919;531.1528112660305;21544;21645.567088658216;22414.103435862566;22458.88770556147;24606.630900536507;21331.68002479964;25359.619605705928;25471.36321591961;25482.745172548268;25505.872470637667;6364.077208804721;6487.805365839033;6510.772122975697;6511.772087976922;6525.575837570566;6078.39216078393;6409.006603976368;6468.417842394184;6482.805515834532;6485.675716214194;6312.368763123697;6335.809925702236;6374.362563743612;6375;6405.647689377082;6352.205974253218;6377.3303803100935;6378.138951241569;6378.681065946684;6390.808275751735;5782.595218334718;5818.767249310023;5843.240378750777;5843.474087332118;5849.736761845727;29204.269893252625;29719.702802972126;29825.70174298251;29857.95497157611;29947.951821686354;2216.4569680428235;2258.108047321315;2259.20927675314;2260.8191344692386;2270.1713874435713;18888.24447022131;19497.707534386995;19528.31650892226;19550.022498874998;19551.706724399002;2199.8790066546494;2228.8551244169075;2258.954820903573;2260.785225403584;2269.6482045283005;173713;181896;197772;205560.91658625132;206777;13910.374033168531;14502.32149641543;15281.9235903822;15471.613209669847;15698.215089245492;20299.594008119755;20383.286584969454;20384.796152038587;20397.796022039885;20411.285605003755;9980.201583873273;9983.700488985196;9997.450140242356;9999.450030248261;10031.949840250752;9058.728238152866;9144.405613635092;9161.221296189899;9181.862272065924;9244.953775231215;8374.455660382057;8605.225529702231;9113.81772364549;9223.76940576491;9264.39781414206;2621;2634.4467661790854;2640;2644.1803041057333;2766.322251048506;957.5116690487902;970.1171933540444;971.4656938683837;979.4564016970508;984.493724601361;20715.378538643865;21191.57616847685;21584.892075539523;21620.56758864814;21762.455938601346;978.6721448314752;988.2094324540483;1024.8155332040155;1028.681108856257;1031.4275577054657;10689.518971646294;10838.024577788037;11236.438178091063;11255.324680519196;11492.540298388138;545271;551175;552397;562936;563735;143420;148347.54957351298;148579;149472;150351;1010.2978429991233;1027.2244455436248;1047.7275908263907;1187.8752730963297;1222.896053835434;21938.903054846876;22397.104115835835;23232.76767232374;23457.765422345557;23487.295381138592;66222;67304;68815.62368752497;68851;70699.64650176716;74178.77463676098;86876;91943;92081;92545;11154.72113197144;11414.771704566028;11551.76896462083;11571.6528504145;11574.305541667527;17958;18250.9049457033;18411.723824142915;18493.53766155857;18535.165917533475;14448.855511445172;14853.257337133316;14941.252937352872;14945.626359341104;15027.924860375628;11107.944460277648;12967.740645186856;13210.075294729277;13506.797398039234;13662.453501859814;14828.481003164838;15104.924475377553;15132.319045642753;15254.618634534225;15273.389064437742;11113.944430278121;11344.319340839576;11376.658700239006;11393.031592314746;11408.942955285502;12488.75022499563;14721.631959201106;14865;15019.32413041394;15047.849521505083 19:36:51 INFO - PID 1661 | |3;mootools.html;1264.6901509130264;1441.963950901228;1448.3337664674257;1453.9709205815884;1462.7805829125632;12903.806442903364;12953.22280663161;13455.932720336397;13481.797773033413;13590.116642418261;13300.600981970556;13406.061575689706;13530.93234533826;13533.864661353384;13535;2701.2166471723226;2759.434315965232;2762.406082692221;2762.875670594818;2766.9723302766915;2671.225344650049;2702.9459410811774;2703.0539311241005;2711.227300219438;2719.3201699575106;2678.08944958714;2679.517686816372;2691.6904555976116;2697.1908427471776;2707.7021527631946;2267.9340709866337;2665.986670066647;2675.5852842809395;2676.264027392463;2677.437738075016;2236.1390864517116;2332.5801355755957;2642.4979253941883;2651.244395347336;2654.349684327333;13114.082014259006;13126.278054706987;13225.206487610774;13242.404091815892;13258.337083145803;7246.855062898765;7528.435367347416;7529.887051694229;7532.284432978913;7532.7740167795055;4057.16828050249;4253.3619957006385;4291.077418355068;4294.377315289265;4320.956790432087;1218.4760552962223;1390.1103293891917;1395.0513650717508;1416.624594482463;1420.2685616907302;19486.902565487224;19531.902340488206;19658.311959081362;19665.80334196654;19715.704264436044;6105.267367915879;6157.7229024694;6193.194884664965;6267.4986001119805;6274.403931626488;13900.16599004063;13916.165030098227;13930.721385572231;13939.303034848217;13957.720845583031;6582.934170658328;7034.577925324497;7058.964705176441;7253.709851605982;7292.744753933587;6517.576357536745;6675.7997260082275;6731.461483081342;6773.051772751819;6779.694913728894;4875.414950205998;4996.850094497171;5064.924026139611;5071.39143302806;5111.616628752859;1461.2328527523136;1462.978055329171;1463.6706740983193;1485.4652325162933;1485.8959872809016;825.7902173316;831.6507067031861;860.4063196394468;867.0765634599131;895.552223888056;6975.372216500539;7110;7777.794441861521;7861.685532578633;7912.446128771046;677.5866721300004;680.4012469027323;686.2965460403027;692.8891377379595;699.4928676709345;4551.908961820745;4756;5026.29631851541;5248.711320877388;5572.4706152915405;17421;17945.551361216076;17971.460856174337;17995;18363.63272734538;17452.738208926876;18060.18729157165;18221.362252321105;18222.908885455487;18305.359312424;7850.411219158586;8866.77833054166;9240.214581760625;9367.969523352289;9421.24630029596;8122.472039317426;8192.508449493049;8198.303144232568;8225.54746682687;8485.787855303666;15911.522654319926;15955.20223988819;15991.040537567784;16086.597835054217;16207.594810129842;15853.207339633203;16280.918595406947;16300.42948496797;16313;16338.183090845649;10384.948075259877;10674.252802303765;10846.891531084588;10891.891081089405;10903;12466.5013399463;12643.98848092132;12650.177738446971;12658.11393202467;12744.299063551594;9951.253655975976;10128.898711013091;10131.848022279819;10253.58985640566;10393.16854651611;9888.159506442033;9908.504574771088;9964.950175249078;10004.299699021;10031.69904902854;9033.638654454011;9095.863562046443;9217.124373184537;9230.584623691815;9232.630694772133;6282.780102696383;6450.870982580416;6548.476121910331;6959.89560156588;7131.215566287808;5822.446867547576;6789.762358317435;6901.378875901193;7205.819854503679;7271.272872712686 19:36:51 INFO - PID 1661 | |4;prototype.html;448.8092560661719;450.91432627800714;460.3646967185282;461.24586301397204;471.71461265934107;6076.994591784712;6501.284858665549;6506.837329066776;6549.607023578576;6567.3760992705675;6162.537809664271;6359.68201589921;6373.267074286458;6421.7752378666755;6425.775097871576;3752.4746535485056;4218.303979843337;4772.68977516462;4804;4819.42166939966;4077.5718549552316;4791.113643975858;4828.396450443694;4841.24960631101;4849.272609108644;3725.0501122213795;4400.339949007658;4706.152892479352;4752.310914917335;4765;4760.523947605212;4762.047590481918;4769.618430525549;4780.713157210561;4787.521247875219;3845.8461661533506;4183.372494125875;4189.371594260855;4202.432671589358;4217.325227963542;6315.179026726542;6325.746970121189;6343.936560634381;6357.696672182192;6360.427561519439;5120.718360490171;5478.986387518322;5728.656280623176;5746.367899531048;5766.221560089376;6392.584482008655;6454.354139694395;6860.6912688929115;6872.347127022975;6882.139732533434;4378.978105109486;4413.646908247332;4663.930041049387;4788.928166077511;4791.760411979387;6980.790576282669;7245.927540724578;7324.926750732478;7412.332890039868;7416.7404140854815;4224.936625950613;4722.763861806896;4754.358161648202;4817.590504807131;4822.879428014257;4671.9254571448755;4920.21276595743;4948.851534453972;4958.925616115761;4974.452810190876;4373.6282415994365;4577.404937358122;5666.4050274721385;5671.773129074873;5683.573731970118;6858.348456896587;6919.135108111486;6943.618101004392;6958.199807022156;6960.965195173992;1687.6540309236577;1703.173960629094;1706.3942300483398;1706.4368758309977;1716.2534297580592;1107.291333546531;1122.5397586989295;1151.9366434846163;1154.5381847260994;1157.9710507237387;6428.260750013808;6555.737770489127;6582.243042050225;6585.904624826324;6617.834554136089;5899.852503687356;6469.385408386196;6598.934010659928;6763.594184348856;7208.927910720826;6566.507511936528;6571.835704107436;6573.671316434159;6574.750797348488;6590.670466476753;3446.7070299024363;3705.166337574078;3747.3254814133174;3748.4002559590335;3753.2868754936476;7388.371988381048;7394.889076663854;7405.592692401862;7411.962940185373;7416.480846340704;6002.519798416116;6666.0667506549125;6933.826654333682;7381.557106573516;7404.407647388196;7027.578345299298;7190.7483238086415;7345.375643070292;7383.07711536058;7388.7783366498015;7174.892376614459;7190.568565886064;7191.82020449472;7205;7205.567665940061;6215.316315205414;6575.736970521126;7022.403095736715;7059.752908648173;7066.434685225273;6201.503879689704;6324.177856878577;7048.541844780073;7391.926080739339;7460.477766556289;4383.408239887574;4384.912301753883;4655.882588178881;4912.606991440604;4915.459299476983;6038.788642397495;7023.613701246481;7211.531250468703;7216.134063912364;7317.8902316466365;6433.388828061326;6921.2732663071665;6951.826204344932;7020.508564400443;7033.859322813618;6386.329435409401;6927.35866969142;6932.064171336805;6940.7917762467205;6977.302269773185;6802.795916122524;6817.965910170616;6854.246032936273;6867.175938887365;6937.479689023445;5325.387580428301;6675.299093595103;6963.502846887944;6982.057422247932;7004.929950700632;6746.831329216612;6753.5610185339765;6753.898691519729;6770.729170833111;6795.359851323488 19:36:51 INFO - PID 1661 | |5;yui.html;818.67662273402;819.569725893906;832.6003518311206;855.6096343441907;859.2352806002659;14742.26288685564;14900.77648835267;15387.846121538782;16824.579385515368;17049.65900681987;16082.758758618631;17981.01104439255;18064.916105033673;18194.727079093824;18303;965.5365424596181;992.082323850439;1008.7326858382535;1009.167436864586;1010.4291075542332;1008.9848652270222;1022.0239671114068;1022.5091955861191;1023.9283250172491;1026.5226669598635;964.7973925475659;1073.5759375046853;1077.7467295185625;1079.6005477973122;1080.2384319055047;1072.099436473366;1073.2969904712427;1075.2849355178796;1077.3535878472933;1078.8273876179774;761.5849362097615;770.3028758973138;776.4875182379602;777.4091690315345;778.1284960843834;17283.481495555152;17689.38087166956;17754.822451775446;17935.910320448445;18030.18864151103;16998.575035624086;17148;17314.04772737499;17417.39039133624;17477.475675729747;14540;14680.97233193677;14767.335469903881;14841.851581484156;14877.702445951021;3257.7719559630837;3350.3801796667694;3556.448750443685;3588.067102553329;3597.9460308095136;98436.50781746117;125081;138486;139307.91038134435;140996;13573.25347105899;13656.93171534136;13728.244946527837;13743.931280343535;13817.170969741848;21951.780482195292;23339.88330058373;26398.6040209397;26715;26920.730792692215;6462.256840463407;7822.530648161129;7927.3658107351275;8012.5192488450875;8064.959675201587;3900.1224724436775;3941.0935484838647;3981.343078392091;3986.8405263789123;3987.980060099739;4573.131105090022;4641.767911604474;4664.39362882823;4678.345031695565;4945.332380128709;903.1690844423147;1019.5412064570972;1053.115383078218;1054.836500342448;1054.8997845204694;646.0954663471178;646.5668002438283;647.2847503508649;667.6695035957135;678.3216783216783;11526.481308341145;11702.941485292518;11862.762744745056;12118.151729379035;12362.876371236352;620.525298146918;643.7907680003924;663.6582160187507;664.8404382948154;665.8368699668612;5234.712090834964;5305.177697456823;5425.643589102724;5664.773409063674;5958.970205148947;125022;126986.36506817407;127305.3634731839;133374;144477;116553;116826;116916;117357;119833.40083299528;103585;109075;110335.37987720763;110940.33589496164;113500.86499135068;12038.458269377725;12306.077044221898;12326.50693972231;12503.812442813183;13248.668783280495;45114.87212819575;45697.074440187396;47679.28481072717;47777.761111194224;48207.75896120497;66259.00611490734;66877.66561167163;67230;67407;67426.325736742;16733.665326693153;16995.320187192374;17092.914535427244;17392.47822565325;17554.034528100565;18823.247070117042;19090.52273693169;19120.904395477934;19128.521786955436;19132.713009304593;17656.117194140497;17853;18252.36116735961;18255.995920223984;18384.264629414673;14568.34442450071;17548.298068077645;17567.912160439115;17661.735073974156;17837.554061148574;16662.666746664756;17422.564435889206;17498.387556435464;17570.648587028445;18061.096945152953;10134.949325253327;11709.765804683688;11810.586629467927;12172.513099475922;12327.93836030814;13495.25776082325;13607.659808504866;14033.719325613634;14095.506657267357;14120.152790832582 19:36:51 INFO - PID 1661 | __end_tp_report 19:36:51 INFO - PID 1661 | __start_cc_report 19:36:51 INFO - PID 1661 | _x_x_mozilla_cycle_collect,455 19:36:51 INFO - PID 1661 | __end_cc_report 19:36:51 INFO - PID 1661 | __startTimestamp1496975811118__endTimestamp 19:36:51 INFO - PID 1661 | 19:36:51 INFO - PID 1661 | ------- Summary: start ------- 19:36:51 INFO - PID 1661 | Number of tests: 6 19:36:51 INFO - PID 1661 | 19:36:51 INFO - PID 1661 | [#0] dojo.html Cycles:165 Average:6092.24 Median:6345.14 stddev:761.83 (12.0%) stddev-sans-first:728.28 19:36:51 INFO - PID 1661 | Values: 3147.0 3585.6 3588.8 3612.2 3613.1 4959.8 6037.5 6073.5 6135.3 6154.5 6114.1 6122.2 6123.2 6148.4 6181.7 5355.5 5397.2 5417.6 5419.1 5425.2 5444.7 5510.8 5513.5 5514.3 5520.0 4405.7 5303.6 5431.8 5461.7 5495.6 4775.7 5419.3 5444.0 5465.6 5492.3 5376.4 5385.8 5388.7 5396.6 5436.6 6039.6 6078.9 6148.8 6162.4 6170.5 6543.4 6550.2 6643.3 6660.3 6676.0 6749.2 6754.8 6761.3 6768.8 6778.3 6230.8 6239.5 6252.0 6270.1 6271.5 6685.1 6760.4 6799.0 6845.7 6849.2 6218.8 6404.4 6522.1 6602.1 6615.3 6140.7 6298.7 6617.7 6694.0 6757.8 6624.6 6636.7 6658.7 6666.1 6677.9 6399.6 6471.6 6571.0 6620.7 6668.4 4623.4 5336.8 5350.9 5354.8 5383.8 4392.3 4703.8 4741.3 4763.3 4770.5 6211.3 6238.9 6243.3 6288.4 6354.3 5772.7 6752.8 6802.4 6803.7 6819.8 5413.3 6347.2 6348.2 6357.4 6392.3 5761.9 5790.7 5798.6 5860.3 6050.0 6776.7 6830.4 6848.7 6873.6 6899.2 6732.1 6743.9 6752.6 6761.2 6779.5 6641.9 6817.8 6869.8 6888.7 6889.5 6488.2 6529.0 6576.5 6599.9 6648.2 6726.8 6743.2 6756.7 6772.5 6775.1 4856.6 5066.5 6378.4 6477.9 6562.4 6541.2 6554.8 6575.2 6595.4 6601.5 5527.9 6179.3 6236.9 6469.4 6528.4 6372.2 6427.5 6572.2 6587.6 6593.6 6267.2 6304.7 6324.4 6345.1 6373.5 19:36:51 INFO - PID 1661 | 19:36:51 INFO - PID 1661 | [#1] ext.html Cycles:180 Average:44856.68 Median:34699.49 stddev:75218.75 (216.8%) stddev-sans-first:75356.67 19:36:51 INFO - PID 1661 | Values: 693.4 790.4 840.7 1237.8 1295.7 39053.6 40576.8 40592.0 40598.8 40631.8 40876.4 41085.0 41089.0 41089.0 41155.4 7841.1 8009.5 8258.7 8489.9 8500.8 8418.8 8553.4 8568.0 8568.4 8624.7 8528.7 8533.7 8553.7 8565.7 8572.4 8188.0 8541.9 8544.5 8546.2 8568.7 7068.9 8365.0 8380.4 8392.5 8393.6 37872.8 38046.4 38108.4 38133.8 38270.2 3627.1 3668.7 3670.5 3672.9 3676.4 30906.4 30911.8 30948.1 30996.4 31000.4 2628.7 3108.7 3110.9 3143.0 3154.5 242258.0 244881.0 277906.2 290675.0 291975.0 50016.2 50295.7 50390.5 50455.2 50473.7 52450.0 52549.7 52764.7 52816.7 52898.7 14286.4 14417.3 14516.9 14583.6 14604.6 11903.3 11927.3 12026.6 12048.8 12064.9 14317.4 14336.8 14396.9 14403.9 14485.3 5301.4 6239.8 6315.0 6320.9 6324.7 974.7 1007.2 1030.5 1047.4 1090.8 20695.8 24949.4 24991.8 25273.6 25509.6 1113.0 1115.2 1139.9 1161.2 1178.1 11106.4 12004.6 12910.5 12917.2 12972.5 301156.5 302058.0 302700.5 303467.0 303625.0 51759.0 52765.9 57660.4 57689.7 58169.7 1244.0 1486.2 1524.3 1535.1 1546.8 19759.8 23221.7 23565.5 24713.5 24757.6 38185.6 39397.2 39478.4 39642.6 39692.4 286221.0 289136.0 289172.0 289750.0 289888.0 36048.6 37964.8 38185.0 38357.7 38461.0 46236.3 46484.5 48344.5 48736.8 48869.3 30704.2 30772.2 32359.8 32754.7 32925.8 30937.5 31328.7 31992.5 32477.4 32690.0 37642.4 38875.0 39272.4 39427.8 39624.4 30798.8 31292.5 32636.0 33034.8 33350.3 23593.8 25118.2 25258.5 25477.5 25541.9 19:36:51 INFO - PID 1661 | 19:36:51 INFO - PID 1661 | [#2] jquery.html Cycles:180 Average:39106.49 Median:2259.08 stddev:96147.15 (4256.0%) stddev-sans-first:96373.15 19:36:51 INFO - PID 1661 | Values: 489.2 502.0 502.7 521.8 531.2 21544.0 21645.6 22414.1 22458.9 24606.6 21331.7 25359.6 25471.4 25482.7 25505.9 6364.1 6487.8 6510.8 6511.8 6525.6 6078.4 6409.0 6468.4 6482.8 6485.7 6312.4 6335.8 6374.4 6375.0 6405.6 6352.2 6377.3 6378.1 6378.7 6390.8 5782.6 5818.8 5843.2 5843.5 5849.7 29204.3 29719.7 29825.7 29858.0 29948.0 2216.5 2258.1 2259.2 2260.8 2270.2 18888.2 19497.7 19528.3 19550.0 19551.7 2199.9 2228.9 2259.0 2260.8 2269.6 173713.0 181896.0 197772.0 205560.9 206777.0 13910.4 14502.3 15281.9 15471.6 15698.2 20299.6 20383.3 20384.8 20397.8 20411.3 9980.2 9983.7 9997.5 9999.5 10031.9 9058.7 9144.4 9161.2 9181.9 9245.0 8374.5 8605.2 9113.8 9223.8 9264.4 2621.0 2634.4 2640.0 2644.2 2766.3 957.5 970.1 971.5 979.5 984.5 20715.4 21191.6 21584.9 21620.6 21762.5 978.7 988.2 1024.8 1028.7 1031.4 10689.5 10838.0 11236.4 11255.3 11492.5 545271.0 551175.0 552397.0 562936.0 563735.0 143420.0 148347.5 148579.0 149472.0 150351.0 1010.3 1027.2 1047.7 1187.9 1222.9 21938.9 22397.1 23232.8 23457.8 23487.3 66222.0 67304.0 68815.6 68851.0 70699.6 74178.8 86876.0 91943.0 92081.0 92545.0 11154.7 11414.8 11551.8 11571.7 11574.3 17958.0 18250.9 18411.7 18493.5 18535.2 14448.9 14853.3 14941.3 14945.6 15027.9 11107.9 12967.7 13210.1 13506.8 13662.5 14828.5 15104.9 15132.3 15254.6 15273.4 11113.9 11344.3 11376.7 11393.0 11408.9 12488.8 14721.6 14865.0 15019.3 15047.8 19:36:51 INFO - PID 1661 | 19:36:51 INFO - PID 1661 | [#3] mootools.html Cycles:180 Average:8147.80 Median:2699.20 stddev:5405.94 (200.3%) stddev-sans-first:5396.36 19:36:51 INFO - PID 1661 | Values: 1264.7 1442.0 1448.3 1454.0 1462.8 12903.8 12953.2 13455.9 13481.8 13590.1 13300.6 13406.1 13530.9 13533.9 13535.0 2701.2 2759.4 2762.4 2762.9 2767.0 2671.2 2702.9 2703.1 2711.2 2719.3 2678.1 2679.5 2691.7 2697.2 2707.7 2267.9 2666.0 2675.6 2676.3 2677.4 2236.1 2332.6 2642.5 2651.2 2654.3 13114.1 13126.3 13225.2 13242.4 13258.3 7246.9 7528.4 7529.9 7532.3 7532.8 4057.2 4253.4 4291.1 4294.4 4321.0 1218.5 1390.1 1395.1 1416.6 1420.3 19486.9 19531.9 19658.3 19665.8 19715.7 6105.3 6157.7 6193.2 6267.5 6274.4 13900.2 13916.2 13930.7 13939.3 13957.7 6582.9 7034.6 7059.0 7253.7 7292.7 6517.6 6675.8 6731.5 6773.1 6779.7 4875.4 4996.9 5064.9 5071.4 5111.6 1461.2 1463.0 1463.7 1485.5 1485.9 825.8 831.7 860.4 867.1 895.6 6975.4 7110.0 7777.8 7861.7 7912.4 677.6 680.4 686.3 692.9 699.5 4551.9 4756.0 5026.3 5248.7 5572.5 17421.0 17945.6 17971.5 17995.0 18363.6 17452.7 18060.2 18221.4 18222.9 18305.4 7850.4 8866.8 9240.2 9368.0 9421.2 8122.5 8192.5 8198.3 8225.5 8485.8 15911.5 15955.2 15991.0 16086.6 16207.6 15853.2 16280.9 16300.4 16313.0 16338.2 10384.9 10674.3 10846.9 10891.9 10903.0 12466.5 12644.0 12650.2 12658.1 12744.3 9951.3 10128.9 10131.8 10253.6 10393.2 9888.2 9908.5 9965.0 10004.3 10031.7 9033.6 9095.9 9217.1 9230.6 9232.6 6282.8 6450.9 6548.5 6959.9 7131.2 5822.4 6789.8 6901.4 7205.8 7271.3 19:36:51 INFO - PID 1661 | 19:36:51 INFO - PID 1661 | [#4] prototype.html Cycles:180 Average:5614.49 Median:6379.80 stddev:1754.51 (27.5%) stddev-sans-first:1716.06 19:36:51 INFO - PID 1661 | Values: 448.8 450.9 460.4 461.2 471.7 6077.0 6501.3 6506.8 6549.6 6567.4 6162.5 6359.7 6373.3 6421.8 6425.8 3752.5 4218.3 4772.7 4804.0 4819.4 4077.6 4791.1 4828.4 4841.2 4849.3 3725.1 4400.3 4706.2 4752.3 4765.0 4760.5 4762.0 4769.6 4780.7 4787.5 3845.8 4183.4 4189.4 4202.4 4217.3 6315.2 6325.7 6343.9 6357.7 6360.4 5120.7 5479.0 5728.7 5746.4 5766.2 6392.6 6454.4 6860.7 6872.3 6882.1 4379.0 4413.6 4663.9 4788.9 4791.8 6980.8 7245.9 7324.9 7412.3 7416.7 4224.9 4722.8 4754.4 4817.6 4822.9 4671.9 4920.2 4948.9 4958.9 4974.5 4373.6 4577.4 5666.4 5671.8 5683.6 6858.3 6919.1 6943.6 6958.2 6961.0 1687.7 1703.2 1706.4 1706.4 1716.3 1107.3 1122.5 1151.9 1154.5 1158.0 6428.3 6555.7 6582.2 6585.9 6617.8 5899.9 6469.4 6598.9 6763.6 7208.9 6566.5 6571.8 6573.7 6574.8 6590.7 3446.7 3705.2 3747.3 3748.4 3753.3 7388.4 7394.9 7405.6 7412.0 7416.5 6002.5 6666.1 6933.8 7381.6 7404.4 7027.6 7190.7 7345.4 7383.1 7388.8 7174.9 7190.6 7191.8 7205.0 7205.6 6215.3 6575.7 7022.4 7059.8 7066.4 6201.5 6324.2 7048.5 7391.9 7460.5 4383.4 4384.9 4655.9 4912.6 4915.5 6038.8 7023.6 7211.5 7216.1 7317.9 6433.4 6921.3 6951.8 7020.5 7033.9 6386.3 6927.4 6932.1 6940.8 6977.3 6802.8 6818.0 6854.2 6867.2 6937.5 5325.4 6675.3 6963.5 6982.1 7004.9 6746.8 6753.6 6753.9 6770.7 6795.4 19:36:51 INFO - PID 1661 | 19:36:51 INFO - PID 1661 | [#5] yui.html Cycles:180 Average:24858.49 Median:17551.17 stddev:36961.81 (210.6%) stddev-sans-first:37021.42 19:36:51 INFO - PID 1661 | Values: 818.7 819.6 832.6 855.6 859.2 14742.3 14900.8 15387.8 16824.6 17049.7 16082.8 17981.0 18064.9 18194.7 18303.0 965.5 992.1 1008.7 1009.2 1010.4 1009.0 1022.0 1022.5 1023.9 1026.5 964.8 1073.6 1077.7 1079.6 1080.2 1072.1 1073.3 1075.3 1077.4 1078.8 761.6 770.3 776.5 777.4 778.1 17283.5 17689.4 17754.8 17935.9 18030.2 16998.6 17148.0 17314.0 17417.4 17477.5 14540.0 14681.0 14767.3 14841.9 14877.7 3257.8 3350.4 3556.4 3588.1 3597.9 98436.5 125081.0 138486.0 139307.9 140996.0 13573.3 13656.9 13728.2 13743.9 13817.2 21951.8 23339.9 26398.6 26715.0 26920.7 6462.3 7822.5 7927.4 8012.5 8065.0 3900.1 3941.1 3981.3 3986.8 3988.0 4573.1 4641.8 4664.4 4678.3 4945.3 903.2 1019.5 1053.1 1054.8 1054.9 646.1 646.6 647.3 667.7 678.3 11526.5 11702.9 11862.8 12118.2 12362.9 620.5 643.8 663.7 664.8 665.8 5234.7 5305.2 5425.6 5664.8 5959.0 125022.0 126986.4 127305.4 133374.0 144477.0 116553.0 116826.0 116916.0 117357.0 119833.4 103585.0 109075.0 110335.4 110940.3 113500.9 12038.5 12306.1 12326.5 12503.8 13248.7 45114.9 45697.1 47679.3 47777.8 48207.8 66259.0 66877.7 67230.0 67407.0 67426.3 16733.7 16995.3 17092.9 17392.5 17554.0 18823.2 19090.5 19120.9 19128.5 19132.7 17656.1 17853.0 18252.4 18256.0 18384.3 14568.3 17548.3 17567.9 17661.7 17837.6 16662.7 17422.6 17498.4 17570.6 18061.1 10134.9 11709.8 11810.6 12172.5 12327.9 13495.3 13607.7 14033.7 14095.5 14120.2 19:36:51 INFO - PID 1661 | -------- Summary: end -------- 19:36:51 INFO - PID 1661 | 19:36:51 INFO - TEST-INFO | 1661: exit 0 19:36:51 INFO - TEST-OK | dromaeo_css | took 1143164ms 19:36:51 INFO - TEST-START | kraken 19:36:51 INFO - operating with platform_type : mac_ 19:36:51 INFO - Initialising browser for kraken test... 19:36:51 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmpz1WLd0/profile 19:36:51 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49229/getInfo.html -foreground) 19:37:25 INFO - TEST-INFO | 1670: killed by SIGTERM 19:37:25 INFO - Browser initialized. 19:37:25 INFO - Running cycle 1/1 for kraken test... 19:37:25 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/zw/k_htx6850ms3t4396777xgwh00000w/T/tmpz1WLd0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:37:27 INFO - PID 1674 | 2017-06-08 19:37:27.290 plugin-container[1675:13030] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 19:37:27 INFO - PID 1674 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:37:27 INFO - PID 1674 | 2017-06-08 19:37:27.291 plugin-container[1675:13030] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 19:37:27 INFO - PID 1674 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:37:27 INFO - PID 1674 | 2017-06-08 19:37:27.291 plugin-container[1675:13030] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:37:27 INFO - PID 1674 | 2017-06-08 19:37:27.990 plugin-container[1676:13076] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 19:37:27 INFO - PID 1674 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:37:27 INFO - PID 1674 | 2017-06-08 19:37:27.991 plugin-container[1676:13076] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b0b, name = 'com.apple.CFPasteboardClient' 19:37:27 INFO - PID 1674 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:37:27 INFO - PID 1674 | 2017-06-08 19:37:27.991 plugin-container[1676:13076] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:37:37 INFO - PID 1674 | RSS: Main: 255041536 19:37:37 INFO - PID 1674 | 19:37:39 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection) 19:37:40 INFO - PID 1674 | RSS: Main: 266866688 19:37:40 INFO - PID 1674 | 19:37:43 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-dft) 19:37:44 INFO - PID 1674 | RSS: Main: 276475904 19:37:44 INFO - PID 1674 | 19:37:47 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-fft) 19:37:48 INFO - PID 1674 | RSS: Main: 279842816 19:37:48 INFO - PID 1674 | 19:37:50 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator) 19:37:51 INFO - PID 1674 | RSS: Main: 281948160 19:37:51 INFO - PID 1674 | 19:37:53 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:37:53 INFO - PID 1674 | RSS: Main: 283684864 19:37:53 INFO - PID 1674 | 19:37:56 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom) 19:37:57 INFO - PID 1674 | RSS: Main: 292503552 19:37:57 INFO - PID 1674 | 19:38:00 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate) 19:38:01 INFO - PID 1674 | RSS: Main: 291598336 19:38:01 INFO - PID 1674 | 19:38:04 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial) 19:38:04 INFO - PID 1674 | RSS: Main: 289964032 19:38:04 INFO - PID 1674 | 19:38:06 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:38:07 INFO - PID 1674 | RSS: Main: 289996800 19:38:07 INFO - PID 1674 | 19:38:08 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:38:09 INFO - PID 1674 | RSS: Main: 289382400 19:38:09 INFO - PID 1674 | 19:38:11 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:38:12 INFO - PID 1674 | RSS: Main: 288714752 19:38:12 INFO - PID 1674 | 19:38:15 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:38:15 INFO - PID 1674 | RSS: Main: 288903168 19:38:15 INFO - PID 1674 | 19:38:18 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:38:19 INFO - PID 1674 | RSS: Main: 288817152 19:38:19 INFO - PID 1674 | 19:38:21 INFO - PID 1674 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49229/tests/kraken/driver.html?testName=ai-astar) 19:38:21 INFO - PID 1674 | RSS: Main: 290598912 19:38:21 INFO - PID 1674 | 19:38:21 INFO - PID 1674 | __start_tp_report 19:38:21 INFO - PID 1674 | _x_x_mozilla_page_load 19:38:21 INFO - PID 1674 | _x_x_mozilla_page_load_details 19:38:21 INFO - PID 1674 | |i|pagename|runs| 19:38:21 INFO - PID 1674 | |0;ai-astar;112;117;99;113;116;116;116;113;115;114 19:38:21 INFO - PID 1674 | |1;audio-beat-detection;142;116;138;183;108;130;111;155;148;111 19:38:21 INFO - PID 1674 | |2;audio-dft;200;199;194;208;239;236;280;274;236;197 19:38:21 INFO - PID 1674 | |3;audio-fft;71;107;96;186;62;62;61;64;123;63 19:38:21 INFO - PID 1674 | |4;audio-oscillator;70;84;157;90;70;90;97;72;55;128 19:38:21 INFO - PID 1674 | |5;imaging-gaussian-blur;93;104;134;94;93;100;98;78;134;135 19:38:21 INFO - PID 1674 | |6;imaging-darkroom;99;100;99;99;100;99;104;99;100;100 19:38:21 INFO - PID 1674 | |7;imaging-desaturate;92;128;89;112;145;90;92;90;90;90 19:38:21 INFO - PID 1674 | |8;json-parse-financial;49;50;50;53;51;50;52;50;52;52 19:38:21 INFO - PID 1674 | |9;json-stringify-tinderbox;56;58;57;58;61;60;54;54;56;56 19:38:21 INFO - PID 1674 | |10;stanford-crypto-aes;83;83;109;102;88;84;89;89;109;90 19:38:21 INFO - PID 1674 | |11;stanford-crypto-ccm;143;150;129;144;149;142;129;148;146;154 19:38:21 INFO - PID 1674 | |12;stanford-crypto-pbkdf2;181;180;180;214;191;184;178;178;181;185 19:38:21 INFO - PID 1674 | |13;stanford-crypto-sha256-iterative;68;71;83;89;70;64;64;86;64;94 19:38:21 INFO - PID 1674 | __end_tp_report 19:38:21 INFO - PID 1674 | __start_cc_report 19:38:21 INFO - PID 1674 | _x_x_mozilla_cycle_collect,1196 19:38:21 INFO - PID 1674 | __end_cc_report 19:38:21 INFO - PID 1674 | __startTimestamp1496975901669__endTimestamp 19:38:21 INFO - PID 1674 | 19:38:21 INFO - PID 1674 | ------- Summary: start ------- 19:38:21 INFO - PID 1674 | Number of tests: 14 19:38:21 INFO - PID 1674 | 19:38:21 INFO - PID 1674 | [#0] ai-astar Cycles:10 Average:113.10 Median:116.00 stddev:5.22 (4.5%) stddev-sans-first:5.52 19:38:21 INFO - PID 1674 | Values: 112.0 117.0 99.0 113.0 116.0 116.0 116.0 113.0 115.0 114.0 19:38:21 INFO - PID 1674 | 19:38:21 INFO - PID 1674 | [#1] audio-beat-detection Cycles:10 Average:134.20 Median:140.00 stddev:24.02 (17.2%) stddev-sans-first:25.31 19:38:21 INFO - PID 1674 | Values: 142.0 116.0 138.0 183.0 108.0 130.0 111.0 155.0 148.0 111.0 19:38:21 INFO - PID 1674 | 19:38:21 INFO - PID 1674 | [#2] audio-dft Cycles:10 Average:226.30 Median:236.00 stddev:31.94 (13.5%) stddev-sans-first:32.43 19:38:21 INFO - PID 1674 | Values: 200.0 199.0 194.0 208.0 239.0 236.0 280.0 274.0 236.0 197.0 19:38:21 INFO - PID 1674 | 19:38:21 INFO - PID 1674 | [#3] audio-fft Cycles:10 Average:89.50 Median:62.50 stddev:40.56 (64.9%) stddev-sans-first:42.46 19:38:21 INFO - PID 1674 | Values: 71.0 107.0 96.0 186.0 62.0 62.0 61.0 64.0 123.0 63.0 19:38:21 INFO - PID 1674 | 19:38:21 INFO - PID 1674 | [#4] audio-oscillator Cycles:10 Average:91.30 Median:78.00 stddev:30.46 (39.1%) stddev-sans-first:31.32 19:38:21 INFO - PID 1674 | Values: 70.0 84.0 157.0 90.0 70.0 90.0 97.0 72.0 55.0 128.0 19:38:21 INFO - PID 1674 | 19:38:21 INFO - PID 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:106.30 Median:85.50 stddev:20.49 (24.0%) stddev-sans-first:21.16 19:38:21 INFO - PID 1674 | Values: 93.0 104.0 134.0 94.0 93.0 100.0 98.0 78.0 134.0 135.0 19:38:21 INFO - PID 1674 | 19:38:21 INFO - PID 1674 | [#6] imaging-darkroom Cycles:10 Average:99.90 Median:99.00 stddev:1.52 (1.5%) stddev-sans-first:1.58 19:38:21 INFO - PID 1674 | Values: 99.0 100.0 99.0 99.0 100.0 99.0 104.0 99.0 100.0 100.0 19:38:21 INFO - PID 1674 | 19:38:21 INFO - PID 1674 | [#7] imaging-desaturate Cycles:10 Average:101.80 Median:90.00 stddev:19.92 (22.1%) stddev-sans-first:20.81 19:38:21 INFO - PID 1674 | Values: 92.0 128.0 89.0 112.0 145.0 90.0 92.0 90.0 90.0 90.0 19:38:21 INFO - PID 1674 | 19:38:21 INFO - PID 1674 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:51.50 stddev:1.29 (2.5%) stddev-sans-first:1.17 19:38:21 INFO - PID 1674 | Values: 49.0 50.0 50.0 53.0 51.0 50.0 52.0 50.0 52.0 52.0 19:38:21 INFO - PID 1674 | 19:38:21 INFO - PID 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:57.00 Median:57.50 stddev:2.31 (4.0%) stddev-sans-first:2.42 19:38:21 INFO - PID 1674 | Values: 56.0 58.0 57.0 58.0 61.0 60.0 54.0 54.0 56.0 56.0 19:38:21 INFO - PID 1674 | 19:38:21 INFO - PID 1674 | [#10] stanford-crypto-aes Cycles:10 Average:92.60 Median:86.00 stddev:10.21 (11.9%) stddev-sans-first:10.22 19:38:21 INFO - PID 1674 | Values: 83.0 83.0 109.0 102.0 88.0 84.0 89.0 89.0 109.0 90.0 19:38:21 INFO - PID 1674 | 19:38:21 INFO - PID 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:143.40 Median:147.00 stddev:8.38 (5.7%) stddev-sans-first:8.89 19:38:21 INFO - PID 1674 | Values: 143.0 150.0 129.0 144.0 149.0 142.0 129.0 148.0 146.0 154.0 19:38:21 INFO - PID 1674 | 19:38:21 INFO - PID 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.20 Median:182.50 stddev:10.84 (5.9%) stddev-sans-first:11.39 19:38:21 INFO - PID 1674 | Values: 181.0 180.0 180.0 214.0 191.0 184.0 178.0 178.0 181.0 185.0 19:38:21 INFO - PID 1674 | 19:38:21 INFO - PID 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.30 Median:77.00 stddev:11.52 (15.0%) stddev-sans-first:11.91 19:38:21 INFO - PID 1674 | Values: 68.0 71.0 83.0 89.0 70.0 64.0 64.0 86.0 64.0 94.0 19:38:21 INFO - PID 1674 | -------- Summary: end -------- 19:38:21 INFO - PID 1674 | 19:38:22 INFO - TEST-INFO | 1674: exit 0 19:38:22 INFO - TEST-OK | kraken | took 90814ms 19:38:22 INFO - SUITE-END | took 1233s 19:38:22 INFO - Completed test suite (00:20:34) 19:38:22 INFO - javascript benchmark 19:38:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8352.372184113088, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3146.9685303146966, 3585.5876574193976, 3588.766730162541, 3612.16920108375, 3613.0967258185456, 4959.826406075788, 6037.456628903404, 6073.514118870491, 6135.29444113927, 6154.538409619279, 6114.0540674973245, 6122.234720659918, 6123.22161559001, 6148.35442278561, 6181.660008699531, 5355.491228333302, 5397.190421436777, 5417.64785288957, 5419.051665958461, 5425.240466334716, 5444.673319600817, 5510.752016159283, 5513.50378465938, 5514.283143191379, 5520.0, 4405.713628614129, 5303.575713942875, 5431.809886653988, 5461.69960652164, 5495.587830912677, 4775.737334446639, 5419.3225846769155, 5443.992861320628, 5465.6447330923365, 5492.340919089695, 5376.381716102659, 5385.838424847261, 5388.73056347185, 5396.622236443451, 5436.592255580847, 6039.607425517372, 6078.8784224315705, 6148.754049838001, 6162.414570615829, 6170.537209709245, 6543.378379053982, 6550.246721627026, 6643.335666433366, 6660.267370589231, 6676.0, 6749.19009718832, 6754.8311292217595, 6761.290064543201, 6768.763093291711, 6778.322167783232, 6230.7819226326865, 6239.500839932794, 6251.999680051215, 6270.122182894398, 6271.466925311308, 6685.0640910272605, 6760.357766012221, 6799.0, 6845.657717114124, 6849.212340580797, 6218.751249950042, 6404.42360187576, 6522.086907832907, 6602.10871532337, 6615.272320044791, 6140.657627863787, 6298.685065746786, 6617.735290588419, 6694.0, 6757.797266082025, 6624.569402988886, 6636.7013484392355, 6658.667066646745, 6666.100076489709, 6677.866442671217, 6399.55203135776, 6471.611703297723, 6571.0, 6620.668966551653, 6668.399844013965, 4623.3758442609815, 5336.813211537577, 5350.91973620396, 5354.759035843396, 5383.838484845461, 4392.3411488277015, 4703.811847526129, 4741.312509686076, 4763.309320148559, 4770.451398089194, 6211.2857021442205, 6238.906416403757, 6243.250809902841, 6288.371162883766, 6354.3010268870385, 5772.682502462405, 6752.83117922046, 6802.3537763912345, 6803.727850886008, 6819.795406137824, 5413.296271484682, 6347.174867267318, 6348.206474190726, 6357.396047375492, 6392.328805475451, 5761.9135712964335, 5790.681512516852, 5798.59409841307, 5860.267466566679, 6049.971504844187, 6776.728930842711, 6830.385265326145, 6848.6918088687125, 6873.553219040747, 6899.17209934811, 6732.124823772878, 6743.8988415172835, 6752.5948443093575, 6761.154855643044, 6779.491538134561, 6641.900371494335, 6817.795466136024, 6869.793906182823, 6888.690008949709, 6889.483288753263, 6488.221413430419, 6528.967355163384, 6576.473882089517, 6599.868002640016, 6648.235452922976, 6726.7981960541265, 6743.15710536183, 6756.69594868242, 6772.52592318533, 6775.051492791112, 4856.611471082234, 5066.468020857904, 6378.394052564999, 6477.8704425912165, 6562.442192413699, 6541.215054193527, 6554.770583029571, 6575.210974683068, 6595.4393876521035, 6601.53789234749, 5527.861803454945, 6179.28938172116, 6236.908541005215, 6469.353064693493, 6528.37980391862, 6372.235331760218, 6427.517936154901, 6572.244191917799, 6587.63767992765, 6593.571417857824, 6267.2165979252595, 6304.71628776697, 6324.367563243639, 6345.1434056401795, 6373.458256048288], "value": 6041.443381225738, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [693.4175292754086, 790.4269404681606, 840.6847432212918, 1237.8205160251757, 1295.6890346316886, 39053.609463905384, 40576.79711601445, 40592.0, 40598.797006015004, 40631.79684101583, 40876.38685419721, 41085.0, 41089.0, 41089.0, 41155.38266925991, 7841.137474877759, 8009.479383840061, 8258.710945116922, 8489.915100848973, 8500.829983400328, 8418.831623367529, 8553.358498112635, 8568.0, 8568.357373197005, 8624.698135565257, 8528.658853645848, 8533.74398768038, 8553.74338769838, 8565.657373705044, 8572.442791218582, 8188.0, 8541.914580854236, 8544.530050847232, 8546.230839224469, 8568.70009549669, 7068.858622827566, 8364.958175209145, 8380.371472139614, 8392.496450212946, 8393.580320983987, 37872.81063594665, 38046.42930356047, 38108.42837357442, 38133.80933095317, 38270.234595307935, 3627.147620309225, 3668.6514781095752, 3670.4861319415036, 3672.8714494992814, 3676.356637588411, 30906.381872362428, 30911.845440772653, 30948.0715578533, 30996.380072398424, 31000.379992400252, 2628.7371262873753, 3108.6580476147833, 3110.891118810853, 3143.0413723814227, 3154.5110507871086, 242258.0, 244881.0, 277906.22093779006, 290675.0, 291975.0, 50016.24975625369, 50295.74852125716, 50390.496095039314, 50455.24317135246, 50473.74763126234, 52450.0, 52549.73725131426, 52764.736176318875, 52816.73591632017, 52898.73550632222, 14286.428542858377, 14417.279136043157, 14516.854831451761, 14583.635409114857, 14604.634884127981, 11903.285802851857, 11927.343996080126, 12026.579069732692, 12048.759024819454, 12064.939675301743, 14317.427302907976, 14336.784948225784, 14396.920230982718, 14403.91970602209, 14485.348159332856, 5301.416844147141, 6239.75040998355, 6315.0, 6320.936790632127, 6324.683765811783, 974.741693451236, 1007.2445665750687, 1030.5105075089423, 1047.3506426015767, 1090.8091084060416, 20695.79304206939, 24949.376265593142, 24991.750082499308, 25273.62089568658, 25509.61735573968, 1113.0, 1115.1524841120645, 1139.9316041037398, 1161.2219812725493, 1178.0810967445407, 11106.389148596743, 12004.579839705568, 12910.483580656857, 12917.22496650204, 12972.545960891324, 301156.4942175319, 302058.0, 302700.4864975661, 303467.0, 303625.0, 51759.0, 52765.88936442499, 57660.42339576551, 57689.71155144197, 58169.70915145397, 1243.9875601244053, 1486.152892851086, 1524.3140586736013, 1535.0636111971912, 1546.798916140917, 19759.802401975798, 23221.651675225225, 23565.528689425773, 24713.505729885663, 24757.62863557084, 38185.6181438182, 39397.2120557593, 39478.40782388324, 39642.60357396389, 39692.40461393024, 286221.0, 289136.0, 289172.0, 289750.0, 289888.0, 36048.639513604525, 37964.810175948944, 38185.0, 38357.65748198911, 38461.038474038374, 46236.30645540387, 46484.53515464938, 48344.516554834, 48736.75631621819, 48869.26696099633, 30704.232394190323, 30772.230694232825, 32359.838200808845, 32754.67245327516, 32925.83537082299, 30937.535936961413, 31328.686713133488, 31992.52011219787, 32477.35045299128, 32690.0, 37642.43536346902, 38875.0, 39272.41091383689, 39427.80286098551, 39624.40563391494, 30798.84600576983, 31292.530612040384, 32636.0, 33034.84378046853, 33350.332993339514, 23593.764062359158, 25118.24645260645, 25258.535273820933, 25477.490450191264, 25541.87229063843], "value": 17897.072810441572, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.17573887998725, 501.9734642655771, 502.7335512178544, 521.7938914128919, 531.1528112660305, 21544.0, 21645.567088658216, 22414.103435862566, 22458.88770556147, 24606.630900536507, 21331.68002479964, 25359.619605705928, 25471.36321591961, 25482.745172548268, 25505.872470637667, 6364.077208804721, 6487.805365839033, 6510.772122975697, 6511.772087976922, 6525.575837570566, 6078.39216078393, 6409.006603976368, 6468.417842394184, 6482.805515834532, 6485.675716214194, 6312.368763123697, 6335.809925702236, 6374.362563743612, 6375.0, 6405.647689377082, 6352.205974253218, 6377.3303803100935, 6378.138951241569, 6378.681065946684, 6390.808275751735, 5782.595218334718, 5818.767249310023, 5843.240378750777, 5843.474087332118, 5849.736761845727, 29204.269893252625, 29719.702802972126, 29825.70174298251, 29857.95497157611, 29947.951821686354, 2216.4569680428235, 2258.108047321315, 2259.20927675314, 2260.8191344692386, 2270.1713874435713, 18888.24447022131, 19497.707534386995, 19528.31650892226, 19550.022498874998, 19551.706724399002, 2199.8790066546494, 2228.8551244169075, 2258.954820903573, 2260.785225403584, 2269.6482045283005, 173713.0, 181896.0, 197772.0, 205560.91658625132, 206777.0, 13910.374033168531, 14502.32149641543, 15281.9235903822, 15471.613209669847, 15698.215089245492, 20299.594008119755, 20383.286584969454, 20384.796152038587, 20397.796022039885, 20411.285605003755, 9980.201583873273, 9983.700488985196, 9997.450140242356, 9999.450030248261, 10031.949840250752, 9058.728238152866, 9144.405613635092, 9161.221296189899, 9181.862272065924, 9244.953775231215, 8374.455660382057, 8605.225529702231, 9113.81772364549, 9223.76940576491, 9264.39781414206, 2621.0, 2634.4467661790854, 2640.0, 2644.1803041057333, 2766.322251048506, 957.5116690487902, 970.1171933540444, 971.4656938683837, 979.4564016970508, 984.493724601361, 20715.378538643865, 21191.57616847685, 21584.892075539523, 21620.56758864814, 21762.455938601346, 978.6721448314752, 988.2094324540483, 1024.8155332040155, 1028.681108856257, 1031.4275577054657, 10689.518971646294, 10838.024577788037, 11236.438178091063, 11255.324680519196, 11492.540298388138, 545271.0, 551175.0, 552397.0, 562936.0, 563735.0, 143420.0, 148347.54957351298, 148579.0, 149472.0, 150351.0, 1010.2978429991233, 1027.2244455436248, 1047.7275908263907, 1187.8752730963297, 1222.896053835434, 21938.903054846876, 22397.104115835835, 23232.76767232374, 23457.765422345557, 23487.295381138592, 66222.0, 67304.0, 68815.62368752497, 68851.0, 70699.64650176716, 74178.77463676098, 86876.0, 91943.0, 92081.0, 92545.0, 11154.72113197144, 11414.771704566028, 11551.76896462083, 11571.6528504145, 11574.305541667527, 17958.0, 18250.9049457033, 18411.723824142915, 18493.53766155857, 18535.165917533475, 14448.855511445172, 14853.257337133316, 14941.252937352872, 14945.626359341104, 15027.924860375628, 11107.944460277648, 12967.740645186856, 13210.075294729277, 13506.797398039234, 13662.453501859814, 14828.481003164838, 15104.924475377553, 15132.319045642753, 15254.618634534225, 15273.389064437742, 11113.944430278121, 11344.319340839576, 11376.658700239006, 11393.031592314746, 11408.942955285502, 12488.75022499563, 14721.631959201106, 14865.0, 15019.32413041394, 15047.849521505083], "value": 11713.625603097973, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1264.6901509130264, 1441.963950901228, 1448.3337664674257, 1453.9709205815884, 1462.7805829125632, 12903.806442903364, 12953.22280663161, 13455.932720336397, 13481.797773033413, 13590.116642418261, 13300.600981970556, 13406.061575689706, 13530.93234533826, 13533.864661353384, 13535.0, 2701.2166471723226, 2759.434315965232, 2762.406082692221, 2762.875670594818, 2766.9723302766915, 2671.225344650049, 2702.9459410811774, 2703.0539311241005, 2711.227300219438, 2719.3201699575106, 2678.08944958714, 2679.517686816372, 2691.6904555976116, 2697.1908427471776, 2707.7021527631946, 2267.9340709866337, 2665.986670066647, 2675.5852842809395, 2676.264027392463, 2677.437738075016, 2236.1390864517116, 2332.5801355755957, 2642.4979253941883, 2651.244395347336, 2654.349684327333, 13114.082014259006, 13126.278054706987, 13225.206487610774, 13242.404091815892, 13258.337083145803, 7246.855062898765, 7528.435367347416, 7529.887051694229, 7532.284432978913, 7532.7740167795055, 4057.16828050249, 4253.3619957006385, 4291.077418355068, 4294.377315289265, 4320.956790432087, 1218.4760552962223, 1390.1103293891917, 1395.0513650717508, 1416.624594482463, 1420.2685616907302, 19486.902565487224, 19531.902340488206, 19658.311959081362, 19665.80334196654, 19715.704264436044, 6105.267367915879, 6157.7229024694, 6193.194884664965, 6267.4986001119805, 6274.403931626488, 13900.16599004063, 13916.165030098227, 13930.721385572231, 13939.303034848217, 13957.720845583031, 6582.934170658328, 7034.577925324497, 7058.964705176441, 7253.709851605982, 7292.744753933587, 6517.576357536745, 6675.7997260082275, 6731.461483081342, 6773.051772751819, 6779.694913728894, 4875.414950205998, 4996.850094497171, 5064.924026139611, 5071.39143302806, 5111.616628752859, 1461.2328527523136, 1462.978055329171, 1463.6706740983193, 1485.4652325162933, 1485.8959872809016, 825.7902173316, 831.6507067031861, 860.4063196394468, 867.0765634599131, 895.552223888056, 6975.372216500539, 7110.0, 7777.794441861521, 7861.685532578633, 7912.446128771046, 677.5866721300004, 680.4012469027323, 686.2965460403027, 692.8891377379595, 699.4928676709345, 4551.908961820745, 4756.0, 5026.29631851541, 5248.711320877388, 5572.4706152915405, 17421.0, 17945.551361216076, 17971.460856174337, 17995.0, 18363.63272734538, 17452.738208926876, 18060.18729157165, 18221.362252321105, 18222.908885455487, 18305.359312424, 7850.411219158586, 8866.77833054166, 9240.214581760625, 9367.969523352289, 9421.24630029596, 8122.472039317426, 8192.508449493049, 8198.303144232568, 8225.54746682687, 8485.787855303666, 15911.522654319926, 15955.20223988819, 15991.040537567784, 16086.597835054217, 16207.594810129842, 15853.207339633203, 16280.918595406947, 16300.42948496797, 16313.0, 16338.183090845649, 10384.948075259877, 10674.252802303765, 10846.891531084588, 10891.891081089405, 10903.0, 12466.5013399463, 12643.98848092132, 12650.177738446971, 12658.11393202467, 12744.299063551594, 9951.253655975976, 10128.898711013091, 10131.848022279819, 10253.58985640566, 10393.16854651611, 9888.159506442033, 9908.504574771088, 9964.950175249078, 10004.299699021, 10031.69904902854, 9033.638654454011, 9095.863562046443, 9217.124373184537, 9230.584623691815, 9232.630694772133, 6282.780102696383, 6450.870982580416, 6548.476121910331, 6959.89560156588, 7131.215566287808, 5822.446867547576, 6789.762358317435, 6901.378875901193, 7205.819854503679, 7271.272872712686], "value": 5973.6956034574005, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [448.8092560661719, 450.91432627800714, 460.3646967185282, 461.24586301397204, 471.71461265934107, 6076.994591784712, 6501.284858665549, 6506.837329066776, 6549.607023578576, 6567.3760992705675, 6162.537809664271, 6359.68201589921, 6373.267074286458, 6421.7752378666755, 6425.775097871576, 3752.4746535485056, 4218.303979843337, 4772.68977516462, 4804.0, 4819.42166939966, 4077.5718549552316, 4791.113643975858, 4828.396450443694, 4841.24960631101, 4849.272609108644, 3725.0501122213795, 4400.339949007658, 4706.152892479352, 4752.310914917335, 4765.0, 4760.523947605212, 4762.047590481918, 4769.618430525549, 4780.713157210561, 4787.521247875219, 3845.8461661533506, 4183.372494125875, 4189.371594260855, 4202.432671589358, 4217.325227963542, 6315.179026726542, 6325.746970121189, 6343.936560634381, 6357.696672182192, 6360.427561519439, 5120.718360490171, 5478.986387518322, 5728.656280623176, 5746.367899531048, 5766.221560089376, 6392.584482008655, 6454.354139694395, 6860.6912688929115, 6872.347127022975, 6882.139732533434, 4378.978105109486, 4413.646908247332, 4663.930041049387, 4788.928166077511, 4791.760411979387, 6980.790576282669, 7245.927540724578, 7324.926750732478, 7412.332890039868, 7416.7404140854815, 4224.936625950613, 4722.763861806896, 4754.358161648202, 4817.590504807131, 4822.879428014257, 4671.9254571448755, 4920.21276595743, 4948.851534453972, 4958.925616115761, 4974.452810190876, 4373.6282415994365, 4577.404937358122, 5666.4050274721385, 5671.773129074873, 5683.573731970118, 6858.348456896587, 6919.135108111486, 6943.618101004392, 6958.199807022156, 6960.965195173992, 1687.6540309236577, 1703.173960629094, 1706.3942300483398, 1706.4368758309977, 1716.2534297580592, 1107.291333546531, 1122.5397586989295, 1151.9366434846163, 1154.5381847260994, 1157.9710507237387, 6428.260750013808, 6555.737770489127, 6582.243042050225, 6585.904624826324, 6617.834554136089, 5899.852503687356, 6469.385408386196, 6598.934010659928, 6763.594184348856, 7208.927910720826, 6566.507511936528, 6571.835704107436, 6573.671316434159, 6574.750797348488, 6590.670466476753, 3446.7070299024363, 3705.166337574078, 3747.3254814133174, 3748.4002559590335, 3753.2868754936476, 7388.371988381048, 7394.889076663854, 7405.592692401862, 7411.962940185373, 7416.480846340704, 6002.519798416116, 6666.0667506549125, 6933.826654333682, 7381.557106573516, 7404.407647388196, 7027.578345299298, 7190.7483238086415, 7345.375643070292, 7383.07711536058, 7388.7783366498015, 7174.892376614459, 7190.568565886064, 7191.82020449472, 7205.0, 7205.567665940061, 6215.316315205414, 6575.736970521126, 7022.403095736715, 7059.752908648173, 7066.434685225273, 6201.503879689704, 6324.177856878577, 7048.541844780073, 7391.926080739339, 7460.477766556289, 4383.408239887574, 4384.912301753883, 4655.882588178881, 4912.606991440604, 4915.459299476983, 6038.788642397495, 7023.613701246481, 7211.531250468703, 7216.134063912364, 7317.8902316466365, 6433.388828061326, 6921.2732663071665, 6951.826204344932, 7020.508564400443, 7033.859322813618, 6386.329435409401, 6927.35866969142, 6932.064171336805, 6940.7917762467205, 6977.302269773185, 6802.795916122524, 6817.965910170616, 6854.246032936273, 6867.175938887365, 6937.479689023445, 5325.387580428301, 6675.299093595103, 6963.502846887944, 6982.057422247932, 7004.929950700632, 6746.831329216612, 6753.5610185339765, 6753.898691519729, 6770.729170833111, 6795.359851323488], "value": 5083.593279307727, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [818.67662273402, 819.569725893906, 832.6003518311206, 855.6096343441907, 859.2352806002659, 14742.26288685564, 14900.77648835267, 15387.846121538782, 16824.579385515368, 17049.65900681987, 16082.758758618631, 17981.01104439255, 18064.916105033673, 18194.727079093824, 18303.0, 965.5365424596181, 992.082323850439, 1008.7326858382535, 1009.167436864586, 1010.4291075542332, 1008.9848652270222, 1022.0239671114068, 1022.5091955861191, 1023.9283250172491, 1026.5226669598635, 964.7973925475659, 1073.5759375046853, 1077.7467295185625, 1079.6005477973122, 1080.2384319055047, 1072.099436473366, 1073.2969904712427, 1075.2849355178796, 1077.3535878472933, 1078.8273876179774, 761.5849362097615, 770.3028758973138, 776.4875182379602, 777.4091690315345, 778.1284960843834, 17283.481495555152, 17689.38087166956, 17754.822451775446, 17935.910320448445, 18030.18864151103, 16998.575035624086, 17148.0, 17314.04772737499, 17417.39039133624, 17477.475675729747, 14540.0, 14680.97233193677, 14767.335469903881, 14841.851581484156, 14877.702445951021, 3257.7719559630837, 3350.3801796667694, 3556.448750443685, 3588.067102553329, 3597.9460308095136, 98436.50781746117, 125081.0, 138486.0, 139307.91038134435, 140996.0, 13573.25347105899, 13656.93171534136, 13728.244946527837, 13743.931280343535, 13817.170969741848, 21951.780482195292, 23339.88330058373, 26398.6040209397, 26715.0, 26920.730792692215, 6462.256840463407, 7822.530648161129, 7927.3658107351275, 8012.5192488450875, 8064.959675201587, 3900.1224724436775, 3941.0935484838647, 3981.343078392091, 3986.8405263789123, 3987.980060099739, 4573.131105090022, 4641.767911604474, 4664.39362882823, 4678.345031695565, 4945.332380128709, 903.1690844423147, 1019.5412064570972, 1053.115383078218, 1054.836500342448, 1054.8997845204694, 646.0954663471178, 646.5668002438283, 647.2847503508649, 667.6695035957135, 678.3216783216783, 11526.481308341145, 11702.941485292518, 11862.762744745056, 12118.151729379035, 12362.876371236352, 620.525298146918, 643.7907680003924, 663.6582160187507, 664.8404382948154, 665.8368699668612, 5234.712090834964, 5305.177697456823, 5425.643589102724, 5664.773409063674, 5958.970205148947, 125022.0, 126986.36506817407, 127305.3634731839, 133374.0, 144477.0, 116553.0, 116826.0, 116916.0, 117357.0, 119833.40083299528, 103585.0, 109075.0, 110335.37987720763, 110940.33589496164, 113500.86499135068, 12038.458269377725, 12306.077044221898, 12326.50693972231, 12503.812442813183, 13248.668783280495, 45114.87212819575, 45697.074440187396, 47679.28481072717, 47777.761111194224, 48207.75896120497, 66259.00611490734, 66877.66561167163, 67230.0, 67407.0, 67426.325736742, 16733.665326693153, 16995.320187192374, 17092.914535427244, 17392.47822565325, 17554.034528100565, 18823.247070117042, 19090.52273693169, 19120.904395477934, 19128.521786955436, 19132.713009304593, 17656.117194140497, 17853.0, 18252.36116735961, 18255.995920223984, 18384.264629414673, 14568.34442450071, 17548.298068077645, 17567.912160439115, 17661.735073974156, 17837.554061148574, 16662.666746664756, 17422.564435889206, 17498.387556435464, 17570.648587028445, 18061.096945152953, 10134.949325253327, 11709.765804683688, 11810.586629467927, 12172.513099475922, 12327.93836030814, 13495.25776082325, 13607.659808504866, 14033.719325613634, 14095.506657267357, 14120.152790832582], "value": 8826.784330719192, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1566.7999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 117.0, 99.0, 113.0, 116.0, 116.0, 116.0, 113.0, 115.0, 114.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 116.0, 138.0, 183.0, 108.0, 130.0, 111.0, 155.0, 148.0, 111.0], "value": 134.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 199.0, 194.0, 208.0, 239.0, 236.0, 280.0, 274.0, 236.0, 197.0], "value": 226.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 107.0, 96.0, 186.0, 62.0, 62.0, 61.0, 64.0, 123.0, 63.0], "value": 89.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 84.0, 157.0, 90.0, 70.0, 90.0, 97.0, 72.0, 55.0, 128.0], "value": 91.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 104.0, 134.0, 94.0, 93.0, 100.0, 98.0, 78.0, 134.0, 135.0], "value": 106.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 100.0, 99.0, 99.0, 100.0, 99.0, 104.0, 99.0, 100.0, 100.0], "value": 99.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 128.0, 89.0, 112.0, 145.0, 90.0, 92.0, 90.0, 90.0, 90.0], "value": 101.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 50.0, 53.0, 51.0, 50.0, 52.0, 50.0, 52.0, 52.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 58.0, 57.0, 58.0, 61.0, 60.0, 54.0, 54.0, 56.0, 56.0], "value": 57.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 109.0, 102.0, 88.0, 84.0, 89.0, 89.0, 109.0, 90.0], "value": 92.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 150.0, 129.0, 144.0, 149.0, 142.0, 129.0, 148.0, 146.0, 154.0], "value": 143.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 180.0, 180.0, 214.0, 191.0, 184.0, 178.0, 178.0, 181.0, 185.0], "value": 185.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 71.0, 83.0, 89.0, 70.0, 64.0, 64.0, 86.0, 64.0, 94.0], "value": 75.3, "unit": "score"}]}]} 19:38:22 INFO - Return code: 0 19:38:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:38:23 INFO - # TBPL SUCCESS # 19:38:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:38:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:38:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:38:23 INFO - 2017-06-08 19:38:23.059 screenresolution[1682:13464] starting screenresolution argv=screenresolution get 19:38:23 INFO - 2017-06-08 19:38:23.062 screenresolution[1682:13464] Display 0: 1600x1200x32@60 19:38:23 INFO - 2017-06-08 19:38:23.068 screenresolution[1683:13467] starting screenresolution argv=screenresolution list 19:38:23 INFO - Available Modes on Display 0 19:38:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:38:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:38:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:38:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:38:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:38:23 INFO - Intel Iris: 19:38:23 INFO - Chipset Model: Intel Iris 19:38:23 INFO - Type: GPU 19:38:23 INFO - Bus: Built-In 19:38:23 INFO - VRAM (Dynamic, Max): 1536 MB 19:38:23 INFO - Vendor: Intel (0x8086) 19:38:23 INFO - Device ID: 0x0a2e 19:38:23 INFO - Revision ID: 0x0009 19:38:23 INFO - Displays: 19:38:23 INFO - Display: 19:38:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:38:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:38:23 INFO - Display Serial Number: 1600x1200 60 19:38:23 INFO - Main Display: Yes 19:38:23 INFO - Mirror: Off 19:38:23 INFO - Online: Yes 19:38:23 INFO - Rotation: Supported 19:38:23 INFO - Return code: 0 19:38:23 INFO - Running post-action listener: _resource_record_post_action 19:38:23 INFO - [mozharness: 2017-06-09 02:38:23.303721Z] Finished run-tests step (success) 19:38:23 INFO - Running post-run listener: _resource_record_post_run 19:38:23 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 33115648; Write bytes: 572985344; Read time: 353; Write time: 2067 19:38:23 INFO - TinderboxPrint: CPU usage
53.1% 19:38:23 INFO - TinderboxPrint: I/O read bytes / time
33,115,648 / 353 19:38:23 INFO - TinderboxPrint: I/O write bytes / time
572,985,344 / 2,067 19:38:23 INFO - TinderboxPrint: CPU idle
2,357.5 (46.9%) 19:38:23 INFO - TinderboxPrint: CPU system
1,015.4 (20.2%) 19:38:23 INFO - TinderboxPrint: CPU user
1,656.8 (32.9%) 19:38:23 INFO - TinderboxPrint: Swap in / out
275,824,640 / 0 19:38:23 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 234013184; Write bytes: 229466112; Read time: 17735; Write time: 424 19:38:23 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:38:23 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 31260160; Write bytes: 325025792; Read time: 333; Write time: 1602 19:38:23 INFO - Running post-run listener: _upload_blobber_files 19:38:23 INFO - Blob upload gear active. 19:38:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:38:23 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 19:38:23 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:38:23 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:38:24 INFO - (blobuploader) - INFO - Open directory for files ... 19:38:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:38:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:38:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:38:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:38:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:38:25 INFO - (blobuploader) - INFO - Done attempting. 19:38:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:38:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:38:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:38:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:38:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:38:26 INFO - (blobuploader) - INFO - Done attempting. 19:38:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:38:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:38:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:38:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:38:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:38:28 INFO - (blobuploader) - INFO - Done attempting. 19:38:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:38:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:38:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:38:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:38:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:38:30 INFO - (blobuploader) - INFO - Done attempting. 19:38:30 INFO - (blobuploader) - INFO - Iteration through files over. 19:38:30 INFO - Return code: 0 19:38:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:38:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:38:30 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0fc3c47f38ef57c91dbe8c3bd38fd9d2178b4f69b196e06a2d24ea81432f8ed2e69f3ca4aa9027d265267d5ddf83fd8276292cfa21fab3252b70fe10999f992", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55574b7ead0922ae0e962aa158b45227907dbd79592397a3c87657ab724fc23c69cb42d0f0de2805c6e916d1fbdc179c75c9ec772b3aff3b772b6287dfe80cd3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b70638a6bd87f006a7d3465e55ecd706057170d56bc1f6c3492431b059be36a9bfa83f254c97e5af21c3a2f97efe02cab6defd40aad55450522e4023c8bd1e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef43dc5d3285bbfc154dbe3bdfb1c2e4931e22eecf5be94652dcb3a95f9cc5bb651fb8aafc856f1adce3044a95305232d7154bfb33a82029d85a27a353421304"} 19:38:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:38:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:38:30 INFO - Contents: 19:38:30 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0fc3c47f38ef57c91dbe8c3bd38fd9d2178b4f69b196e06a2d24ea81432f8ed2e69f3ca4aa9027d265267d5ddf83fd8276292cfa21fab3252b70fe10999f992", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55574b7ead0922ae0e962aa158b45227907dbd79592397a3c87657ab724fc23c69cb42d0f0de2805c6e916d1fbdc179c75c9ec772b3aff3b772b6287dfe80cd3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b70638a6bd87f006a7d3465e55ecd706057170d56bc1f6c3492431b059be36a9bfa83f254c97e5af21c3a2f97efe02cab6defd40aad55450522e4023c8bd1e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef43dc5d3285bbfc154dbe3bdfb1c2e4931e22eecf5be94652dcb3a95f9cc5bb651fb8aafc856f1adce3044a95305232d7154bfb33a82029d85a27a353421304"} 19:38:30 INFO - Running post-run listener: copy_logs_to_upload_dir 19:38:30 INFO - Copying logs to upload dir... 19:38:30 INFO - mkdir: /builds/slave/test/build/upload/logs 19:38:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1340.567303 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-06-08 19:38:30.589408) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-08 19:38:30.593365) ========= 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.u7NKI1eUWi/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.T4BeyEAOjo/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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/Firefox/sha512/f0fc3c47f38ef57c91dbe8c3bd38fd9d2178b4f69b196e06a2d24ea81432f8ed2e69f3ca4aa9027d265267d5ddf83fd8276292cfa21fab3252b70fe10999f992", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55574b7ead0922ae0e962aa158b45227907dbd79592397a3c87657ab724fc23c69cb42d0f0de2805c6e916d1fbdc179c75c9ec772b3aff3b772b6287dfe80cd3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b70638a6bd87f006a7d3465e55ecd706057170d56bc1f6c3492431b059be36a9bfa83f254c97e5af21c3a2f97efe02cab6defd40aad55450522e4023c8bd1e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef43dc5d3285bbfc154dbe3bdfb1c2e4931e22eecf5be94652dcb3a95f9cc5bb651fb8aafc856f1adce3044a95305232d7154bfb33a82029d85a27a353421304"} build_url:https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011289 build_url: 'https://queue.taskcluster.net/v1/task/SpoS0iwSTJSr8Ev-xJPyhg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0fc3c47f38ef57c91dbe8c3bd38fd9d2178b4f69b196e06a2d24ea81432f8ed2e69f3ca4aa9027d265267d5ddf83fd8276292cfa21fab3252b70fe10999f992", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55574b7ead0922ae0e962aa158b45227907dbd79592397a3c87657ab724fc23c69cb42d0f0de2805c6e916d1fbdc179c75c9ec772b3aff3b772b6287dfe80cd3", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8b70638a6bd87f006a7d3465e55ecd706057170d56bc1f6c3492431b059be36a9bfa83f254c97e5af21c3a2f97efe02cab6defd40aad55450522e4023c8bd1e5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef43dc5d3285bbfc154dbe3bdfb1c2e4931e22eecf5be94652dcb3a95f9cc5bb651fb8aafc856f1adce3044a95305232d7154bfb33a82029d85a27a353421304"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-08 19:38:30.628010) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 19:38:30.628357) ========= 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.u7NKI1eUWi/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.T4BeyEAOjo/Listeners TMPDIR=/var/folders/zw/k_htx6850ms3t4396777xgwh00000w/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.005054 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 19:38:30.676728) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-08 19:38:30.677078) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-08 19:38:30.677420) ========= ========= Total master_lag: 0.18 =========